builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1461739060.83 results: success (0) buildid: 20160426204703 builduid: 3128f97fa2c4407092549a518d577e25 revision: 3b60d399d4eef579688a499675c337a252a7bcac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:40.831152) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:40.831614) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:40.831908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:40.861108) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:40.861429) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 23:37:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 103M=0s 2016-04-26 23:37:41 (103 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.043100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.043527) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021288 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.093554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.093913) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3b60d399d4eef579688a499675c337a252a7bcac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3b60d399d4eef579688a499675c337a252a7bcac --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=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 23:37:41,865 truncating revision to first 12 chars 2016-04-26 23:37:41,865 Setting DEBUG logging. 2016-04-26 23:37:41,865 attempt 1/10 2016-04-26 23:37:41,865 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3b60d399d4ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 23:37:42,099 unpacking tar archive at: mozilla-aurora-3b60d399d4ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.497142 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.603903) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.604202) ========= script_repo_revision: 3b60d399d4eef579688a499675c337a252a7bcac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.604638) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.604916) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 23:37:41.614927) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-26 23:37:41.615252) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:37:42 INFO - MultiFileLogger online at 20160426 23:37:42 in /builds/slave/test 23:37:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:37:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:37:42 INFO - {'append_to_log': False, 23:37:42 INFO - 'base_work_dir': '/builds/slave/test', 23:37:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:37:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:37:42 INFO - 'branch': 'Mozilla-Aurora', 23:37:42 INFO - 'buildbot_json_path': 'buildprops.json', 23:37:42 INFO - 'config_files': ('talos/linux_config.py',), 23:37:42 INFO - 'default_actions': ('clobber', 23:37:42 INFO - 'read-buildbot-config', 23:37:42 INFO - 'download-and-extract', 23:37:42 INFO - 'populate-webroot', 23:37:42 INFO - 'create-virtualenv', 23:37:42 INFO - 'install', 23:37:42 INFO - 'run-tests'), 23:37:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:37:42 INFO - 'download_minidump_stackwalk': True, 23:37:42 INFO - 'download_symbols': 'ondemand', 23:37:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:37:42 INFO - 'tooltool.py': '/tools/tooltool.py', 23:37:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:37:42 INFO - '/tools/misc-python/virtualenv.py')}, 23:37:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:37:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:37:42 INFO - 'installer_path': 'installer.exe', 23:37:42 INFO - 'log_level': 'info', 23:37:42 INFO - 'log_name': 'talos', 23:37:42 INFO - 'log_to_console': True, 23:37:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:37:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:37:42 INFO - 'opt_config_files': (), 23:37:42 INFO - 'pip_index': False, 23:37:42 INFO - 'sps_profile': False, 23:37:42 INFO - 'sps_profile_interval': 0, 23:37:42 INFO - 'suite': 'dromaeojs', 23:37:42 INFO - 'system_bits': '32', 23:37:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:37:42 INFO - 'title': 'talos-linux64-ix-011', 23:37:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:37:42 INFO - 'use_talos_json': True, 23:37:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:37:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:37:42 INFO - 'work_dir': 'build'} 23:37:42 INFO - ##### 23:37:42 INFO - ##### Running clobber step. 23:37:42 INFO - ##### 23:37:42 INFO - Running pre-action listener: _resource_record_pre_action 23:37:42 INFO - Running main action method: clobber 23:37:42 INFO - rmtree: /builds/slave/test/build 23:37:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:37:42 INFO - Running post-action listener: _resource_record_post_action 23:37:42 INFO - ##### 23:37:42 INFO - ##### Running read-buildbot-config step. 23:37:42 INFO - ##### 23:37:42 INFO - Running pre-action listener: _resource_record_pre_action 23:37:42 INFO - Running main action method: read_buildbot_config 23:37:42 INFO - Using buildbot properties: 23:37:42 INFO - { 23:37:42 INFO - "project": "", 23:37:42 INFO - "product": "firefox", 23:37:42 INFO - "script_repo_revision": "production", 23:37:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:37:42 INFO - "repository": "", 23:37:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:37:42 INFO - "buildid": "20160426204703", 23:37:42 INFO - "pgo_build": "True", 23:37:42 INFO - "basedir": "/builds/slave/test", 23:37:42 INFO - "buildnumber": 471, 23:37:42 INFO - "slavename": "talos-linux64-ix-011", 23:37:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:37:42 INFO - "platform": "ubuntu64_hw", 23:37:42 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:37:42 INFO - "branch": "mozilla-aurora", 23:37:42 INFO - "repo_path": "releases/mozilla-aurora", 23:37:42 INFO - "revision": "3b60d399d4eef579688a499675c337a252a7bcac", 23:37:42 INFO - "stage_platform": "linux64-pgo", 23:37:42 INFO - "builduid": "3128f97fa2c4407092549a518d577e25", 23:37:42 INFO - "slavebuilddir": "test" 23:37:42 INFO - } 23:37:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 23:37:42 INFO - Running post-action listener: _resource_record_post_action 23:37:42 INFO - ##### 23:37:42 INFO - ##### Running download-and-extract step. 23:37:42 INFO - ##### 23:37:42 INFO - Running pre-action listener: _resource_record_pre_action 23:37:42 INFO - Running main action method: download_and_extract 23:37:42 INFO - mkdir: /builds/slave/test/build/tests 23:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:37:42 INFO - https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:37:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 23:37:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 23:37:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 23:37:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:37:47 INFO - Downloaded 1361 bytes. 23:37:47 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 23:37:47 INFO - Using the following test package requirements: 23:37:47 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 23:37:47 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:37:47 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:37:47 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'jsshell-linux-x86_64.zip'], 23:37:47 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:37:47 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 23:37:47 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:37:47 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:37:47 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:37:47 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 23:37:47 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:37:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:37:47 INFO - https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:37:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:37:51 INFO - Downloaded 23821217 bytes. 23:37:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:37:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:37:52 INFO - Return code: 0 23:37:52 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:37:52 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')]} 23:37:52 INFO - https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:37:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 23:37:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:37:58 INFO - Downloaded 23821217 bytes. 23:37:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:37:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:37:59 INFO - Return code: 0 23:37:59 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')]} 23:37:59 INFO - https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:37:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 23:37:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 23:37:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 23:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:38:01 INFO - Downloaded 10840145 bytes. 23:38:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:38:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:38:01 INFO - Return code: 0 23:38:01 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')]} 23:38:01 INFO - https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:38:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 23:38:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 23:38:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 23:38:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:38:04 INFO - Downloaded 62676195 bytes. 23:38:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 23:38:04 INFO - mkdir: /builds/slave/test/properties 23:38:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:38:04 INFO - Writing to file /builds/slave/test/properties/build_url 23:38:04 INFO - Contents: 23:38:04 INFO - build_url:https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 23:38:04 INFO - Running post-action listener: _resource_record_post_action 23:38:04 INFO - Running post-action listener: set_extra_try_arguments 23:38:04 INFO - ##### 23:38:04 INFO - ##### Running populate-webroot step. 23:38:04 INFO - ##### 23:38:04 INFO - Running pre-action listener: _resource_record_pre_action 23:38:04 INFO - Running main action method: populate_webroot 23:38:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:38:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:38:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:38:05 INFO - '--tppagecycles', 23:38:05 INFO - '7'], 23:38:05 INFO - 'tests': ['tsvgm']}}, 23:38:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:38:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:38:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:38:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:38:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:38:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:38:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['damp', 'tps']}, 23:38:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'tests': ['damp', 'tps']}, 23:38:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['dromaeo_dom']}, 23:38:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:38:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'other-e10s': {'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['a11yr', 23:38:05 INFO - 'ts_paint', 23:38:05 INFO - 'tpaint', 23:38:05 INFO - 'sessionrestore', 23:38:05 INFO - 'sessionrestore_no_auto_restore', 23:38:05 INFO - 'tabpaint']}, 23:38:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['tsvgx', 23:38:05 INFO - 'tsvgr_opacity', 23:38:05 INFO - 'tart', 23:38:05 INFO - 'tscrollx', 23:38:05 INFO - 'cart']}, 23:38:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:38:05 INFO - 'tsvgr_opacity', 23:38:05 INFO - 'tart', 23:38:05 INFO - 'tscrollx', 23:38:05 INFO - 'cart']}, 23:38:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'talos_options': ['--disable-e10s'], 23:38:05 INFO - 'tests': ['tp5o']}, 23:38:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'tests': ['tp5o']}, 23:38:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'talos_options': ['--disable-e10s', 23:38:05 INFO - '--xperf_path', 23:38:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:38:05 INFO - 'tests': ['tp5n']}, 23:38:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:38:05 INFO - 'talos_options': ['--xperf_path', 23:38:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:38:05 INFO - 'tests': ['tp5n']}}, 23:38:05 INFO - 'talos.zip': {'path': '', 23:38:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:38:05 INFO - Running post-action listener: _resource_record_post_action 23:38:05 INFO - ##### 23:38:05 INFO - ##### Running create-virtualenv step. 23:38:05 INFO - ##### 23:38:05 INFO - Running pre-action listener: _resource_record_pre_action 23:38:05 INFO - Running main action method: create_virtualenv 23:38:05 INFO - Creating virtualenv /builds/slave/test/build/venv 23:38:05 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 23:38:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:38:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:38:05 INFO - Using real prefix '/usr' 23:38:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:38:05 INFO - Installing distribute.............................................................................................................................................................................................done. 23:38:07 INFO - Installing pip.................done. 23:38:07 INFO - Return code: 0 23:38:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:38:07 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')]} 23:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:07 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')]} 23:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:07 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:07 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 23:38:07 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 23:38:07 INFO - Using env: {'DISPLAY': ':0', 23:38:07 INFO - 'HOME': '/home/cltbld', 23:38:07 INFO - 'LANG': 'en_US.UTF-8', 23:38:07 INFO - 'LANGUAGE': 'en_US:en', 23:38:07 INFO - 'LOGNAME': 'cltbld', 23:38:07 INFO - 'MAIL': '/var/mail/cltbld', 23:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:07 INFO - 'MOZ_NO_REMOTE': '1', 23:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:07 INFO - 'NO_EM_RESTART': '1', 23:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:07 INFO - 'PWD': '/builds/slave/test', 23:38:07 INFO - 'SHELL': '/bin/bash', 23:38:07 INFO - 'SHLVL': '1', 23:38:07 INFO - 'TERM': 'linux', 23:38:07 INFO - 'TMOUT': '86400', 23:38:07 INFO - 'USER': 'cltbld', 23:38:07 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:07 INFO - '_': '/tools/buildbot/bin/python'} 23:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:07 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 23:38:07 INFO - Cleaning up... 23:38:07 INFO - Return code: 0 23:38:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:38:07 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')]} 23:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:07 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')]} 23:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:07 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:07 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 23:38:07 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 23:38:07 INFO - Using env: {'DISPLAY': ':0', 23:38:07 INFO - 'HOME': '/home/cltbld', 23:38:07 INFO - 'LANG': 'en_US.UTF-8', 23:38:07 INFO - 'LANGUAGE': 'en_US:en', 23:38:07 INFO - 'LOGNAME': 'cltbld', 23:38:07 INFO - 'MAIL': '/var/mail/cltbld', 23:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:07 INFO - 'MOZ_NO_REMOTE': '1', 23:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:07 INFO - 'NO_EM_RESTART': '1', 23:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:07 INFO - 'PWD': '/builds/slave/test', 23:38:07 INFO - 'SHELL': '/bin/bash', 23:38:07 INFO - 'SHLVL': '1', 23:38:07 INFO - 'TERM': 'linux', 23:38:07 INFO - 'TMOUT': '86400', 23:38:07 INFO - 'USER': 'cltbld', 23:38:07 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:07 INFO - '_': '/tools/buildbot/bin/python'} 23:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:08 INFO - Downloading/unpacking psutil>=0.7.1 23:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:38:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:38:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:38:10 INFO - Installing collected packages: psutil 23:38:10 INFO - Running setup.py install for psutil 23:38:10 INFO - building 'psutil._psutil_linux' extension 23:38:10 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 23:38:10 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 23:38:10 INFO - building 'psutil._psutil_posix' extension 23:38:10 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 23:38:10 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 23:38:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:38:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:38:10 INFO - Successfully installed psutil 23:38:10 INFO - Cleaning up... 23:38:10 INFO - Return code: 0 23:38:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:38:10 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')]} 23:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:10 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')]} 23:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:10 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:10 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 23:38:10 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 23:38:10 INFO - Using env: {'DISPLAY': ':0', 23:38:10 INFO - 'HOME': '/home/cltbld', 23:38:10 INFO - 'LANG': 'en_US.UTF-8', 23:38:10 INFO - 'LANGUAGE': 'en_US:en', 23:38:10 INFO - 'LOGNAME': 'cltbld', 23:38:10 INFO - 'MAIL': '/var/mail/cltbld', 23:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:10 INFO - 'MOZ_NO_REMOTE': '1', 23:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:10 INFO - 'NO_EM_RESTART': '1', 23:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:10 INFO - 'PWD': '/builds/slave/test', 23:38:10 INFO - 'SHELL': '/bin/bash', 23:38:10 INFO - 'SHLVL': '1', 23:38:10 INFO - 'TERM': 'linux', 23:38:10 INFO - 'TMOUT': '86400', 23:38:10 INFO - 'USER': 'cltbld', 23:38:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:10 INFO - '_': '/tools/buildbot/bin/python'} 23:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:38:10 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 23:38:10 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 23:38:10 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 23:38:10 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 23:38:10 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 23:38:10 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 23:38:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:38:12 INFO - Installing collected packages: mozsystemmonitor 23:38:12 INFO - Running setup.py install for mozsystemmonitor 23:38:12 INFO - Successfully installed mozsystemmonitor 23:38:12 INFO - Cleaning up... 23:38:12 INFO - Return code: 0 23:38:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:12 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:12 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 23:38:12 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 23:38:12 INFO - Using env: {'DISPLAY': ':0', 23:38:12 INFO - 'HOME': '/home/cltbld', 23:38:12 INFO - 'LANG': 'en_US.UTF-8', 23:38:12 INFO - 'LANGUAGE': 'en_US:en', 23:38:12 INFO - 'LOGNAME': 'cltbld', 23:38:12 INFO - 'MAIL': '/var/mail/cltbld', 23:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:12 INFO - 'MOZ_NO_REMOTE': '1', 23:38:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:12 INFO - 'NO_EM_RESTART': '1', 23:38:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:12 INFO - 'PWD': '/builds/slave/test', 23:38:12 INFO - 'SHELL': '/bin/bash', 23:38:12 INFO - 'SHLVL': '1', 23:38:12 INFO - 'TERM': 'linux', 23:38:12 INFO - 'TMOUT': '86400', 23:38:12 INFO - 'USER': 'cltbld', 23:38:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:12 INFO - '_': '/tools/buildbot/bin/python'} 23:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:13 INFO - Downloading/unpacking blobuploader==1.2.4 23:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:14 INFO - Downloading blobuploader-1.2.4.tar.gz 23:38:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:38:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:38:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:38:15 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 23:38:15 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 23:38:15 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 23:38:15 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 23:38:15 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 23:38:15 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 23:38:15 INFO - Downloading docopt-0.6.1.tar.gz 23:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:38:15 INFO - Installing collected packages: blobuploader, requests, docopt 23:38:15 INFO - Running setup.py install for blobuploader 23:38:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:38:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:38:15 INFO - Running setup.py install for requests 23:38:16 INFO - Running setup.py install for docopt 23:38:16 INFO - Successfully installed blobuploader requests docopt 23:38:16 INFO - Cleaning up... 23:38:16 INFO - Return code: 0 23:38:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:16 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:16 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 23:38:16 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 23:38:16 INFO - Using env: {'DISPLAY': ':0', 23:38:16 INFO - 'HOME': '/home/cltbld', 23:38:16 INFO - 'LANG': 'en_US.UTF-8', 23:38:16 INFO - 'LANGUAGE': 'en_US:en', 23:38:16 INFO - 'LOGNAME': 'cltbld', 23:38:16 INFO - 'MAIL': '/var/mail/cltbld', 23:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:16 INFO - 'MOZ_NO_REMOTE': '1', 23:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:16 INFO - 'NO_EM_RESTART': '1', 23:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:16 INFO - 'PWD': '/builds/slave/test', 23:38:16 INFO - 'SHELL': '/bin/bash', 23:38:16 INFO - 'SHLVL': '1', 23:38:16 INFO - 'TERM': 'linux', 23:38:16 INFO - 'TMOUT': '86400', 23:38:16 INFO - 'USER': 'cltbld', 23:38:16 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:16 INFO - '_': '/tools/buildbot/bin/python'} 23:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:38:16 INFO - Running setup.py (path:/tmp/pip-0OIGML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:38:16 INFO - Running setup.py (path:/tmp/pip-93A9HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:38:16 INFO - Running setup.py (path:/tmp/pip-7QcDX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:38:16 INFO - Running setup.py (path:/tmp/pip-8Mr8bM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:38:16 INFO - Running setup.py (path:/tmp/pip-zlrwGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:38:16 INFO - Running setup.py (path:/tmp/pip-kKeIvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:38:16 INFO - Running setup.py (path:/tmp/pip-YDKoY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:38:17 INFO - Running setup.py (path:/tmp/pip-fRnHFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:38:17 INFO - Running setup.py (path:/tmp/pip-hmuhkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:38:17 INFO - Running setup.py (path:/tmp/pip-aYdfNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:38:17 INFO - Running setup.py (path:/tmp/pip-r5GrRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:38:17 INFO - Running setup.py (path:/tmp/pip-PRbiRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:38:17 INFO - Running setup.py (path:/tmp/pip-IJmSmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:38:17 INFO - Running setup.py (path:/tmp/pip-JvoGFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:38:17 INFO - Running setup.py (path:/tmp/pip-d4wyEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:38:17 INFO - Running setup.py (path:/tmp/pip-OyG_06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:38:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:38:17 INFO - Running setup.py (path:/tmp/pip-WSBWYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:38:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:38:17 INFO - Running setup.py install for manifestparser 23:38:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:38:17 INFO - Running setup.py install for mozcrash 23:38:17 INFO - Running setup.py install for mozdebug 23:38:18 INFO - Running setup.py install for mozdevice 23:38:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for mozfile 23:38:18 INFO - Running setup.py install for mozhttpd 23:38:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for mozinfo 23:38:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for mozInstall 23:38:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for mozleak 23:38:18 INFO - Running setup.py install for mozlog 23:38:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for moznetwork 23:38:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:38:18 INFO - Running setup.py install for mozprocess 23:38:18 INFO - Running setup.py install for mozprofile 23:38:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:38:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:38:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:38:19 INFO - Running setup.py install for mozrunner 23:38:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:38:19 INFO - Running setup.py install for mozscreenshot 23:38:19 INFO - Running setup.py install for moztest 23:38:19 INFO - Running setup.py install for mozversion 23:38:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:38:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:38:19 INFO - Cleaning up... 23:38:19 INFO - Return code: 0 23:38:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:19 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:19 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 23:38:19 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 23:38:19 INFO - Using env: {'DISPLAY': ':0', 23:38:19 INFO - 'HOME': '/home/cltbld', 23:38:19 INFO - 'LANG': 'en_US.UTF-8', 23:38:19 INFO - 'LANGUAGE': 'en_US:en', 23:38:19 INFO - 'LOGNAME': 'cltbld', 23:38:19 INFO - 'MAIL': '/var/mail/cltbld', 23:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:19 INFO - 'MOZ_NO_REMOTE': '1', 23:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:19 INFO - 'NO_EM_RESTART': '1', 23:38:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:19 INFO - 'PWD': '/builds/slave/test', 23:38:19 INFO - 'SHELL': '/bin/bash', 23:38:19 INFO - 'SHLVL': '1', 23:38:19 INFO - 'TERM': 'linux', 23:38:19 INFO - 'TMOUT': '86400', 23:38:19 INFO - 'USER': 'cltbld', 23:38:19 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:19 INFO - '_': '/tools/buildbot/bin/python'} 23:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:38:19 INFO - Running setup.py (path:/tmp/pip-zgbUiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:38:19 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)) 23:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:38:19 INFO - Running setup.py (path:/tmp/pip-y9v3za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:38:19 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)) 23:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:38:19 INFO - Running setup.py (path:/tmp/pip-fhDWmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:38:19 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)) 23:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:38:19 INFO - Running setup.py (path:/tmp/pip-3sezHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:38:20 INFO - Running setup.py (path:/tmp/pip-gvG0J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:38:20 INFO - Running setup.py (path:/tmp/pip-LorLPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:38:20 INFO - Running setup.py (path:/tmp/pip-5ghU6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:38:20 INFO - Running setup.py (path:/tmp/pip-R2rLl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:38:20 INFO - Running setup.py (path:/tmp/pip-q7mE8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:38:20 INFO - Running setup.py (path:/tmp/pip-wUmHWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:38:20 INFO - Running setup.py (path:/tmp/pip-HOlck0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:38:20 INFO - Running setup.py (path:/tmp/pip-xtt4TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:38:20 INFO - Running setup.py (path:/tmp/pip-ge9nl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:38:20 INFO - Running setup.py (path:/tmp/pip-WJOv4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:38:20 INFO - Running setup.py (path:/tmp/pip-9xUUM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:38:20 INFO - Running setup.py (path:/tmp/pip-CyIS5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:38:20 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)) 23:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:38:20 INFO - Running setup.py (path:/tmp/pip-VaEhqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:38:20 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)) 23:38:20 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)) 23:38:20 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)) 23:38:20 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)) 23:38:20 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)) 23:38:20 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)) 23:38:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:22 INFO - Downloading blessings-1.6.tar.gz 23:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:38:22 INFO - Installing collected packages: blessings 23:38:22 INFO - Running setup.py install for blessings 23:38:23 INFO - Successfully installed blessings 23:38:23 INFO - Cleaning up... 23:38:23 INFO - Return code: 0 23:38:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:38:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:38:23 INFO - Reading from file tmpfile_stdout 23:38:23 INFO - Current package versions: 23:38:23 INFO - argparse == 1.2.1 23:38:23 INFO - blessings == 1.6 23:38:23 INFO - blobuploader == 1.2.4 23:38:23 INFO - docopt == 0.6.1 23:38:23 INFO - manifestparser == 1.1 23:38:23 INFO - mozInstall == 1.12 23:38:23 INFO - mozcrash == 0.17 23:38:23 INFO - mozdebug == 0.1 23:38:23 INFO - mozdevice == 0.48 23:38:23 INFO - mozfile == 1.2 23:38:23 INFO - mozhttpd == 0.7 23:38:23 INFO - mozinfo == 0.9 23:38:23 INFO - mozleak == 0.1 23:38:23 INFO - mozlog == 3.1 23:38:23 INFO - moznetwork == 0.27 23:38:23 INFO - mozprocess == 0.22 23:38:23 INFO - mozprofile == 0.28 23:38:23 INFO - mozrunner == 6.11 23:38:23 INFO - mozscreenshot == 0.1 23:38:23 INFO - mozsystemmonitor == 0.0 23:38:23 INFO - moztest == 0.7 23:38:23 INFO - mozversion == 1.4 23:38:23 INFO - psutil == 3.1.1 23:38:23 INFO - requests == 1.2.3 23:38:23 INFO - wsgiref == 0.1.2 23:38:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:38:23 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')]} 23:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:23 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')]} 23:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:23 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:23 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 23:38:23 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 23:38:23 INFO - Using env: {'DISPLAY': ':0', 23:38:23 INFO - 'HOME': '/home/cltbld', 23:38:23 INFO - 'LANG': 'en_US.UTF-8', 23:38:23 INFO - 'LANGUAGE': 'en_US:en', 23:38:23 INFO - 'LOGNAME': 'cltbld', 23:38:23 INFO - 'MAIL': '/var/mail/cltbld', 23:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:23 INFO - 'MOZ_NO_REMOTE': '1', 23:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:23 INFO - 'NO_EM_RESTART': '1', 23:38:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:23 INFO - 'PWD': '/builds/slave/test', 23:38:23 INFO - 'SHELL': '/bin/bash', 23:38:23 INFO - 'SHLVL': '1', 23:38:23 INFO - 'TERM': 'linux', 23:38:23 INFO - 'TMOUT': '86400', 23:38:23 INFO - 'USER': 'cltbld', 23:38:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:23 INFO - '_': '/tools/buildbot/bin/python'} 23:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 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)) 23:38:23 INFO - Cleaning up... 23:38:23 INFO - Return code: 0 23:38:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:38:23 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')]} 23:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:23 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')]} 23:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:23 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:23 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 23:38:23 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 23:38:23 INFO - Using env: {'DISPLAY': ':0', 23:38:23 INFO - 'HOME': '/home/cltbld', 23:38:23 INFO - 'LANG': 'en_US.UTF-8', 23:38:23 INFO - 'LANGUAGE': 'en_US:en', 23:38:23 INFO - 'LOGNAME': 'cltbld', 23:38:23 INFO - 'MAIL': '/var/mail/cltbld', 23:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:23 INFO - 'MOZ_NO_REMOTE': '1', 23:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:23 INFO - 'NO_EM_RESTART': '1', 23:38:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:23 INFO - 'PWD': '/builds/slave/test', 23:38:23 INFO - 'SHELL': '/bin/bash', 23:38:23 INFO - 'SHLVL': '1', 23:38:23 INFO - 'TERM': 'linux', 23:38:23 INFO - 'TMOUT': '86400', 23:38:23 INFO - 'USER': 'cltbld', 23:38:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:23 INFO - '_': '/tools/buildbot/bin/python'} 23:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:23 INFO - Downloading/unpacking jsonschema 23:38:23 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 23:38:23 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 23:38:23 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 23:38:23 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 23:38:23 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 23:38:23 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 23:38:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:38:25 INFO - Downloading/unpacking functools32 (from jsonschema) 23:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:25 INFO - Downloading functools32-3.2.3-2.tar.gz 23:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:38:25 INFO - warning: no files found matching '*.txt' 23:38:25 INFO - no previously-included directories found matching 'build' 23:38:25 INFO - no previously-included directories found matching 'dist' 23:38:25 INFO - no previously-included directories found matching '.git*' 23:38:25 INFO - Installing collected packages: jsonschema, functools32 23:38:25 INFO - Running setup.py install for functools32 23:38:26 INFO - warning: no files found matching '*.txt' 23:38:26 INFO - no previously-included directories found matching 'build' 23:38:26 INFO - no previously-included directories found matching 'dist' 23:38:26 INFO - no previously-included directories found matching '.git*' 23:38:26 INFO - Successfully installed jsonschema functools32 23:38:26 INFO - Cleaning up... 23:38:26 INFO - Return code: 0 23:38:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:38:26 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')]} 23:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:38:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:26 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')]} 23:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:38:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:38:26 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 0x7f132b20df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd805a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', '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 23:38:26 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 23:38:26 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 23:38:26 INFO - Using env: {'DISPLAY': ':0', 23:38:26 INFO - 'HOME': '/home/cltbld', 23:38:26 INFO - 'LANG': 'en_US.UTF-8', 23:38:26 INFO - 'LANGUAGE': 'en_US:en', 23:38:26 INFO - 'LOGNAME': 'cltbld', 23:38:26 INFO - 'MAIL': '/var/mail/cltbld', 23:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:26 INFO - 'MOZ_NO_REMOTE': '1', 23:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:26 INFO - 'NO_EM_RESTART': '1', 23:38:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:26 INFO - 'PWD': '/builds/slave/test', 23:38:26 INFO - 'SHELL': '/bin/bash', 23:38:26 INFO - 'SHLVL': '1', 23:38:26 INFO - 'TERM': 'linux', 23:38:26 INFO - 'TMOUT': '86400', 23:38:26 INFO - 'USER': 'cltbld', 23:38:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:26 INFO - '_': '/tools/buildbot/bin/python'} 23:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:38:26 INFO - Downloading/unpacking flake8 23:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:38:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:38:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:38:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 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 23:38:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:38:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:38:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:38:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:38:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:38:28 INFO - no previously-included directories found matching 'docs/_build' 23:38:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:38:28 INFO - Running setup.py install for pep8 23:38:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:38:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:38:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:38:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:38:29 INFO - no previously-included directories found matching 'docs/_build' 23:38:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:38:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:38:29 INFO - Cleaning up... 23:38:29 INFO - Return code: 0 23:38:29 INFO - Running post-action listener: _resource_record_post_action 23:38:29 INFO - Running post-action listener: _start_resource_monitoring 23:38:29 INFO - Starting resource monitoring. 23:38:29 INFO - ##### 23:38:29 INFO - ##### Running install step. 23:38:29 INFO - ##### 23:38:29 INFO - Running pre-action listener: _resource_record_pre_action 23:38:29 INFO - Running main action method: install 23:38:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:38:29 INFO - Reading from file tmpfile_stdout 23:38:29 INFO - Detecting whether we're running mozinstall >=1.0... 23:38:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:38:29 INFO - Reading from file tmpfile_stdout 23:38:29 INFO - Output received: 23:38:29 INFO - Usage: mozinstall [options] installer 23:38:29 INFO - Options: 23:38:29 INFO - -h, --help show this help message and exit 23:38:29 INFO - -d DEST, --destination=DEST 23:38:29 INFO - Directory to install application into. [default: 23:38:29 INFO - "/builds/slave/test"] 23:38:29 INFO - --app=APP Application being installed. [default: firefox] 23:38:29 INFO - mkdir: /builds/slave/test/build/application 23:38:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:38:45 INFO - Reading from file tmpfile_stdout 23:38:45 INFO - Output received: 23:38:45 INFO - /builds/slave/test/build/application/firefox/firefox 23:38:45 INFO - Running post-action listener: _resource_record_post_action 23:38:45 INFO - ##### 23:38:45 INFO - ##### Running run-tests step. 23:38:45 INFO - ##### 23:38:45 INFO - Running pre-action listener: _resource_record_pre_action 23:38:45 INFO - Running main action method: run_tests 23:38:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:38:45 INFO - Python 2.7.3 23:38:45 INFO - Return code: 0 23:38:45 INFO - grabbing minidump binary from tooltool 23:38:45 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')]} 23:38:45 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 0xe0f410>, '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 0xe10310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe107a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:38:45 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 23:38:45 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 23:38:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:38:45 INFO - Return code: 0 23:38:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:38:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:38:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:38:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:38:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:38:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:38:46 INFO - Return code: 0 23:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 23:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 23:38:46 INFO - Using env: {'DISPLAY': ':0', 23:38:46 INFO - 'HOME': '/home/cltbld', 23:38:46 INFO - 'LANG': 'en_US.UTF-8', 23:38:46 INFO - 'LANGUAGE': 'en_US:en', 23:38:46 INFO - 'LOGNAME': 'cltbld', 23:38:46 INFO - 'MAIL': '/var/mail/cltbld', 23:38:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:38:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:38:46 INFO - 'MOZ_NO_REMOTE': '1', 23:38:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:38:46 INFO - 'NO_EM_RESTART': '1', 23:38:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:38:46 INFO - 'PWD': '/builds/slave/test', 23:38:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:38:46 INFO - 'SHELL': '/bin/bash', 23:38:46 INFO - 'SHLVL': '1', 23:38:46 INFO - 'TERM': 'linux', 23:38:46 INFO - 'TMOUT': '86400', 23:38:46 INFO - 'USER': 'cltbld', 23:38:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148', 23:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:38:46 INFO - '_': '/tools/buildbot/bin/python'} 23:38:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 23:38:46 INFO - mozversion application_buildid: 20160426204703 23:38:46 INFO - mozversion application_changeset: 3b60d399d4eef579688a499675c337a252a7bcac 23:38:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:38:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:38:46 INFO - mozversion application_name: Firefox 23:38:46 INFO - mozversion application_remotingname: firefox-dev 23:38:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:38:46 INFO - mozversion application_vendor: Mozilla 23:38:46 INFO - mozversion application_version: 48.0a2 23:38:46 INFO - mozversion platform_buildid: 20160426204703 23:38:46 INFO - mozversion platform_changeset: 3b60d399d4eef579688a499675c337a252a7bcac 23:38:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:38:46 INFO - mozversion platform_version: 48.0a2 23:38:46 INFO - using testdate: 1461739126 23:38:46 INFO - actual date: 1461739126 23:38:46 INFO - starting webserver on 'localhost:42864' 23:38:46 INFO - SUITE-START | Running 2 tests 23:38:46 INFO - TEST-START | dromaeo_css 23:38:46 INFO - operating with platform_type : linux_ 23:38:46 INFO - Initialising browser for dromaeo_css test... 23:38:46 INFO - TEST-INFO | started process 29265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAN9ASd/profile http://localhost:42864/getInfo.html) 23:38:51 INFO - PROCESS | 29265 | __metrics Screen width/height:1600/1200 23:38:51 INFO - PROCESS | 29265 | colorDepth:24 23:38:51 INFO - PROCESS | 29265 | Browser inner width/height: 1024/697 23:38:51 INFO - PROCESS | 29265 | __metrics 23:38:52 ERROR - PROCESS | 29265 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:38:53 INFO - TEST-INFO | 29265: exit 0 23:38:53 INFO - Browser initialized. 23:38:53 INFO - Running cycle 1/1 for dromaeo_css test... 23:38:53 INFO - TEST-INFO | started process 29355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAN9ASd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:39:05 INFO - PROCESS | 29355 | RSS: Main: 142282752 23:39:05 INFO - PROCESS | 29355 | 23:39:56 INFO - PROCESS | 29355 | 1461739196505 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] 23:42:04 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42864/tests/dromaeo/cssquery-ext.html) 23:42:06 INFO - PROCESS | 29355 | RSS: Main: 250077184 23:42:06 INFO - PROCESS | 29355 | 23:45:08 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-ext.html (next: http://localhost:42864/tests/dromaeo/cssquery-jquery.html) 23:45:08 INFO - PROCESS | 29355 | RSS: Main: 225153024 23:45:08 INFO - PROCESS | 29355 | 23:48:10 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42864/tests/dromaeo/cssquery-mootools.html) 23:48:11 INFO - PROCESS | 29355 | RSS: Main: 228245504 23:48:11 INFO - PROCESS | 29355 | 23:51:13 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42864/tests/dromaeo/cssquery-prototype.html) 23:51:13 INFO - PROCESS | 29355 | RSS: Main: 223862784 23:51:13 INFO - PROCESS | 29355 | 23:54:16 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42864/tests/dromaeo/cssquery-yui.html) 23:54:17 INFO - PROCESS | 29355 | RSS: Main: 212639744 23:54:17 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | Cycle 1(1): loaded http://localhost:42864/tests/dromaeo/cssquery-yui.html (next: http://localhost:42864/tests/dromaeo/cssquery-dojo.html) 23:57:19 INFO - PROCESS | 29355 | RSS: Main: 253972480 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | __start_tp_report 23:57:19 INFO - PROCESS | 29355 | _x_x_mozilla_page_load 23:57:19 INFO - PROCESS | 29355 | _x_x_mozilla_page_load_details 23:57:19 INFO - PROCESS | 29355 | |i|pagename|runs| 23:57:19 INFO - PROCESS | 29355 | |0;dojo.html;3288.375208710345;3361.5629968104145;3379.3917094922917;3387.6442973487783;3417.9658203417957;5504.779808807648;5516.668999860011;5522.309711286084;5548.278723765915;5550.77796888124;5486.945130548694;5553.416891226424;5553.8333849984565;5556.805511807088;5561.193626924093;4930.235813448921;5023.547880690737;5025.949740502585;5087.821926232583;5096.311997880276;4943.505649435046;5018.172001619729;5025.572826309768;5027.472115427882;5031.144705400073;5032.672876263049;5052.823151189709;5086.542211200991;5095.541401273885;5109.20807274873;5113.897722045557;5138.460461651547;5147.7683504242395;5155.046316431472;5191.454897235773;4934.654574179809;4964.503549645006;5026.47222041684;5045.899082018375;5053.823116190915;5410.053240682904;5413.160960051198;5450.155225939986;5464.863378415532;5467.0705979983495;5859.121131830217;5861.443162899518;5868.4424979626865;5870.647761134345;5874.588778785486;5893.204417403638;5897.3218079920925;5913.349531551526;5917.822465326047;5967.940320596826;5558.471945165203;5576.637518561281;5594.496495315441;5596.524295434852;5610.18652295419;5864.38423965486;6091.329953705089;6103.23709536308;6103.42017508336;6103.84740381494;5727.455891690282;5783.334916484574;5784.045632470679;5795.594308398372;5802.6808525531505;5959.195508606283;6003.939960600425;6005.609635373687;6008.218931538872;6017.0974353846395;5645.5483561314995;5824.242848429677;5942.584019118706;5971.671558064261;5984.222051133324;5928.199693041384;5933.050711886078;5938.792142275;5940.376260492672;5973.611715238496;4543.432070991126;4546.045330480636;4546.29532422475;4548.408706868086;4571.977140114278;3987.222491614107;3996.080901392696;4068.105016896278;4100.794960251976;4109.527404348538;5643.915341269884;5646.435356464321;5651.12407576818;5660.915086273709;5675.404082571353;5897.351291357947;5909.349971503131;5916.319623243296;5918.822435326947;5923;5441.047816632074;5549.223108764776;5553.722313884289;5591.916121258185;5660.85847853807;5140.640155189176;5142.87142821425;5150.48495150482;5151.8196863109615;5157.974210128926;6226.501879849601;6231.937680623227;6238.781642642486;6257.906131408033;6278.466330361879;5807.94192058074;5909.70451477433;5958.433948774859;6002.039673652194;6015.218021657157;6237.220347456568;6237.5633705641985;6249.093881387083;6271.874562508816;6280.528960328085;5893.381194974638;5908;5913.05391137416;5917.556183286185;5949.25634295713;5783.652980821164;5856.297244330707;5966.492848107786;5976.013957696931;5982.700864956648;5782.190493330853;5829.59192856496;5856.238688970407;5856.326522449803;5860.238169038168;5637.548996080386;5763.049096899133;5800.303963524404;5808.593398462067;5812.593118481665;5567.832965011056;5645.717714114196;5648.491635752802;5666.206731057738;5677.545796336366;5761.711914404347;5787.334456537552;5791.449812267828;5793.159991801081;5797.9420205797405;5578.832635020955;5581.162825576196;5642.322921249476;5656.5757568181725;5690.971545142248 23:57:19 INFO - PROCESS | 29355 | |1;ext.html;608.3824917708527;667.6861874918789;670.6345041952138;673.0509980926894;675.898615207719;30918;31465.370692586162;39810.004749881235;41124.794376028076;41270.58729412705;44937.325940110924;45746;45808.312875306816;45837;45990;7867.7246296379635;8866.866996995033;9088.727338179866;9094.727158185266;9096.77258068547;10314.793704125914;10576.947115264413;10579.418132002736;10584.470776461185;10584.735381615445;10624.468776561142;10791.892081079206;10796.892031079706;10802.783944321109;10803.081738052275;7839.8432031359625;10506.422146781924;10584.523696433678;10677.412742299131;10815.459227038695;10609.946950265277;10757.677269681844;10775.461226938622;10780.40707761073;10780.730481738019;41771.79114104441;42516.36225456621;42578.36132458015;42582.36126458106;42611;3734.6452087051684;4187.874363769091;4403.757793321398;4475.7985890635;4486.528914463967;34355;34847.128821779406;34946.82526587376;35159.648403515894;35492.82253588716;4411.382406463098;4474.395956545857;4490.7754612269255;4501.324801279801;4564;369656;369836;374682;375387;377529.99999999726;38554.80722596369;39004.219915602094;71784.28215717776;72830;73043.63478182575;40211.39682904759;40307.193856122714;40323.59676403257;40437.393439098436;40502.1899562013;17029.48911532656;17263.13684315779;17272.740908886375;17273.136343182792;17275.654486910444;13036.739265214832;13296.468141274241;14068;14443.205623690588;14584.927075364767;9887.110160085484;9901.306908516479;9962.053604907522;10019.749506262257;10036.64871729486;3777.7544459610035;3783.281176576442;3803.372443546784;3834.6357096076385;3891.715733807837;1118.6028959719345;1129.2998341028451;1170.5200867644226;1204.5362535423924;1266.6896610330527;31444.52833207504;34872.82563587216;34882;34941.1264718379;34955.82522087373;1189.7858385490522;1199.4962115911337;1200.4657927222477;1218.4943248551929;1294.8446186457686;17676.204570794343;17798.91100544489;17974;18021.7296740549;18034.098295085194;571418;579481;581220;581586;583458;64485.35514644887;65013.67493162504;65196;65479.34520654828;65598.34401656018;1370.0888908875486;1477.6232060824636;1533.6702608938908;1538.223197285386;1558.6570954390015;31204.219894502818;32943.50584741279;33186;33629.66370336363;33956.830215848764;53872.19191712049;54844.45155548553;55003.724981374835;55106;55161;395672;397630;398193;398317;398726;26158.215253542425;27981.160565183076;28751.42497150087;28770;28788.568171477862;43934.074555936;45520.54479455163;45837.77081114573;45929.31106033345;46200;38533.614663853004;38649.42025869671;39041.60958390493;41255.381169281885;41446.58553414427;41410;41843.58156418519;42365.78817105895;43577.56422435735;43706.56293437152;41192.588074118874;42575.7871210642;42851.357229640955;42914;43356.78321608372;35835.64164358323;36135.819320903225;37539.43690844694;37722.43416348702;38007.80996095002;28354.639750501086;28638.713612863605;28853.85573072121;28879.42241155123;28889 23:57:19 INFO - PROCESS | 29355 | |2;jquery.html;435.85398891371386;440.825873779857;444.9110177964408;444.9733016019039;445.7682005357214;19569.021548922552;19795.802041979576;20590.691139632898;20614.278500252494;20627;18613.7207941881;20428.89785551074;20701.585968280626;20759.065842037107;20778.480537986557;5832.737526811304;6077.058056001305;6089.45194932456;6107.7556897724035;6108.81673549792;5855.707214639272;5928.288605367362;5939.613925094855;5947.137665038566;5977.282726072877;5873.647581145145;5904.7047647617665;5920.851978700545;5929.466348028676;5983.880322393549;5876.794312199074;5973.790917317873;5976.193213916153;5977.611455255416;6002.969985150046;4417.646588272931;5298.788048478094;5379.354477462691;5383.488568585978;5385.838424847261;24427.755722442726;24497.755022449724;24516.264512064667;24577.13980010709;24637.753622463726;2493.252024392675;2569.832960857538;2786.4845003674386;2926.6926972667775;2970.495015847311;14516.346764395628;15244.314005869763;15262.847371526253;15495.535133946;15537.300821462948;2071.0576687607104;2097.25547430663;2168;2190.167736260211;2256.311824893407;81222.59388703077;82922.17077829204;83135.16864831335;83635.16364836274;84190;11082.445877706245;11230.66308010761;11291.717707057389;11330.150238732129;11586.13104017202;16228.43200488001;16451.506454806375;16524.504264872074;16555.83444165543;16558.66882662364;9162.954185229031;9201;9268.212201962908;9299.814003719888;9431.434113953048;9305.674301399418;9319.068093190626;9324.580393882292;9421.199198068103;9424.811503770023;6994.5803251805055;7118.9644051779405;7134.108236470442;7162.104736907887;7253.528520646247;2004.959900801976;2074.626567217885;2107.747070351568;2108.7785782492924;2121.2787652198326;991.2565575818137;1151.5163631274884;1169.7418809369972;1178.988210117905;1210.5823490895627;20118.69821952672;20903.408724789257;20983.580328393346;21134.788652113588;21196.258130965343;1057.3391630231106;1066.3974854207347;1165.5862168930078;1176.9525122640857;1237.3442075699893;10423.739406514898;10868.891311086787;10900.400477973788;10932.344059356465;10957;523968;534363;541326;541812;563942;100453;100940.49529752304;101094;103343;103571.48214258881;1130.4856290387854;1132.6262333429947;1138.7665528566624;1143.89704926557;1148.8104462763552;16916.57708557296;16946.576335591708;16950.745738814174;17086.316547338465;17387.56531086733;37346.813265933495;38230.42654360242;38796.41805372978;39355.409668854416;40506;53755;59863;60430.697846512245;60436.09345859903;60507;9052.094790520896;9360.578773955325;9439.575219115293;9503.477308748084;9522.476263805556;11182.66452006441;12248;14557.41770329175;14618.561443156723;14762.188079655292;12345.444454999368;12575.43410546546;12586.748265034465;12772.680682983;12795.552155674879;12136.002275687699;12326.07554433439;12330.75338493243;12333.444994975069;12670.493180272686;12798.936005320285;12904.8064279034;13171.2755798432;13233.933830330787;13323.66690832737;9225.907740922505;9322.48726320059;9325.440473571607;9359.157675808938;9610.903890961;11940.940295298467;11993.160478766402;12737.745245095231;12834.871651283367;13139.73720525565 23:57:19 INFO - PROCESS | 29355 | |3;mootools.html;1450.4343306110611;1543.884208684349;1604.3662753212482;1764.4530195639345;1839.6412699523598;8295.17048295171;8685.34859885508;8703.086175951528;8706.216440520351;8707.521086340257;8466.745997620066;8587.48475091495;8635.093315201908;8653.264472519828;8677.47935123893;2101.7162683037823;2161.351594521645;2327.2552783109413;2380.7143142822842;2427.2232885476656;2403.3510952043025;2407.1093695332665;2426.8179886508583;2426.9029238830426;2429.708434987804;2287.8970446329954;2293.8279629027807;2325.802306803915;2378.357843382286;2380.452495925938;2036.297477370305;2256.5148493074066;2309.976900230993;2329.3943574670625;2344.1795371619796;2283.3949003513917;2303.343547089088;2304.988475057614;2306.1121468234683;2309.93070207894;8203.58982050895;8235.794105147359;8250.051244106944;8260.793480162984;8262.628181731836;5202.167653175512;5397.78408863645;5405.945940540583;5408.567314614821;5416.83749487516;3119.859606317721;3325.817080060596;3331.283773988604;3332.933341333184;3338.1320856577463;1138.5332013874354;1155.5493357590547;1172.3962159487871;1180.0559552358166;1188.3226560860312;11660.358680272582;11948.761024779542;11952.40237988097;11966.760664786743;11974.221675591145;4613.953860461353;4722.6221902247735;4780.972091000452;4782.450018247877;4845.781939812717;9528.618855245713;9550.235981121494;9584.568694408768;9595.616175352907;9600.855987160197;5307.416184219733;5309.097453432925;5316.813911513079;5319.813806516753;5324.121519949165;4445.088756804847;4838.927416088762;4933.186024305949;4937.259411088366;4983.252512123138;3864.1305706215876;3896.1038961038553;3952.1898010907694;3953.723239373216;4070;1175.7589694112687;1231.335079057317;1239.9194052386747;1242.502998800469;1249.2691775311544;923.4320892650971;957.0190554681368;1031.5564307347763;1062.2935747727674;1080.7622323088908;5983.192269043623;6107.755689772359;6115.235595550556;6154.78458253959;6189.566730328834;1010.5654568535454;1018.3533456255332;1021.22897212604;1049.160671462842;1063.723431907694;4286.271333873249;4419.337099435046;4478.171538265431;4480.238359478896;4529.320601909674;10885.18361122919;11211.60759373418;11351.94324028391;11418.771624567462;11441.599544016086;10950.288231264942;11022.283551569122;11024.44877756125;11047.944760276147;11311.264767790068;7882.448228623941;8025.197480251928;8065.919340806634;8161.347092232607;8188.303994160563;6147.354527774519;6189.535784816067;6189.721462534107;6209.813705588839;6239.563230573816;10016.298859079794;10172.033656802592;10199.796004080024;10213.387196768217;10259.281850270409;10048.89951100509;10173.949130254301;10214.182865370902;10311.123554497652;10461.424621645578;6722.9663851680425;7048.647567621701;7235.963820181076;7474.701011959678;7590.278923502258;8087.47431416956;8111;8140.470869393471;8159.714409995621;8188.181181881764;7139.892901606376;7312.232215617283;7360.742374017098;7430.331270185493;7444.888326675213;7022.12223472066;7071.504994650325;7075.433965282868;7138.964305178441;7263.527870688388;6944;6950.895736564057;7011.859762804614;7020.613866237406;7026.6135362553505;5144.562712169508;5190.455002224712;5230.738463076755;5234.738263086754;5306.11434358687;5510.090835012178;5520.06158952987;5547;5566.081596536526;5629.718514074362 23:57:19 INFO - PROCESS | 29355 | |4;prototype.html;356.39590893435627;358.98564057437704;359.51645037424663;360.38914040701025;363.29883325182385;5344.412114667383;5464.781408743655;5472.5621950243985;5483.698396588186;5485;5423.755930983115;5427.3215848019;5437.510624043835;5441.537469315113;5445.83662490125;3822.3310920588924;4027.637512623863;4195.223883581547;4273.145370925812;4299.398084268205;4273.337632666941;4274.572542745716;4282.336237883133;4307.719998200107;4327.5239723630375;4052.7973601319964;4284.164587905359;4286.828526858938;4286.914261714764;4287.292596721537;4149.170165966803;4226.323788193905;4242.787860606957;4252.8511502097435;4262.722923009995;3804.6385593368864;3807.5240594925635;3811.123441608418;3818.350880350319;3821.751586146919;5256;5289.170293892787;5293;5297.629165958385;5303.469653034705;4900.607951363918;4901.725551356637;4914.11545921734;4917.901641967141;4929.6302777291485;5328.520433161034;5522.23888055972;5555.444455554412;5563.664720467099;5574.9721251393885;4053.5338436079933;4077.612626800449;4192.538820729717;4207.621314081717;4220.53574106848;5632.35227948787;5812.331581868097;5821.621594596338;5883.352831188566;5890.087036509348;4172.165566886635;4240.1307731914885;4240.787960601958;4243.193793179286;4246.8513602024395;4307.289297265978;4313;4314;4318.935215971763;4322.8703138905885;4887.804487820447;4889.755512224446;4901.1913034349645;4924;4929.408470983505;5457.6452530586175;5733.581765231899;5737.741801618937;5742.6554406734895;5742.770289188469;1543.8224604170664;1557.548310989823;1561.554956837296;1564.108458178827;1564.7496400575853;1028.665683652816;1049.7952899184738;1051.9474026298808;1061.3790932304535;1069.5989004123453;5864.67744274069;5924.970375148183;5928.673922934194;5956.344802071769;5961.794136169645;6225.8132256032395;6254.15568898202;6262;6271.560990730605;6286.994080947026;5364.436734142937;5642.661440313512;5659.773609055674;5727.169560413717;5899.646021238739;3367.494875768605;3374.611919629274;3375.814330211813;3377.7635565510177;3379.6282408935;6439.678016099177;6638.402543771083;6652.268250492442;6653.667316634149;6656.933430665728;6394.712237949303;6461.19235095613;6466.418022377915;6470.223573171155;6494.512911531654;5895.498882594941;6042.728077236535;6159;6196.1635179250225;6203.689815509207;5875.559333049953;5885.764569417175;5902.881942361215;5910.527157827278;5927.644341339534;5588.580856435702;5776.989026920356;5798.82603521895;5822.359540450462;5825.533957283492;6082.452579267887;6120.296165940974;6128.63228206309;6141.416565426277;6141.631502109888;4179.080602267496;4234.38601402801;4242.618164365099;4246.235677578065;4250.21371046345;5556.916646250229;5899.262592175978;5921.2006379138265;5941.197938278277;5957.493613042767;5756.474534248511;5768.105943578752;5768.451997060272;5798.768049277982;5799.884002320015;5603.859903502445;5735.311762588516;5737.540996720336;5757.992351338583;5761.971190144022;5667.1782591524825;5688.63023903445;5696.515796157373;5697.515711164598;5711.628744131618;5727.339071669169;5766.913496297643;5775.595708300379;5786.450287222707;5805;5461.672299662033;5663.943360566506;5671.943280567141;5679.148127780866;5688.459596338341 23:57:19 INFO - PROCESS | 29355 | |5;yui.html;901.1889299630332;913.2965229070287;925.6204955968052;934.7242563443783;955.3646824861468;14316.141031538114;14805.629859253524;14832;14838.851611483882;14872.479463218777;13291.202527848336;13327;13343.132696374752;13351.532696355629;13368.264745438997;735.9595222262786;753.9660715267826;754.9396048316122;754.9396048316149;903.7740564858785;926.6988228825659;930.8277968575835;931.5947562810165;936.8454205056157;938.197840846077;970.1656575345197;975.9707208783713;982.9361091529064;983.2527279267737;984.5766320482189;971.8493633486821;975.7170420578024;976.9218462522981;979.5836769372988;980.2452111873853;589.5755056359415;601.5999360425312;602.3193791016167;696.7073828991785;710.939570136539;14184.503542376071;14194.645133871736;14216.502422415166;14224.502142424966;14259.429622815074;13424.597262082056;14216.644583885382;14351.78472322916;14474.42102315906;14846.109233445919;10991.120710343153;11172.273802202832;11258.831117533244;11558.364289964049;11596;3981.144054028369;4002.079521710023;4005.83976640934;4031.6371526562457;4124.13393187431;55395.16907246395;55584;56035.43964560384;57426.7128664354;57633.84732305372;11792.351420671774;11883.643490695293;11980.820287695691;12109.75780484403;12262.754744905053;21417.250396236057;21426.678599821014;21428.89285553562;21459.141634334763;21467.89266053691;8068.596570171468;8083;8178;8277.296429803535;8333.125021872738;4872.098661747588;4899.730514821647;4915.066137433876;4930.654854160174;5079.339685840817;3465.462853257749;3573.5533058367705;3576.8390422431053;3576.9284614307567;3577.83899724513;657.7960832142052;659.8944168932948;661.2131563439492;665.5939876675225;678.9185297764201;763.5723994563062;778.5497156061999;790.8022994251437;796.8207153390441;809.5790189101634;10134.442605656759;10187.949060254652;10316.948415258026;10318;10326.380417174994;759.1080480435467;760.9657565409569;823.2261674026396;831.8377916306263;832.2759199496477;4647.1635105680625;4700.365450664117;4714.245720684674;4741;4758.952410475851;65956.34043659599;66021.00968485477;66149;66188.66905665537;67346.32653673498;44407.77796110999;44654.33018504725;54571.90856182854;58708.41291587115;59575.702121489114;52893.73553132287;53276.73361633167;53318.93686046866;54040.45959540433;55272;10045;10120;10162.898371016196;10441.164706823283;10483;33931.660683393835;33973.83013084919;34395;35839.104022399646;36443;44610.330845036704;45638.31542526931;45647.54352456433;46589.068218636115;48633.02733945372;12679.302638354979;12815.935920320338;13018.869811302146;13149.342532873126;13171.07802453819;13348.473755771072;13618.387172577455;13767.802979532245;13811.792823107862;13936.442542298195;13179.538716144889;13360.154220098779;13369.799453008018;13775.311234438037;13777.242251676254;12936.741265174456;13299.401526931118;13410.195388276734;13539.932300338829;14111.788323174955;13411;13679.589612311647;13742.381592828147;13854.930725346308;14115;11213.327200368005;11465.885341146482;11522.13583981188;11557.75386671178;11558.364289964133;11417.200795944203;11509;11666.88333116658;11906.345151016778;12057.939710301393 23:57:19 INFO - PROCESS | 29355 | __end_tp_report 23:57:19 INFO - PROCESS | 29355 | __start_cc_report 23:57:19 INFO - PROCESS | 29355 | _x_x_mozilla_cycle_collect,846 23:57:19 INFO - PROCESS | 29355 | __end_cc_report 23:57:19 INFO - PROCESS | 29355 | __startTimestamp1461740239708__endTimestamp 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | ------- Summary: start ------- 23:57:19 INFO - PROCESS | 29355 | Number of tests: 6 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#0] dojo.html Cycles:165 Average:5514.51 Median:5660.92 stddev:609.75 (10.8%) stddev-sans-first:586.08 23:57:19 INFO - PROCESS | 29355 | Values: 3288.4 3361.6 3379.4 3387.6 3418.0 5504.8 5516.7 5522.3 5548.3 5550.8 5486.9 5553.4 5553.8 5556.8 5561.2 4930.2 5023.5 5025.9 5087.8 5096.3 4943.5 5018.2 5025.6 5027.5 5031.1 5032.7 5052.8 5086.5 5095.5 5109.2 5113.9 5138.5 5147.8 5155.0 5191.5 4934.7 4964.5 5026.5 5045.9 5053.8 5410.1 5413.2 5450.2 5464.9 5467.1 5859.1 5861.4 5868.4 5870.6 5874.6 5893.2 5897.3 5913.3 5917.8 5967.9 5558.5 5576.6 5594.5 5596.5 5610.2 5864.4 6091.3 6103.2 6103.4 6103.8 5727.5 5783.3 5784.0 5795.6 5802.7 5959.2 6003.9 6005.6 6008.2 6017.1 5645.5 5824.2 5942.6 5971.7 5984.2 5928.2 5933.1 5938.8 5940.4 5973.6 4543.4 4546.0 4546.3 4548.4 4572.0 3987.2 3996.1 4068.1 4100.8 4109.5 5643.9 5646.4 5651.1 5660.9 5675.4 5897.4 5909.3 5916.3 5918.8 5923.0 5441.0 5549.2 5553.7 5591.9 5660.9 5140.6 5142.9 5150.5 5151.8 5158.0 6226.5 6231.9 6238.8 6257.9 6278.5 5807.9 5909.7 5958.4 6002.0 6015.2 6237.2 6237.6 6249.1 6271.9 6280.5 5893.4 5908.0 5913.1 5917.6 5949.3 5783.7 5856.3 5966.5 5976.0 5982.7 5782.2 5829.6 5856.2 5856.3 5860.2 5637.5 5763.0 5800.3 5808.6 5812.6 5567.8 5645.7 5648.5 5666.2 5677.5 5761.7 5787.3 5791.4 5793.2 5797.9 5578.8 5581.2 5642.3 5656.6 5691.0 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#1] ext.html Cycles:180 Average:60787.94 Median:20905.59 stddev:122075.63 (583.9%) stddev-sans-first:122334.47 23:57:19 INFO - PROCESS | 29355 | Values: 608.4 667.7 670.6 673.1 675.9 30918.0 31465.4 39810.0 41124.8 41270.6 44937.3 45746.0 45808.3 45837.0 45990.0 7867.7 8866.9 9088.7 9094.7 9096.8 10314.8 10576.9 10579.4 10584.5 10584.7 10624.5 10791.9 10796.9 10802.8 10803.1 7839.8 10506.4 10584.5 10677.4 10815.5 10609.9 10757.7 10775.5 10780.4 10780.7 41771.8 42516.4 42578.4 42582.4 42611.0 3734.6 4187.9 4403.8 4475.8 4486.5 34355.0 34847.1 34946.8 35159.6 35492.8 4411.4 4474.4 4490.8 4501.3 4564.0 369656.0 369836.0 374682.0 375387.0 377530.0 38554.8 39004.2 71784.3 72830.0 73043.6 40211.4 40307.2 40323.6 40437.4 40502.2 17029.5 17263.1 17272.7 17273.1 17275.7 13036.7 13296.5 14068.0 14443.2 14584.9 9887.1 9901.3 9962.1 10019.7 10036.6 3777.8 3783.3 3803.4 3834.6 3891.7 1118.6 1129.3 1170.5 1204.5 1266.7 31444.5 34872.8 34882.0 34941.1 34955.8 1189.8 1199.5 1200.5 1218.5 1294.8 17676.2 17798.9 17974.0 18021.7 18034.1 571418.0 579481.0 581220.0 581586.0 583458.0 64485.4 65013.7 65196.0 65479.3 65598.3 1370.1 1477.6 1533.7 1538.2 1558.7 31204.2 32943.5 33186.0 33629.7 33956.8 53872.2 54844.5 55003.7 55106.0 55161.0 395672.0 397630.0 398193.0 398317.0 398726.0 26158.2 27981.2 28751.4 28770.0 28788.6 43934.1 45520.5 45837.8 45929.3 46200.0 38533.6 38649.4 39041.6 41255.4 41446.6 41410.0 41843.6 42365.8 43577.6 43706.6 41192.6 42575.8 42851.4 42914.0 43356.8 35835.6 36135.8 37539.4 37722.4 38007.8 28354.6 28638.7 28853.9 28879.4 28889.0 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#2] jquery.html Cycles:180 Average:31361.98 Median:2144.64 stddev:89085.56 (4153.9%) stddev-sans-first:89305.20 23:57:19 INFO - PROCESS | 29355 | Values: 435.9 440.8 444.9 445.0 445.8 19569.0 19795.8 20590.7 20614.3 20627.0 18613.7 20428.9 20701.6 20759.1 20778.5 5832.7 6077.1 6089.5 6107.8 6108.8 5855.7 5928.3 5939.6 5947.1 5977.3 5873.6 5904.7 5920.9 5929.5 5983.9 5876.8 5973.8 5976.2 5977.6 6003.0 4417.6 5298.8 5379.4 5383.5 5385.8 24427.8 24497.8 24516.3 24577.1 24637.8 2493.3 2569.8 2786.5 2926.7 2970.5 14516.3 15244.3 15262.8 15495.5 15537.3 2071.1 2097.3 2168.0 2190.2 2256.3 81222.6 82922.2 83135.2 83635.2 84190.0 11082.4 11230.7 11291.7 11330.2 11586.1 16228.4 16451.5 16524.5 16555.8 16558.7 9163.0 9201.0 9268.2 9299.8 9431.4 9305.7 9319.1 9324.6 9421.2 9424.8 6994.6 7119.0 7134.1 7162.1 7253.5 2005.0 2074.6 2107.7 2108.8 2121.3 991.3 1151.5 1169.7 1179.0 1210.6 20118.7 20903.4 20983.6 21134.8 21196.3 1057.3 1066.4 1165.6 1177.0 1237.3 10423.7 10868.9 10900.4 10932.3 10957.0 523968.0 534363.0 541326.0 541812.0 563942.0 100453.0 100940.5 101094.0 103343.0 103571.5 1130.5 1132.6 1138.8 1143.9 1148.8 16916.6 16946.6 16950.7 17086.3 17387.6 37346.8 38230.4 38796.4 39355.4 40506.0 53755.0 59863.0 60430.7 60436.1 60507.0 9052.1 9360.6 9439.6 9503.5 9522.5 11182.7 12248.0 14557.4 14618.6 14762.2 12345.4 12575.4 12586.7 12772.7 12795.6 12136.0 12326.1 12330.8 12333.4 12670.5 12798.9 12904.8 13171.3 13233.9 13323.7 9225.9 9322.5 9325.4 9359.2 9610.9 11940.9 11993.2 12737.7 12834.9 13139.7 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#3] mootools.html Cycles:180 Average:5756.32 Median:4751.80 stddev:3209.65 (67.5%) stddev-sans-first:3202.35 23:57:19 INFO - PROCESS | 29355 | Values: 1450.4 1543.9 1604.4 1764.5 1839.6 8295.2 8685.3 8703.1 8706.2 8707.5 8466.7 8587.5 8635.1 8653.3 8677.5 2101.7 2161.4 2327.3 2380.7 2427.2 2403.4 2407.1 2426.8 2426.9 2429.7 2287.9 2293.8 2325.8 2378.4 2380.5 2036.3 2256.5 2310.0 2329.4 2344.2 2283.4 2303.3 2305.0 2306.1 2309.9 8203.6 8235.8 8250.1 8260.8 8262.6 5202.2 5397.8 5405.9 5408.6 5416.8 3119.9 3325.8 3331.3 3332.9 3338.1 1138.5 1155.5 1172.4 1180.1 1188.3 11660.4 11948.8 11952.4 11966.8 11974.2 4614.0 4722.6 4781.0 4782.5 4845.8 9528.6 9550.2 9584.6 9595.6 9600.9 5307.4 5309.1 5316.8 5319.8 5324.1 4445.1 4838.9 4933.2 4937.3 4983.3 3864.1 3896.1 3952.2 3953.7 4070.0 1175.8 1231.3 1239.9 1242.5 1249.3 923.4 957.0 1031.6 1062.3 1080.8 5983.2 6107.8 6115.2 6154.8 6189.6 1010.6 1018.4 1021.2 1049.2 1063.7 4286.3 4419.3 4478.2 4480.2 4529.3 10885.2 11211.6 11351.9 11418.8 11441.6 10950.3 11022.3 11024.4 11047.9 11311.3 7882.4 8025.2 8065.9 8161.3 8188.3 6147.4 6189.5 6189.7 6209.8 6239.6 10016.3 10172.0 10199.8 10213.4 10259.3 10048.9 10173.9 10214.2 10311.1 10461.4 6723.0 7048.6 7236.0 7474.7 7590.3 8087.5 8111.0 8140.5 8159.7 8188.2 7139.9 7312.2 7360.7 7430.3 7444.9 7022.1 7071.5 7075.4 7139.0 7263.5 6944.0 6950.9 7011.9 7020.6 7026.6 5144.6 5190.5 5230.7 5234.7 5306.1 5510.1 5520.1 5547.0 5566.1 5629.7 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#4] prototype.html Cycles:180 Average:4894.60 Median:5468.67 stddev:1440.32 (26.3%) stddev-sans-first:1403.50 23:57:19 INFO - PROCESS | 29355 | Values: 356.4 359.0 359.5 360.4 363.3 5344.4 5464.8 5472.6 5483.7 5485.0 5423.8 5427.3 5437.5 5441.5 5445.8 3822.3 4027.6 4195.2 4273.1 4299.4 4273.3 4274.6 4282.3 4307.7 4327.5 4052.8 4284.2 4286.8 4286.9 4287.3 4149.2 4226.3 4242.8 4252.9 4262.7 3804.6 3807.5 3811.1 3818.4 3821.8 5256.0 5289.2 5293.0 5297.6 5303.5 4900.6 4901.7 4914.1 4917.9 4929.6 5328.5 5522.2 5555.4 5563.7 5575.0 4053.5 4077.6 4192.5 4207.6 4220.5 5632.4 5812.3 5821.6 5883.4 5890.1 4172.2 4240.1 4240.8 4243.2 4246.9 4307.3 4313.0 4314.0 4318.9 4322.9 4887.8 4889.8 4901.2 4924.0 4929.4 5457.6 5733.6 5737.7 5742.7 5742.8 1543.8 1557.5 1561.6 1564.1 1564.7 1028.7 1049.8 1051.9 1061.4 1069.6 5864.7 5925.0 5928.7 5956.3 5961.8 6225.8 6254.2 6262.0 6271.6 6287.0 5364.4 5642.7 5659.8 5727.2 5899.6 3367.5 3374.6 3375.8 3377.8 3379.6 6439.7 6638.4 6652.3 6653.7 6656.9 6394.7 6461.2 6466.4 6470.2 6494.5 5895.5 6042.7 6159.0 6196.2 6203.7 5875.6 5885.8 5902.9 5910.5 5927.6 5588.6 5777.0 5798.8 5822.4 5825.5 6082.5 6120.3 6128.6 6141.4 6141.6 4179.1 4234.4 4242.6 4246.2 4250.2 5556.9 5899.3 5921.2 5941.2 5957.5 5756.5 5768.1 5768.5 5798.8 5799.9 5603.9 5735.3 5737.5 5758.0 5762.0 5667.2 5688.6 5696.5 5697.5 5711.6 5727.3 5766.9 5775.6 5786.5 5805.0 5461.7 5663.9 5671.9 5679.1 5688.5 23:57:19 INFO - PROCESS | 29355 | 23:57:19 INFO - PROCESS | 29355 | [#5] yui.html Cycles:180 Average:15425.32 Median:3991.61 stddev:17627.93 (441.6%) stddev-sans-first:17643.64 23:57:19 INFO - PROCESS | 29355 | Values: 901.2 913.3 925.6 934.7 955.4 14316.1 14805.6 14832.0 14838.9 14872.5 13291.2 13327.0 13343.1 13351.5 13368.3 736.0 754.0 754.9 754.9 903.8 926.7 930.8 931.6 936.8 938.2 970.2 976.0 982.9 983.3 984.6 971.8 975.7 976.9 979.6 980.2 589.6 601.6 602.3 696.7 710.9 14184.5 14194.6 14216.5 14224.5 14259.4 13424.6 14216.6 14351.8 14474.4 14846.1 10991.1 11172.3 11258.8 11558.4 11596.0 3981.1 4002.1 4005.8 4031.6 4124.1 55395.2 55584.0 56035.4 57426.7 57633.8 11792.4 11883.6 11980.8 12109.8 12262.8 21417.3 21426.7 21428.9 21459.1 21467.9 8068.6 8083.0 8178.0 8277.3 8333.1 4872.1 4899.7 4915.1 4930.7 5079.3 3465.5 3573.6 3576.8 3576.9 3577.8 657.8 659.9 661.2 665.6 678.9 763.6 778.5 790.8 796.8 809.6 10134.4 10187.9 10316.9 10318.0 10326.4 759.1 761.0 823.2 831.8 832.3 4647.2 4700.4 4714.2 4741.0 4759.0 65956.3 66021.0 66149.0 66188.7 67346.3 44407.8 44654.3 54571.9 58708.4 59575.7 52893.7 53276.7 53318.9 54040.5 55272.0 10045.0 10120.0 10162.9 10441.2 10483.0 33931.7 33973.8 34395.0 35839.1 36443.0 44610.3 45638.3 45647.5 46589.1 48633.0 12679.3 12815.9 13018.9 13149.3 13171.1 13348.5 13618.4 13767.8 13811.8 13936.4 13179.5 13360.2 13369.8 13775.3 13777.2 12936.7 13299.4 13410.2 13539.9 14111.8 13411.0 13679.6 13742.4 13854.9 14115.0 11213.3 11465.9 11522.1 11557.8 11558.4 11417.2 11509.0 11666.9 11906.3 12057.9 23:57:19 INFO - PROCESS | 29355 | -------- Summary: end -------- 23:57:19 INFO - PROCESS | 29355 | 23:57:20 INFO - TEST-INFO | 29355: exit 0 23:57:20 INFO - TEST-OK | dromaeo_css | took 1114188ms 23:57:20 INFO - TEST-START | kraken 23:57:20 INFO - operating with platform_type : linux_ 23:57:20 INFO - Initialising browser for kraken test... 23:57:20 INFO - TEST-INFO | started process 29550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB7UtkY/profile http://localhost:42864/getInfo.html) 23:57:25 INFO - PROCESS | 29550 | __metrics Screen width/height:1600/1200 23:57:25 INFO - PROCESS | 29550 | colorDepth:24 23:57:25 INFO - PROCESS | 29550 | Browser inner width/height: 1024/697 23:57:25 INFO - PROCESS | 29550 | __metrics 23:57:26 ERROR - PROCESS | 29550 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:57:27 INFO - TEST-INFO | 29550: exit 0 23:57:27 INFO - Browser initialized. 23:57:27 INFO - Running cycle 1/1 for kraken test... 23:57:27 INFO - TEST-INFO | started process 29641 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB7UtkY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:57:39 INFO - PROCESS | 29641 | RSS: Main: 143097856 23:57:39 INFO - PROCESS | 29641 | 23:57:41 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42864/tests/kraken/driver.html?testName=audio-beat-detection) 23:57:42 INFO - PROCESS | 29641 | RSS: Main: 190943232 23:57:42 INFO - PROCESS | 29641 | 23:57:45 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42864/tests/kraken/driver.html?testName=audio-dft) 23:57:45 INFO - PROCESS | 29641 | RSS: Main: 184127488 23:57:45 INFO - PROCESS | 29641 | 23:57:48 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42864/tests/kraken/driver.html?testName=audio-fft) 23:57:49 INFO - PROCESS | 29641 | RSS: Main: 273428480 23:57:49 INFO - PROCESS | 29641 | 23:57:51 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42864/tests/kraken/driver.html?testName=audio-oscillator) 23:57:51 INFO - PROCESS | 29641 | RSS: Main: 182251520 23:57:51 INFO - PROCESS | 29641 | 23:57:54 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42864/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:57:54 INFO - PROCESS | 29641 | RSS: Main: 181190656 23:57:54 INFO - PROCESS | 29641 | 23:57:57 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42864/tests/kraken/driver.html?testName=imaging-darkroom) 23:57:58 INFO - PROCESS | 29641 | RSS: Main: 215633920 23:57:58 INFO - PROCESS | 29641 | 23:58:01 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42864/tests/kraken/driver.html?testName=imaging-desaturate) 23:58:02 INFO - PROCESS | 29641 | RSS: Main: 213868544 23:58:02 INFO - PROCESS | 29641 | 23:58:04 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42864/tests/kraken/driver.html?testName=json-parse-financial) 23:58:05 INFO - PROCESS | 29641 | RSS: Main: 214130688 23:58:05 INFO - PROCESS | 29641 | 23:58:07 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42864/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:58:07 INFO - PROCESS | 29641 | RSS: Main: 195915776 23:58:07 INFO - PROCESS | 29641 | 23:58:09 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:58:09 INFO - PROCESS | 29641 | RSS: Main: 193507328 23:58:09 INFO - PROCESS | 29641 | 23:58:11 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:58:12 INFO - PROCESS | 29641 | RSS: Main: 206536704 23:58:12 INFO - PROCESS | 29641 | 23:58:15 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:58:15 INFO - PROCESS | 29641 | RSS: Main: 215068672 23:58:15 INFO - PROCESS | 29641 | 23:58:18 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:58:19 INFO - PROCESS | 29641 | RSS: Main: 209158144 23:58:19 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | Cycle 1(1): loaded http://localhost:42864/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42864/tests/kraken/driver.html?testName=ai-astar) 23:58:20 INFO - PROCESS | 29641 | RSS: Main: 273571840 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | __start_tp_report 23:58:20 INFO - PROCESS | 29641 | _x_x_mozilla_page_load 23:58:20 INFO - PROCESS | 29641 | _x_x_mozilla_page_load_details 23:58:20 INFO - PROCESS | 29641 | |i|pagename|runs| 23:58:20 INFO - PROCESS | 29641 | |0;ai-astar;109;110;105;109;112;111;102;108;111;110 23:58:20 INFO - PROCESS | 29641 | |1;audio-beat-detection;156;127;150;175;128;158;154;133;184;128 23:58:20 INFO - PROCESS | 29641 | |2;audio-dft;160;162;161;160;163;161;161;164;162;160 23:58:20 INFO - PROCESS | 29641 | |3;audio-fft;75;75;73;172;76;75;72;75;162;73 23:58:20 INFO - PROCESS | 29641 | |4;audio-oscillator;93;94;166;91;92;93;156;91;93;150 23:58:20 INFO - PROCESS | 29641 | |5;imaging-gaussian-blur;116;117;116;116;116;116;117;117;116;117 23:58:20 INFO - PROCESS | 29641 | |6;imaging-darkroom;146;145;145;146;145;146;146;145;146;146 23:58:20 INFO - PROCESS | 29641 | |7;imaging-desaturate;95;95;94;94;95;94;95;95;94;95 23:58:20 INFO - PROCESS | 29641 | |8;json-parse-financial;52;56;54;54;53;52;56;65;54;52 23:58:20 INFO - PROCESS | 29641 | |9;json-stringify-tinderbox;64;58;57;58;58;58;58;58;58;61 23:58:20 INFO - PROCESS | 29641 | |10;stanford-crypto-aes;72;71;71;72;74;72;73;73;75;73 23:58:20 INFO - PROCESS | 29641 | |11;stanford-crypto-ccm;121;119;126;123;123;117;123;124;155;129 23:58:20 INFO - PROCESS | 29641 | |12;stanford-crypto-pbkdf2;161;165;176;162;166;168;160;165;160;159 23:58:20 INFO - PROCESS | 29641 | |13;stanford-crypto-sha256-iterative;66;67;69;66;68;68;67;66;66;67 23:58:20 INFO - PROCESS | 29641 | __end_tp_report 23:58:20 INFO - PROCESS | 29641 | __start_cc_report 23:58:20 INFO - PROCESS | 29641 | _x_x_mozilla_cycle_collect,1629 23:58:20 INFO - PROCESS | 29641 | __end_cc_report 23:58:20 INFO - PROCESS | 29641 | __startTimestamp1461740300965__endTimestamp 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | ------- Summary: start ------- 23:58:20 INFO - PROCESS | 29641 | Number of tests: 14 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:3.06 (2.8%) stddev-sans-first:3.24 23:58:20 INFO - PROCESS | 29641 | Values: 109.0 110.0 105.0 109.0 112.0 111.0 102.0 108.0 111.0 110.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:155.00 stddev:20.22 (13.0%) stddev-sans-first:21.30 23:58:20 INFO - PROCESS | 29641 | Values: 156.0 127.0 150.0 175.0 128.0 158.0 154.0 133.0 184.0 128.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.35 (0.8%) stddev-sans-first:1.33 23:58:20 INFO - PROCESS | 29641 | Values: 160.0 162.0 161.0 160.0 163.0 161.0 161.0 164.0 162.0 160.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#3] audio-fft Cycles:10 Average:92.80 Median:75.00 stddev:39.20 (52.3%) stddev-sans-first:41.04 23:58:20 INFO - PROCESS | 29641 | Values: 75.0 75.0 73.0 172.0 76.0 75.0 72.0 75.0 162.0 73.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#4] audio-oscillator Cycles:10 Average:111.90 Median:92.50 stddev:31.60 (34.2%) stddev-sans-first:32.76 23:58:20 INFO - PROCESS | 29641 | Values: 93.0 94.0 166.0 91.0 92.0 93.0 156.0 91.0 93.0 150.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:58:20 INFO - PROCESS | 29641 | Values: 116.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:58:20 INFO - PROCESS | 29641 | Values: 146.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:58:20 INFO - PROCESS | 29641 | Values: 95.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:3.88 (7.2%) stddev-sans-first:3.98 23:58:20 INFO - PROCESS | 29641 | Values: 52.0 56.0 54.0 54.0 53.0 52.0 56.0 65.0 54.0 52.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:58.00 stddev:2.10 (3.6%) stddev-sans-first:1.09 23:58:20 INFO - PROCESS | 29641 | Values: 64.0 58.0 57.0 58.0 58.0 58.0 58.0 58.0 58.0 61.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:73.00 stddev:1.26 (1.7%) stddev-sans-first:1.32 23:58:20 INFO - PROCESS | 29641 | Values: 72.0 71.0 71.0 72.0 74.0 72.0 73.0 73.0 75.0 73.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:123.50 stddev:10.73 (8.7%) stddev-sans-first:11.23 23:58:20 INFO - PROCESS | 29641 | Values: 121.0 119.0 126.0 123.0 123.0 117.0 123.0 124.0 155.0 129.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.00 stddev:5.12 (3.1%) stddev-sans-first:5.29 23:58:20 INFO - PROCESS | 29641 | Values: 161.0 165.0 176.0 162.0 166.0 168.0 160.0 165.0 160.0 159.0 23:58:20 INFO - PROCESS | 29641 | 23:58:20 INFO - PROCESS | 29641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.05 (1.6%) stddev-sans-first:1.05 23:58:20 INFO - PROCESS | 29641 | Values: 66.0 67.0 69.0 66.0 68.0 68.0 67.0 66.0 66.0 67.0 23:58:20 INFO - PROCESS | 29641 | -------- Summary: end -------- 23:58:20 INFO - PROCESS | 29641 | 23:58:21 INFO - TEST-INFO | 29641: exit 0 23:58:21 INFO - TEST-OK | kraken | took 61522ms 23:58:21 INFO - SUITE-END | took 1175s 23:58:22 INFO - Completed test suite (00:19:35) 23:58:22 INFO - javascript benchmark 23:58:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3288.375208710345, 3361.5629968104145, 3379.3917094922917, 3387.6442973487783, 3417.9658203417957, 5504.779808807648, 5516.668999860011, 5522.309711286084, 5548.278723765915, 5550.77796888124, 5486.945130548694, 5553.416891226424, 5553.8333849984565, 5556.805511807088, 5561.193626924093, 4930.235813448921, 5023.547880690737, 5025.949740502585, 5087.821926232583, 5096.311997880276, 4943.505649435046, 5018.172001619729, 5025.572826309768, 5027.472115427882, 5031.144705400073, 5032.672876263049, 5052.823151189709, 5086.542211200991, 5095.541401273885, 5109.20807274873, 5113.897722045557, 5138.460461651547, 5147.7683504242395, 5155.046316431472, 5191.454897235773, 4934.654574179809, 4964.503549645006, 5026.47222041684, 5045.899082018375, 5053.823116190915, 5410.053240682904, 5413.160960051198, 5450.155225939986, 5464.863378415532, 5467.0705979983495, 5859.121131830217, 5861.443162899518, 5868.4424979626865, 5870.647761134345, 5874.588778785486, 5893.204417403638, 5897.3218079920925, 5913.349531551526, 5917.822465326047, 5967.940320596826, 5558.471945165203, 5576.637518561281, 5594.496495315441, 5596.524295434852, 5610.18652295419, 5864.38423965486, 6091.329953705089, 6103.23709536308, 6103.42017508336, 6103.84740381494, 5727.455891690282, 5783.334916484574, 5784.045632470679, 5795.594308398372, 5802.6808525531505, 5959.195508606283, 6003.939960600425, 6005.609635373687, 6008.218931538872, 6017.0974353846395, 5645.5483561314995, 5824.242848429677, 5942.584019118706, 5971.671558064261, 5984.222051133324, 5928.199693041384, 5933.050711886078, 5938.792142275, 5940.376260492672, 5973.611715238496, 4543.432070991126, 4546.045330480636, 4546.29532422475, 4548.408706868086, 4571.977140114278, 3987.222491614107, 3996.080901392696, 4068.105016896278, 4100.794960251976, 4109.527404348538, 5643.915341269884, 5646.435356464321, 5651.12407576818, 5660.915086273709, 5675.404082571353, 5897.351291357947, 5909.349971503131, 5916.319623243296, 5918.822435326947, 5923.0, 5441.047816632074, 5549.223108764776, 5553.722313884289, 5591.916121258185, 5660.85847853807, 5140.640155189176, 5142.87142821425, 5150.48495150482, 5151.8196863109615, 5157.974210128926, 6226.501879849601, 6231.937680623227, 6238.781642642486, 6257.906131408033, 6278.466330361879, 5807.94192058074, 5909.70451477433, 5958.433948774859, 6002.039673652194, 6015.218021657157, 6237.220347456568, 6237.5633705641985, 6249.093881387083, 6271.874562508816, 6280.528960328085, 5893.381194974638, 5908.0, 5913.05391137416, 5917.556183286185, 5949.25634295713, 5783.652980821164, 5856.297244330707, 5966.492848107786, 5976.013957696931, 5982.700864956648, 5782.190493330853, 5829.59192856496, 5856.238688970407, 5856.326522449803, 5860.238169038168, 5637.548996080386, 5763.049096899133, 5800.303963524404, 5808.593398462067, 5812.593118481665, 5567.832965011056, 5645.717714114196, 5648.491635752802, 5666.206731057738, 5677.545796336366, 5761.711914404347, 5787.334456537552, 5791.449812267828, 5793.159991801081, 5797.9420205797405, 5578.832635020955, 5581.162825576196, 5642.322921249476, 5656.5757568181725, 5690.971545142248], "value": 5475.320482315335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.3824917708527, 667.6861874918789, 670.6345041952138, 673.0509980926894, 675.898615207719, 30918.0, 31465.370692586162, 39810.004749881235, 41124.794376028076, 41270.58729412705, 44937.325940110924, 45746.0, 45808.312875306816, 45837.0, 45990.0, 7867.7246296379635, 8866.866996995033, 9088.727338179866, 9094.727158185266, 9096.77258068547, 10314.793704125914, 10576.947115264413, 10579.418132002736, 10584.470776461185, 10584.735381615445, 10624.468776561142, 10791.892081079206, 10796.892031079706, 10802.783944321109, 10803.081738052275, 7839.8432031359625, 10506.422146781924, 10584.523696433678, 10677.412742299131, 10815.459227038695, 10609.946950265277, 10757.677269681844, 10775.461226938622, 10780.40707761073, 10780.730481738019, 41771.79114104441, 42516.36225456621, 42578.36132458015, 42582.36126458106, 42611.0, 3734.6452087051684, 4187.874363769091, 4403.757793321398, 4475.7985890635, 4486.528914463967, 34355.0, 34847.128821779406, 34946.82526587376, 35159.648403515894, 35492.82253588716, 4411.382406463098, 4474.395956545857, 4490.7754612269255, 4501.324801279801, 4564.0, 369656.0, 369836.0, 374682.0, 375387.0, 377529.99999999726, 38554.80722596369, 39004.219915602094, 71784.28215717776, 72830.0, 73043.63478182575, 40211.39682904759, 40307.193856122714, 40323.59676403257, 40437.393439098436, 40502.1899562013, 17029.48911532656, 17263.13684315779, 17272.740908886375, 17273.136343182792, 17275.654486910444, 13036.739265214832, 13296.468141274241, 14068.0, 14443.205623690588, 14584.927075364767, 9887.110160085484, 9901.306908516479, 9962.053604907522, 10019.749506262257, 10036.64871729486, 3777.7544459610035, 3783.281176576442, 3803.372443546784, 3834.6357096076385, 3891.715733807837, 1118.6028959719345, 1129.2998341028451, 1170.5200867644226, 1204.5362535423924, 1266.6896610330527, 31444.52833207504, 34872.82563587216, 34882.0, 34941.1264718379, 34955.82522087373, 1189.7858385490522, 1199.4962115911337, 1200.4657927222477, 1218.4943248551929, 1294.8446186457686, 17676.204570794343, 17798.91100544489, 17974.0, 18021.7296740549, 18034.098295085194, 571418.0, 579481.0, 581220.0, 581586.0, 583458.0, 64485.35514644887, 65013.67493162504, 65196.0, 65479.34520654828, 65598.34401656018, 1370.0888908875486, 1477.6232060824636, 1533.6702608938908, 1538.223197285386, 1558.6570954390015, 31204.219894502818, 32943.50584741279, 33186.0, 33629.66370336363, 33956.830215848764, 53872.19191712049, 54844.45155548553, 55003.724981374835, 55106.0, 55161.0, 395672.0, 397630.0, 398193.0, 398317.0, 398726.0, 26158.215253542425, 27981.160565183076, 28751.42497150087, 28770.0, 28788.568171477862, 43934.074555936, 45520.54479455163, 45837.77081114573, 45929.31106033345, 46200.0, 38533.614663853004, 38649.42025869671, 39041.60958390493, 41255.381169281885, 41446.58553414427, 41410.0, 41843.58156418519, 42365.78817105895, 43577.56422435735, 43706.56293437152, 41192.588074118874, 42575.7871210642, 42851.357229640955, 42914.0, 43356.78321608372, 35835.64164358323, 36135.819320903225, 37539.43690844694, 37722.43416348702, 38007.80996095002, 28354.639750501086, 28638.713612863605, 28853.85573072121, 28879.42241155123, 28889.0], "value": 20191.938273659413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.85398891371386, 440.825873779857, 444.9110177964408, 444.9733016019039, 445.7682005357214, 19569.021548922552, 19795.802041979576, 20590.691139632898, 20614.278500252494, 20627.0, 18613.7207941881, 20428.89785551074, 20701.585968280626, 20759.065842037107, 20778.480537986557, 5832.737526811304, 6077.058056001305, 6089.45194932456, 6107.7556897724035, 6108.81673549792, 5855.707214639272, 5928.288605367362, 5939.613925094855, 5947.137665038566, 5977.282726072877, 5873.647581145145, 5904.7047647617665, 5920.851978700545, 5929.466348028676, 5983.880322393549, 5876.794312199074, 5973.790917317873, 5976.193213916153, 5977.611455255416, 6002.969985150046, 4417.646588272931, 5298.788048478094, 5379.354477462691, 5383.488568585978, 5385.838424847261, 24427.755722442726, 24497.755022449724, 24516.264512064667, 24577.13980010709, 24637.753622463726, 2493.252024392675, 2569.832960857538, 2786.4845003674386, 2926.6926972667775, 2970.495015847311, 14516.346764395628, 15244.314005869763, 15262.847371526253, 15495.535133946, 15537.300821462948, 2071.0576687607104, 2097.25547430663, 2168.0, 2190.167736260211, 2256.311824893407, 81222.59388703077, 82922.17077829204, 83135.16864831335, 83635.16364836274, 84190.0, 11082.445877706245, 11230.66308010761, 11291.717707057389, 11330.150238732129, 11586.13104017202, 16228.43200488001, 16451.506454806375, 16524.504264872074, 16555.83444165543, 16558.66882662364, 9162.954185229031, 9201.0, 9268.212201962908, 9299.814003719888, 9431.434113953048, 9305.674301399418, 9319.068093190626, 9324.580393882292, 9421.199198068103, 9424.811503770023, 6994.5803251805055, 7118.9644051779405, 7134.108236470442, 7162.104736907887, 7253.528520646247, 2004.959900801976, 2074.626567217885, 2107.747070351568, 2108.7785782492924, 2121.2787652198326, 991.2565575818137, 1151.5163631274884, 1169.7418809369972, 1178.988210117905, 1210.5823490895627, 20118.69821952672, 20903.408724789257, 20983.580328393346, 21134.788652113588, 21196.258130965343, 1057.3391630231106, 1066.3974854207347, 1165.5862168930078, 1176.9525122640857, 1237.3442075699893, 10423.739406514898, 10868.891311086787, 10900.400477973788, 10932.344059356465, 10957.0, 523968.0, 534363.0, 541326.0, 541812.0, 563942.0, 100453.0, 100940.49529752304, 101094.0, 103343.0, 103571.48214258881, 1130.4856290387854, 1132.6262333429947, 1138.7665528566624, 1143.89704926557, 1148.8104462763552, 16916.57708557296, 16946.576335591708, 16950.745738814174, 17086.316547338465, 17387.56531086733, 37346.813265933495, 38230.42654360242, 38796.41805372978, 39355.409668854416, 40506.0, 53755.0, 59863.0, 60430.697846512245, 60436.09345859903, 60507.0, 9052.094790520896, 9360.578773955325, 9439.575219115293, 9503.477308748084, 9522.476263805556, 11182.66452006441, 12248.0, 14557.41770329175, 14618.561443156723, 14762.188079655292, 12345.444454999368, 12575.43410546546, 12586.748265034465, 12772.680682983, 12795.552155674879, 12136.002275687699, 12326.07554433439, 12330.75338493243, 12333.444994975069, 12670.493180272686, 12798.936005320285, 12904.8064279034, 13171.2755798432, 13233.933830330787, 13323.66690832737, 9225.907740922505, 9322.48726320059, 9325.440473571607, 9359.157675808938, 9610.903890961, 11940.940295298467, 11993.160478766402, 12737.745245095231, 12834.871651283367, 13139.73720525565], "value": 10038.200630736543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.4343306110611, 1543.884208684349, 1604.3662753212482, 1764.4530195639345, 1839.6412699523598, 8295.17048295171, 8685.34859885508, 8703.086175951528, 8706.216440520351, 8707.521086340257, 8466.745997620066, 8587.48475091495, 8635.093315201908, 8653.264472519828, 8677.47935123893, 2101.7162683037823, 2161.351594521645, 2327.2552783109413, 2380.7143142822842, 2427.2232885476656, 2403.3510952043025, 2407.1093695332665, 2426.8179886508583, 2426.9029238830426, 2429.708434987804, 2287.8970446329954, 2293.8279629027807, 2325.802306803915, 2378.357843382286, 2380.452495925938, 2036.297477370305, 2256.5148493074066, 2309.976900230993, 2329.3943574670625, 2344.1795371619796, 2283.3949003513917, 2303.343547089088, 2304.988475057614, 2306.1121468234683, 2309.93070207894, 8203.58982050895, 8235.794105147359, 8250.051244106944, 8260.793480162984, 8262.628181731836, 5202.167653175512, 5397.78408863645, 5405.945940540583, 5408.567314614821, 5416.83749487516, 3119.859606317721, 3325.817080060596, 3331.283773988604, 3332.933341333184, 3338.1320856577463, 1138.5332013874354, 1155.5493357590547, 1172.3962159487871, 1180.0559552358166, 1188.3226560860312, 11660.358680272582, 11948.761024779542, 11952.40237988097, 11966.760664786743, 11974.221675591145, 4613.953860461353, 4722.6221902247735, 4780.972091000452, 4782.450018247877, 4845.781939812717, 9528.618855245713, 9550.235981121494, 9584.568694408768, 9595.616175352907, 9600.855987160197, 5307.416184219733, 5309.097453432925, 5316.813911513079, 5319.813806516753, 5324.121519949165, 4445.088756804847, 4838.927416088762, 4933.186024305949, 4937.259411088366, 4983.252512123138, 3864.1305706215876, 3896.1038961038553, 3952.1898010907694, 3953.723239373216, 4070.0, 1175.7589694112687, 1231.335079057317, 1239.9194052386747, 1242.502998800469, 1249.2691775311544, 923.4320892650971, 957.0190554681368, 1031.5564307347763, 1062.2935747727674, 1080.7622323088908, 5983.192269043623, 6107.755689772359, 6115.235595550556, 6154.78458253959, 6189.566730328834, 1010.5654568535454, 1018.3533456255332, 1021.22897212604, 1049.160671462842, 1063.723431907694, 4286.271333873249, 4419.337099435046, 4478.171538265431, 4480.238359478896, 4529.320601909674, 10885.18361122919, 11211.60759373418, 11351.94324028391, 11418.771624567462, 11441.599544016086, 10950.288231264942, 11022.283551569122, 11024.44877756125, 11047.944760276147, 11311.264767790068, 7882.448228623941, 8025.197480251928, 8065.919340806634, 8161.347092232607, 8188.303994160563, 6147.354527774519, 6189.535784816067, 6189.721462534107, 6209.813705588839, 6239.563230573816, 10016.298859079794, 10172.033656802592, 10199.796004080024, 10213.387196768217, 10259.281850270409, 10048.89951100509, 10173.949130254301, 10214.182865370902, 10311.123554497652, 10461.424621645578, 6722.9663851680425, 7048.647567621701, 7235.963820181076, 7474.701011959678, 7590.278923502258, 8087.47431416956, 8111.0, 8140.470869393471, 8159.714409995621, 8188.181181881764, 7139.892901606376, 7312.232215617283, 7360.742374017098, 7430.331270185493, 7444.888326675213, 7022.12223472066, 7071.504994650325, 7075.433965282868, 7138.964305178441, 7263.527870688388, 6944.0, 6950.895736564057, 7011.859762804614, 7020.613866237406, 7026.6135362553505, 5144.562712169508, 5190.455002224712, 5230.738463076755, 5234.738263086754, 5306.11434358687, 5510.090835012178, 5520.06158952987, 5547.0, 5566.081596536526, 5629.718514074362], "value": 4649.413726900822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.39590893435627, 358.98564057437704, 359.51645037424663, 360.38914040701025, 363.29883325182385, 5344.412114667383, 5464.781408743655, 5472.5621950243985, 5483.698396588186, 5485.0, 5423.755930983115, 5427.3215848019, 5437.510624043835, 5441.537469315113, 5445.83662490125, 3822.3310920588924, 4027.637512623863, 4195.223883581547, 4273.145370925812, 4299.398084268205, 4273.337632666941, 4274.572542745716, 4282.336237883133, 4307.719998200107, 4327.5239723630375, 4052.7973601319964, 4284.164587905359, 4286.828526858938, 4286.914261714764, 4287.292596721537, 4149.170165966803, 4226.323788193905, 4242.787860606957, 4252.8511502097435, 4262.722923009995, 3804.6385593368864, 3807.5240594925635, 3811.123441608418, 3818.350880350319, 3821.751586146919, 5256.0, 5289.170293892787, 5293.0, 5297.629165958385, 5303.469653034705, 4900.607951363918, 4901.725551356637, 4914.11545921734, 4917.901641967141, 4929.6302777291485, 5328.520433161034, 5522.23888055972, 5555.444455554412, 5563.664720467099, 5574.9721251393885, 4053.5338436079933, 4077.612626800449, 4192.538820729717, 4207.621314081717, 4220.53574106848, 5632.35227948787, 5812.331581868097, 5821.621594596338, 5883.352831188566, 5890.087036509348, 4172.165566886635, 4240.1307731914885, 4240.787960601958, 4243.193793179286, 4246.8513602024395, 4307.289297265978, 4313.0, 4314.0, 4318.935215971763, 4322.8703138905885, 4887.804487820447, 4889.755512224446, 4901.1913034349645, 4924.0, 4929.408470983505, 5457.6452530586175, 5733.581765231899, 5737.741801618937, 5742.6554406734895, 5742.770289188469, 1543.8224604170664, 1557.548310989823, 1561.554956837296, 1564.108458178827, 1564.7496400575853, 1028.665683652816, 1049.7952899184738, 1051.9474026298808, 1061.3790932304535, 1069.5989004123453, 5864.67744274069, 5924.970375148183, 5928.673922934194, 5956.344802071769, 5961.794136169645, 6225.8132256032395, 6254.15568898202, 6262.0, 6271.560990730605, 6286.994080947026, 5364.436734142937, 5642.661440313512, 5659.773609055674, 5727.169560413717, 5899.646021238739, 3367.494875768605, 3374.611919629274, 3375.814330211813, 3377.7635565510177, 3379.6282408935, 6439.678016099177, 6638.402543771083, 6652.268250492442, 6653.667316634149, 6656.933430665728, 6394.712237949303, 6461.19235095613, 6466.418022377915, 6470.223573171155, 6494.512911531654, 5895.498882594941, 6042.728077236535, 6159.0, 6196.1635179250225, 6203.689815509207, 5875.559333049953, 5885.764569417175, 5902.881942361215, 5910.527157827278, 5927.644341339534, 5588.580856435702, 5776.989026920356, 5798.82603521895, 5822.359540450462, 5825.533957283492, 6082.452579267887, 6120.296165940974, 6128.63228206309, 6141.416565426277, 6141.631502109888, 4179.080602267496, 4234.38601402801, 4242.618164365099, 4246.235677578065, 4250.21371046345, 5556.916646250229, 5899.262592175978, 5921.2006379138265, 5941.197938278277, 5957.493613042767, 5756.474534248511, 5768.105943578752, 5768.451997060272, 5798.768049277982, 5799.884002320015, 5603.859903502445, 5735.311762588516, 5737.540996720336, 5757.992351338583, 5761.971190144022, 5667.1782591524825, 5688.63023903445, 5696.515796157373, 5697.515711164598, 5711.628744131618, 5727.339071669169, 5766.913496297643, 5775.595708300379, 5786.450287222707, 5805.0, 5461.672299662033, 5663.943360566506, 5671.943280567141, 5679.148127780866, 5688.459596338341], "value": 4448.383030399336, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.1889299630332, 913.2965229070287, 925.6204955968052, 934.7242563443783, 955.3646824861468, 14316.141031538114, 14805.629859253524, 14832.0, 14838.851611483882, 14872.479463218777, 13291.202527848336, 13327.0, 13343.132696374752, 13351.532696355629, 13368.264745438997, 735.9595222262786, 753.9660715267826, 754.9396048316122, 754.9396048316149, 903.7740564858785, 926.6988228825659, 930.8277968575835, 931.5947562810165, 936.8454205056157, 938.197840846077, 970.1656575345197, 975.9707208783713, 982.9361091529064, 983.2527279267737, 984.5766320482189, 971.8493633486821, 975.7170420578024, 976.9218462522981, 979.5836769372988, 980.2452111873853, 589.5755056359415, 601.5999360425312, 602.3193791016167, 696.7073828991785, 710.939570136539, 14184.503542376071, 14194.645133871736, 14216.502422415166, 14224.502142424966, 14259.429622815074, 13424.597262082056, 14216.644583885382, 14351.78472322916, 14474.42102315906, 14846.109233445919, 10991.120710343153, 11172.273802202832, 11258.831117533244, 11558.364289964049, 11596.0, 3981.144054028369, 4002.079521710023, 4005.83976640934, 4031.6371526562457, 4124.13393187431, 55395.16907246395, 55584.0, 56035.43964560384, 57426.7128664354, 57633.84732305372, 11792.351420671774, 11883.643490695293, 11980.820287695691, 12109.75780484403, 12262.754744905053, 21417.250396236057, 21426.678599821014, 21428.89285553562, 21459.141634334763, 21467.89266053691, 8068.596570171468, 8083.0, 8178.0, 8277.296429803535, 8333.125021872738, 4872.098661747588, 4899.730514821647, 4915.066137433876, 4930.654854160174, 5079.339685840817, 3465.462853257749, 3573.5533058367705, 3576.8390422431053, 3576.9284614307567, 3577.83899724513, 657.7960832142052, 659.8944168932948, 661.2131563439492, 665.5939876675225, 678.9185297764201, 763.5723994563062, 778.5497156061999, 790.8022994251437, 796.8207153390441, 809.5790189101634, 10134.442605656759, 10187.949060254652, 10316.948415258026, 10318.0, 10326.380417174994, 759.1080480435467, 760.9657565409569, 823.2261674026396, 831.8377916306263, 832.2759199496477, 4647.1635105680625, 4700.365450664117, 4714.245720684674, 4741.0, 4758.952410475851, 65956.34043659599, 66021.00968485477, 66149.0, 66188.66905665537, 67346.32653673498, 44407.77796110999, 44654.33018504725, 54571.90856182854, 58708.41291587115, 59575.702121489114, 52893.73553132287, 53276.73361633167, 53318.93686046866, 54040.45959540433, 55272.0, 10045.0, 10120.0, 10162.898371016196, 10441.164706823283, 10483.0, 33931.660683393835, 33973.83013084919, 34395.0, 35839.104022399646, 36443.0, 44610.330845036704, 45638.31542526931, 45647.54352456433, 46589.068218636115, 48633.02733945372, 12679.302638354979, 12815.935920320338, 13018.869811302146, 13149.342532873126, 13171.07802453819, 13348.473755771072, 13618.387172577455, 13767.802979532245, 13811.792823107862, 13936.442542298195, 13179.538716144889, 13360.154220098779, 13369.799453008018, 13775.311234438037, 13777.242251676254, 12936.741265174456, 13299.401526931118, 13410.195388276734, 13539.932300338829, 14111.788323174955, 13411.0, 13679.589612311647, 13742.381592828147, 13854.930725346308, 14115.0, 11213.327200368005, 11465.885341146482, 11522.13583981188, 11557.75386671178, 11558.364289964133, 11417.200795944203, 11509.0, 11666.88333116658, 11906.345151016778, 12057.939710301393], "value": 7157.238119595227, "unit": "score"}], "name": "dromaeo_css", "value": 7400.689099370571, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 105.0, 109.0, 112.0, 111.0, 102.0, 108.0, 111.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 150.0, 175.0, 128.0, 158.0, 154.0, 133.0, 184.0, 128.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 163.0, 161.0, 161.0, 164.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 73.0, 172.0, 76.0, 75.0, 72.0, 75.0, 162.0, 73.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 166.0, 91.0, 92.0, 93.0, 156.0, 91.0, 93.0, 150.0], "value": 111.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 56.0, 54.0, 54.0, 53.0, 52.0, 56.0, 65.0, 54.0, 52.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 61.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 72.0, 74.0, 72.0, 73.0, 73.0, 75.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 126.0, 123.0, 123.0, 117.0, 123.0, 124.0, 155.0, 129.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 176.0, 162.0, 166.0, 168.0, 160.0, 165.0, 160.0, 159.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 66.0, 68.0, 68.0, 67.0, 66.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1524.1, "alertThreshold": 2.0}]} 23:58:22 INFO - Return code: 0 23:58:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:58:22 ERROR - # TBPL SUCCESS # 23:58:22 INFO - Running post-action listener: _resource_record_post_action 23:58:22 INFO - Running post-run listener: _resource_record_post_run 23:58:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 496971776; Read time: 12; Write time: 816284 23:58:23 INFO - install - Wall time: 16s; CPU: 18.0%; Read bytes: 0; Write bytes: 248844288; Read time: 0; Write time: 502428 23:58:23 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248127488; Read time: 12; Write time: 313856 23:58:23 INFO - Running post-run listener: _upload_blobber_files 23:58:23 INFO - Blob upload gear active. 23:58:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:58:23 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 23:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:58:23 INFO - (blobuploader) - INFO - Open directory for files ... 23:58:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:58:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:58:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:58:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:58:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:58:24 INFO - (blobuploader) - INFO - Done attempting. 23:58:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:58:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:58:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:58:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:58:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:58:25 INFO - (blobuploader) - INFO - Done attempting. 23:58:25 INFO - (blobuploader) - INFO - Iteration through files over. 23:58:25 INFO - Return code: 0 23:58:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:58:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:58:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39dff5b8f81c8bcb5d6087275354666a3cb3baf4cfd1f53082e3aa3c5ea205dc291a51a0198296c882c952f35ac000a21a077a281f877a2d4477b4c0942649b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88cc177afecfefa423d89206fc682ef29f620851fe14c4f6f119f1feb8c8037c1f100d552c79d7aac4b133273374c76156da6a49a9d840dae945b631d24b6bd8"} 23:58:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:58:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:58:25 INFO - Contents: 23:58:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39dff5b8f81c8bcb5d6087275354666a3cb3baf4cfd1f53082e3aa3c5ea205dc291a51a0198296c882c952f35ac000a21a077a281f877a2d4477b4c0942649b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88cc177afecfefa423d89206fc682ef29f620851fe14c4f6f119f1feb8c8037c1f100d552c79d7aac4b133273374c76156da6a49a9d840dae945b631d24b6bd8"} 23:58:25 INFO - Running post-run listener: copy_logs_to_upload_dir 23:58:25 INFO - Copying logs to upload dir... 23:58:25 INFO - mkdir: /builds/slave/test/build/upload/logs 23:58:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.083884 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-26 23:58:24.716487) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 23:58:24.720111) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39dff5b8f81c8bcb5d6087275354666a3cb3baf4cfd1f53082e3aa3c5ea205dc291a51a0198296c882c952f35ac000a21a077a281f877a2d4477b4c0942649b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88cc177afecfefa423d89206fc682ef29f620851fe14c4f6f119f1feb8c8037c1f100d552c79d7aac4b133273374c76156da6a49a9d840dae945b631d24b6bd8"} build_url:https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/L59TZYsPRXqHH5z-uSHLJg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39dff5b8f81c8bcb5d6087275354666a3cb3baf4cfd1f53082e3aa3c5ea205dc291a51a0198296c882c952f35ac000a21a077a281f877a2d4477b4c0942649b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88cc177afecfefa423d89206fc682ef29f620851fe14c4f6f119f1feb8c8037c1f100d552c79d7aac4b133273374c76156da6a49a9d840dae945b631d24b6bd8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 23:58:24.744631) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:58:24.744951) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461733754.19343-1331577148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 23:58:24.829530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 23:58:24.829853) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 23:58:24.830143) ========= ========= Total master_lag: 0.17 =========