builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1463069276.77 results: success (0) buildid: 20160512061943 builduid: 5f55196abfaf45eb8d0a191876533746 revision: fca62ad9c45ca847ac004a2b16807a63763e5c29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.773633) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.774162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.774448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.791862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.792184) ========= 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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 09:07:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.0M=0s 2016-05-12 09:07:57 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138743 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.943164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.943507) ========= 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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013478 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.994270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:56.994664) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fca62ad9c45ca847ac004a2b16807a63763e5c29 --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 fca62ad9c45ca847ac004a2b16807a63763e5c29 --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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 09:07:58,056 truncating revision to first 12 chars 2016-05-12 09:07:58,056 Setting DEBUG logging. 2016-05-12 09:07:58,056 attempt 1/10 2016-05-12 09:07:58,056 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fca62ad9c45c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 09:07:58,316 unpacking tar archive at: mozilla-aurora-fca62ad9c45c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540897 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:57.549018) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:57.549541) ========= script_repo_revision: fca62ad9c45ca847ac004a2b16807a63763e5c29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:57.549965) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:57.550374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 09:07:57.561771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-05-12 09:07:57.562431) ========= /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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:58 INFO - MultiFileLogger online at 20160512 09:07:58 in /builds/slave/test 09:07:58 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 09:07:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:58 INFO - {'append_to_log': False, 09:07:58 INFO - 'base_work_dir': '/builds/slave/test', 09:07:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:07:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:58 INFO - 'branch': 'Mozilla-Aurora', 09:07:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:58 INFO - 'config_files': ('talos/linux_config.py',), 09:07:58 INFO - 'default_actions': ('clobber', 09:07:58 INFO - 'read-buildbot-config', 09:07:58 INFO - 'download-and-extract', 09:07:58 INFO - 'populate-webroot', 09:07:58 INFO - 'create-virtualenv', 09:07:58 INFO - 'install', 09:07:58 INFO - 'run-tests'), 09:07:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:58 INFO - 'download_minidump_stackwalk': True, 09:07:58 INFO - 'download_symbols': 'ondemand', 09:07:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:58 INFO - 'installer_path': 'installer.exe', 09:07:58 INFO - 'log_level': 'info', 09:07:58 INFO - 'log_name': 'talos', 09:07:58 INFO - 'log_to_console': True, 09:07:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:58 INFO - 'opt_config_files': (), 09:07:58 INFO - 'pip_index': False, 09:07:58 INFO - 'sps_profile': False, 09:07:58 INFO - 'sps_profile_interval': 0, 09:07:58 INFO - 'suite': 'dromaeojs', 09:07:58 INFO - 'system_bits': '32', 09:07:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:58 INFO - 'title': 'talos-linux64-ix-063', 09:07:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:58 INFO - 'use_talos_json': True, 09:07:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:58 INFO - 'work_dir': 'build'} 09:07:58 INFO - ##### 09:07:58 INFO - ##### Running clobber step. 09:07:58 INFO - ##### 09:07:58 INFO - Running pre-action listener: _resource_record_pre_action 09:07:58 INFO - Running main action method: clobber 09:07:58 INFO - rmtree: /builds/slave/test/build 09:07:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:59 INFO - Running post-action listener: _resource_record_post_action 09:07:59 INFO - ##### 09:07:59 INFO - ##### Running read-buildbot-config step. 09:07:59 INFO - ##### 09:07:59 INFO - Running pre-action listener: _resource_record_pre_action 09:07:59 INFO - Running main action method: read_buildbot_config 09:07:59 INFO - Using buildbot properties: 09:07:59 INFO - { 09:07:59 INFO - "project": "", 09:07:59 INFO - "product": "firefox", 09:07:59 INFO - "script_repo_revision": "production", 09:07:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:07:59 INFO - "repository": "", 09:07:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:07:59 INFO - "buildid": "20160512061943", 09:07:59 INFO - "pgo_build": "True", 09:07:59 INFO - "basedir": "/builds/slave/test", 09:07:59 INFO - "buildnumber": 231, 09:07:59 INFO - "slavename": "talos-linux64-ix-063", 09:07:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:07:59 INFO - "platform": "ubuntu64_hw", 09:07:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:07:59 INFO - "branch": "mozilla-aurora", 09:07:59 INFO - "repo_path": "releases/mozilla-aurora", 09:07:59 INFO - "revision": "fca62ad9c45ca847ac004a2b16807a63763e5c29", 09:07:59 INFO - "stage_platform": "linux64-pgo", 09:07:59 INFO - "builduid": "5f55196abfaf45eb8d0a191876533746", 09:07:59 INFO - "slavebuilddir": "test" 09:07:59 INFO - } 09:07:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 09:07:59 INFO - Running post-action listener: _resource_record_post_action 09:07:59 INFO - ##### 09:07:59 INFO - ##### Running download-and-extract step. 09:07:59 INFO - ##### 09:07:59 INFO - Running pre-action listener: _resource_record_pre_action 09:07:59 INFO - Running main action method: download_and_extract 09:07:59 INFO - mkdir: /builds/slave/test/build/tests 09:07: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')]} 09:07:59 INFO - https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:07:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:07:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:07:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:07:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:00 INFO - Downloaded 1361 bytes. 09:08:00 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 09:08:00 INFO - Using the following test package requirements: 09:08:00 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 09:08:00 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:08:00 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:08:00 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'jsshell-linux-x86_64.zip'], 09:08:00 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:08:00 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 09:08:00 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:08:00 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:08:00 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:08:00 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 09:08:00 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:08:00 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:00 INFO - https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:01 INFO - Downloaded 23799980 bytes. 09:08:01 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'] 09:08:01 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 09:08:02 INFO - Return code: 0 09:08:02 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 09:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:02 INFO - https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:08:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:08:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:08:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:03 INFO - Downloaded 23799980 bytes. 09:08:03 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'] 09:08:03 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 09:08:06 INFO - Return code: 0 09:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:06 INFO - https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:08:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:08:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:08:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:07 INFO - Downloaded 10840145 bytes. 09:08:07 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'] 09:08:07 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 09:08:07 INFO - Return code: 0 09:08: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')]} 09:08:07 INFO - https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:08:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:08:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:08:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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 09:08:09 INFO - Downloaded 62701708 bytes. 09:08:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:08:09 INFO - mkdir: /builds/slave/test/properties 09:08:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:08:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:08:09 INFO - Contents: 09:08:09 INFO - build_url:https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:08:10 INFO - Running post-action listener: _resource_record_post_action 09:08:10 INFO - Running post-action listener: set_extra_try_arguments 09:08:10 INFO - ##### 09:08:10 INFO - ##### Running populate-webroot step. 09:08:10 INFO - ##### 09:08:10 INFO - Running pre-action listener: _resource_record_pre_action 09:08:10 INFO - Running main action method: populate_webroot 09:08:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:08:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:08:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:08:10 INFO - '--tppagecycles', 09:08:10 INFO - '7'], 09:08:10 INFO - 'tests': ['tsvgm']}}, 09:08:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:08:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:08:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:08:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:08:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['damp', 'tps']}, 09:08:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'tests': ['damp', 'tps']}, 09:08:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['dromaeo_dom']}, 09:08:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:08:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['a11yr', 09:08:10 INFO - 'ts_paint', 09:08:10 INFO - 'tpaint', 09:08:10 INFO - 'sessionrestore', 09:08:10 INFO - 'sessionrestore_no_auto_restore', 09:08:10 INFO - 'tabpaint']}, 09:08:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['tsvgx', 09:08:10 INFO - 'tsvgr_opacity', 09:08:10 INFO - 'tart', 09:08:10 INFO - 'tscrollx', 09:08:10 INFO - 'cart']}, 09:08:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:08:10 INFO - 'tsvgr_opacity', 09:08:10 INFO - 'tart', 09:08:10 INFO - 'tscrollx', 09:08:10 INFO - 'cart']}, 09:08:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'talos_options': ['--disable-e10s'], 09:08:10 INFO - 'tests': ['tp5o']}, 09:08:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'tests': ['tp5o']}, 09:08:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'talos_options': ['--disable-e10s', 09:08:10 INFO - '--xperf_path', 09:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:10 INFO - 'tests': ['tp5n']}, 09:08:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:08:10 INFO - 'talos_options': ['--xperf_path', 09:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:10 INFO - 'tests': ['tp5n']}}, 09:08:10 INFO - 'talos.zip': {'path': '', 09:08:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:08:10 INFO - Running post-action listener: _resource_record_post_action 09:08:10 INFO - ##### 09:08:10 INFO - ##### Running create-virtualenv step. 09:08:10 INFO - ##### 09:08:10 INFO - Running pre-action listener: _resource_record_pre_action 09:08:10 INFO - Running main action method: create_virtualenv 09:08:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:08:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:08:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:08:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:08:10 INFO - Using real prefix '/usr' 09:08:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:08:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:12 INFO - Installing pip.................done. 09:08:12 INFO - Return code: 0 09:08:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08: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', 'pip>=1.5'] in /builds/slave/test/build 09:08: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 pip>=1.5 09:08:12 INFO - Using env: {'DISPLAY': ':0', 09:08:12 INFO - 'HOME': '/home/cltbld', 09:08:12 INFO - 'LANG': 'en_US.UTF-8', 09:08:12 INFO - 'LANGUAGE': 'en_US:en', 09:08:12 INFO - 'LOGNAME': 'cltbld', 09:08:12 INFO - 'MAIL': '/var/mail/cltbld', 09:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:12 INFO - 'MOZ_NO_REMOTE': '1', 09:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:12 INFO - 'NO_EM_RESTART': '1', 09:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:12 INFO - 'PWD': '/builds/slave/test', 09:08:12 INFO - 'SHELL': '/bin/bash', 09:08:12 INFO - 'SHLVL': '1', 09:08:12 INFO - 'TERM': 'linux', 09:08:12 INFO - 'TMOUT': '86400', 09:08:12 INFO - 'USER': 'cltbld', 09:08:12 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:12 INFO - '_': '/tools/buildbot/bin/python'} 09:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:08:12 INFO - Cleaning up... 09:08:12 INFO - Return code: 0 09:08:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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', '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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08: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', 'psutil>=0.7.1'] in /builds/slave/test/build 09:08: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 psutil>=0.7.1 09:08:12 INFO - Using env: {'DISPLAY': ':0', 09:08:12 INFO - 'HOME': '/home/cltbld', 09:08:12 INFO - 'LANG': 'en_US.UTF-8', 09:08:12 INFO - 'LANGUAGE': 'en_US:en', 09:08:12 INFO - 'LOGNAME': 'cltbld', 09:08:12 INFO - 'MAIL': '/var/mail/cltbld', 09:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:12 INFO - 'MOZ_NO_REMOTE': '1', 09:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:12 INFO - 'NO_EM_RESTART': '1', 09:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:12 INFO - 'PWD': '/builds/slave/test', 09:08:12 INFO - 'SHELL': '/bin/bash', 09:08:12 INFO - 'SHLVL': '1', 09:08:12 INFO - 'TERM': 'linux', 09:08:12 INFO - 'TMOUT': '86400', 09:08:12 INFO - 'USER': 'cltbld', 09:08:12 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:12 INFO - '_': '/tools/buildbot/bin/python'} 09:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:13 INFO - Downloading/unpacking psutil>=0.7.1 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:15 INFO - Installing collected packages: psutil 09:08:15 INFO - Running setup.py install for psutil 09:08:15 INFO - building 'psutil._psutil_linux' extension 09:08:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:08:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:08:15 INFO - building 'psutil._psutil_posix' extension 09:08:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:08:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:15 INFO - Successfully installed psutil 09:08:15 INFO - Cleaning up... 09:08:15 INFO - Return code: 0 09:08:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:15 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:08:15 INFO - Using env: {'DISPLAY': ':0', 09:08:15 INFO - 'HOME': '/home/cltbld', 09:08:15 INFO - 'LANG': 'en_US.UTF-8', 09:08:15 INFO - 'LANGUAGE': 'en_US:en', 09:08:15 INFO - 'LOGNAME': 'cltbld', 09:08:15 INFO - 'MAIL': '/var/mail/cltbld', 09:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:15 INFO - 'MOZ_NO_REMOTE': '1', 09:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:15 INFO - 'NO_EM_RESTART': '1', 09:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:15 INFO - 'PWD': '/builds/slave/test', 09:08:15 INFO - 'SHELL': '/bin/bash', 09:08:15 INFO - 'SHLVL': '1', 09:08:15 INFO - 'TERM': 'linux', 09:08:15 INFO - 'TMOUT': '86400', 09:08:15 INFO - 'USER': 'cltbld', 09:08:15 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:15 INFO - '_': '/tools/buildbot/bin/python'} 09:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:08:17 INFO - Installing collected packages: mozsystemmonitor 09:08:17 INFO - Running setup.py install for mozsystemmonitor 09:08:17 INFO - Successfully installed mozsystemmonitor 09:08:17 INFO - Cleaning up... 09:08:17 INFO - Return code: 0 09:08:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:17 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:08:17 INFO - Using env: {'DISPLAY': ':0', 09:08:17 INFO - 'HOME': '/home/cltbld', 09:08:17 INFO - 'LANG': 'en_US.UTF-8', 09:08:17 INFO - 'LANGUAGE': 'en_US:en', 09:08:17 INFO - 'LOGNAME': 'cltbld', 09:08:17 INFO - 'MAIL': '/var/mail/cltbld', 09:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:17 INFO - 'MOZ_NO_REMOTE': '1', 09:08:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:17 INFO - 'NO_EM_RESTART': '1', 09:08:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:17 INFO - 'PWD': '/builds/slave/test', 09:08:17 INFO - 'SHELL': '/bin/bash', 09:08:17 INFO - 'SHLVL': '1', 09:08:17 INFO - 'TERM': 'linux', 09:08:17 INFO - 'TMOUT': '86400', 09:08:17 INFO - 'USER': 'cltbld', 09:08:17 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:17 INFO - '_': '/tools/buildbot/bin/python'} 09:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:20 INFO - Downloading docopt-0.6.1.tar.gz 09:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:20 INFO - Running setup.py install for blobuploader 09:08:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:21 INFO - Running setup.py install for requests 09:08:21 INFO - Running setup.py install for docopt 09:08:21 INFO - Successfully installed blobuploader requests docopt 09:08:21 INFO - Cleaning up... 09:08:21 INFO - Return code: 0 09:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:21 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:21 INFO - Using env: {'DISPLAY': ':0', 09:08:21 INFO - 'HOME': '/home/cltbld', 09:08:21 INFO - 'LANG': 'en_US.UTF-8', 09:08:21 INFO - 'LANGUAGE': 'en_US:en', 09:08:21 INFO - 'LOGNAME': 'cltbld', 09:08:21 INFO - 'MAIL': '/var/mail/cltbld', 09:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:21 INFO - 'MOZ_NO_REMOTE': '1', 09:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:21 INFO - 'NO_EM_RESTART': '1', 09:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:21 INFO - 'PWD': '/builds/slave/test', 09:08:21 INFO - 'SHELL': '/bin/bash', 09:08:21 INFO - 'SHLVL': '1', 09:08:21 INFO - 'TERM': 'linux', 09:08:21 INFO - 'TMOUT': '86400', 09:08:21 INFO - 'USER': 'cltbld', 09:08:21 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:21 INFO - '_': '/tools/buildbot/bin/python'} 09:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:21 INFO - Running setup.py (path:/tmp/pip-7HU8cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:21 INFO - Running setup.py (path:/tmp/pip-dMdKuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:21 INFO - Running setup.py (path:/tmp/pip-mO8Fqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:21 INFO - Running setup.py (path:/tmp/pip-WEtNKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:22 INFO - Running setup.py (path:/tmp/pip-VM2pzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:22 INFO - Running setup.py (path:/tmp/pip-2OfmlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:22 INFO - Running setup.py (path:/tmp/pip-J8UPC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:22 INFO - Running setup.py (path:/tmp/pip-hRHJD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:22 INFO - Running setup.py (path:/tmp/pip-iUsFAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:22 INFO - Running setup.py (path:/tmp/pip-YxkPSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:22 INFO - Running setup.py (path:/tmp/pip-o07OhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:22 INFO - Running setup.py (path:/tmp/pip-oy6qf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:22 INFO - Running setup.py (path:/tmp/pip-6iz758-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:22 INFO - Running setup.py (path:/tmp/pip-8jzGVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:22 INFO - Running setup.py (path:/tmp/pip-5mp6gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:22 INFO - Running setup.py (path:/tmp/pip-z6S1Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:22 INFO - Running setup.py (path:/tmp/pip-oS4wOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:22 INFO - Running setup.py install for manifestparser 09:08:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for mozcrash 09:08:23 INFO - Running setup.py install for mozdebug 09:08:23 INFO - Running setup.py install for mozdevice 09:08:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for mozfile 09:08:23 INFO - Running setup.py install for mozhttpd 09:08:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for mozinfo 09:08:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for mozInstall 09:08:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for mozleak 09:08:23 INFO - Running setup.py install for mozlog 09:08:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:23 INFO - Running setup.py install for moznetwork 09:08:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Running setup.py install for mozprocess 09:08:24 INFO - Running setup.py install for mozprofile 09:08:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Running setup.py install for mozrunner 09:08:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Running setup.py install for mozscreenshot 09:08:24 INFO - Running setup.py install for moztest 09:08:24 INFO - Running setup.py install for mozversion 09:08:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:24 INFO - Cleaning up... 09:08:24 INFO - Return code: 0 09:08:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:24 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:24 INFO - Using env: {'DISPLAY': ':0', 09:08:24 INFO - 'HOME': '/home/cltbld', 09:08:24 INFO - 'LANG': 'en_US.UTF-8', 09:08:24 INFO - 'LANGUAGE': 'en_US:en', 09:08:24 INFO - 'LOGNAME': 'cltbld', 09:08:24 INFO - 'MAIL': '/var/mail/cltbld', 09:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:24 INFO - 'MOZ_NO_REMOTE': '1', 09:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:24 INFO - 'NO_EM_RESTART': '1', 09:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:24 INFO - 'PWD': '/builds/slave/test', 09:08:24 INFO - 'SHELL': '/bin/bash', 09:08:24 INFO - 'SHLVL': '1', 09:08:24 INFO - 'TERM': 'linux', 09:08:24 INFO - 'TMOUT': '86400', 09:08:24 INFO - 'USER': 'cltbld', 09:08:24 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:24 INFO - '_': '/tools/buildbot/bin/python'} 09:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:24 INFO - Running setup.py (path:/tmp/pip-6EhlXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:25 INFO - Running setup.py (path:/tmp/pip-Rh09T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:25 INFO - Running setup.py (path:/tmp/pip-ae6rZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:25 INFO - Running setup.py (path:/tmp/pip-kWMhkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:25 INFO - Running setup.py (path:/tmp/pip-dvCPis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:25 INFO - Running setup.py (path:/tmp/pip-teAY6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:25 INFO - Running setup.py (path:/tmp/pip-_WXUwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:25 INFO - Running setup.py (path:/tmp/pip-7Tk5k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:25 INFO - Running setup.py (path:/tmp/pip-OnC4yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:25 INFO - Running setup.py (path:/tmp/pip-Afk_lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:25 INFO - Running setup.py (path:/tmp/pip-RNdsRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:25 INFO - Running setup.py (path:/tmp/pip-GcI6eg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:25 INFO - Running setup.py (path:/tmp/pip-lapE_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:25 INFO - Running setup.py (path:/tmp/pip-jlQQd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:25 INFO - Running setup.py (path:/tmp/pip-gLmkuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:26 INFO - Running setup.py (path:/tmp/pip-Kp_Rnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:26 INFO - Running setup.py (path:/tmp/pip-C8gpGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:28 INFO - Downloading blessings-1.6.tar.gz 09:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:28 INFO - Installing collected packages: blessings 09:08:28 INFO - Running setup.py install for blessings 09:08:28 INFO - Successfully installed blessings 09:08:28 INFO - Cleaning up... 09:08:28 INFO - Return code: 0 09:08:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:28 INFO - Reading from file tmpfile_stdout 09:08:28 INFO - Current package versions: 09:08:28 INFO - argparse == 1.2.1 09:08:28 INFO - blessings == 1.6 09:08:28 INFO - blobuploader == 1.2.4 09:08:28 INFO - docopt == 0.6.1 09:08:28 INFO - manifestparser == 1.1 09:08:28 INFO - mozInstall == 1.12 09:08:28 INFO - mozcrash == 0.17 09:08:28 INFO - mozdebug == 0.1 09:08:28 INFO - mozdevice == 0.48 09:08:28 INFO - mozfile == 1.2 09:08:28 INFO - mozhttpd == 0.7 09:08:28 INFO - mozinfo == 0.9 09:08:28 INFO - mozleak == 0.1 09:08:28 INFO - mozlog == 3.1 09:08:28 INFO - moznetwork == 0.27 09:08:28 INFO - mozprocess == 0.22 09:08:28 INFO - mozprofile == 0.28 09:08:28 INFO - mozrunner == 6.11 09:08:28 INFO - mozscreenshot == 0.1 09:08:28 INFO - mozsystemmonitor == 0.0 09:08:28 INFO - moztest == 0.7 09:08:28 INFO - mozversion == 1.4 09:08:28 INFO - psutil == 3.1.1 09:08:28 INFO - requests == 1.2.3 09:08:28 INFO - wsgiref == 0.1.2 09:08:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:28 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:28 INFO - Using env: {'DISPLAY': ':0', 09:08:28 INFO - 'HOME': '/home/cltbld', 09:08:28 INFO - 'LANG': 'en_US.UTF-8', 09:08:28 INFO - 'LANGUAGE': 'en_US:en', 09:08:28 INFO - 'LOGNAME': 'cltbld', 09:08:28 INFO - 'MAIL': '/var/mail/cltbld', 09:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:28 INFO - 'MOZ_NO_REMOTE': '1', 09:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:28 INFO - 'NO_EM_RESTART': '1', 09:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:28 INFO - 'PWD': '/builds/slave/test', 09:08:28 INFO - 'SHELL': '/bin/bash', 09:08:28 INFO - 'SHLVL': '1', 09:08:28 INFO - 'TERM': 'linux', 09:08:28 INFO - 'TMOUT': '86400', 09:08:28 INFO - 'USER': 'cltbld', 09:08:28 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:28 INFO - '_': '/tools/buildbot/bin/python'} 09:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:28 INFO - Cleaning up... 09:08:28 INFO - Return code: 0 09:08:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:28 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 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:08:28 INFO - Using env: {'DISPLAY': ':0', 09:08:28 INFO - 'HOME': '/home/cltbld', 09:08:28 INFO - 'LANG': 'en_US.UTF-8', 09:08:28 INFO - 'LANGUAGE': 'en_US:en', 09:08:28 INFO - 'LOGNAME': 'cltbld', 09:08:28 INFO - 'MAIL': '/var/mail/cltbld', 09:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:28 INFO - 'MOZ_NO_REMOTE': '1', 09:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:28 INFO - 'NO_EM_RESTART': '1', 09:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:28 INFO - 'PWD': '/builds/slave/test', 09:08:28 INFO - 'SHELL': '/bin/bash', 09:08:28 INFO - 'SHLVL': '1', 09:08:28 INFO - 'TERM': 'linux', 09:08:28 INFO - 'TMOUT': '86400', 09:08:28 INFO - 'USER': 'cltbld', 09:08:28 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:28 INFO - '_': '/tools/buildbot/bin/python'} 09:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:28 INFO - Downloading/unpacking jsonschema 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:30 INFO - Downloading/unpacking functools32 (from jsonschema) 09:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:31 INFO - warning: no files found matching '*.txt' 09:08:31 INFO - no previously-included directories found matching 'build' 09:08:31 INFO - no previously-included directories found matching 'dist' 09:08:31 INFO - no previously-included directories found matching '.git*' 09:08:31 INFO - Installing collected packages: jsonschema, functools32 09:08:31 INFO - Running setup.py install for functools32 09:08:31 INFO - warning: no files found matching '*.txt' 09:08:31 INFO - no previously-included directories found matching 'build' 09:08:31 INFO - no previously-included directories found matching 'dist' 09:08:31 INFO - no previously-included directories found matching '.git*' 09:08:31 INFO - Successfully installed jsonschema functools32 09:08:31 INFO - Cleaning up... 09:08:31 INFO - Return code: 0 09:08:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0a59a4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cc7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, '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': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:08:31 INFO - Using env: {'DISPLAY': ':0', 09:08:31 INFO - 'HOME': '/home/cltbld', 09:08:31 INFO - 'LANG': 'en_US.UTF-8', 09:08:31 INFO - 'LANGUAGE': 'en_US:en', 09:08:31 INFO - 'LOGNAME': 'cltbld', 09:08:31 INFO - 'MAIL': '/var/mail/cltbld', 09:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:31 INFO - 'MOZ_NO_REMOTE': '1', 09:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:31 INFO - 'NO_EM_RESTART': '1', 09:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:31 INFO - 'PWD': '/builds/slave/test', 09:08:31 INFO - 'SHELL': '/bin/bash', 09:08:31 INFO - 'SHLVL': '1', 09:08:31 INFO - 'TERM': 'linux', 09:08:31 INFO - 'TMOUT': '86400', 09:08:31 INFO - 'USER': 'cltbld', 09:08:31 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:31 INFO - '_': '/tools/buildbot/bin/python'} 09:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:31 INFO - Downloading/unpacking flake8 09:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:08:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:08:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:34 INFO - no previously-included directories found matching 'docs/_build' 09:08:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:08:34 INFO - Running setup.py install for pep8 09:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:34 INFO - no previously-included directories found matching 'docs/_build' 09:08:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:08:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:08:34 INFO - Cleaning up... 09:08:34 INFO - Return code: 0 09:08:34 INFO - Running post-action listener: _resource_record_post_action 09:08:34 INFO - Running post-action listener: _start_resource_monitoring 09:08:34 INFO - Starting resource monitoring. 09:08:34 INFO - ##### 09:08:34 INFO - ##### Running install step. 09:08:34 INFO - ##### 09:08:34 INFO - Running pre-action listener: _resource_record_pre_action 09:08:34 INFO - Running main action method: install 09:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:34 INFO - Reading from file tmpfile_stdout 09:08:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:34 INFO - Reading from file tmpfile_stdout 09:08:34 INFO - Output received: 09:08:34 INFO - Usage: mozinstall [options] installer 09:08:34 INFO - Options: 09:08:34 INFO - -h, --help show this help message and exit 09:08:34 INFO - -d DEST, --destination=DEST 09:08:34 INFO - Directory to install application into. [default: 09:08:34 INFO - "/builds/slave/test"] 09:08:34 INFO - --app=APP Application being installed. [default: firefox] 09:08:34 INFO - mkdir: /builds/slave/test/build/application 09:08:34 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'] 09:08:34 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 09:08:50 INFO - Reading from file tmpfile_stdout 09:08:50 INFO - Output received: 09:08:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:08:50 INFO - Running post-action listener: _resource_record_post_action 09:08:50 INFO - ##### 09:08:50 INFO - ##### Running run-tests step. 09:08:50 INFO - ##### 09:08:50 INFO - Running pre-action listener: _resource_record_pre_action 09:08:50 INFO - Running main action method: run_tests 09:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:50 INFO - Python 2.7.3 09:08:50 INFO - Return code: 0 09:08:50 INFO - grabbing minidump binary from tooltool 09:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235b640>, '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 0x235c540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235c9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:08:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:08:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:50 INFO - Return code: 0 09:08:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:08:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:08:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:08:51 INFO - Return code: 0 09:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 09:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 09:08:51 INFO - Using env: {'DISPLAY': ':0', 09:08:51 INFO - 'HOME': '/home/cltbld', 09:08:51 INFO - 'LANG': 'en_US.UTF-8', 09:08:51 INFO - 'LANGUAGE': 'en_US:en', 09:08:51 INFO - 'LOGNAME': 'cltbld', 09:08:51 INFO - 'MAIL': '/var/mail/cltbld', 09:08:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:51 INFO - 'MOZ_NO_REMOTE': '1', 09:08:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:51 INFO - 'NO_EM_RESTART': '1', 09:08:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:51 INFO - 'PWD': '/builds/slave/test', 09:08:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:51 INFO - 'SHELL': '/bin/bash', 09:08:51 INFO - 'SHLVL': '1', 09:08:51 INFO - 'TERM': 'linux', 09:08:51 INFO - 'TMOUT': '86400', 09:08:51 INFO - 'USER': 'cltbld', 09:08:51 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530', 09:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:51 INFO - '_': '/tools/buildbot/bin/python'} 09:08:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 09:08:51 INFO - mozversion application_buildid: 20160512061943 09:08:51 INFO - mozversion application_changeset: fca62ad9c45ca847ac004a2b16807a63763e5c29 09:08:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:08:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:51 INFO - mozversion application_name: Firefox 09:08:51 INFO - mozversion application_remotingname: firefox-dev 09:08:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:08:51 INFO - mozversion application_vendor: Mozilla 09:08:51 INFO - mozversion application_version: 48.0a2 09:08:51 INFO - mozversion platform_buildid: 20160512061943 09:08:51 INFO - mozversion platform_changeset: fca62ad9c45ca847ac004a2b16807a63763e5c29 09:08:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:08:51 INFO - mozversion platform_version: 48.0a2 09:08:51 INFO - using testdate: 1463069331 09:08:51 INFO - actual date: 1463069331 09:08:51 INFO - starting webserver on 'localhost:48005' 09:08:51 INFO - SUITE-START | Running 2 tests 09:08:51 INFO - TEST-START | dromaeo_css 09:08:51 INFO - operating with platform_type : linux_ 09:08:51 INFO - Initialising browser for dromaeo_css test... 09:08:51 INFO - TEST-INFO | started process 28791 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppce7cr/profile http://localhost:48005/getInfo.html) 09:08:55 INFO - PROCESS | 28791 | __metrics Screen width/height:1600/1200 09:08:55 INFO - PROCESS | 28791 | colorDepth:24 09:08:55 INFO - PROCESS | 28791 | Browser inner width/height: 1024/697 09:08:55 INFO - PROCESS | 28791 | __metrics 09:08:57 ERROR - PROCESS | 28791 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:08:58 INFO - TEST-INFO | 28791: exit 0 09:08:58 INFO - Browser initialized. 09:08:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:58 INFO - TEST-INFO | started process 28887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppce7cr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:09 INFO - PROCESS | 28887 | RSS: Main: 142487552 09:09:09 INFO - PROCESS | 28887 | 09:10:00 INFO - PROCESS | 28887 | 1463069400780 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:12:08 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48005/tests/dromaeo/cssquery-ext.html) 09:12:09 INFO - PROCESS | 28887 | RSS: Main: 250650624 09:12:09 INFO - PROCESS | 28887 | 09:15:11 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-ext.html (next: http://localhost:48005/tests/dromaeo/cssquery-jquery.html) 09:15:12 INFO - PROCESS | 28887 | RSS: Main: 220532736 09:15:12 INFO - PROCESS | 28887 | 09:18:13 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48005/tests/dromaeo/cssquery-mootools.html) 09:18:14 INFO - PROCESS | 28887 | RSS: Main: 224952320 09:18:14 INFO - PROCESS | 28887 | 09:21:16 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48005/tests/dromaeo/cssquery-prototype.html) 09:21:16 INFO - PROCESS | 28887 | RSS: Main: 217436160 09:21:16 INFO - PROCESS | 28887 | 09:24:19 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48005/tests/dromaeo/cssquery-yui.html) 09:24:19 INFO - PROCESS | 28887 | RSS: Main: 181653504 09:24:19 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | Cycle 1(1): loaded http://localhost:48005/tests/dromaeo/cssquery-yui.html (next: http://localhost:48005/tests/dromaeo/cssquery-dojo.html) 09:27:22 INFO - PROCESS | 28887 | RSS: Main: 250191872 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | __start_tp_report 09:27:22 INFO - PROCESS | 28887 | _x_x_mozilla_page_load 09:27:22 INFO - PROCESS | 28887 | _x_x_mozilla_page_load_details 09:27:22 INFO - PROCESS | 28887 | |i|pagename|runs| 09:27:22 INFO - PROCESS | 28887 | |0;dojo.html;3265.6571060038696;3329.384063948169;3339.0483712142045;3364.360771453425;3388.1360253135463;5337.599680023999;5417.756200970956;5421.051316019701;5424.0779067558515;5440.156775699768;5432;5436.048691478995;5447.645903016301;5453.182022696597;5475;4807.326974223612;4992.026554821811;4994.575461085812;5045.772940217681;5107.131787596118;4913.31213630092;4978.228374601942;4991.32617096691;4993.350864387625;4994.126027945095;4909.189983652693;4971.154903666367;4972;4984.850454486371;4989.126902792015;4999.200127979524;5000.599952003831;5022.824201152978;5025.3718285214345;5050.696958182502;4867;4904.852854414374;4965.801367945278;4975.975120124376;4985.052839960432;5405.21624364465;5413.106837371829;5431.538319242869;5448.291722076105;5448.564114870801;5671.234383358276;5683.261176047087;5694.601377903548;5700;5702.201691763156;5745.770169193227;5822;5849.122631605251;5916.112583112567;5923.526117910556;5391.245225668409;5426.267453893753;5431.266778984826;5433.049216387156;5437.157240627709;5743.282089738783;5849.327327357323;5849.736761845727;5852.473277404969;5866;5648.802291919846;5705.2012718219485;5719.19931209631;5722.77108915647;5755.366909639936;5811.070228763378;5843.853903652443;5848.707564621837;5849.766009359578;5851.327097383855;5732.140178973187;5735.369109397963;5793.594448388572;5804.158397032406;5818.854528636818;5833.562482813806;5837.357890632027;5853.180554722342;5858.56060795442;5893.793717219876;4489.551044895549;4510.391097201836;4515.0969806038265;4515.63874890008;4538.296564032515;3965.147493288957;4002.5597184309704;4031.274370613259;4058.3303754880703;4093.5497095319492;5486.615936884459;5509.779608815683;5511.696856672841;5513.889722205534;5550.528205102531;5751.022326204556;5752.309722833287;5758.568107391962;5759.049756790167;5759.395263497357;5522.22688823565;5581.413951535112;5615.943840561542;5702.8289151325525;5703.74333155009;4880.048390563806;4901.803927842846;4908.533689299511;4909.042736666316;4950.985048065138;6063.72713227897;6095.420935011167;6097.024476083805;6100.23747031621;6106.389361063947;5795.420457954255;5833.79581714638;5900.232969713909;5919.31927828288;5925.081612350093;6080.361562035922;6088.969555152196;6090.451859332681;6093.6039157454625;6097.5731698780655;5549.611527193058;5742.913856292075;5750.597458177887;5758.798442054508;5761.337446193574;5827.096799996008;5871.383504731942;5881.941180588139;5908.3500814911185;5933.22868027171;5700.144978253295;5710.543156547549;5714.20001199824;5724.169995350734;5727.942720572741;5499.972500137474;5641.069223578228;5689.089745640677;5694.829155125353;5700.743466543932;5603.271574695264;5627.915581266366;5636.210930469655;5637.605367624227;5673.035583950656;5709.543236540983;5741.052726300114;5741.3971532988435;5753.165790960204;5754.367019627922;5484.91772623403;5531.8893622126525;5576.442355764391;5624.072028115315;5624.915626265528 09:27:22 INFO - PROCESS | 28887 | |1;ext.html;668.4886062162445;674.6828990374522;676.0974099577068;679.7179170644183;750.40717832912;32582;33011.17472063196;38354.80822595886;38676;38951.220975580545;45216.773916130456;46063;46064.309035364495;46179;46210.30684539726;9011.954940225307;9139.908600914005;9158.358914875962;9168.72493825183;9170.770730731752;8897.33270004749;9014.414063085911;9016.323775716848;9017.54912254388;9037.096290370942;9035.774105647346;9081.091890810932;9088.272938164931;9093.63625454981;9100.044495327995;9031;9087;9088.363814532986;9102.681406150787;9107.408018478778;8966.910330896673;9059.139381758723;9092.727218183465;9094.727158185266;9112.635494580209;42143.1571368574;42549.36175957363;42661.57338426607;42675.7866210667;42681.1463770726;3652.6164752700847;3653.8721144759807;3741.8316175772156;3873.1866308075055;4047.655949244318;32547.18632034218;33299.500507492405;33329.66670333314;33509.664903350895;33594.83202583996;4040.898977525556;4072.1652061327654;4105.568915263914;4124.175164967019;4139.627433530996;239440.0139996514;239894;240333.99999999825;240359;240532;38987.22025559473;60379.396206038255;60394.0940885887;60740.39259607347;60858.39141608527;37469.625303746616;37552.061198470255;37556.24887502235;37564.43653345202;37565.248695026494;15282.923585382225;15485.303161357766;15489.457868974532;15498.5350439487;15511;13322.20066795976;13334.066615336833;13378.464861405435;13456.663583410493;13464.663383415296;9208.355415121012;9376.718698438921;9520.190783783317;9563.952180239054;9624.615015399306;3574.606793252792;3597.73017023719;3606.711463082947;3635.236600313911;3700.407934730484;1104.8950349716763;1183.5325046606542;1210.5218438716659;1211.5396149463147;1216.7748966441059;33518.32963340719;34180;34359;34460;34526.48210276848;1258.7608354412632;1283.1467074395423;1305.1646945954597;1319.3073636340805;1339.7960355215616;17659.30696562659;17978.730319045226;18006.819931800776;18048.495298116304;18093.677047633082;476176;786586;801524;802741;803480;63771;63996;64667;64768;64768.676156619855;1486.2494440307576;1486.2791546100132;1492.1271056431892;1493.8506149384975;1524.6036030632115;31882.362352752352;33598.83200583982;33927.830360848035;33939;34298.657013430544;51121;51542.134339925404;51738.223926641884;51864.74067629638;51945.22082168695;367949;370300;371164;371303;371406;30920.381592368478;49965;50313;50804.491955079975;51241.48758512516;48416.75791621019;48780.75609622071;48781.268280976525;48840.75579622221;49747.50252497574;40088.59911400849;40409.59590404176;40646.39030414487;41163.176736464506;41292.587074128875;39645.60354396419;40389.59610403976;40939.38590921079;40970;41142.382864257655;41007.58992410038;41776.58223417727;42219.78890105653;42757.1448571033;42868;35895.46156807702;36488.635113648525;36834;37222.813885930394;37231.81384093062;27827.16518504448;28037.71962280351;28075.859620702584;28221;28402 09:27:22 INFO - PROCESS | 28887 | |2;jquery.html;411.8990847242426;414.943982562354;415.32716998462496;433.19858262214905;433.2915682858526;18720.15759290832;19079.809201907978;19295.903520482396;19302.710459343103;20190.19239230429;19205.519862003457;19313.517162070955;19647;19738;19790.505237369038;5364.249005139283;5552.444755524456;5578.163275508686;5780.7687692492245;5802.245708057946;5531.9723401382935;5548.528375088103;5560.110382338827;5570.637908535952;5584.553235741131;5425.9728701356435;5782.306123265193;5785.681787501686;5786.710664466781;5788.826335209929;5688.886222275552;5742.2247996520355;5747.885042299152;5750.597458177908;5752.769889204426;5277.155655095166;5505.119180931032;5509.807156749494;5516.338039435253;5520.254765606632;12887.16233444823;13400.932995335059;13428.53000145;13520.32398380077;13690.452381904712;2427.878606069707;2449;2508.2224510401834;2667.333166708323;2752.8575641108914;9753.951230243803;10156.898431015668;13301;14051.508197213048;14480.637984050378;1952.9414117576353;2020.7720715636437;2058.5265388960624;2094.7800480949586;2102.7266455360705;78564.60717696375;79152.20847791563;84367.15632843654;85144;85308;11020.614278500214;11209.038418276768;11220.775584488265;11285.830712539318;11336.659900203007;15271.847281527265;15405.922970385076;15435;15444.45944391941;15458.227088645523;8284.212999765012;8422.115677853859;8448.535330556879;8532.957335213285;8583.613737381833;8092;8093.514389136671;8123.7156699515235;8157.877631835528;8163.183681631908;4791.95208047922;4796.184648609745;4810.783514741775;4942.950570494321;4975.253711943237;1935.7773856006459;2012.386222202213;2017.2032047341218;2020.3130935482006;2118.8304935605115;982.1455333859445;1020.8519764634086;1083.8103331916882;1090.3675867996542;1149.0061097150904;18021.549461263312;18628.441146765617;18738.344157954405;19120.808791911903;19470.80529194718;1078.8058149533003;1097.5719469406938;1097.9890201098046;1099.1756182862853;1137.3394843528376;10199.964042430003;10907.945460272647;10918.617848375268;10942.890571094187;10957.780844383067;532751;534039;547721;552602;565905;56364;56761.14858277129;58180.41819581835;96733;99072.50463747635;1102.5699977008812;1148.9655310340704;1152.124385467051;1160.489384670742;1176.7822952753768;16726.665466690356;16906.492805215865;17080.316787328367;17085.145742713063;17416.216270267614;38589.80705096456;39432.80283598564;40324;40720.592794072865;41125.383119252634;54875;57889.71055144697;58678;58944;59129.40870591411;8544.444611100258;8661.480311181349;8837.293016558531;8854.114588541095;12111;13535.187888726708;13964.511242106477;13985.090969086978;14565.781513277521;14784.334704938088;6200.720967556381;9793.706188814347;12376.81434778461;13017.153884997824;13369.933150334185;12296.262224266573;12491.812622810483;12511.374431278582;12919.677008074497;13131.540396086091;13079.542216022393;13251.602451926457;13374.531891383755;13441.932790335986;13453.932730336286;12997.415116319598;13210.27343496117;13315.800262996256;13329.86670133325;13436.126651767603;13092.672683182996;13148.408321625358;13197;13296.667583310495;13388.531401400904 09:27:22 INFO - PROCESS | 28887 | |3;mootools.html;1474.9778753318694;1547.5280039587924;1559.3139018831714;1745.9214335354907;1869.8971556564384;7784.260495252949;8064.91935080649;8067.19328067193;8076.232757887999;8080.515169089858;7941.761747147595;8090.514569125842;8098.109207987117;8107.148749381318;8117.228863257996;2080.5734824361043;2343.3438637181616;2359.2922123362964;2377.738448770634;2392.95214095718;2333.9183128590503;2334.6964894563685;2340.3564019894493;2341.7658234176615;2343.4258606641397;2267.342470683508;2319.9536009279805;2328.092044102801;2329.860208387494;2331.7318508371586;2001.4395969128668;2238.6977758002704;2290.541891621682;2291.255342013852;2295.6097463431256;2303.170858490942;2310.1336998625516;2315.0623997281127;2324.418895276181;2406.4104294447798;7821.2960833524685;7866.410019248579;7871.842563148763;7878.606069696492;7887.684492620288;5004.074246264453;5091.439941606421;5117.897642047175;5138.049460849755;5183.326167598226;3436.329915666446;3447.120984149026;3490.6509349065145;3508.7543871928974;3518.437050072002;1246.4131994261115;1250.7123361626786;1277;1301.3298151452009;1320.2011735121603;11164.051055660279;11202.943985280102;11230.494627741771;11230.831537526861;11283.040941519981;4426.070525189682;4441.045175287329;4520.389747384127;4532.388127602798;4534.319852022223;9343.205827504738;9361.859572106423;9387.624495020124;9390.85913711295;9394.295427842939;4571.611413029929;5236.9738151309;5249.08141075318;5252.133397989365;5262.71055091966;4611.608013318905;4645.163870503342;4669.34629151919;4697.131030759252;4723.48041715411;3976.4830572025453;4002.959970400317;4078.6125318094732;4084.101497670508;4095.4061661059577;1315.7039666075057;1319.3667039820748;1335.1121504199793;1339.6249050265942;1347.1647578501393;917.4220241247971;978.2174260591498;1017.6082208349691;1114.2144787924494;1115.9218854680094;5884.382139875337;5886.6173698709445;5890.734916928748;5902.911456328158;6043.09353596964;963.3064193780467;1048.6696690542456;1053.4258828938248;1061.9734506637242;1088.684281558355;3984.143409167043;4035.9798201009394;4046.5548789633117;4056.371262454324;4070.816813243411;9998.80002399948;10267.9486602568;10469.9476502617;10487.94756026215;10489.055984961238;10330.48347582633;10409.843852342221;10582.365058096539;10592.470376481146;10602.257841951143;7752.26353496417;7855.135935047255;7971.202879711982;8000.51996880189;8030.156833532512;5740.598158128891;5843.006688862988;5892.558058145571;5919.200907877554;5971.731272092679;8903.06517815646;8971.50656713887;9067;9269.119433653792;9285.72142835716;9185.378174658463;9260.953695231481;9299;9453.810923781624;9811.80376392454;6303.621782693053;6757.22291936414;6820.215675197217;6858.588484690935;6943.47923905699;7563.886541701989;7614.847703046019;7641.083070031632;7656.157822639394;7666.156722760604;6286.968565157145;6419.45434638061;6524.380183882524;6524.706388212637;6621.27166011748;6677.899831502435;6707.1616047994;6822.658867056528;6843.520953533205;6878.3465570770695;6534.444572211415;6561.639109848813;6569.080328754074;6586.4401525870835;6587.934120658923;4841.806327746851;4863;4954.677945933503;5002.349694539686;5013.774380152975;5057.140286151437;5082.720450375265;5123.590112791042;5127.92308115386;5152.201408781645 09:27:22 INFO - PROCESS | 28887 | |4;prototype.html;336.5557464147325;349.82333921369707;351.1204432895596;354.0952865428832;357.4834364343523;6154.107654390116;6157.1687822143995;6166.537509686775;6212.912740270457;6215.6636323190505;5861.472467477926;5930.258717660293;6030.276366835986;6132.632042077467;6140.048292514656;4651.488336283006;4683.461401938786;4694.765261736899;4695.953040469586;4696.85909422717;4683.765811709418;4693.389859318301;4695.2722328039035;4696.084263568606;4712.858614241578;4473.843415480459;4560.293154561048;4655.580997710205;4658.90682186356;4670.34615153877;4670.883227919312;4671.299305104261;4686.507916668735;4686.835960741358;4703.059388122372;4129.2773764591075;4129.380592911088;4129.834806607732;4132.152908653718;4138.461999940018;5863.179154918314;5871.266091738533;5872.530197584226;5876.706164691791;5877.500412464946;5444.863878403072;5450.4549545045575;5455.836324910219;5460.972695136538;5473.233747275384;6205.317415084337;6394.23269207698;6409.038644203407;6428.5178611604315;6433.453156481704;4465.68740188187;4465.732056076646;4474.731516109011;4486.865394038184;4492.460904691426;6717.596944183316;6785.558938668971;6803.591784492946;6804.455643548505;6807.795766126975;4568.520305367889;4570.063137056895;4573.634109271316;4580.129775342674;4581.541845815458;4633.536646335407;4635.026644404646;4637.489876113625;4638.0491999140095;4642.001969576557;5250.501202385767;5360.981413531416;5368.8657783554945;5377.892442151135;5380.192971054373;6495.1231583736535;6517.055027021055;6542.7710030149665;6543.9202531582705;6543.967280163569;1551.9068855868684;1585.207396301849;1600.8639265662316;1617.0378624718273;1619.5789094835193;1037.7146284771748;1072.1958531101673;1075.6611667324767;1075.7471994081384;1080.0657431322006;6324.968375158095;6333;6355.269144048401;6364.204474440695;6371.585846919936;6587.80236592903;6595.241547222035;6599.670016499156;6604.405603495708;6618.966905165444;6327.145835312266;6341.175647165839;6351.396617321347;6361.363863613694;6367.395097465734;3561.9109522261633;3571.482135090397;3572.481990111471;3574.03501054719;3575.213453040327;6815.079964204868;6968.756093536702;6972.651367431608;6996.090508233897;6998.650067496605;6734.057231987427;6760.864782704319;6764.661766911635;6768.424683901923;6773;6949.861002779916;6969.23338432772;6973.686184121727;6979;6980.371766541036;6591.209054913441;6598.472122230306;6598.8350291243105;6599.0761293418;6602.6698665067515;6293.056041593798;6467.482601391972;6510.023496475566;6522.358444829852;6535;6924.6537673117155;6971.302869713191;6972.954056891507;6975.058367120374;6980.05769221169;4534.115847409856;4540.8637740867825;4542.432195975503;4546.431696037996;4552.928417570064;6434.871302574016;6583.275839657729;6584.637844918576;6584.868302634016;6595.736170553124;6357.110004599267;6532.444742196776;6534.771283005072;6536.182977127859;6545.541812073109;6423.9036414452885;6446.258680251643;6492.383223593751;6501.577397469149;6506.869862602627;6390.456811170917;6393.424591786761;6398.4561312287115;6401.359864013748;6415.358464153547;6363.204599425072;6531.183602049744;6548.672566371758;6552.6068435894;6560.606363618198;6427.196600424947;6483;6506.284308725942;6507.739690412331;6511.446527045254 09:27:22 INFO - PROCESS | 28887 | |5;yui.html;792.5720111139984;848.3001523742913;879.3580686099148;906.8458362078446;934.1825902335456;15707.057576545416;15921.124338161397;15936.282867270986;15954.76067858983;15955.441559545417;14614.561563153122;14646.853531464709;14667.926660366684;14669.853301466983;14680.559583212495;765.8276887700265;901.9864702029442;918.9172974432299;919.4299534288717;923.4828496042204;918.091089821079;921.2123634292684;925.4909799610222;926.3932124458457;926.8285367207054;976.3995142987047;979.8432250839867;979.902009799018;980.7057882635181;982.0866594067514;814.4217605500072;961.3030552849164;979.2215188924822;979.7942432089234;980.4411485453295;710.8933659951049;714.4962801225124;714.7176865138293;715.4669771020571;716.2121666167227;14631.341589628491;14709.485168019175;14753.852461475355;14784.556463306119;14807.185604791732;14537.709245815024;14892.776608350883;15058.623534411618;15353.309101090366;15411.3835446582;11874.762504749942;12074.939625301906;12125.818112728317;12142.02863770905;12510.18683785551;4108.157827645326;4129.938050929239;4143.751374917515;4157.854475093387;4178.310578754502;55247;55972.16041759336;56070.719646401914;56306.15540766892;56506.434935650526;10704;11071.667849964513;11077.723056923642;11379.203455758017;11387.772244555063;19812.504687382643;19895;20064.297749578986;20146.294879679142;20192.69710954337;8292.917070829335;8399.53802540854;8426;8440.324774018063;8667.91332086671;4977.701337919664;4980.203167493184;5013.498650134958;5056.82301119459;5199.636025478256;3564.8930532084078;3616.168281295264;3624.6737793598704;3626.818659067036;3627.619099994515;603.841459497649;638.9393007664357;652.6051738698114;670.4636290967206;737.1670012885405;767.7926959720844;771.8340556780207;783.2011348424575;785.1285073568334;797.1868693932157;9514.33399662017;9670.178034866974;9753.512324383895;9805.166560842266;9888.258380621483;613.2426453330133;653.0106888064586;662.4435474201694;664.6244871647503;696.905917701114;4437.334399839985;4629.236176030984;4633.536646335407;4747.311639812277;4773.689710168828;59330.703346482995;59724;59935.10097348543;59950.40049599535;59965.70017149974;50899.745501273;51360;52422.213666795025;52551.73724131432;53153;46984;47283.29075063808;48090;48164;48338.5166148341;9369.672061477815;9508.33441659105;9561.521923903916;9574.808503829745;9644.276679248944;38336.80831595824;38805.41791873068;39779.801100995464;39863.40204896871;39922.600773991886;45564;47211;48259.5174048255;49020.75489622672;49143.26285105655;11504.194706370474;11596.362200079077;11726.179167458196;11742.236754610924;11897.940510297392;13866.168029918237;14178.149311040957;14186.219757913414;14664.486742963945;15130.167840768863;11926;12985.220886746825;13012.8048079277;13733.038687292194;14030.438782448997;12367.505299787908;12574.622761317176;12794.424250908918;13244.801327979896;13836.308184590931;12393.62819115428;12439.191452555555;12744.4265008073;13071.15037522558;13474.19154850712;10726.678199654023;11093.278936869077;11100;11292.661220163409;11357;11094.833577496505;11412.372319522507;11455.198136130724;11557.133215009077;11724.88275117272 09:27:22 INFO - PROCESS | 28887 | __end_tp_report 09:27:22 INFO - PROCESS | 28887 | __start_cc_report 09:27:22 INFO - PROCESS | 28887 | _x_x_mozilla_cycle_collect,848 09:27:22 INFO - PROCESS | 28887 | __end_cc_report 09:27:22 INFO - PROCESS | 28887 | __startTimestamp1463070442377__endTimestamp 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | ------- Summary: start ------- 09:27:22 INFO - PROCESS | 28887 | Number of tests: 6 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#0] dojo.html Cycles:165 Average:5418.23 Median:5636.21 stddev:586.41 (10.4%) stddev-sans-first:563.37 09:27:22 INFO - PROCESS | 28887 | Values: 3265.7 3329.4 3339.0 3364.4 3388.1 5337.6 5417.8 5421.1 5424.1 5440.2 5432.0 5436.0 5447.6 5453.2 5475.0 4807.3 4992.0 4994.6 5045.8 5107.1 4913.3 4978.2 4991.3 4993.4 4994.1 4909.2 4971.2 4972.0 4984.9 4989.1 4999.2 5000.6 5022.8 5025.4 5050.7 4867.0 4904.9 4965.8 4976.0 4985.1 5405.2 5413.1 5431.5 5448.3 5448.6 5671.2 5683.3 5694.6 5700.0 5702.2 5745.8 5822.0 5849.1 5916.1 5923.5 5391.2 5426.3 5431.3 5433.0 5437.2 5743.3 5849.3 5849.7 5852.5 5866.0 5648.8 5705.2 5719.2 5722.8 5755.4 5811.1 5843.9 5848.7 5849.8 5851.3 5732.1 5735.4 5793.6 5804.2 5818.9 5833.6 5837.4 5853.2 5858.6 5893.8 4489.6 4510.4 4515.1 4515.6 4538.3 3965.1 4002.6 4031.3 4058.3 4093.5 5486.6 5509.8 5511.7 5513.9 5550.5 5751.0 5752.3 5758.6 5759.0 5759.4 5522.2 5581.4 5615.9 5702.8 5703.7 4880.0 4901.8 4908.5 4909.0 4951.0 6063.7 6095.4 6097.0 6100.2 6106.4 5795.4 5833.8 5900.2 5919.3 5925.1 6080.4 6089.0 6090.5 6093.6 6097.6 5549.6 5742.9 5750.6 5758.8 5761.3 5827.1 5871.4 5881.9 5908.4 5933.2 5700.1 5710.5 5714.2 5724.2 5727.9 5500.0 5641.1 5689.1 5694.8 5700.7 5603.3 5627.9 5636.2 5637.6 5673.0 5709.5 5741.1 5741.4 5753.2 5754.4 5484.9 5531.9 5576.4 5624.1 5624.9 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#1] ext.html Cycles:180 Average:60610.01 Median:22228.63 stddev:134896.63 (606.9%) stddev-sans-first:135199.98 09:27:22 INFO - PROCESS | 28887 | Values: 668.5 674.7 676.1 679.7 750.4 32582.0 33011.2 38354.8 38676.0 38951.2 45216.8 46063.0 46064.3 46179.0 46210.3 9012.0 9139.9 9158.4 9168.7 9170.8 8897.3 9014.4 9016.3 9017.5 9037.1 9035.8 9081.1 9088.3 9093.6 9100.0 9031.0 9087.0 9088.4 9102.7 9107.4 8966.9 9059.1 9092.7 9094.7 9112.6 42143.2 42549.4 42661.6 42675.8 42681.1 3652.6 3653.9 3741.8 3873.2 4047.7 32547.2 33299.5 33329.7 33509.7 33594.8 4040.9 4072.2 4105.6 4124.2 4139.6 239440.0 239894.0 240334.0 240359.0 240532.0 38987.2 60379.4 60394.1 60740.4 60858.4 37469.6 37552.1 37556.2 37564.4 37565.2 15282.9 15485.3 15489.5 15498.5 15511.0 13322.2 13334.1 13378.5 13456.7 13464.7 9208.4 9376.7 9520.2 9564.0 9624.6 3574.6 3597.7 3606.7 3635.2 3700.4 1104.9 1183.5 1210.5 1211.5 1216.8 33518.3 34180.0 34359.0 34460.0 34526.5 1258.8 1283.1 1305.2 1319.3 1339.8 17659.3 17978.7 18006.8 18048.5 18093.7 476176.0 786586.0 801524.0 802741.0 803480.0 63771.0 63996.0 64667.0 64768.0 64768.7 1486.2 1486.3 1492.1 1493.9 1524.6 31882.4 33598.8 33927.8 33939.0 34298.7 51121.0 51542.1 51738.2 51864.7 51945.2 367949.0 370300.0 371164.0 371303.0 371406.0 30920.4 49965.0 50313.0 50804.5 51241.5 48416.8 48780.8 48781.3 48840.8 49747.5 40088.6 40409.6 40646.4 41163.2 41292.6 39645.6 40389.6 40939.4 40970.0 41142.4 41007.6 41776.6 42219.8 42757.1 42868.0 35895.5 36488.6 36834.0 37222.8 37231.8 27827.2 28037.7 28075.9 28221.0 28402.0 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#2] jquery.html Cycles:180 Average:30144.47 Median:11105.25 stddev:89605.09 (806.9%) stddev-sans-first:89828.65 09:27:22 INFO - PROCESS | 28887 | Values: 411.9 414.9 415.3 433.2 433.3 18720.2 19079.8 19295.9 19302.7 20190.2 19205.5 19313.5 19647.0 19738.0 19790.5 5364.2 5552.4 5578.2 5780.8 5802.2 5532.0 5548.5 5560.1 5570.6 5584.6 5426.0 5782.3 5785.7 5786.7 5788.8 5688.9 5742.2 5747.9 5750.6 5752.8 5277.2 5505.1 5509.8 5516.3 5520.3 12887.2 13400.9 13428.5 13520.3 13690.5 2427.9 2449.0 2508.2 2667.3 2752.9 9754.0 10156.9 13301.0 14051.5 14480.6 1952.9 2020.8 2058.5 2094.8 2102.7 78564.6 79152.2 84367.2 85144.0 85308.0 11020.6 11209.0 11220.8 11285.8 11336.7 15271.8 15405.9 15435.0 15444.5 15458.2 8284.2 8422.1 8448.5 8533.0 8583.6 8092.0 8093.5 8123.7 8157.9 8163.2 4792.0 4796.2 4810.8 4943.0 4975.3 1935.8 2012.4 2017.2 2020.3 2118.8 982.1 1020.9 1083.8 1090.4 1149.0 18021.5 18628.4 18738.3 19120.8 19470.8 1078.8 1097.6 1098.0 1099.2 1137.3 10200.0 10907.9 10918.6 10942.9 10957.8 532751.0 534039.0 547721.0 552602.0 565905.0 56364.0 56761.1 58180.4 96733.0 99072.5 1102.6 1149.0 1152.1 1160.5 1176.8 16726.7 16906.5 17080.3 17085.1 17416.2 38589.8 39432.8 40324.0 40720.6 41125.4 54875.0 57889.7 58678.0 58944.0 59129.4 8544.4 8661.5 8837.3 8854.1 12111.0 13535.2 13964.5 13985.1 14565.8 14784.3 6200.7 9793.7 12376.8 13017.2 13369.9 12296.3 12491.8 12511.4 12919.7 13131.5 13079.5 13251.6 13374.5 13441.9 13453.9 12997.4 13210.3 13315.8 13329.9 13436.1 13092.7 13148.4 13197.0 13296.7 13388.5 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#3] mootools.html Cycles:180 Average:5462.27 Median:5003.21 stddev:2947.96 (58.9%) stddev-sans-first:2941.00 09:27:22 INFO - PROCESS | 28887 | Values: 1475.0 1547.5 1559.3 1745.9 1869.9 7784.3 8064.9 8067.2 8076.2 8080.5 7941.8 8090.5 8098.1 8107.1 8117.2 2080.6 2343.3 2359.3 2377.7 2393.0 2333.9 2334.7 2340.4 2341.8 2343.4 2267.3 2320.0 2328.1 2329.9 2331.7 2001.4 2238.7 2290.5 2291.3 2295.6 2303.2 2310.1 2315.1 2324.4 2406.4 7821.3 7866.4 7871.8 7878.6 7887.7 5004.1 5091.4 5117.9 5138.0 5183.3 3436.3 3447.1 3490.7 3508.8 3518.4 1246.4 1250.7 1277.0 1301.3 1320.2 11164.1 11202.9 11230.5 11230.8 11283.0 4426.1 4441.0 4520.4 4532.4 4534.3 9343.2 9361.9 9387.6 9390.9 9394.3 4571.6 5237.0 5249.1 5252.1 5262.7 4611.6 4645.2 4669.3 4697.1 4723.5 3976.5 4003.0 4078.6 4084.1 4095.4 1315.7 1319.4 1335.1 1339.6 1347.2 917.4 978.2 1017.6 1114.2 1115.9 5884.4 5886.6 5890.7 5902.9 6043.1 963.3 1048.7 1053.4 1062.0 1088.7 3984.1 4036.0 4046.6 4056.4 4070.8 9998.8 10267.9 10469.9 10487.9 10489.1 10330.5 10409.8 10582.4 10592.5 10602.3 7752.3 7855.1 7971.2 8000.5 8030.2 5740.6 5843.0 5892.6 5919.2 5971.7 8903.1 8971.5 9067.0 9269.1 9285.7 9185.4 9261.0 9299.0 9453.8 9811.8 6303.6 6757.2 6820.2 6858.6 6943.5 7563.9 7614.8 7641.1 7656.2 7666.2 6287.0 6419.5 6524.4 6524.7 6621.3 6677.9 6707.2 6822.7 6843.5 6878.3 6534.4 6561.6 6569.1 6586.4 6587.9 4841.8 4863.0 4954.7 5002.3 5013.8 5057.1 5082.7 5123.6 5127.9 5152.2 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#4] prototype.html Cycles:180 Average:5420.02 Median:6254.36 stddev:1639.40 (26.2%) stddev-sans-first:1598.99 09:27:22 INFO - PROCESS | 28887 | Values: 336.6 349.8 351.1 354.1 357.5 6154.1 6157.2 6166.5 6212.9 6215.7 5861.5 5930.3 6030.3 6132.6 6140.0 4651.5 4683.5 4694.8 4696.0 4696.9 4683.8 4693.4 4695.3 4696.1 4712.9 4473.8 4560.3 4655.6 4658.9 4670.3 4670.9 4671.3 4686.5 4686.8 4703.1 4129.3 4129.4 4129.8 4132.2 4138.5 5863.2 5871.3 5872.5 5876.7 5877.5 5444.9 5450.5 5455.8 5461.0 5473.2 6205.3 6394.2 6409.0 6428.5 6433.5 4465.7 4465.7 4474.7 4486.9 4492.5 6717.6 6785.6 6803.6 6804.5 6807.8 4568.5 4570.1 4573.6 4580.1 4581.5 4633.5 4635.0 4637.5 4638.0 4642.0 5250.5 5361.0 5368.9 5377.9 5380.2 6495.1 6517.1 6542.8 6543.9 6544.0 1551.9 1585.2 1600.9 1617.0 1619.6 1037.7 1072.2 1075.7 1075.7 1080.1 6325.0 6333.0 6355.3 6364.2 6371.6 6587.8 6595.2 6599.7 6604.4 6619.0 6327.1 6341.2 6351.4 6361.4 6367.4 3561.9 3571.5 3572.5 3574.0 3575.2 6815.1 6968.8 6972.7 6996.1 6998.7 6734.1 6760.9 6764.7 6768.4 6773.0 6949.9 6969.2 6973.7 6979.0 6980.4 6591.2 6598.5 6598.8 6599.1 6602.7 6293.1 6467.5 6510.0 6522.4 6535.0 6924.7 6971.3 6973.0 6975.1 6980.1 4534.1 4540.9 4542.4 4546.4 4552.9 6434.9 6583.3 6584.6 6584.9 6595.7 6357.1 6532.4 6534.8 6536.2 6545.5 6423.9 6446.3 6492.4 6501.6 6506.9 6390.5 6393.4 6398.5 6401.4 6415.4 6363.2 6531.2 6548.7 6552.6 6560.6 6427.2 6483.0 6506.3 6507.7 6511.4 09:27:22 INFO - PROCESS | 28887 | 09:27:22 INFO - PROCESS | 28887 | [#5] yui.html Cycles:180 Average:15183.81 Median:47247.15 stddev:16941.79 (35.9%) stddev-sans-first:16954.85 09:27:22 INFO - PROCESS | 28887 | Values: 792.6 848.3 879.4 906.8 934.2 15707.1 15921.1 15936.3 15954.8 15955.4 14614.6 14646.9 14667.9 14669.9 14680.6 765.8 902.0 918.9 919.4 923.5 918.1 921.2 925.5 926.4 926.8 976.4 979.8 979.9 980.7 982.1 814.4 961.3 979.2 979.8 980.4 710.9 714.5 714.7 715.5 716.2 14631.3 14709.5 14753.9 14784.6 14807.2 14537.7 14892.8 15058.6 15353.3 15411.4 11874.8 12074.9 12125.8 12142.0 12510.2 4108.2 4129.9 4143.8 4157.9 4178.3 55247.0 55972.2 56070.7 56306.2 56506.4 10704.0 11071.7 11077.7 11379.2 11387.8 19812.5 19895.0 20064.3 20146.3 20192.7 8292.9 8399.5 8426.0 8440.3 8667.9 4977.7 4980.2 5013.5 5056.8 5199.6 3564.9 3616.2 3624.7 3626.8 3627.6 603.8 638.9 652.6 670.5 737.2 767.8 771.8 783.2 785.1 797.2 9514.3 9670.2 9753.5 9805.2 9888.3 613.2 653.0 662.4 664.6 696.9 4437.3 4629.2 4633.5 4747.3 4773.7 59330.7 59724.0 59935.1 59950.4 59965.7 50899.7 51360.0 52422.2 52551.7 53153.0 46984.0 47283.3 48090.0 48164.0 48338.5 9369.7 9508.3 9561.5 9574.8 9644.3 38336.8 38805.4 39779.8 39863.4 39922.6 45564.0 47211.0 48259.5 49020.8 49143.3 11504.2 11596.4 11726.2 11742.2 11897.9 13866.2 14178.1 14186.2 14664.5 15130.2 11926.0 12985.2 13012.8 13733.0 14030.4 12367.5 12574.6 12794.4 13244.8 13836.3 12393.6 12439.2 12744.4 13071.2 13474.2 10726.7 11093.3 11100.0 11292.7 11357.0 11094.8 11412.4 11455.2 11557.1 11724.9 09:27:22 INFO - PROCESS | 28887 | -------- Summary: end -------- 09:27:22 INFO - PROCESS | 28887 | 09:27:23 INFO - TEST-INFO | 28887: exit 0 09:27:23 INFO - TEST-OK | dromaeo_css | took 1111606ms 09:27:23 INFO - TEST-START | kraken 09:27:23 INFO - operating with platform_type : linux_ 09:27:23 INFO - Initialising browser for kraken test... 09:27:23 INFO - TEST-INFO | started process 29081 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsnN1FR/profile http://localhost:48005/getInfo.html) 09:27:27 INFO - PROCESS | 29081 | __metrics Screen width/height:1600/1200 09:27:27 INFO - PROCESS | 29081 | colorDepth:24 09:27:27 INFO - PROCESS | 29081 | Browser inner width/height: 1024/697 09:27:27 INFO - PROCESS | 29081 | __metrics 09:27:28 ERROR - PROCESS | 29081 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:27:29 INFO - TEST-INFO | 29081: exit 0 09:27:29 INFO - Browser initialized. 09:27:29 INFO - Running cycle 1/1 for kraken test... 09:27:29 INFO - TEST-INFO | started process 29172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsnN1FR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:41 INFO - PROCESS | 29172 | RSS: Main: 141668352 09:27:41 INFO - PROCESS | 29172 | 09:27:43 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48005/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:44 INFO - PROCESS | 29172 | RSS: Main: 196702208 09:27:44 INFO - PROCESS | 29172 | 09:27:47 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48005/tests/kraken/driver.html?testName=audio-dft) 09:27:47 INFO - PROCESS | 29172 | RSS: Main: 182284288 09:27:47 INFO - PROCESS | 29172 | 09:27:50 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48005/tests/kraken/driver.html?testName=audio-fft) 09:27:51 INFO - PROCESS | 29172 | RSS: Main: 272658432 09:27:51 INFO - PROCESS | 29172 | 09:27:53 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48005/tests/kraken/driver.html?testName=audio-oscillator) 09:27:53 INFO - PROCESS | 29172 | RSS: Main: 179249152 09:27:53 INFO - PROCESS | 29172 | 09:27:56 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:56 INFO - PROCESS | 29172 | RSS: Main: 177303552 09:27:56 INFO - PROCESS | 29172 | 09:27:59 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48005/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:00 INFO - PROCESS | 29172 | RSS: Main: 212475904 09:28:00 INFO - PROCESS | 29172 | 09:28:03 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48005/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:04 INFO - PROCESS | 29172 | RSS: Main: 213372928 09:28:04 INFO - PROCESS | 29172 | 09:28:07 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48005/tests/kraken/driver.html?testName=json-parse-financial) 09:28:07 INFO - PROCESS | 29172 | RSS: Main: 213733376 09:28:07 INFO - PROCESS | 29172 | 09:28:09 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:09 INFO - PROCESS | 29172 | RSS: Main: 193306624 09:28:09 INFO - PROCESS | 29172 | 09:28:11 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:12 INFO - PROCESS | 29172 | RSS: Main: 196214784 09:28:12 INFO - PROCESS | 29172 | 09:28:14 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:14 INFO - PROCESS | 29172 | RSS: Main: 210411520 09:28:14 INFO - PROCESS | 29172 | 09:28:17 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:17 INFO - PROCESS | 29172 | RSS: Main: 214212608 09:28:17 INFO - PROCESS | 29172 | 09:28:20 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:21 INFO - PROCESS | 29172 | RSS: Main: 211464192 09:28:21 INFO - PROCESS | 29172 | 09:28:22 INFO - PROCESS | 29172 | Cycle 1(1): loaded http://localhost:48005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48005/tests/kraken/driver.html?testName=ai-astar) 09:28:23 INFO - PROCESS | 29172 | RSS: Main: 275070976 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | __start_tp_report 09:28:23 INFO - PROCESS | 29172 | _x_x_mozilla_page_load 09:28:23 INFO - PROCESS | 29172 | _x_x_mozilla_page_load_details 09:28:23 INFO - PROCESS | 29172 | |i|pagename|runs| 09:28:23 INFO - PROCESS | 29172 | |0;ai-astar;111;111;111;113;113;112;105;111;110;110 09:28:23 INFO - PROCESS | 29172 | |1;audio-beat-detection;150;126;158;181;124;148;150;127;181;128 09:28:23 INFO - PROCESS | 29172 | |2;audio-dft;161;163;160;162;160;160;160;159;159;161 09:28:23 INFO - PROCESS | 29172 | |3;audio-fft;76;75;76;172;73;76;74;75;164;76 09:28:23 INFO - PROCESS | 29172 | |4;audio-oscillator;93;92;151;93;92;93;150;92;92;163 09:28:23 INFO - PROCESS | 29172 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;117;117;116 09:28:23 INFO - PROCESS | 29172 | |6;imaging-darkroom;146;146;145;146;148;146;145;145;146;145 09:28:23 INFO - PROCESS | 29172 | |7;imaging-desaturate;95;95;94;94;94;94;96;95;94;95 09:28:23 INFO - PROCESS | 29172 | |8;json-parse-financial;54;57;54;54;54;54;56;77;53;53 09:28:23 INFO - PROCESS | 29172 | |9;json-stringify-tinderbox;58;58;57;57;58;57;57;58;57;58 09:28:23 INFO - PROCESS | 29172 | |10;stanford-crypto-aes;73;72;74;72;73;81;74;73;75;73 09:28:23 INFO - PROCESS | 29172 | |11;stanford-crypto-ccm;116;117;128;112;110;114;115;111;140;136 09:28:23 INFO - PROCESS | 29172 | |12;stanford-crypto-pbkdf2;163;161;162;164;158;158;159;161;161;162 09:28:23 INFO - PROCESS | 29172 | |13;stanford-crypto-sha256-iterative;66;67;68;69;67;67;68;66;68;64 09:28:23 INFO - PROCESS | 29172 | __end_tp_report 09:28:23 INFO - PROCESS | 29172 | __start_cc_report 09:28:23 INFO - PROCESS | 29172 | _x_x_mozilla_cycle_collect,1584 09:28:23 INFO - PROCESS | 29172 | __end_cc_report 09:28:23 INFO - PROCESS | 29172 | __startTimestamp1463070503123__endTimestamp 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | ------- Summary: start ------- 09:28:23 INFO - PROCESS | 29172 | Number of tests: 14 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.26 (2.0%) stddev-sans-first:2.40 09:28:23 INFO - PROCESS | 29172 | Values: 111.0 111.0 111.0 113.0 113.0 112.0 105.0 111.0 110.0 110.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:150.00 stddev:21.56 (14.4%) stddev-sans-first:22.84 09:28:23 INFO - PROCESS | 29172 | Values: 150.0 126.0 158.0 181.0 124.0 148.0 150.0 127.0 181.0 128.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 09:28:23 INFO - PROCESS | 29172 | Values: 161.0 163.0 160.0 162.0 160.0 160.0 160.0 159.0 159.0 161.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#3] audio-fft Cycles:10 Average:93.70 Median:75.50 stddev:39.22 (51.9%) stddev-sans-first:41.07 09:28:23 INFO - PROCESS | 29172 | Values: 76.0 75.0 76.0 172.0 73.0 76.0 74.0 75.0 164.0 76.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:92.00 stddev:30.26 (32.9%) stddev-sans-first:31.38 09:28:23 INFO - PROCESS | 29172 | Values: 93.0 92.0 151.0 93.0 92.0 93.0 150.0 92.0 92.0 163.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 09:28:23 INFO - PROCESS | 29172 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 09:28:23 INFO - PROCESS | 29172 | Values: 146.0 146.0 145.0 146.0 148.0 146.0 145.0 145.0 146.0 145.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 09:28:23 INFO - PROCESS | 29172 | Values: 95.0 95.0 94.0 94.0 94.0 94.0 96.0 95.0 94.0 95.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:54.00 stddev:7.28 (13.5%) stddev-sans-first:7.66 09:28:23 INFO - PROCESS | 29172 | Values: 54.0 57.0 54.0 54.0 54.0 54.0 56.0 77.0 53.0 53.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#9] json-stringify-tinderbox Cycles:10 Average:57.50 Median:58.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 09:28:23 INFO - PROCESS | 29172 | Values: 58.0 58.0 57.0 57.0 58.0 57.0 57.0 58.0 57.0 58.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.50 stddev:2.62 (3.6%) stddev-sans-first:2.76 09:28:23 INFO - PROCESS | 29172 | Values: 73.0 72.0 74.0 72.0 73.0 81.0 74.0 73.0 75.0 73.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:116.50 stddev:10.81 (9.3%) stddev-sans-first:11.37 09:28:23 INFO - PROCESS | 29172 | Values: 116.0 117.0 128.0 112.0 110.0 114.0 115.0 111.0 140.0 136.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.50 stddev:2.02 (1.3%) stddev-sans-first:2.00 09:28:23 INFO - PROCESS | 29172 | Values: 163.0 161.0 162.0 164.0 158.0 158.0 159.0 161.0 161.0 162.0 09:28:23 INFO - PROCESS | 29172 | 09:28:23 INFO - PROCESS | 29172 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.41 (2.1%) stddev-sans-first:1.45 09:28:23 INFO - PROCESS | 29172 | Values: 66.0 67.0 68.0 69.0 67.0 67.0 68.0 66.0 68.0 64.0 09:28:23 INFO - PROCESS | 29172 | -------- Summary: end -------- 09:28:23 INFO - PROCESS | 29172 | 09:28:24 INFO - TEST-INFO | 29172: exit 0 09:28:24 INFO - TEST-OK | kraken | took 60974ms 09:28:24 INFO - SUITE-END | took 1172s 09:28:24 INFO - Completed test suite (00:19:32) 09:28:24 INFO - javascript benchmark 09:28:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.6571060038696, 3329.384063948169, 3339.0483712142045, 3364.360771453425, 3388.1360253135463, 5337.599680023999, 5417.756200970956, 5421.051316019701, 5424.0779067558515, 5440.156775699768, 5432.0, 5436.048691478995, 5447.645903016301, 5453.182022696597, 5475.0, 4807.326974223612, 4992.026554821811, 4994.575461085812, 5045.772940217681, 5107.131787596118, 4913.31213630092, 4978.228374601942, 4991.32617096691, 4993.350864387625, 4994.126027945095, 4909.189983652693, 4971.154903666367, 4972.0, 4984.850454486371, 4989.126902792015, 4999.200127979524, 5000.599952003831, 5022.824201152978, 5025.3718285214345, 5050.696958182502, 4867.0, 4904.852854414374, 4965.801367945278, 4975.975120124376, 4985.052839960432, 5405.21624364465, 5413.106837371829, 5431.538319242869, 5448.291722076105, 5448.564114870801, 5671.234383358276, 5683.261176047087, 5694.601377903548, 5700.0, 5702.201691763156, 5745.770169193227, 5822.0, 5849.122631605251, 5916.112583112567, 5923.526117910556, 5391.245225668409, 5426.267453893753, 5431.266778984826, 5433.049216387156, 5437.157240627709, 5743.282089738783, 5849.327327357323, 5849.736761845727, 5852.473277404969, 5866.0, 5648.802291919846, 5705.2012718219485, 5719.19931209631, 5722.77108915647, 5755.366909639936, 5811.070228763378, 5843.853903652443, 5848.707564621837, 5849.766009359578, 5851.327097383855, 5732.140178973187, 5735.369109397963, 5793.594448388572, 5804.158397032406, 5818.854528636818, 5833.562482813806, 5837.357890632027, 5853.180554722342, 5858.56060795442, 5893.793717219876, 4489.551044895549, 4510.391097201836, 4515.0969806038265, 4515.63874890008, 4538.296564032515, 3965.147493288957, 4002.5597184309704, 4031.274370613259, 4058.3303754880703, 4093.5497095319492, 5486.615936884459, 5509.779608815683, 5511.696856672841, 5513.889722205534, 5550.528205102531, 5751.022326204556, 5752.309722833287, 5758.568107391962, 5759.049756790167, 5759.395263497357, 5522.22688823565, 5581.413951535112, 5615.943840561542, 5702.8289151325525, 5703.74333155009, 4880.048390563806, 4901.803927842846, 4908.533689299511, 4909.042736666316, 4950.985048065138, 6063.72713227897, 6095.420935011167, 6097.024476083805, 6100.23747031621, 6106.389361063947, 5795.420457954255, 5833.79581714638, 5900.232969713909, 5919.31927828288, 5925.081612350093, 6080.361562035922, 6088.969555152196, 6090.451859332681, 6093.6039157454625, 6097.5731698780655, 5549.611527193058, 5742.913856292075, 5750.597458177887, 5758.798442054508, 5761.337446193574, 5827.096799996008, 5871.383504731942, 5881.941180588139, 5908.3500814911185, 5933.22868027171, 5700.144978253295, 5710.543156547549, 5714.20001199824, 5724.169995350734, 5727.942720572741, 5499.972500137474, 5641.069223578228, 5689.089745640677, 5694.829155125353, 5700.743466543932, 5603.271574695264, 5627.915581266366, 5636.210930469655, 5637.605367624227, 5673.035583950656, 5709.543236540983, 5741.052726300114, 5741.3971532988435, 5753.165790960204, 5754.367019627922, 5484.91772623403, 5531.8893622126525, 5576.442355764391, 5624.072028115315, 5624.915626265528], "value": 5381.376643297893, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.4886062162445, 674.6828990374522, 676.0974099577068, 679.7179170644183, 750.40717832912, 32582.0, 33011.17472063196, 38354.80822595886, 38676.0, 38951.220975580545, 45216.773916130456, 46063.0, 46064.309035364495, 46179.0, 46210.30684539726, 9011.954940225307, 9139.908600914005, 9158.358914875962, 9168.72493825183, 9170.770730731752, 8897.33270004749, 9014.414063085911, 9016.323775716848, 9017.54912254388, 9037.096290370942, 9035.774105647346, 9081.091890810932, 9088.272938164931, 9093.63625454981, 9100.044495327995, 9031.0, 9087.0, 9088.363814532986, 9102.681406150787, 9107.408018478778, 8966.910330896673, 9059.139381758723, 9092.727218183465, 9094.727158185266, 9112.635494580209, 42143.1571368574, 42549.36175957363, 42661.57338426607, 42675.7866210667, 42681.1463770726, 3652.6164752700847, 3653.8721144759807, 3741.8316175772156, 3873.1866308075055, 4047.655949244318, 32547.18632034218, 33299.500507492405, 33329.66670333314, 33509.664903350895, 33594.83202583996, 4040.898977525556, 4072.1652061327654, 4105.568915263914, 4124.175164967019, 4139.627433530996, 239440.0139996514, 239894.0, 240333.99999999825, 240359.0, 240532.0, 38987.22025559473, 60379.396206038255, 60394.0940885887, 60740.39259607347, 60858.39141608527, 37469.625303746616, 37552.061198470255, 37556.24887502235, 37564.43653345202, 37565.248695026494, 15282.923585382225, 15485.303161357766, 15489.457868974532, 15498.5350439487, 15511.0, 13322.20066795976, 13334.066615336833, 13378.464861405435, 13456.663583410493, 13464.663383415296, 9208.355415121012, 9376.718698438921, 9520.190783783317, 9563.952180239054, 9624.615015399306, 3574.606793252792, 3597.73017023719, 3606.711463082947, 3635.236600313911, 3700.407934730484, 1104.8950349716763, 1183.5325046606542, 1210.5218438716659, 1211.5396149463147, 1216.7748966441059, 33518.32963340719, 34180.0, 34359.0, 34460.0, 34526.48210276848, 1258.7608354412632, 1283.1467074395423, 1305.1646945954597, 1319.3073636340805, 1339.7960355215616, 17659.30696562659, 17978.730319045226, 18006.819931800776, 18048.495298116304, 18093.677047633082, 476176.0, 786586.0, 801524.0, 802741.0, 803480.0, 63771.0, 63996.0, 64667.0, 64768.0, 64768.676156619855, 1486.2494440307576, 1486.2791546100132, 1492.1271056431892, 1493.8506149384975, 1524.6036030632115, 31882.362352752352, 33598.83200583982, 33927.830360848035, 33939.0, 34298.657013430544, 51121.0, 51542.134339925404, 51738.223926641884, 51864.74067629638, 51945.22082168695, 367949.0, 370300.0, 371164.0, 371303.0, 371406.0, 30920.381592368478, 49965.0, 50313.0, 50804.491955079975, 51241.48758512516, 48416.75791621019, 48780.75609622071, 48781.268280976525, 48840.75579622221, 49747.50252497574, 40088.59911400849, 40409.59590404176, 40646.39030414487, 41163.176736464506, 41292.587074128875, 39645.60354396419, 40389.59610403976, 40939.38590921079, 40970.0, 41142.382864257655, 41007.58992410038, 41776.58223417727, 42219.78890105653, 42757.1448571033, 42868.0, 35895.46156807702, 36488.635113648525, 36834.0, 37222.813885930394, 37231.81384093062, 27827.16518504448, 28037.71962280351, 28075.859620702584, 28221.0, 28402.0], "value": 19696.486714207593, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.8990847242426, 414.943982562354, 415.32716998462496, 433.19858262214905, 433.2915682858526, 18720.15759290832, 19079.809201907978, 19295.903520482396, 19302.710459343103, 20190.19239230429, 19205.519862003457, 19313.517162070955, 19647.0, 19738.0, 19790.505237369038, 5364.249005139283, 5552.444755524456, 5578.163275508686, 5780.7687692492245, 5802.245708057946, 5531.9723401382935, 5548.528375088103, 5560.110382338827, 5570.637908535952, 5584.553235741131, 5425.9728701356435, 5782.306123265193, 5785.681787501686, 5786.710664466781, 5788.826335209929, 5688.886222275552, 5742.2247996520355, 5747.885042299152, 5750.597458177908, 5752.769889204426, 5277.155655095166, 5505.119180931032, 5509.807156749494, 5516.338039435253, 5520.254765606632, 12887.16233444823, 13400.932995335059, 13428.53000145, 13520.32398380077, 13690.452381904712, 2427.878606069707, 2449.0, 2508.2224510401834, 2667.333166708323, 2752.8575641108914, 9753.951230243803, 10156.898431015668, 13301.0, 14051.508197213048, 14480.637984050378, 1952.9414117576353, 2020.7720715636437, 2058.5265388960624, 2094.7800480949586, 2102.7266455360705, 78564.60717696375, 79152.20847791563, 84367.15632843654, 85144.0, 85308.0, 11020.614278500214, 11209.038418276768, 11220.775584488265, 11285.830712539318, 11336.659900203007, 15271.847281527265, 15405.922970385076, 15435.0, 15444.45944391941, 15458.227088645523, 8284.212999765012, 8422.115677853859, 8448.535330556879, 8532.957335213285, 8583.613737381833, 8092.0, 8093.514389136671, 8123.7156699515235, 8157.877631835528, 8163.183681631908, 4791.95208047922, 4796.184648609745, 4810.783514741775, 4942.950570494321, 4975.253711943237, 1935.7773856006459, 2012.386222202213, 2017.2032047341218, 2020.3130935482006, 2118.8304935605115, 982.1455333859445, 1020.8519764634086, 1083.8103331916882, 1090.3675867996542, 1149.0061097150904, 18021.549461263312, 18628.441146765617, 18738.344157954405, 19120.808791911903, 19470.80529194718, 1078.8058149533003, 1097.5719469406938, 1097.9890201098046, 1099.1756182862853, 1137.3394843528376, 10199.964042430003, 10907.945460272647, 10918.617848375268, 10942.890571094187, 10957.780844383067, 532751.0, 534039.0, 547721.0, 552602.0, 565905.0, 56364.0, 56761.14858277129, 58180.41819581835, 96733.0, 99072.50463747635, 1102.5699977008812, 1148.9655310340704, 1152.124385467051, 1160.489384670742, 1176.7822952753768, 16726.665466690356, 16906.492805215865, 17080.316787328367, 17085.145742713063, 17416.216270267614, 38589.80705096456, 39432.80283598564, 40324.0, 40720.592794072865, 41125.383119252634, 54875.0, 57889.71055144697, 58678.0, 58944.0, 59129.40870591411, 8544.444611100258, 8661.480311181349, 8837.293016558531, 8854.114588541095, 12111.0, 13535.187888726708, 13964.511242106477, 13985.090969086978, 14565.781513277521, 14784.334704938088, 6200.720967556381, 9793.706188814347, 12376.81434778461, 13017.153884997824, 13369.933150334185, 12296.262224266573, 12491.812622810483, 12511.374431278582, 12919.677008074497, 13131.540396086091, 13079.542216022393, 13251.602451926457, 13374.531891383755, 13441.932790335986, 13453.932730336286, 12997.415116319598, 13210.27343496117, 13315.800262996256, 13329.86670133325, 13436.126651767603, 13092.672683182996, 13148.408321625358, 13197.0, 13296.667583310495, 13388.531401400904], "value": 9467.6142640474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.9778753318694, 1547.5280039587924, 1559.3139018831714, 1745.9214335354907, 1869.8971556564384, 7784.260495252949, 8064.91935080649, 8067.19328067193, 8076.232757887999, 8080.515169089858, 7941.761747147595, 8090.514569125842, 8098.109207987117, 8107.148749381318, 8117.228863257996, 2080.5734824361043, 2343.3438637181616, 2359.2922123362964, 2377.738448770634, 2392.95214095718, 2333.9183128590503, 2334.6964894563685, 2340.3564019894493, 2341.7658234176615, 2343.4258606641397, 2267.342470683508, 2319.9536009279805, 2328.092044102801, 2329.860208387494, 2331.7318508371586, 2001.4395969128668, 2238.6977758002704, 2290.541891621682, 2291.255342013852, 2295.6097463431256, 2303.170858490942, 2310.1336998625516, 2315.0623997281127, 2324.418895276181, 2406.4104294447798, 7821.2960833524685, 7866.410019248579, 7871.842563148763, 7878.606069696492, 7887.684492620288, 5004.074246264453, 5091.439941606421, 5117.897642047175, 5138.049460849755, 5183.326167598226, 3436.329915666446, 3447.120984149026, 3490.6509349065145, 3508.7543871928974, 3518.437050072002, 1246.4131994261115, 1250.7123361626786, 1277.0, 1301.3298151452009, 1320.2011735121603, 11164.051055660279, 11202.943985280102, 11230.494627741771, 11230.831537526861, 11283.040941519981, 4426.070525189682, 4441.045175287329, 4520.389747384127, 4532.388127602798, 4534.319852022223, 9343.205827504738, 9361.859572106423, 9387.624495020124, 9390.85913711295, 9394.295427842939, 4571.611413029929, 5236.9738151309, 5249.08141075318, 5252.133397989365, 5262.71055091966, 4611.608013318905, 4645.163870503342, 4669.34629151919, 4697.131030759252, 4723.48041715411, 3976.4830572025453, 4002.959970400317, 4078.6125318094732, 4084.101497670508, 4095.4061661059577, 1315.7039666075057, 1319.3667039820748, 1335.1121504199793, 1339.6249050265942, 1347.1647578501393, 917.4220241247971, 978.2174260591498, 1017.6082208349691, 1114.2144787924494, 1115.9218854680094, 5884.382139875337, 5886.6173698709445, 5890.734916928748, 5902.911456328158, 6043.09353596964, 963.3064193780467, 1048.6696690542456, 1053.4258828938248, 1061.9734506637242, 1088.684281558355, 3984.143409167043, 4035.9798201009394, 4046.5548789633117, 4056.371262454324, 4070.816813243411, 9998.80002399948, 10267.9486602568, 10469.9476502617, 10487.94756026215, 10489.055984961238, 10330.48347582633, 10409.843852342221, 10582.365058096539, 10592.470376481146, 10602.257841951143, 7752.26353496417, 7855.135935047255, 7971.202879711982, 8000.51996880189, 8030.156833532512, 5740.598158128891, 5843.006688862988, 5892.558058145571, 5919.200907877554, 5971.731272092679, 8903.06517815646, 8971.50656713887, 9067.0, 9269.119433653792, 9285.72142835716, 9185.378174658463, 9260.953695231481, 9299.0, 9453.810923781624, 9811.80376392454, 6303.621782693053, 6757.22291936414, 6820.215675197217, 6858.588484690935, 6943.47923905699, 7563.886541701989, 7614.847703046019, 7641.083070031632, 7656.157822639394, 7666.156722760604, 6286.968565157145, 6419.45434638061, 6524.380183882524, 6524.706388212637, 6621.27166011748, 6677.899831502435, 6707.1616047994, 6822.658867056528, 6843.520953533205, 6878.3465570770695, 6534.444572211415, 6561.639109848813, 6569.080328754074, 6586.4401525870835, 6587.934120658923, 4841.806327746851, 4863.0, 4954.677945933503, 5002.349694539686, 5013.774380152975, 5057.140286151437, 5082.720450375265, 5123.590112791042, 5127.92308115386, 5152.201408781645], "value": 4493.514265660378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.5557464147325, 349.82333921369707, 351.1204432895596, 354.0952865428832, 357.4834364343523, 6154.107654390116, 6157.1687822143995, 6166.537509686775, 6212.912740270457, 6215.6636323190505, 5861.472467477926, 5930.258717660293, 6030.276366835986, 6132.632042077467, 6140.048292514656, 4651.488336283006, 4683.461401938786, 4694.765261736899, 4695.953040469586, 4696.85909422717, 4683.765811709418, 4693.389859318301, 4695.2722328039035, 4696.084263568606, 4712.858614241578, 4473.843415480459, 4560.293154561048, 4655.580997710205, 4658.90682186356, 4670.34615153877, 4670.883227919312, 4671.299305104261, 4686.507916668735, 4686.835960741358, 4703.059388122372, 4129.2773764591075, 4129.380592911088, 4129.834806607732, 4132.152908653718, 4138.461999940018, 5863.179154918314, 5871.266091738533, 5872.530197584226, 5876.706164691791, 5877.500412464946, 5444.863878403072, 5450.4549545045575, 5455.836324910219, 5460.972695136538, 5473.233747275384, 6205.317415084337, 6394.23269207698, 6409.038644203407, 6428.5178611604315, 6433.453156481704, 4465.68740188187, 4465.732056076646, 4474.731516109011, 4486.865394038184, 4492.460904691426, 6717.596944183316, 6785.558938668971, 6803.591784492946, 6804.455643548505, 6807.795766126975, 4568.520305367889, 4570.063137056895, 4573.634109271316, 4580.129775342674, 4581.541845815458, 4633.536646335407, 4635.026644404646, 4637.489876113625, 4638.0491999140095, 4642.001969576557, 5250.501202385767, 5360.981413531416, 5368.8657783554945, 5377.892442151135, 5380.192971054373, 6495.1231583736535, 6517.055027021055, 6542.7710030149665, 6543.9202531582705, 6543.967280163569, 1551.9068855868684, 1585.207396301849, 1600.8639265662316, 1617.0378624718273, 1619.5789094835193, 1037.7146284771748, 1072.1958531101673, 1075.6611667324767, 1075.7471994081384, 1080.0657431322006, 6324.968375158095, 6333.0, 6355.269144048401, 6364.204474440695, 6371.585846919936, 6587.80236592903, 6595.241547222035, 6599.670016499156, 6604.405603495708, 6618.966905165444, 6327.145835312266, 6341.175647165839, 6351.396617321347, 6361.363863613694, 6367.395097465734, 3561.9109522261633, 3571.482135090397, 3572.481990111471, 3574.03501054719, 3575.213453040327, 6815.079964204868, 6968.756093536702, 6972.651367431608, 6996.090508233897, 6998.650067496605, 6734.057231987427, 6760.864782704319, 6764.661766911635, 6768.424683901923, 6773.0, 6949.861002779916, 6969.23338432772, 6973.686184121727, 6979.0, 6980.371766541036, 6591.209054913441, 6598.472122230306, 6598.8350291243105, 6599.0761293418, 6602.6698665067515, 6293.056041593798, 6467.482601391972, 6510.023496475566, 6522.358444829852, 6535.0, 6924.6537673117155, 6971.302869713191, 6972.954056891507, 6975.058367120374, 6980.05769221169, 4534.115847409856, 4540.8637740867825, 4542.432195975503, 4546.431696037996, 4552.928417570064, 6434.871302574016, 6583.275839657729, 6584.637844918576, 6584.868302634016, 6595.736170553124, 6357.110004599267, 6532.444742196776, 6534.771283005072, 6536.182977127859, 6545.541812073109, 6423.9036414452885, 6446.258680251643, 6492.383223593751, 6501.577397469149, 6506.869862602627, 6390.456811170917, 6393.424591786761, 6398.4561312287115, 6401.359864013748, 6415.358464153547, 6363.204599425072, 6531.183602049744, 6548.672566371758, 6552.6068435894, 6560.606363618198, 6427.196600424947, 6483.0, 6506.284308725942, 6507.739690412331, 6511.446527045254], "value": 4884.604542251645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.5720111139984, 848.3001523742913, 879.3580686099148, 906.8458362078446, 934.1825902335456, 15707.057576545416, 15921.124338161397, 15936.282867270986, 15954.76067858983, 15955.441559545417, 14614.561563153122, 14646.853531464709, 14667.926660366684, 14669.853301466983, 14680.559583212495, 765.8276887700265, 901.9864702029442, 918.9172974432299, 919.4299534288717, 923.4828496042204, 918.091089821079, 921.2123634292684, 925.4909799610222, 926.3932124458457, 926.8285367207054, 976.3995142987047, 979.8432250839867, 979.902009799018, 980.7057882635181, 982.0866594067514, 814.4217605500072, 961.3030552849164, 979.2215188924822, 979.7942432089234, 980.4411485453295, 710.8933659951049, 714.4962801225124, 714.7176865138293, 715.4669771020571, 716.2121666167227, 14631.341589628491, 14709.485168019175, 14753.852461475355, 14784.556463306119, 14807.185604791732, 14537.709245815024, 14892.776608350883, 15058.623534411618, 15353.309101090366, 15411.3835446582, 11874.762504749942, 12074.939625301906, 12125.818112728317, 12142.02863770905, 12510.18683785551, 4108.157827645326, 4129.938050929239, 4143.751374917515, 4157.854475093387, 4178.310578754502, 55247.0, 55972.16041759336, 56070.719646401914, 56306.15540766892, 56506.434935650526, 10704.0, 11071.667849964513, 11077.723056923642, 11379.203455758017, 11387.772244555063, 19812.504687382643, 19895.0, 20064.297749578986, 20146.294879679142, 20192.69710954337, 8292.917070829335, 8399.53802540854, 8426.0, 8440.324774018063, 8667.91332086671, 4977.701337919664, 4980.203167493184, 5013.498650134958, 5056.82301119459, 5199.636025478256, 3564.8930532084078, 3616.168281295264, 3624.6737793598704, 3626.818659067036, 3627.619099994515, 603.841459497649, 638.9393007664357, 652.6051738698114, 670.4636290967206, 737.1670012885405, 767.7926959720844, 771.8340556780207, 783.2011348424575, 785.1285073568334, 797.1868693932157, 9514.33399662017, 9670.178034866974, 9753.512324383895, 9805.166560842266, 9888.258380621483, 613.2426453330133, 653.0106888064586, 662.4435474201694, 664.6244871647503, 696.905917701114, 4437.334399839985, 4629.236176030984, 4633.536646335407, 4747.311639812277, 4773.689710168828, 59330.703346482995, 59724.0, 59935.10097348543, 59950.40049599535, 59965.70017149974, 50899.745501273, 51360.0, 52422.213666795025, 52551.73724131432, 53153.0, 46984.0, 47283.29075063808, 48090.0, 48164.0, 48338.5166148341, 9369.672061477815, 9508.33441659105, 9561.521923903916, 9574.808503829745, 9644.276679248944, 38336.80831595824, 38805.41791873068, 39779.801100995464, 39863.40204896871, 39922.600773991886, 45564.0, 47211.0, 48259.5174048255, 49020.75489622672, 49143.26285105655, 11504.194706370474, 11596.362200079077, 11726.179167458196, 11742.236754610924, 11897.940510297392, 13866.168029918237, 14178.149311040957, 14186.219757913414, 14664.486742963945, 15130.167840768863, 11926.0, 12985.220886746825, 13012.8048079277, 13733.038687292194, 14030.438782448997, 12367.505299787908, 12574.622761317176, 12794.424250908918, 13244.801327979896, 13836.308184590931, 12393.62819115428, 12439.191452555555, 12744.4265008073, 13071.15037522558, 13474.19154850712, 10726.678199654023, 11093.278936869077, 11100.0, 11292.661220163409, 11357.0, 11094.833577496505, 11412.372319522507, 11455.198136130724, 11557.133215009077, 11724.88275117272], "value": 7105.554328890172, "unit": "score"}], "name": "dromaeo_css", "value": 7341.127354665374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 113.0, 113.0, 112.0, 105.0, 111.0, 110.0, 110.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 158.0, 181.0, 124.0, 148.0, 150.0, 127.0, 181.0, 128.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 160.0, 162.0, 160.0, 160.0, 160.0, 159.0, 159.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 172.0, 73.0, 76.0, 74.0, 75.0, 164.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 151.0, 93.0, 92.0, 93.0, 150.0, 92.0, 92.0, 163.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 148.0, 146.0, 145.0, 145.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 54.0, 54.0, 54.0, 54.0, 56.0, 77.0, 53.0, 53.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 57.0, 58.0, 57.0, 57.0, 58.0, 57.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 74.0, 72.0, 73.0, 81.0, 74.0, 73.0, 75.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 128.0, 112.0, 110.0, 114.0, 115.0, 111.0, 140.0, 136.0], "value": 119.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 164.0, 158.0, 158.0, 159.0, 161.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 69.0, 67.0, 67.0, 68.0, 66.0, 68.0, 64.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1516.3000000000004, "alertThreshold": 2.0}]} 09:28:24 INFO - Return code: 0 09:28:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:28:24 ERROR - # TBPL SUCCESS # 09:28:24 INFO - Running post-action listener: _resource_record_post_action 09:28:24 INFO - Running post-run listener: _resource_record_post_run 09:28:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 496197632; Read time: 384; Write time: 839884 09:28:25 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 246472704; Read time: 0; Write time: 533320 09:28:25 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 247439360; Read time: 384; Write time: 304432 09:28:25 INFO - Running post-run listener: _upload_blobber_files 09:28:25 INFO - Blob upload gear active. 09:28:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:28:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:26 INFO - (blobuploader) - INFO - Done attempting. 09:28:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:27 INFO - (blobuploader) - INFO - Done attempting. 09:28:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:27 INFO - Return code: 0 09:28:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f0923cbfae621fe173ebdf49439e841c65ddde58c836ceeb5feb7d2e6337fbabc7a713e8a3ad399d4f1edcf4c037f4c5ceaf34770bb9e6ef1184819d0288bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7efc53a596f77c91d4b31df913a88dcfc8b97bc3093064d4a19765402daa15eb34de6b8d269a03d36275d7390a7258042f594cf2a6d50595cfa2404b83a632c1"} 09:28:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:27 INFO - Contents: 09:28:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f0923cbfae621fe173ebdf49439e841c65ddde58c836ceeb5feb7d2e6337fbabc7a713e8a3ad399d4f1edcf4c037f4c5ceaf34770bb9e6ef1184819d0288bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7efc53a596f77c91d4b31df913a88dcfc8b97bc3093064d4a19765402daa15eb34de6b8d269a03d36275d7390a7258042f594cf2a6d50595cfa2404b83a632c1"} 09:28:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:27 INFO - Copying logs to upload dir... 09:28:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.954792 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-05-12 09:28:26.524527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:28:26.527947) ========= 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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 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/80f0923cbfae621fe173ebdf49439e841c65ddde58c836ceeb5feb7d2e6337fbabc7a713e8a3ad399d4f1edcf4c037f4c5ceaf34770bb9e6ef1184819d0288bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7efc53a596f77c91d4b31df913a88dcfc8b97bc3093064d4a19765402daa15eb34de6b8d269a03d36275d7390a7258042f594cf2a6d50595cfa2404b83a632c1"} build_url:https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/eUf0c2lcRh2nXLIsRSx0gQ/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/80f0923cbfae621fe173ebdf49439e841c65ddde58c836ceeb5feb7d2e6337fbabc7a713e8a3ad399d4f1edcf4c037f4c5ceaf34770bb9e6ef1184819d0288bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7efc53a596f77c91d4b31df913a88dcfc8b97bc3093064d4a19765402daa15eb34de6b8d269a03d36275d7390a7258042f594cf2a6d50595cfa2404b83a632c1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:28:26.552591) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:28:26.552940) ========= 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=33d5923976266a1c518c4bf7000001ab-1463069277.49576-816905530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:28:26.637693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:28:26.638006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:28:26.638318) ========= ========= Total master_lag: 0.16 =========