builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1461686376.37 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.367073) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.367471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.367832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.389960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.390340) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 08:59:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.6M=0s 2016-04-26 08:59:37 (50.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119262 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.522916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.523234) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017313 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.611416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:36.611814) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --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 cbeae0c4410bef3864b3d3f4ad939321c61cb15b --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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 08:59:37,474 truncating revision to first 12 chars 2016-04-26 08:59:37,474 Setting DEBUG logging. 2016-04-26 08:59:37,474 attempt 1/10 2016-04-26 08:59:37,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 08:59:37,709 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541710 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:37.166486) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:37.166817) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:37.167214) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:37.167480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:37.178181) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-26 08:59:37.178450) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:38 INFO - MultiFileLogger online at 20160426 08:59:38 in /builds/slave/test 08:59:38 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 08:59:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:38 INFO - {'append_to_log': False, 08:59:38 INFO - 'base_work_dir': '/builds/slave/test', 08:59:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:59:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:38 INFO - 'branch': 'Mozilla-Aurora', 08:59:38 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:38 INFO - 'config_files': ('talos/linux_config.py',), 08:59:38 INFO - 'default_actions': ('clobber', 08:59:38 INFO - 'read-buildbot-config', 08:59:38 INFO - 'download-and-extract', 08:59:38 INFO - 'populate-webroot', 08:59:38 INFO - 'create-virtualenv', 08:59:38 INFO - 'install', 08:59:38 INFO - 'run-tests'), 08:59:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:38 INFO - 'download_minidump_stackwalk': True, 08:59:38 INFO - 'download_symbols': 'ondemand', 08:59:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:38 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:38 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:38 INFO - 'installer_path': 'installer.exe', 08:59:38 INFO - 'log_level': 'info', 08:59:38 INFO - 'log_name': 'talos', 08:59:38 INFO - 'log_to_console': True, 08:59:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:38 INFO - 'opt_config_files': (), 08:59:38 INFO - 'pip_index': False, 08:59:38 INFO - 'sps_profile': False, 08:59:38 INFO - 'sps_profile_interval': 0, 08:59:38 INFO - 'suite': 'dromaeojs', 08:59:38 INFO - 'system_bits': '32', 08:59:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:38 INFO - 'title': 'talos-linux64-ix-042', 08:59:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:38 INFO - 'use_talos_json': True, 08:59:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:38 INFO - 'work_dir': 'build'} 08:59:38 INFO - ##### 08:59:38 INFO - ##### Running clobber step. 08:59:38 INFO - ##### 08:59:38 INFO - Running pre-action listener: _resource_record_pre_action 08:59:38 INFO - Running main action method: clobber 08:59:38 INFO - rmtree: /builds/slave/test/build 08:59:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:39 INFO - Running post-action listener: _resource_record_post_action 08:59:39 INFO - ##### 08:59:39 INFO - ##### Running read-buildbot-config step. 08:59:39 INFO - ##### 08:59:39 INFO - Running pre-action listener: _resource_record_pre_action 08:59:39 INFO - Running main action method: read_buildbot_config 08:59:39 INFO - Using buildbot properties: 08:59:39 INFO - { 08:59:39 INFO - "project": "", 08:59:39 INFO - "product": "firefox", 08:59:39 INFO - "script_repo_revision": "production", 08:59:39 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:59:39 INFO - "repository": "", 08:59:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:59:39 INFO - "buildid": "20160426062558", 08:59:39 INFO - "pgo_build": "True", 08:59:39 INFO - "basedir": "/builds/slave/test", 08:59:39 INFO - "buildnumber": 469, 08:59:39 INFO - "slavename": "talos-linux64-ix-042", 08:59:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:59:39 INFO - "platform": "ubuntu64_hw", 08:59:39 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:59:39 INFO - "branch": "mozilla-aurora", 08:59:39 INFO - "repo_path": "releases/mozilla-aurora", 08:59:39 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 08:59:39 INFO - "stage_platform": "linux64-pgo", 08:59:39 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 08:59:39 INFO - "slavebuilddir": "test" 08:59:39 INFO - } 08:59:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 08:59:39 INFO - Running post-action listener: _resource_record_post_action 08:59:39 INFO - ##### 08:59:39 INFO - ##### Running download-and-extract step. 08:59:39 INFO - ##### 08:59:39 INFO - Running pre-action listener: _resource_record_pre_action 08:59:39 INFO - Running main action method: download_and_extract 08:59:39 INFO - mkdir: /builds/slave/test/build/tests 08:59:39 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')]} 08:59:39 INFO - https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 08:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 08:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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 08:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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 08:59:44 INFO - Downloaded 1361 bytes. 08:59:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - Using the following test package requirements: 08:59:44 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 08:59:44 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:44 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:59:44 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'jsshell-linux-x86_64.zip'], 08:59:44 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:44 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 08:59:44 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:59:44 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:59:44 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:44 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 08:59:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:44 INFO - https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 08:59:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 08:59:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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 08:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:04 INFO - Downloaded 23820445 bytes. 09:00:04 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:00:04 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:00:05 INFO - Return code: 0 09:00:05 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:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:05 INFO - https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 09:00:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:06 INFO - Downloaded 23820445 bytes. 09:00:06 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:00:06 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:00:08 INFO - Return code: 0 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:08 INFO - https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 09:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:09 INFO - Downloaded 10840145 bytes. 09:00:09 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:00:09 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:00:09 INFO - Return code: 0 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:09 INFO - https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZiPQa71WSP-SD0XstXuXXg/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:00:11 INFO - Downloaded 62689705 bytes. 09:00:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:11 INFO - mkdir: /builds/slave/test/properties 09:00:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:11 INFO - Contents: 09:00:11 INFO - build_url:https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 09:00:12 INFO - Running post-action listener: _resource_record_post_action 09:00:12 INFO - Running post-action listener: set_extra_try_arguments 09:00:12 INFO - ##### 09:00:12 INFO - ##### Running populate-webroot step. 09:00:12 INFO - ##### 09:00:12 INFO - Running pre-action listener: _resource_record_pre_action 09:00:12 INFO - Running main action method: populate_webroot 09:00:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:12 INFO - '--tppagecycles', 09:00:12 INFO - '7'], 09:00:12 INFO - 'tests': ['tsvgm']}}, 09:00:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['damp', 'tps']}, 09:00:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'tests': ['damp', 'tps']}, 09:00:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['dromaeo_dom']}, 09:00:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['a11yr', 09:00:12 INFO - 'ts_paint', 09:00:12 INFO - 'tpaint', 09:00:12 INFO - 'sessionrestore', 09:00:12 INFO - 'sessionrestore_no_auto_restore', 09:00:12 INFO - 'tabpaint']}, 09:00:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['tsvgx', 09:00:12 INFO - 'tsvgr_opacity', 09:00:12 INFO - 'tart', 09:00:12 INFO - 'tscrollx', 09:00:12 INFO - 'cart']}, 09:00:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:12 INFO - 'tsvgr_opacity', 09:00:12 INFO - 'tart', 09:00:12 INFO - 'tscrollx', 09:00:12 INFO - 'cart']}, 09:00:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'talos_options': ['--disable-e10s'], 09:00:12 INFO - 'tests': ['tp5o']}, 09:00:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'tests': ['tp5o']}, 09:00:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'talos_options': ['--disable-e10s', 09:00:12 INFO - '--xperf_path', 09:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:12 INFO - 'tests': ['tp5n']}, 09:00:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:12 INFO - 'talos_options': ['--xperf_path', 09:00:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:12 INFO - 'tests': ['tp5n']}}, 09:00:12 INFO - 'talos.zip': {'path': '', 09:00:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:12 INFO - Running post-action listener: _resource_record_post_action 09:00:12 INFO - ##### 09:00:12 INFO - ##### Running create-virtualenv step. 09:00:12 INFO - ##### 09:00:12 INFO - Running pre-action listener: _resource_record_pre_action 09:00:12 INFO - Running main action method: create_virtualenv 09:00:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:12 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:00:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:12 INFO - Using real prefix '/usr' 09:00:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:15 INFO - Installing pip.................done. 09:00:15 INFO - Return code: 0 09:00:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00: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:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00: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', 'pip>=1.5'] in /builds/slave/test/build 09:00: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 pip>=1.5 09:00:15 INFO - Using env: {'DISPLAY': ':0', 09:00:15 INFO - 'HOME': '/home/cltbld', 09:00:15 INFO - 'LANG': 'en_US.UTF-8', 09:00:15 INFO - 'LANGUAGE': 'en_US:en', 09:00:15 INFO - 'LOGNAME': 'cltbld', 09:00:15 INFO - 'MAIL': '/var/mail/cltbld', 09:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:15 INFO - 'MOZ_NO_REMOTE': '1', 09:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:15 INFO - 'NO_EM_RESTART': '1', 09:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:15 INFO - 'PWD': '/builds/slave/test', 09:00:15 INFO - 'SHELL': '/bin/bash', 09:00:15 INFO - 'SHLVL': '1', 09:00:15 INFO - 'TERM': 'linux', 09:00:15 INFO - 'TMOUT': '86400', 09:00:15 INFO - 'USER': 'cltbld', 09:00:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:15 INFO - '_': '/tools/buildbot/bin/python'} 09:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:15 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:00:15 INFO - Cleaning up... 09:00:15 INFO - Return code: 0 09:00:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00: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:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', '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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00: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', 'psutil>=0.7.1'] in /builds/slave/test/build 09:00: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 psutil>=0.7.1 09:00:15 INFO - Using env: {'DISPLAY': ':0', 09:00:15 INFO - 'HOME': '/home/cltbld', 09:00:15 INFO - 'LANG': 'en_US.UTF-8', 09:00:15 INFO - 'LANGUAGE': 'en_US:en', 09:00:15 INFO - 'LOGNAME': 'cltbld', 09:00:15 INFO - 'MAIL': '/var/mail/cltbld', 09:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:15 INFO - 'MOZ_NO_REMOTE': '1', 09:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:15 INFO - 'NO_EM_RESTART': '1', 09:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:15 INFO - 'PWD': '/builds/slave/test', 09:00:15 INFO - 'SHELL': '/bin/bash', 09:00:15 INFO - 'SHLVL': '1', 09:00:15 INFO - 'TERM': 'linux', 09:00:15 INFO - 'TMOUT': '86400', 09:00:15 INFO - 'USER': 'cltbld', 09:00:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:15 INFO - '_': '/tools/buildbot/bin/python'} 09:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:15 INFO - Downloading/unpacking psutil>=0.7.1 09:00: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:00: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:00: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:00: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:00: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:00: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:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:17 INFO - Installing collected packages: psutil 09:00:17 INFO - Running setup.py install for psutil 09:00:17 INFO - building 'psutil._psutil_linux' extension 09:00:17 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:00:18 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:00:18 INFO - building 'psutil._psutil_posix' extension 09:00:18 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:00:18 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:00:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:18 INFO - Successfully installed psutil 09:00:18 INFO - Cleaning up... 09:00:18 INFO - Return code: 0 09:00:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:00:18 INFO - Using env: {'DISPLAY': ':0', 09:00:18 INFO - 'HOME': '/home/cltbld', 09:00:18 INFO - 'LANG': 'en_US.UTF-8', 09:00:18 INFO - 'LANGUAGE': 'en_US:en', 09:00:18 INFO - 'LOGNAME': 'cltbld', 09:00:18 INFO - 'MAIL': '/var/mail/cltbld', 09:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:18 INFO - 'MOZ_NO_REMOTE': '1', 09:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:18 INFO - 'NO_EM_RESTART': '1', 09:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:18 INFO - 'PWD': '/builds/slave/test', 09:00:18 INFO - 'SHELL': '/bin/bash', 09:00:18 INFO - 'SHLVL': '1', 09:00:18 INFO - 'TERM': 'linux', 09:00:18 INFO - 'TMOUT': '86400', 09:00:18 INFO - 'USER': 'cltbld', 09:00:18 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:18 INFO - '_': '/tools/buildbot/bin/python'} 09:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00: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:00: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:00: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:00: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:00: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:00: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:00:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:20 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:00:20 INFO - Installing collected packages: mozsystemmonitor 09:00:20 INFO - Running setup.py install for mozsystemmonitor 09:00:20 INFO - Successfully installed mozsystemmonitor 09:00:20 INFO - Cleaning up... 09:00:20 INFO - Return code: 0 09:00:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:20 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:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:20 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:00:20 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:00:20 INFO - Using env: {'DISPLAY': ':0', 09:00:20 INFO - 'HOME': '/home/cltbld', 09:00:20 INFO - 'LANG': 'en_US.UTF-8', 09:00:20 INFO - 'LANGUAGE': 'en_US:en', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MAIL': '/var/mail/cltbld', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SHLVL': '1', 09:00:20 INFO - 'TERM': 'linux', 09:00:20 INFO - 'TMOUT': '86400', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:22 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:00:22 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:00:22 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:00:22 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:00:22 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:00:22 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:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:23 INFO - Downloading docopt-0.6.1.tar.gz 09:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:23 INFO - Running setup.py install for blobuploader 09:00:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:23 INFO - Running setup.py install for requests 09:00:24 INFO - Running setup.py install for docopt 09:00:24 INFO - Successfully installed blobuploader requests docopt 09:00:24 INFO - Cleaning up... 09:00:24 INFO - Return code: 0 09:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:24 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:00:24 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:00:24 INFO - Using env: {'DISPLAY': ':0', 09:00:24 INFO - 'HOME': '/home/cltbld', 09:00:24 INFO - 'LANG': 'en_US.UTF-8', 09:00:24 INFO - 'LANGUAGE': 'en_US:en', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MAIL': '/var/mail/cltbld', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SHLVL': '1', 09:00:24 INFO - 'TERM': 'linux', 09:00:24 INFO - 'TMOUT': '86400', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - '_': '/tools/buildbot/bin/python'} 09:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:24 INFO - Running setup.py (path:/tmp/pip-jyuCXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 INFO - Running setup.py (path:/tmp/pip-Z2LTi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 INFO - Running setup.py (path:/tmp/pip-vtYFpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 INFO - Running setup.py (path:/tmp/pip-MT5WQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:24 INFO - Running setup.py (path:/tmp/pip-M5mJaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 INFO - Running setup.py (path:/tmp/pip-JLNTj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 INFO - Running setup.py (path:/tmp/pip-YUTimo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:24 INFO - Running setup.py (path:/tmp/pip-EIytqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:25 INFO - Running setup.py (path:/tmp/pip-wyxJs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:25 INFO - Running setup.py (path:/tmp/pip-L4db6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 INFO - Running setup.py (path:/tmp/pip-6fgxtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:25 INFO - Running setup.py (path:/tmp/pip-9BOkev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:25 INFO - Running setup.py (path:/tmp/pip-PVIL2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:25 INFO - Running setup.py (path:/tmp/pip-LOUJRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 INFO - Running setup.py (path:/tmp/pip-IHYozz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:25 INFO - Running setup.py (path:/tmp/pip-qj9Hq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:25 INFO - Running setup.py (path:/tmp/pip-4e6FaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:25 INFO - Running setup.py install for manifestparser 09:00:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Running setup.py install for mozcrash 09:00:25 INFO - Running setup.py install for mozdebug 09:00:25 INFO - Running setup.py install for mozdevice 09:00:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozfile 09:00:26 INFO - Running setup.py install for mozhttpd 09:00:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozinfo 09:00:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozInstall 09:00:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozleak 09:00:26 INFO - Running setup.py install for mozlog 09:00:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for moznetwork 09:00:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozprocess 09:00:26 INFO - Running setup.py install for mozprofile 09:00:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:27 INFO - Running setup.py install for mozrunner 09:00:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:27 INFO - Running setup.py install for mozscreenshot 09:00:27 INFO - Running setup.py install for moztest 09:00:27 INFO - Running setup.py install for mozversion 09:00:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:27 INFO - Cleaning up... 09:00:27 INFO - Return code: 0 09:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:27 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:00:27 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:00:27 INFO - Using env: {'DISPLAY': ':0', 09:00:27 INFO - 'HOME': '/home/cltbld', 09:00:27 INFO - 'LANG': 'en_US.UTF-8', 09:00:27 INFO - 'LANGUAGE': 'en_US:en', 09:00:27 INFO - 'LOGNAME': 'cltbld', 09:00:27 INFO - 'MAIL': '/var/mail/cltbld', 09:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:27 INFO - 'MOZ_NO_REMOTE': '1', 09:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:27 INFO - 'NO_EM_RESTART': '1', 09:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:27 INFO - 'PWD': '/builds/slave/test', 09:00:27 INFO - 'SHELL': '/bin/bash', 09:00:27 INFO - 'SHLVL': '1', 09:00:27 INFO - 'TERM': 'linux', 09:00:27 INFO - 'TMOUT': '86400', 09:00:27 INFO - 'USER': 'cltbld', 09:00:27 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:27 INFO - '_': '/tools/buildbot/bin/python'} 09:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:27 INFO - Running setup.py (path:/tmp/pip-Oq4qC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:27 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:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:27 INFO - Running setup.py (path:/tmp/pip-1ds4Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:27 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:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:27 INFO - Running setup.py (path:/tmp/pip-piE1dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:27 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:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:27 INFO - Running setup.py (path:/tmp/pip-VlgfIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:27 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:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:27 INFO - Running setup.py (path:/tmp/pip-sgtnYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:28 INFO - Running setup.py (path:/tmp/pip-BaFtA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:28 INFO - Running setup.py (path:/tmp/pip-CE8QW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:28 INFO - Running setup.py (path:/tmp/pip-l_qKBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:28 INFO - Running setup.py (path:/tmp/pip-tOuqld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:28 INFO - Running setup.py (path:/tmp/pip-b9BjzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:28 INFO - Running setup.py (path:/tmp/pip-uVtZQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:28 INFO - Running setup.py (path:/tmp/pip-iQ5YNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:28 INFO - Running setup.py (path:/tmp/pip-_XFutr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:28 INFO - Running setup.py (path:/tmp/pip-xIKrW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 INFO - Running setup.py (path:/tmp/pip-2G2IIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:28 INFO - Running setup.py (path:/tmp/pip-SQmB0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:28 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:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:28 INFO - Running setup.py (path:/tmp/pip-CA94PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:28 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:00:28 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:00:28 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:00:28 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:00:28 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:00:28 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:00:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00: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:00: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:00: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:00: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:00: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:00: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:00:30 INFO - Downloading blessings-1.6.tar.gz 09:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:30 INFO - Installing collected packages: blessings 09:00:30 INFO - Running setup.py install for blessings 09:00:30 INFO - Successfully installed blessings 09:00:30 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:31 INFO - Reading from file tmpfile_stdout 09:00:31 INFO - Current package versions: 09:00:31 INFO - argparse == 1.2.1 09:00:31 INFO - blessings == 1.6 09:00:31 INFO - blobuploader == 1.2.4 09:00:31 INFO - docopt == 0.6.1 09:00:31 INFO - manifestparser == 1.1 09:00:31 INFO - mozInstall == 1.12 09:00:31 INFO - mozcrash == 0.17 09:00:31 INFO - mozdebug == 0.1 09:00:31 INFO - mozdevice == 0.48 09:00:31 INFO - mozfile == 1.2 09:00:31 INFO - mozhttpd == 0.7 09:00:31 INFO - mozinfo == 0.9 09:00:31 INFO - mozleak == 0.1 09:00:31 INFO - mozlog == 3.1 09:00:31 INFO - moznetwork == 0.27 09:00:31 INFO - mozprocess == 0.22 09:00:31 INFO - mozprofile == 0.28 09:00:31 INFO - mozrunner == 6.11 09:00:31 INFO - mozscreenshot == 0.1 09:00:31 INFO - mozsystemmonitor == 0.0 09:00:31 INFO - moztest == 0.7 09:00:31 INFO - mozversion == 1.4 09:00:31 INFO - psutil == 3.1.1 09:00:31 INFO - requests == 1.2.3 09:00:31 INFO - wsgiref == 0.1.2 09:00:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:31 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:00:31 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:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 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:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00: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:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00: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', 'jsonschema'] in /builds/slave/test/build 09:00: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 jsonschema 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Downloading/unpacking jsonschema 09:00: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:00: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:00: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:00: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:00: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:00: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:00:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:33 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00: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:00: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:00: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:00: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:00: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:00: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:00:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:33 INFO - warning: no files found matching '*.txt' 09:00:33 INFO - no previously-included directories found matching 'build' 09:00:33 INFO - no previously-included directories found matching 'dist' 09:00:33 INFO - no previously-included directories found matching '.git*' 09:00:33 INFO - Installing collected packages: jsonschema, functools32 09:00:33 INFO - Running setup.py install for functools32 09:00:33 INFO - warning: no files found matching '*.txt' 09:00:33 INFO - no previously-included directories found matching 'build' 09:00:33 INFO - no previously-included directories found matching 'dist' 09:00:33 INFO - no previously-included directories found matching '.git*' 09:00:34 INFO - Successfully installed jsonschema functools32 09:00:34 INFO - Cleaning up... 09:00:34 INFO - Return code: 0 09:00:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:34 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:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:34 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:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:34 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 0x7fc673c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13315a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', '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:00:34 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:00:34 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:00:34 INFO - Using env: {'DISPLAY': ':0', 09:00:34 INFO - 'HOME': '/home/cltbld', 09:00:34 INFO - 'LANG': 'en_US.UTF-8', 09:00:34 INFO - 'LANGUAGE': 'en_US:en', 09:00:34 INFO - 'LOGNAME': 'cltbld', 09:00:34 INFO - 'MAIL': '/var/mail/cltbld', 09:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:34 INFO - 'MOZ_NO_REMOTE': '1', 09:00:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:34 INFO - 'NO_EM_RESTART': '1', 09:00:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:34 INFO - 'PWD': '/builds/slave/test', 09:00:34 INFO - 'SHELL': '/bin/bash', 09:00:34 INFO - 'SHLVL': '1', 09:00:34 INFO - 'TERM': 'linux', 09:00:34 INFO - 'TMOUT': '86400', 09:00:34 INFO - 'USER': 'cltbld', 09:00:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:34 INFO - '_': '/tools/buildbot/bin/python'} 09:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:34 INFO - Downloading/unpacking flake8 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:36 INFO - no previously-included directories found matching 'docs/_build' 09:00:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:36 INFO - Running setup.py install for pep8 09:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:36 INFO - no previously-included directories found matching 'docs/_build' 09:00:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:37 INFO - Cleaning up... 09:00:37 INFO - Return code: 0 09:00:37 INFO - Running post-action listener: _resource_record_post_action 09:00:37 INFO - Running post-action listener: _start_resource_monitoring 09:00:37 INFO - Starting resource monitoring. 09:00:37 INFO - ##### 09:00:37 INFO - ##### Running install step. 09:00:37 INFO - ##### 09:00:37 INFO - Running pre-action listener: _resource_record_pre_action 09:00:37 INFO - Running main action method: install 09:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:37 INFO - Reading from file tmpfile_stdout 09:00:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:37 INFO - Reading from file tmpfile_stdout 09:00:37 INFO - Output received: 09:00:37 INFO - Usage: mozinstall [options] installer 09:00:37 INFO - Options: 09:00:37 INFO - -h, --help show this help message and exit 09:00:37 INFO - -d DEST, --destination=DEST 09:00:37 INFO - Directory to install application into. [default: 09:00:37 INFO - "/builds/slave/test"] 09:00:37 INFO - --app=APP Application being installed. [default: firefox] 09:00:37 INFO - mkdir: /builds/slave/test/build/application 09:00:37 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:00:37 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:00:53 INFO - Reading from file tmpfile_stdout 09:00:53 INFO - Output received: 09:00:53 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:53 INFO - Running post-action listener: _resource_record_post_action 09:00:53 INFO - ##### 09:00:53 INFO - ##### Running run-tests step. 09:00:53 INFO - ##### 09:00:53 INFO - Running pre-action listener: _resource_record_pre_action 09:00:53 INFO - Running main action method: run_tests 09:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:53 INFO - Python 2.7.3 09:00:53 INFO - Return code: 0 09:00:53 INFO - grabbing minidump binary from tooltool 09:00:53 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:00:53 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 0x13c0410>, '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 0x13c1310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c17a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:53 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:00:53 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:00:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:53 INFO - Return code: 0 09:00:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:54 INFO - Return code: 0 09:00:54 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/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:00:54 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/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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:00:54 INFO - Using env: {'DISPLAY': ':0', 09:00:54 INFO - 'HOME': '/home/cltbld', 09:00:54 INFO - 'LANG': 'en_US.UTF-8', 09:00:54 INFO - 'LANGUAGE': 'en_US:en', 09:00:54 INFO - 'LOGNAME': 'cltbld', 09:00:54 INFO - 'MAIL': '/var/mail/cltbld', 09:00:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:54 INFO - 'MOZ_NO_REMOTE': '1', 09:00:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:54 INFO - 'NO_EM_RESTART': '1', 09:00:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:54 INFO - 'PWD': '/builds/slave/test', 09:00:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:54 INFO - 'SHELL': '/bin/bash', 09:00:54 INFO - 'SHLVL': '1', 09:00:54 INFO - 'TERM': 'linux', 09:00:54 INFO - 'TMOUT': '86400', 09:00:54 INFO - 'USER': 'cltbld', 09:00:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774', 09:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:54 INFO - '_': '/tools/buildbot/bin/python'} 09:00:54 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/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:00:54 INFO - mozversion application_buildid: 20160426062558 09:00:54 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 09:00:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:00:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:54 INFO - mozversion application_name: Firefox 09:00:54 INFO - mozversion application_remotingname: firefox-dev 09:00:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:00:54 INFO - mozversion application_vendor: Mozilla 09:00:54 INFO - mozversion application_version: 48.0a2 09:00:54 INFO - mozversion platform_buildid: 20160426062558 09:00:54 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 09:00:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:00:54 INFO - mozversion platform_version: 48.0a2 09:00:54 INFO - using testdate: 1461686454 09:00:54 INFO - actual date: 1461686454 09:00:54 INFO - starting webserver on 'localhost:55848' 09:00:54 INFO - SUITE-START | Running 2 tests 09:00:54 INFO - TEST-START | dromaeo_css 09:00:54 INFO - operating with platform_type : linux_ 09:00:54 INFO - Initialising browser for dromaeo_css test... 09:00:54 INFO - TEST-INFO | started process 19968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Z6u0y/profile http://localhost:55848/getInfo.html) 09:00:58 INFO - PROCESS | 19968 | __metrics Screen width/height:1600/1200 09:00:58 INFO - PROCESS | 19968 | colorDepth:24 09:00:58 INFO - PROCESS | 19968 | Browser inner width/height: 1024/697 09:00:58 INFO - PROCESS | 19968 | __metrics 09:00:59 ERROR - PROCESS | 19968 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:01:00 INFO - TEST-INFO | 19968: exit 0 09:01:00 INFO - Browser initialized. 09:01:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:00 INFO - TEST-INFO | started process 20057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Z6u0y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:12 INFO - PROCESS | 20057 | RSS: Main: 142819328 09:01:12 INFO - PROCESS | 20057 | 09:02:03 INFO - PROCESS | 20057 | 1461686523739 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:04:10 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55848/tests/dromaeo/cssquery-ext.html) 09:04:11 INFO - PROCESS | 20057 | RSS: Main: 253112320 09:04:11 INFO - PROCESS | 20057 | 09:07:14 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-ext.html (next: http://localhost:55848/tests/dromaeo/cssquery-jquery.html) 09:07:14 INFO - PROCESS | 20057 | RSS: Main: 219287552 09:07:14 INFO - PROCESS | 20057 | 09:10:16 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55848/tests/dromaeo/cssquery-mootools.html) 09:10:17 INFO - PROCESS | 20057 | RSS: Main: 222232576 09:10:17 INFO - PROCESS | 20057 | 09:13:19 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55848/tests/dromaeo/cssquery-prototype.html) 09:13:19 INFO - PROCESS | 20057 | RSS: Main: 219942912 09:13:19 INFO - PROCESS | 20057 | 09:16:22 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55848/tests/dromaeo/cssquery-yui.html) 09:16:22 INFO - PROCESS | 20057 | RSS: Main: 210481152 09:16:22 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-yui.html (next: http://localhost:55848/tests/dromaeo/cssquery-dojo.html) 09:19:25 INFO - PROCESS | 20057 | RSS: Main: 258822144 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | __start_tp_report 09:19:25 INFO - PROCESS | 20057 | _x_x_mozilla_page_load 09:19:25 INFO - PROCESS | 20057 | _x_x_mozilla_page_load_details 09:19:25 INFO - PROCESS | 20057 | |i|pagename|runs| 09:19:25 INFO - PROCESS | 20057 | |0;dojo.html;3293.5718356613643;3345.866165353386;3353.9496907546377;3360.176756694612;3401.4217583010886;5556.666600003998;5575.69333686647;5590.888182236358;5594.160875868612;5597.524210442118;5553.444655534445;5574.526165275956;5575.135853942636;5579.163125531172;5596.552275817935;4887.535684110004;5052.444231134581;5059.569936555397;5117.116042312892;5175.171972484403;4937.185364414867;5019.14674505335;5066.771995260204;5129.487051294878;5133.30700355451;5124.564412024983;5142;5167.560757335612;5174.146265866128;5175.171972484403;5102.056119617882;5105.774614092591;5157.638965272414;5172.88782911672;5189.065968125755;4939.53074457926;4948.1093403187415;5065.11360511909;5071.974640126776;5100.5409513143995;5465.234867118606;5487.972560137174;5489.945100548984;5506.944930550723;5509.311336082989;5684.488396044376;5952.166696662471;5956.910646340265;5962.970185149046;5964.314103878066;6020.157177995085;6062.333143354227;6074.48366888815;6101.481374083208;6106.2061931948565;5630.40880707528;5631.296087988961;5648.01159797038;5660.179274005287;5686.09022556389;5916.112583112567;6042.335343112254;6102.603330783485;6122.112293717386;6153.077038444179;5865.648061116347;5873.353931067579;5893.5579831512805;5901.262342207224;5916.319623243296;5942;5953.523718102541;5957.851053723605;5963.612365196248;5964.075568286922;5739.598228123991;5910.379410161957;5929.407059294036;5946.316173640001;5968.253968253968;5972.104184372292;6003.549733769984;6007.729652165663;6008.489278411384;6011.639301641828;4557.339185818038;4604.723716576949;4608.677392582487;4613.700109492872;4643.582077613031;3987.5813039631003;4087.5708050654252;4088.2436749201493;4114.6091121343425;4154.937675934803;5469;5547.639403438763;5550.27846379968;5553.77784888608;5560.165975103686;5882.55880808941;5904.202932604129;5904.822855314347;5907.49786268172;5910.828025477707;5368.973155134199;5543.805966791143;5572.749226284827;5630.887382252332;5691.402402747734;5176.06830770465;5196.064708352457;5197.792088316425;5204.245384419238;5207.947920520822;5903.940960590339;6023.969880150571;6062.696865156725;6079.878402432015;6093.238345206849;5989.9700501496345;6050.42520960508;6060.272767267956;6085.087236914498;6090.177825993434;5969.582129250911;5980.312264089685;6014.458698717136;6025.035994241076;6034;5758.856028599319;5890.617109887844;5896.14505896634;5898.587098903035;5904.881902361843;5917.733701983335;5972.58191926561;5976.372480889444;5978.910316345171;6016.067509536029;5762.337331206965;5799.594028417971;5801.042827933344;5801.477866991991;5805.796797112081;5534.612577119563;5730.455606717355;5796.04365279741;5797.4782269595935;5818.214541036906;5669.943300566942;5671.773129074791;5671.914921276267;5675.177099320493;5690.971545142248;5779.624324418899;5807.738651760766;5814.389489103583;5814.476697097281;5821.621594596338;5497.587680923867;5665.291838520185;5683.9715801420725;5786.623869448473;5803.39064398249 09:19:25 INFO - PROCESS | 20057 | |1;ext.html;599.9910001349981;663.2472144116427;670.8758879607273;673.6022752787966;674.929132441094;39961.60038399615;41407.58592414075;41460.37809432861;41487.58512414875;41585.79207103964;42178.57821421785;42899.571004289945;42962;42965.7851710741;43021.7848910755;8171.550564718938;8380.120087390827;8622.65509379624;8846.601902914384;10335.948320258389;10455.84316235257;10627.362358258491;10627.36235825853;10629.202809789258;10631.149508039376;10579.523921423515;10712.25014249003;10714.624988125417;10716.73208169798;10719.303245289031;8119.106898241218;10257.43584102874;10487.265891387606;10491.318064325795;10563.524641391155;10271.794564108675;10387.065164135189;10477.528511217013;10479.68560943173;10485.213608979357;43737;44359.33460998088;44454.33318500225;44541.10917781659;44555.33167002498;3866.9226615467815;3880.611938806125;4064.979675101635;4078.5105787305415;4308.526062133163;33780.49329260063;34268.82865585681;34448;34511.6548834511;34518.48222276668;3926.803659817026;3943.3099207638547;3948.4669569608022;3981.6217459341315;3992.0818211811156;353276;358014;359986;361195.9999999974;361376;60733.392666072774;62166.68916655388;62175;62258.37741622526;62301.68849155725;60713;61894.38105618976;61975.690121550004;61995.69002155051;62014;18442.723359149622;18641.161147748382;18669;18676.159572819255;18677.626447470975;12468.189567678071;12893.54872579474;13035.283059431633;13059.412326445332;13070.673233169056;20218.292359767336;20516;20554.794452055587;20824.58350833005;21377.572448551255;7695.730649427243;7760.146383897767;7793.80515487106;7866.331361834308;7985.72049978248;1151.614209239914;1169;1182.9171957963033;1244.3715923458597;1259.9446342619112;32837.83581082127;33184.50223246653;33423.83288083544;35158.64841351605;36231.4565281521;1229.0966139887175;1231.2797013746879;1256.6795467155816;1269.7079671675567;1347.5687779910334;17246.82753172477;17803.10984450798;17848.643027139384;17905.37331193402;18044.90977545104;555216;573824;575769;576132;577046;64546.67726661337;65563.67218163879;65685.67157164184;65713.67143164254;65779.34220657732;1418.4750894028332;1502.301429835132;1583.6674298397465;1594.7767312576248;1623.0342945947143;32259.838700806347;33573.49639755455;34184.82907585546;34204;34722.305553888276;53047.73476132595;54040.45959540354;54621;54643;54769;362028;364307;364475;366542;367303;37390;43359.56640433555;43968.34047489226;45384.77307613441;47508.7624561875;49260;49386.506134938194;50073.24890126724;50518;50849.237261440365;41136.79431602823;41284.79357603193;42139.15721685488;42268;43376;41278.587214128675;41925;42605.14789704251;43461.782691086344;43880.56119438765;42391;42947.35578966256;43125.3531197026;43279.13441731211;43480.565194347655;35935.281294373446;38067.80966095152;38210.617893821815;38276.80861595674;38365.232695346494;28141.437171256868;28620.856895715388;28816.711832881403;29308.56037159487;29355.706442935298 09:19:25 INFO - PROCESS | 20057 | |2;jquery.html;423.7881059470265;434.3853447371969;434.7478462491755;447.1303315052223;454.3843092609516;18947.905260473697;19627.411177664675;19726.112324945378;20508.28221012265;20553.999999999964;19164;19548.804511954877;20028.799712002874;20476.59046819063;20483;5754.65472071675;5876.7355469004;5886.823395298148;5925.259342582177;5936.792212272571;5781.682007489587;5795.652260864341;5851.4148585141575;5903.763849446039;5920.911186332209;5873.030949893263;5887.116932460144;5894;5901.380355062721;5904.645721256738;5726.19833223347;5896.115582662614;5993.850153746148;6014.218151640281;6018.608790428608;5648.632838865502;5812.389699081577;5817.272840894888;5818.679972601505;5828;14594.34325455357;14901.92549037248;14949.327280272413;14951.102933824006;18529.44411667652;2188.2997440818945;2384.141708984764;2490.3525083478426;2690.8520031398266;3073.9077827664983;10338.9483052585;10429.269951103426;10584.153267738562;10939.617113401073;10989.450527473595;1998.7701414337391;2145.463634091477;2193.824494040473;2198.560287942402;2266.7619899910437;64207.67896160536;74100.25899741042;84685;86366;97732;17628.383006594708;18618.720719189223;19021.809781902004;19180.424587262405;19204.903975480032;28842.278943026173;33940.321193576485;34605.480917786255;34833.47749783755;34861.30277394438;9909.157721593601;10008;10099.394036357842;10436.112930400848;10446;8074.555899425471;8456.179170529014;8457.23884850354;8468.407211495136;8836.116388361112;9250.398724082914;9421.24630029596;9442.716718498456;9678.806423871623;9794.461304628172;3600.2017871145667;3676.841794834672;3739.289534988398;3790.3556395412847;3839.30892439358;1056.9101626361692;1076.3810808784824;1086.4079076903106;1124.5726623882872;1140.5950887434842;20007.899960500104;20129;20159.596808063758;20217;20346.89826550858;1055.7255113670346;1096.1340540972542;1111.699841042914;1117.1621284036971;1182.8166634171716;11087.833682494607;11482.311061336346;11492.655220343404;11809;11853.88146118545;474253;474836;482213;484673;500884;55301;69656.303436966;97257.51371243098;98557.50721246492;99259.50370248103;1044.4203467075695;1052.7578656909106;1054.1935419404163;1071.3625392891217;1077.385890042668;17871.553211169823;17979.550511237325;18037;18102.728459073387;18188.454346369632;29595.85202073976;37356.25287494289;37812;40046.59953400429;40470.392944106454;44241.33637995368;46029.309560357295;46846.76576617095;49154;50469.74765126151;8428.873566896624;9180.219681326895;9201.30990175726;9346.299027572823;9467.289953253396;12464.314462704639;12907.677308067374;13089.934550327187;13259.071864969359;13282.53511127106;7648.617569121633;9123.726288211365;10895.782084358427;10909.236353455182;11271.718207044889;11853.466594003117;11855.466504007512;11872.643820685393;12211.633650990485;12224.938875305566;12057.81913271284;12133.939330303292;12190.634280971584;12336;12366.072544559014;9011.369204155646;9126.817463650557;9291.6283348668;9354.906450935403;9416.858747118935;12362.567310144103;12432.191907525981;12445.688857778629;12495.875041249472;12699.746005080031 09:19:25 INFO - PROCESS | 20057 | |3;mootools.html;1391.916485010899;1567.349549936776;1585.714571377152;1764.2237415537163;1907.95230119247;7907.960460197698;8206.466579672324;8244.42289039766;8250.504969701822;8258.83482330353;7920.366370690345;8144.837103257946;8160.755177344674;8174.550399728013;8181.140980197083;2048.160254295739;2324.244620498336;2368.3368656776042;2407.6268178432283;2436.1838784007377;2392.7966122879575;2399.1483023526657;2404.2907342333965;2407.3981504623844;2408.8795560222006;2322.094383190557;2401.519696060786;2404.2426635609813;2405.0860672944254;2405.386626410263;2037.195307853397;2327.325075728037;2390.6772585701056;2393.497365553236;2396.484755777499;2252.1329288223988;2256.131389415087;2261.4007288068674;2263.4567703751145;2294.8393612447135;8219.178082191793;8255.133211012817;8275.213854683796;8298.70954516589;8307.003159620825;5107.770150343244;5270.6574072685535;5272.57819374453;5278.2346559749;5365.409804921455;3328;3447.241606846515;3459.2735525539665;3570.8214589270433;3604.567451905762;1127.0814286356592;1167.9080060143792;1176.9646910592696;1181.3030312115807;1197.8742232065595;11662.825057624143;11663.591774287945;11678.76642467146;11706.946374826306;11713.590024349107;4736.739479328601;4763.428388593391;4798.2802579613335;4817.933307773155;4879.463259041502;9837.80324393508;9862.55618497155;9864.654737084167;9867.901320986843;9878.308518403786;4624.583787459145;5327.440618734977;5337.733113344318;5341.305630268118;5342.973285133549;4764.523547645208;4842.055799119208;4863;4872.415310162733;4876.463589005278;3894.844206231719;3936.5472970608175;3979.4428779970826;4022.1352409232154;4045.8179381927885;1320.5576131995697;1324.1856258401206;1355.9389827457787;1364.5769811358512;1366.077897419238;1085.733995171188;1092.126298960844;1100.9614663486739;1104.4808939798281;1122.1527746551465;5878.882422351529;6011.338752737195;6114.419130182714;6191.318954915046;6203.751849925952;455.58996902787754;462.2557682131765;463.5225717510969;468.2274247491598;469.9342092107108;4278.358246263086;4362.869113926524;4460.665450091256;4471.150481408522;4474.440694913136;9845.261605379625;10206.234532410099;10350.378977261389;10388.272820902615;10414.47927603632;10278.17774578032;10287.639932602473;10289.639862604772;10297.897021029843;10300.124489418484;7623.847523049508;7983.2415920487465;7991.760247192594;7992.24073713008;8004.51972881629;5910.202122713378;6031.819045428644;6065.150878876872;6069.119419787465;6132.41742034506;9389.10803473669;9570.425774453555;9668.951655241679;9688.515574221401;9699.563519641491;9183.724488265363;9643.807123857623;9929.255305851946;9988.05113514215;10000.149987250874;6758.2903795102775;7235.059442272471;7354.191038985814;7422.925770742223;7588.165301816906;7940.523568585903;7961.442699011013;7999.880001800094;8000.079990801132;8024.919750802417;7127.7148914042855;7373;7387.667554960147;7389.408847292311;7533.924660753322;7110.7511237106455;7214.567125972459;7228.746993855189;7251.56490610565;7338.743143989935;6668.866622667617;6732.596044237362;6767;6778.186617605918;6784.457243420416;5209.557187639093;5245.239440281214;5301.125314323093;5368;5506.201600767947;5193.221016847503;5201.271821944855;5262.657927234717;5374.785008599612;5389.083855744454 09:19:25 INFO - PROCESS | 20057 | |4;prototype.html;355.06971734056776;359.8556590043662;362.2808724681507;366.8679275460832;370.4942753141961;5266.710330931797;5483.698396588186;5540.612157149001;5549.694766787825;5565.0817615093465;5320.9467905320935;5415.783368665258;5423.376311724153;5431.809886653968;5438.945610543893;4056.411820286057;4080.8571699990357;4239.618434340909;4265.573442655733;4291.506476755167;4227.534971153171;4228.703990720651;4245.150969806035;4246.617804397619;4249.766262855542;4202.642775364097;4202.8232095013445;4233.6824738144605;4238.936415953764;4239.342901850218;3880.2239552089695;3989.2221016901717;4126.154138401651;4185.727927684706;4242.193983143224;3713.3316003119426;3724.962750372516;3742.831572579213;3744.6255374462608;3765.3222419964395;5210.218467229908;5228.503292187236;5239.21411788231;5239.973800131013;5254.737263136828;4633.189191891405;4873.659743570511;4874.731889746063;4883.487233840467;4885.462599114095;5678.801241956553;5687.744051517691;5702.486776190121;5727.684977326246;5736.913946290809;4164.292070348048;4173.373993900909;4187.078842654611;4188.32986722123;4192.496900371944;5841.824745257649;5922.940770592325;5924.170616113747;5925.822225333247;5966.880662386728;4114.860272658201;4121.876343709693;4139.482564679415;4143.91712165755;4150.232207041707;4206.57934206583;4211.389348544505;4214.473190851144;4214.599613036757;4224.070704445017;4602.654800889946;4736.431628204568;4777.378940737682;4794.568488836021;4814.903701926012;5678.829635110871;5684.801031963944;5698.6580805151825;5729.140628905614;5746.798862039808;1540.2991638804317;1570.685862827439;1572.9134897580514;1573.0090043272894;1573.9291731872092;988.6292640259902;1005.7535903703639;1036.0209601926258;1045.46158728255;1055.646358469921;5332.173513105475;5507.9430915842095;5523.011715782315;5531.3915469298345;5546.972265138648;5864.824055278348;5874.1776151338845;5880.588358814927;5882.617629854046;5900.940990590039;5300.893982120413;5449.700266485302;5476.370217424967;5479.643823151562;5510.586705997066;3337.7162941150273;3346.832658367072;3358.529805827186;3360.428727116396;3365.730741540671;5690.374058853523;6342.587731797418;6346.143270658495;6357.777477788255;6370.6814659267775;5963.314218864886;5978.850528736816;6004.7598096076545;6018.21763170785;6028.969855150696;5923.229980102559;5930.8813823723285;5947.434993675594;5957.553183511167;5961.850953726105;5707.400722924033;5903.468687818117;5911.467967882912;5934.673592952429;5936.762529498772;5858.384869588632;5862.589618726648;5878.559108066826;5878.823635290948;5882.794102206402;5829.417058294137;5895.29256489224;5962.582619216613;5978.641281523122;5983.491403230774;4315.913681726411;4333.848315308949;4380.824767009284;4393.648508119279;4406.735595864258;5833.220867635242;5849.883002339844;5853.795117170878;5859.619124756877;5906.616069955439;5432.511074003359;5611.607187496836;5711.628744131618;5717.113847353667;5743.368229494668;5623.437656234508;5631.1271752878365;5689.6586204827845;5706.971465142814;5712.714364281686;5353.804939974591;5404.162354835007;5611.298587676541;5628.85927851807;5639.605227634026;5720.541261978169;5742.827715168552;5762.798302059407;5769.105788602773;5797.652140871562;5624.1563765433875;5643.209950607001;5647.491725744702;5650.604457687922;5652.915206271991 09:19:25 INFO - PROCESS | 20057 | |5;yui.html;860.5310105992234;882.6469412235107;889.719738282441;892.2861710631494;909.8217807938714;13669.658258543528;13884.23636699981;14056.64858378541;14104.50634227801;14113.364898579563;13812.861871381283;13863.514776982807;13909.652258693537;13915.791263131036;13916;755.5919803306208;891.8751374807532;908.7728067982988;915.6703586708779;919.3748251189189;912.4616476278994;918.1277786103228;919.2278486071698;920.9907900920972;921.5162039929024;974.8878878928907;975.6926568131051;976.9413835169912;977.3451787302489;977.7213494154167;778.6029125146159;920.8020275640736;946.6118891254622;946.6260826973341;947.6967370441462;692.8510370270366;696.7596179318127;697.5117417807555;697.9965100174518;700.4361489001352;13354.732905341838;13401.66495837602;13404.396802143927;13406.061575689706;13426.261555614437;13299.335033248395;13379.86620133796;13615.115017523829;13707.246101464416;14163.291835408188;12042.939785301105;12073;12131.696707582381;12135.575254866126;12279.570215042431;3779;3800.1069748609057;3800.923981520354;3835;4029.174019326031;52105.739471302404;52188.47811521874;52259;52276;52723;10389.688309350731;10735.355878647306;10805.297655652357;10896.073833724222;11110.666679999613;19036.42890713281;19169.904150479157;19502.414927552196;19502.804971950383;19604.313849015216;8018;8072.152423995396;8074.878876816852;8092.595370231465;8237.0527389351;4684.508126646721;4777.283407488904;4874.683145595525;4886.096072226578;4915.582175515121;3420.504026916133;3517.595476520182;3524.4184709523156;3528.68241858234;3532.2405682778326;677.4884961853769;683.6171743823475;698.1866125963196;699.422976044765;701.3722718167314;673.5251647588439;683.5351960666695;736.8452624948713;742.8551432470616;763.5533213070305;9495.287853410884;10140.391576505433;10158.492075396201;10273.178145748323;10408;671.4695390641339;726.2483329753679;764.4152223548989;766.2031487253306;793.3375631347889;4335.978320108379;4373.540778218241;4413.117376524708;4441.200583894865;4511.684182107221;61915.690421548505;67530;69136.308636914;69345.3065469349;69529.30470695229;54291.72854135783;60206;60396;61421.692891535255;62172.68913655403;52899.20651190158;55707.721461393245;55810.162847557316;56351.43648563544;57318.713406432704;9529.571169297536;9542.809143817223;9570.760730981781;9666.516674166123;9695.418274903528;35612;36163.457548137325;36640.26719465649;36758.26483470262;37109.628903711695;45252;47444.7627761859;50352.74823625859;50757.74621127018;51076.74461627668;12420.937895310466;12695.682607934876;12879.742405152032;12892.48430062787;13322;14095.436182552994;14338.928305358406;14603.853961460249;14613.707725845636;14728.189949552878;13114.803277950648;13322.333883305602;13520.459181632625;13553.661158470723;14036.78944815808;12650.177738446971;12965.870341296844;12971.870281297444;13308.534201303295;13387.397567109309;13517.864821352054;13543.187408755506;13594.320283985564;13862.584122476343;14005.649858753613;11099;11141.944290278496;11457.713557161138;11759;12186;11405.828912566152;11481.253718508271;11520.596779112691;11550.075993920635;11656.242344247597 09:19:25 INFO - PROCESS | 20057 | __end_tp_report 09:19:25 INFO - PROCESS | 20057 | __start_cc_report 09:19:25 INFO - PROCESS | 20057 | _x_x_mozilla_cycle_collect,907 09:19:25 INFO - PROCESS | 20057 | __end_cc_report 09:19:25 INFO - PROCESS | 20057 | __startTimestamp1461687565321__endTimestamp 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | ------- Summary: start ------- 09:19:25 INFO - PROCESS | 20057 | Number of tests: 6 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#0] dojo.html Cycles:165 Average:5527.55 Median:5686.09 stddev:598.26 (10.5%) stddev-sans-first:573.85 09:19:25 INFO - PROCESS | 20057 | Values: 3293.6 3345.9 3353.9 3360.2 3401.4 5556.7 5575.7 5590.9 5594.2 5597.5 5553.4 5574.5 5575.1 5579.2 5596.6 4887.5 5052.4 5059.6 5117.1 5175.2 4937.2 5019.1 5066.8 5129.5 5133.3 5124.6 5142.0 5167.6 5174.1 5175.2 5102.1 5105.8 5157.6 5172.9 5189.1 4939.5 4948.1 5065.1 5072.0 5100.5 5465.2 5488.0 5489.9 5506.9 5509.3 5684.5 5952.2 5956.9 5963.0 5964.3 6020.2 6062.3 6074.5 6101.5 6106.2 5630.4 5631.3 5648.0 5660.2 5686.1 5916.1 6042.3 6102.6 6122.1 6153.1 5865.6 5873.4 5893.6 5901.3 5916.3 5942.0 5953.5 5957.9 5963.6 5964.1 5739.6 5910.4 5929.4 5946.3 5968.3 5972.1 6003.5 6007.7 6008.5 6011.6 4557.3 4604.7 4608.7 4613.7 4643.6 3987.6 4087.6 4088.2 4114.6 4154.9 5469.0 5547.6 5550.3 5553.8 5560.2 5882.6 5904.2 5904.8 5907.5 5910.8 5369.0 5543.8 5572.7 5630.9 5691.4 5176.1 5196.1 5197.8 5204.2 5207.9 5903.9 6024.0 6062.7 6079.9 6093.2 5990.0 6050.4 6060.3 6085.1 6090.2 5969.6 5980.3 6014.5 6025.0 6034.0 5758.9 5890.6 5896.1 5898.6 5904.9 5917.7 5972.6 5976.4 5978.9 6016.1 5762.3 5799.6 5801.0 5801.5 5805.8 5534.6 5730.5 5796.0 5797.5 5818.2 5669.9 5671.8 5671.9 5675.2 5691.0 5779.6 5807.7 5814.4 5814.5 5821.6 5497.6 5665.3 5684.0 5786.6 5803.4 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#1] ext.html Cycles:180 Average:61058.86 Median:21116.08 stddev:117267.69 (555.3%) stddev-sans-first:117508.80 09:19:25 INFO - PROCESS | 20057 | Values: 600.0 663.2 670.9 673.6 674.9 39961.6 41407.6 41460.4 41487.6 41585.8 42178.6 42899.6 42962.0 42965.8 43021.8 8171.6 8380.1 8622.7 8846.6 10335.9 10455.8 10627.4 10627.4 10629.2 10631.1 10579.5 10712.3 10714.6 10716.7 10719.3 8119.1 10257.4 10487.3 10491.3 10563.5 10271.8 10387.1 10477.5 10479.7 10485.2 43737.0 44359.3 44454.3 44541.1 44555.3 3866.9 3880.6 4065.0 4078.5 4308.5 33780.5 34268.8 34448.0 34511.7 34518.5 3926.8 3943.3 3948.5 3981.6 3992.1 353276.0 358014.0 359986.0 361196.0 361376.0 60733.4 62166.7 62175.0 62258.4 62301.7 60713.0 61894.4 61975.7 61995.7 62014.0 18442.7 18641.2 18669.0 18676.2 18677.6 12468.2 12893.5 13035.3 13059.4 13070.7 20218.3 20516.0 20554.8 20824.6 21377.6 7695.7 7760.1 7793.8 7866.3 7985.7 1151.6 1169.0 1182.9 1244.4 1259.9 32837.8 33184.5 33423.8 35158.6 36231.5 1229.1 1231.3 1256.7 1269.7 1347.6 17246.8 17803.1 17848.6 17905.4 18044.9 555216.0 573824.0 575769.0 576132.0 577046.0 64546.7 65563.7 65685.7 65713.7 65779.3 1418.5 1502.3 1583.7 1594.8 1623.0 32259.8 33573.5 34184.8 34204.0 34722.3 53047.7 54040.5 54621.0 54643.0 54769.0 362028.0 364307.0 364475.0 366542.0 367303.0 37390.0 43359.6 43968.3 45384.8 47508.8 49260.0 49386.5 50073.2 50518.0 50849.2 41136.8 41284.8 42139.2 42268.0 43376.0 41278.6 41925.0 42605.1 43461.8 43880.6 42391.0 42947.4 43125.4 43279.1 43480.6 35935.3 38067.8 38210.6 38276.8 38365.2 28141.4 28620.9 28816.7 29308.6 29355.7 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#2] jquery.html Cycles:180 Average:29134.79 Median:15766.57 stddev:79420.23 (503.7%) stddev-sans-first:79613.77 09:19:25 INFO - PROCESS | 20057 | Values: 423.8 434.4 434.7 447.1 454.4 18947.9 19627.4 19726.1 20508.3 20554.0 19164.0 19548.8 20028.8 20476.6 20483.0 5754.7 5876.7 5886.8 5925.3 5936.8 5781.7 5795.7 5851.4 5903.8 5920.9 5873.0 5887.1 5894.0 5901.4 5904.6 5726.2 5896.1 5993.9 6014.2 6018.6 5648.6 5812.4 5817.3 5818.7 5828.0 14594.3 14901.9 14949.3 14951.1 18529.4 2188.3 2384.1 2490.4 2690.9 3073.9 10338.9 10429.3 10584.2 10939.6 10989.5 1998.8 2145.5 2193.8 2198.6 2266.8 64207.7 74100.3 84685.0 86366.0 97732.0 17628.4 18618.7 19021.8 19180.4 19204.9 28842.3 33940.3 34605.5 34833.5 34861.3 9909.2 10008.0 10099.4 10436.1 10446.0 8074.6 8456.2 8457.2 8468.4 8836.1 9250.4 9421.2 9442.7 9678.8 9794.5 3600.2 3676.8 3739.3 3790.4 3839.3 1056.9 1076.4 1086.4 1124.6 1140.6 20007.9 20129.0 20159.6 20217.0 20346.9 1055.7 1096.1 1111.7 1117.2 1182.8 11087.8 11482.3 11492.7 11809.0 11853.9 474253.0 474836.0 482213.0 484673.0 500884.0 55301.0 69656.3 97257.5 98557.5 99259.5 1044.4 1052.8 1054.2 1071.4 1077.4 17871.6 17979.6 18037.0 18102.7 18188.5 29595.9 37356.3 37812.0 40046.6 40470.4 44241.3 46029.3 46846.8 49154.0 50469.7 8428.9 9180.2 9201.3 9346.3 9467.3 12464.3 12907.7 13089.9 13259.1 13282.5 7648.6 9123.7 10895.8 10909.2 11271.7 11853.5 11855.5 11872.6 12211.6 12224.9 12057.8 12133.9 12190.6 12336.0 12366.1 9011.4 9126.8 9291.6 9354.9 9416.9 12362.6 12432.2 12445.7 12495.9 12699.7 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#3] mootools.html Cycles:180 Average:5628.01 Median:4993.62 stddev:3069.97 (61.5%) stddev-sans-first:3062.07 09:19:25 INFO - PROCESS | 20057 | Values: 1391.9 1567.3 1585.7 1764.2 1908.0 7908.0 8206.5 8244.4 8250.5 8258.8 7920.4 8144.8 8160.8 8174.6 8181.1 2048.2 2324.2 2368.3 2407.6 2436.2 2392.8 2399.1 2404.3 2407.4 2408.9 2322.1 2401.5 2404.2 2405.1 2405.4 2037.2 2327.3 2390.7 2393.5 2396.5 2252.1 2256.1 2261.4 2263.5 2294.8 8219.2 8255.1 8275.2 8298.7 8307.0 5107.8 5270.7 5272.6 5278.2 5365.4 3328.0 3447.2 3459.3 3570.8 3604.6 1127.1 1167.9 1177.0 1181.3 1197.9 11662.8 11663.6 11678.8 11706.9 11713.6 4736.7 4763.4 4798.3 4817.9 4879.5 9837.8 9862.6 9864.7 9867.9 9878.3 4624.6 5327.4 5337.7 5341.3 5343.0 4764.5 4842.1 4863.0 4872.4 4876.5 3894.8 3936.5 3979.4 4022.1 4045.8 1320.6 1324.2 1355.9 1364.6 1366.1 1085.7 1092.1 1101.0 1104.5 1122.2 5878.9 6011.3 6114.4 6191.3 6203.8 455.6 462.3 463.5 468.2 469.9 4278.4 4362.9 4460.7 4471.2 4474.4 9845.3 10206.2 10350.4 10388.3 10414.5 10278.2 10287.6 10289.6 10297.9 10300.1 7623.8 7983.2 7991.8 7992.2 8004.5 5910.2 6031.8 6065.2 6069.1 6132.4 9389.1 9570.4 9669.0 9688.5 9699.6 9183.7 9643.8 9929.3 9988.1 10000.1 6758.3 7235.1 7354.2 7422.9 7588.2 7940.5 7961.4 7999.9 8000.1 8024.9 7127.7 7373.0 7387.7 7389.4 7533.9 7110.8 7214.6 7228.7 7251.6 7338.7 6668.9 6732.6 6767.0 6778.2 6784.5 5209.6 5245.2 5301.1 5368.0 5506.2 5193.2 5201.3 5262.7 5374.8 5389.1 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#4] prototype.html Cycles:180 Average:4818.60 Median:5463.04 stddev:1395.92 (25.6%) stddev-sans-first:1359.04 09:19:25 INFO - PROCESS | 20057 | Values: 355.1 359.9 362.3 366.9 370.5 5266.7 5483.7 5540.6 5549.7 5565.1 5320.9 5415.8 5423.4 5431.8 5438.9 4056.4 4080.9 4239.6 4265.6 4291.5 4227.5 4228.7 4245.2 4246.6 4249.8 4202.6 4202.8 4233.7 4238.9 4239.3 3880.2 3989.2 4126.2 4185.7 4242.2 3713.3 3725.0 3742.8 3744.6 3765.3 5210.2 5228.5 5239.2 5240.0 5254.7 4633.2 4873.7 4874.7 4883.5 4885.5 5678.8 5687.7 5702.5 5727.7 5736.9 4164.3 4173.4 4187.1 4188.3 4192.5 5841.8 5922.9 5924.2 5925.8 5966.9 4114.9 4121.9 4139.5 4143.9 4150.2 4206.6 4211.4 4214.5 4214.6 4224.1 4602.7 4736.4 4777.4 4794.6 4814.9 5678.8 5684.8 5698.7 5729.1 5746.8 1540.3 1570.7 1572.9 1573.0 1573.9 988.6 1005.8 1036.0 1045.5 1055.6 5332.2 5507.9 5523.0 5531.4 5547.0 5864.8 5874.2 5880.6 5882.6 5900.9 5300.9 5449.7 5476.4 5479.6 5510.6 3337.7 3346.8 3358.5 3360.4 3365.7 5690.4 6342.6 6346.1 6357.8 6370.7 5963.3 5978.9 6004.8 6018.2 6029.0 5923.2 5930.9 5947.4 5957.6 5961.9 5707.4 5903.5 5911.5 5934.7 5936.8 5858.4 5862.6 5878.6 5878.8 5882.8 5829.4 5895.3 5962.6 5978.6 5983.5 4315.9 4333.8 4380.8 4393.6 4406.7 5833.2 5849.9 5853.8 5859.6 5906.6 5432.5 5611.6 5711.6 5717.1 5743.4 5623.4 5631.1 5689.7 5707.0 5712.7 5353.8 5404.2 5611.3 5628.9 5639.6 5720.5 5742.8 5762.8 5769.1 5797.7 5624.2 5643.2 5647.5 5650.6 5652.9 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - PROCESS | 20057 | [#5] yui.html Cycles:180 Average:15560.23 Median:4393.33 stddev:18226.61 (414.9%) stddev-sans-first:18244.31 09:19:25 INFO - PROCESS | 20057 | Values: 860.5 882.6 889.7 892.3 909.8 13669.7 13884.2 14056.6 14104.5 14113.4 13812.9 13863.5 13909.7 13915.8 13916.0 755.6 891.9 908.8 915.7 919.4 912.5 918.1 919.2 921.0 921.5 974.9 975.7 976.9 977.3 977.7 778.6 920.8 946.6 946.6 947.7 692.9 696.8 697.5 698.0 700.4 13354.7 13401.7 13404.4 13406.1 13426.3 13299.3 13379.9 13615.1 13707.2 14163.3 12042.9 12073.0 12131.7 12135.6 12279.6 3779.0 3800.1 3800.9 3835.0 4029.2 52105.7 52188.5 52259.0 52276.0 52723.0 10389.7 10735.4 10805.3 10896.1 11110.7 19036.4 19169.9 19502.4 19502.8 19604.3 8018.0 8072.2 8074.9 8092.6 8237.1 4684.5 4777.3 4874.7 4886.1 4915.6 3420.5 3517.6 3524.4 3528.7 3532.2 677.5 683.6 698.2 699.4 701.4 673.5 683.5 736.8 742.9 763.6 9495.3 10140.4 10158.5 10273.2 10408.0 671.5 726.2 764.4 766.2 793.3 4336.0 4373.5 4413.1 4441.2 4511.7 61915.7 67530.0 69136.3 69345.3 69529.3 54291.7 60206.0 60396.0 61421.7 62172.7 52899.2 55707.7 55810.2 56351.4 57318.7 9529.6 9542.8 9570.8 9666.5 9695.4 35612.0 36163.5 36640.3 36758.3 37109.6 45252.0 47444.8 50352.7 50757.7 51076.7 12420.9 12695.7 12879.7 12892.5 13322.0 14095.4 14338.9 14603.9 14613.7 14728.2 13114.8 13322.3 13520.5 13553.7 14036.8 12650.2 12965.9 12971.9 13308.5 13387.4 13517.9 13543.2 13594.3 13862.6 14005.6 11099.0 11141.9 11457.7 11759.0 12186.0 11405.8 11481.3 11520.6 11550.1 11656.2 09:19:25 INFO - PROCESS | 20057 | -------- Summary: end -------- 09:19:25 INFO - PROCESS | 20057 | 09:19:25 INFO - TEST-INFO | 20057: exit 0 09:19:25 INFO - TEST-OK | dromaeo_css | took 1111782ms 09:19:25 INFO - TEST-START | kraken 09:19:25 INFO - operating with platform_type : linux_ 09:19:25 INFO - Initialising browser for kraken test... 09:19:25 INFO - TEST-INFO | started process 20251 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptxMu1G/profile http://localhost:55848/getInfo.html) 09:19:29 INFO - PROCESS | 20251 | __metrics Screen width/height:1600/1200 09:19:29 INFO - PROCESS | 20251 | colorDepth:24 09:19:29 INFO - PROCESS | 20251 | Browser inner width/height: 1024/697 09:19:29 INFO - PROCESS | 20251 | __metrics 09:19:30 ERROR - PROCESS | 20251 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:19:31 INFO - TEST-INFO | 20251: exit 0 09:19:31 INFO - Browser initialized. 09:19:31 INFO - Running cycle 1/1 for kraken test... 09:19:31 INFO - TEST-INFO | started process 20343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptxMu1G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:43 INFO - PROCESS | 20343 | RSS: Main: 142082048 09:19:43 INFO - PROCESS | 20343 | 09:19:45 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:46 INFO - PROCESS | 20343 | RSS: Main: 190681088 09:19:46 INFO - PROCESS | 20343 | 09:19:49 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-dft) 09:19:49 INFO - PROCESS | 20343 | RSS: Main: 184406016 09:19:49 INFO - PROCESS | 20343 | 09:19:52 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-fft) 09:19:53 INFO - PROCESS | 20343 | RSS: Main: 273534976 09:19:53 INFO - PROCESS | 20343 | 09:19:55 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-oscillator) 09:19:55 INFO - PROCESS | 20343 | RSS: Main: 181690368 09:19:55 INFO - PROCESS | 20343 | 09:19:58 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:58 INFO - PROCESS | 20343 | RSS: Main: 180695040 09:19:58 INFO - PROCESS | 20343 | 09:20:01 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:02 INFO - PROCESS | 20343 | RSS: Main: 216186880 09:20:02 INFO - PROCESS | 20343 | 09:20:05 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:06 INFO - PROCESS | 20343 | RSS: Main: 214523904 09:20:06 INFO - PROCESS | 20343 | 09:20:08 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55848/tests/kraken/driver.html?testName=json-parse-financial) 09:20:09 INFO - PROCESS | 20343 | RSS: Main: 214192128 09:20:09 INFO - PROCESS | 20343 | 09:20:11 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55848/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:11 INFO - PROCESS | 20343 | RSS: Main: 194412544 09:20:11 INFO - PROCESS | 20343 | 09:20:13 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:14 INFO - PROCESS | 20343 | RSS: Main: 195579904 09:20:14 INFO - PROCESS | 20343 | 09:20:15 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:16 INFO - PROCESS | 20343 | RSS: Main: 207683584 09:20:16 INFO - PROCESS | 20343 | 09:20:19 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:19 INFO - PROCESS | 20343 | RSS: Main: 215445504 09:20:19 INFO - PROCESS | 20343 | 09:20:22 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:22 INFO - PROCESS | 20343 | RSS: Main: 213155840 09:20:22 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55848/tests/kraken/driver.html?testName=ai-astar) 09:20:24 INFO - PROCESS | 20343 | RSS: Main: 277131264 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | __start_tp_report 09:20:24 INFO - PROCESS | 20343 | _x_x_mozilla_page_load 09:20:24 INFO - PROCESS | 20343 | _x_x_mozilla_page_load_details 09:20:24 INFO - PROCESS | 20343 | |i|pagename|runs| 09:20:24 INFO - PROCESS | 20343 | |0;ai-astar;109;109;105;110;111;112;99;110;110;110 09:20:24 INFO - PROCESS | 20343 | |1;audio-beat-detection;151;126;156;181;132;158;152;128;186;126 09:20:24 INFO - PROCESS | 20343 | |2;audio-dft;164;162;161;161;159;160;161;165;162;161 09:20:24 INFO - PROCESS | 20343 | |3;audio-fft;78;78;76;170;79;77;74;77;166;76 09:20:24 INFO - PROCESS | 20343 | |4;audio-oscillator;92;93;156;93;93;93;153;93;93;147 09:20:24 INFO - PROCESS | 20343 | |5;imaging-gaussian-blur;117;116;117;116;117;117;116;116;116;116 09:20:24 INFO - PROCESS | 20343 | |6;imaging-darkroom;145;146;146;146;145;145;146;145;144;145 09:20:24 INFO - PROCESS | 20343 | |7;imaging-desaturate;95;95;94;95;95;96;95;95;95;95 09:20:24 INFO - PROCESS | 20343 | |8;json-parse-financial;57;56;55;55;55;53;56;76;54;53 09:20:24 INFO - PROCESS | 20343 | |9;json-stringify-tinderbox;58;57;57;57;57;57;57;58;57;58 09:20:24 INFO - PROCESS | 20343 | |10;stanford-crypto-aes;73;73;73;73;74;74;80;73;74;74 09:20:24 INFO - PROCESS | 20343 | |11;stanford-crypto-ccm;118;118;115;114;120;116;116;116;149;118 09:20:24 INFO - PROCESS | 20343 | |12;stanford-crypto-pbkdf2;160;160;159;160;168;168;162;162;162;160 09:20:24 INFO - PROCESS | 20343 | |13;stanford-crypto-sha256-iterative;67;66;68;67;67;67;67;66;72;66 09:20:24 INFO - PROCESS | 20343 | __end_tp_report 09:20:24 INFO - PROCESS | 20343 | __start_cc_report 09:20:24 INFO - PROCESS | 20343 | _x_x_mozilla_cycle_collect,1605 09:20:24 INFO - PROCESS | 20343 | __end_cc_report 09:20:24 INFO - PROCESS | 20343 | __startTimestamp1461687624900__endTimestamp 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | ------- Summary: start ------- 09:20:24 INFO - PROCESS | 20343 | Number of tests: 14 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.81 (3.5%) stddev-sans-first:4.03 09:20:24 INFO - PROCESS | 20343 | Values: 109.0 109.0 105.0 110.0 111.0 112.0 99.0 110.0 110.0 110.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#1] audio-beat-detection Cycles:10 Average:149.60 Median:154.00 stddev:21.91 (14.2%) stddev-sans-first:23.23 09:20:24 INFO - PROCESS | 20343 | Values: 151.0 126.0 156.0 181.0 132.0 158.0 152.0 128.0 186.0 126.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.78 (1.1%) stddev-sans-first:1.66 09:20:24 INFO - PROCESS | 20343 | Values: 164.0 162.0 161.0 161.0 159.0 160.0 161.0 165.0 162.0 161.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#3] audio-fft Cycles:10 Average:95.10 Median:77.00 stddev:38.46 (49.9%) stddev-sans-first:40.29 09:20:24 INFO - PROCESS | 20343 | Values: 78.0 78.0 76.0 170.0 79.0 77.0 74.0 77.0 166.0 76.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#4] audio-oscillator Cycles:10 Average:110.60 Median:93.00 stddev:28.65 (30.8%) stddev-sans-first:29.59 09:20:24 INFO - PROCESS | 20343 | Values: 92.0 93.0 156.0 93.0 93.0 93.0 153.0 93.0 93.0 147.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:20:24 INFO - PROCESS | 20343 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 116.0 116.0 116.0 116.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:20:24 INFO - PROCESS | 20343 | Values: 145.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 144.0 145.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:20:24 INFO - PROCESS | 20343 | Values: 95.0 95.0 94.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:55.50 stddev:6.80 (12.2%) stddev-sans-first:7.21 09:20:24 INFO - PROCESS | 20343 | Values: 57.0 56.0 55.0 55.0 55.0 53.0 56.0 76.0 54.0 53.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#9] json-stringify-tinderbox Cycles:10 Average:57.30 Median:57.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 09:20:24 INFO - PROCESS | 20343 | Values: 58.0 57.0 57.0 57.0 57.0 57.0 57.0 58.0 57.0 58.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:74.00 stddev:2.13 (2.9%) stddev-sans-first:2.22 09:20:24 INFO - PROCESS | 20343 | Values: 73.0 73.0 73.0 73.0 74.0 74.0 80.0 73.0 74.0 74.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#11] stanford-crypto-ccm Cycles:10 Average:120.00 Median:118.00 stddev:10.34 (8.8%) stddev-sans-first:10.94 09:20:24 INFO - PROCESS | 20343 | Values: 118.0 118.0 115.0 114.0 120.0 116.0 116.0 116.0 149.0 118.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.00 stddev:3.28 (2.0%) stddev-sans-first:3.39 09:20:24 INFO - PROCESS | 20343 | Values: 160.0 160.0 159.0 160.0 168.0 168.0 162.0 162.0 162.0 160.0 09:20:24 INFO - PROCESS | 20343 | 09:20:24 INFO - PROCESS | 20343 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.77 (2.6%) stddev-sans-first:1.87 09:20:24 INFO - PROCESS | 20343 | Values: 67.0 66.0 68.0 67.0 67.0 67.0 67.0 66.0 72.0 66.0 09:20:24 INFO - PROCESS | 20343 | -------- Summary: end -------- 09:20:24 INFO - PROCESS | 20343 | 09:20:25 INFO - TEST-INFO | 20343: exit 0 09:20:25 INFO - TEST-OK | kraken | took 59901ms 09:20:25 INFO - SUITE-END | took 1171s 09:20:26 INFO - Completed test suite (00:19:31) 09:20:26 INFO - javascript benchmark 09:20:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.5718356613643, 3345.866165353386, 3353.9496907546377, 3360.176756694612, 3401.4217583010886, 5556.666600003998, 5575.69333686647, 5590.888182236358, 5594.160875868612, 5597.524210442118, 5553.444655534445, 5574.526165275956, 5575.135853942636, 5579.163125531172, 5596.552275817935, 4887.535684110004, 5052.444231134581, 5059.569936555397, 5117.116042312892, 5175.171972484403, 4937.185364414867, 5019.14674505335, 5066.771995260204, 5129.487051294878, 5133.30700355451, 5124.564412024983, 5142.0, 5167.560757335612, 5174.146265866128, 5175.171972484403, 5102.056119617882, 5105.774614092591, 5157.638965272414, 5172.88782911672, 5189.065968125755, 4939.53074457926, 4948.1093403187415, 5065.11360511909, 5071.974640126776, 5100.5409513143995, 5465.234867118606, 5487.972560137174, 5489.945100548984, 5506.944930550723, 5509.311336082989, 5684.488396044376, 5952.166696662471, 5956.910646340265, 5962.970185149046, 5964.314103878066, 6020.157177995085, 6062.333143354227, 6074.48366888815, 6101.481374083208, 6106.2061931948565, 5630.40880707528, 5631.296087988961, 5648.01159797038, 5660.179274005287, 5686.09022556389, 5916.112583112567, 6042.335343112254, 6102.603330783485, 6122.112293717386, 6153.077038444179, 5865.648061116347, 5873.353931067579, 5893.5579831512805, 5901.262342207224, 5916.319623243296, 5942.0, 5953.523718102541, 5957.851053723605, 5963.612365196248, 5964.075568286922, 5739.598228123991, 5910.379410161957, 5929.407059294036, 5946.316173640001, 5968.253968253968, 5972.104184372292, 6003.549733769984, 6007.729652165663, 6008.489278411384, 6011.639301641828, 4557.339185818038, 4604.723716576949, 4608.677392582487, 4613.700109492872, 4643.582077613031, 3987.5813039631003, 4087.5708050654252, 4088.2436749201493, 4114.6091121343425, 4154.937675934803, 5469.0, 5547.639403438763, 5550.27846379968, 5553.77784888608, 5560.165975103686, 5882.55880808941, 5904.202932604129, 5904.822855314347, 5907.49786268172, 5910.828025477707, 5368.973155134199, 5543.805966791143, 5572.749226284827, 5630.887382252332, 5691.402402747734, 5176.06830770465, 5196.064708352457, 5197.792088316425, 5204.245384419238, 5207.947920520822, 5903.940960590339, 6023.969880150571, 6062.696865156725, 6079.878402432015, 6093.238345206849, 5989.9700501496345, 6050.42520960508, 6060.272767267956, 6085.087236914498, 6090.177825993434, 5969.582129250911, 5980.312264089685, 6014.458698717136, 6025.035994241076, 6034.0, 5758.856028599319, 5890.617109887844, 5896.14505896634, 5898.587098903035, 5904.881902361843, 5917.733701983335, 5972.58191926561, 5976.372480889444, 5978.910316345171, 6016.067509536029, 5762.337331206965, 5799.594028417971, 5801.042827933344, 5801.477866991991, 5805.796797112081, 5534.612577119563, 5730.455606717355, 5796.04365279741, 5797.4782269595935, 5818.214541036906, 5669.943300566942, 5671.773129074791, 5671.914921276267, 5675.177099320493, 5690.971545142248, 5779.624324418899, 5807.738651760766, 5814.389489103583, 5814.476697097281, 5821.621594596338, 5497.587680923867, 5665.291838520185, 5683.9715801420725, 5786.623869448473, 5803.39064398249], "value": 5489.5959513734415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.9910001349981, 663.2472144116427, 670.8758879607273, 673.6022752787966, 674.929132441094, 39961.60038399615, 41407.58592414075, 41460.37809432861, 41487.58512414875, 41585.79207103964, 42178.57821421785, 42899.571004289945, 42962.0, 42965.7851710741, 43021.7848910755, 8171.550564718938, 8380.120087390827, 8622.65509379624, 8846.601902914384, 10335.948320258389, 10455.84316235257, 10627.362358258491, 10627.36235825853, 10629.202809789258, 10631.149508039376, 10579.523921423515, 10712.25014249003, 10714.624988125417, 10716.73208169798, 10719.303245289031, 8119.106898241218, 10257.43584102874, 10487.265891387606, 10491.318064325795, 10563.524641391155, 10271.794564108675, 10387.065164135189, 10477.528511217013, 10479.68560943173, 10485.213608979357, 43737.0, 44359.33460998088, 44454.33318500225, 44541.10917781659, 44555.33167002498, 3866.9226615467815, 3880.611938806125, 4064.979675101635, 4078.5105787305415, 4308.526062133163, 33780.49329260063, 34268.82865585681, 34448.0, 34511.6548834511, 34518.48222276668, 3926.803659817026, 3943.3099207638547, 3948.4669569608022, 3981.6217459341315, 3992.0818211811156, 353276.0, 358014.0, 359986.0, 361195.9999999974, 361376.0, 60733.392666072774, 62166.68916655388, 62175.0, 62258.37741622526, 62301.68849155725, 60713.0, 61894.38105618976, 61975.690121550004, 61995.69002155051, 62014.0, 18442.723359149622, 18641.161147748382, 18669.0, 18676.159572819255, 18677.626447470975, 12468.189567678071, 12893.54872579474, 13035.283059431633, 13059.412326445332, 13070.673233169056, 20218.292359767336, 20516.0, 20554.794452055587, 20824.58350833005, 21377.572448551255, 7695.730649427243, 7760.146383897767, 7793.80515487106, 7866.331361834308, 7985.72049978248, 1151.614209239914, 1169.0, 1182.9171957963033, 1244.3715923458597, 1259.9446342619112, 32837.83581082127, 33184.50223246653, 33423.83288083544, 35158.64841351605, 36231.4565281521, 1229.0966139887175, 1231.2797013746879, 1256.6795467155816, 1269.7079671675567, 1347.5687779910334, 17246.82753172477, 17803.10984450798, 17848.643027139384, 17905.37331193402, 18044.90977545104, 555216.0, 573824.0, 575769.0, 576132.0, 577046.0, 64546.67726661337, 65563.67218163879, 65685.67157164184, 65713.67143164254, 65779.34220657732, 1418.4750894028332, 1502.301429835132, 1583.6674298397465, 1594.7767312576248, 1623.0342945947143, 32259.838700806347, 33573.49639755455, 34184.82907585546, 34204.0, 34722.305553888276, 53047.73476132595, 54040.45959540354, 54621.0, 54643.0, 54769.0, 362028.0, 364307.0, 364475.0, 366542.0, 367303.0, 37390.0, 43359.56640433555, 43968.34047489226, 45384.77307613441, 47508.7624561875, 49260.0, 49386.506134938194, 50073.24890126724, 50518.0, 50849.237261440365, 41136.79431602823, 41284.79357603193, 42139.15721685488, 42268.0, 43376.0, 41278.587214128675, 41925.0, 42605.14789704251, 43461.782691086344, 43880.56119438765, 42391.0, 42947.35578966256, 43125.3531197026, 43279.13441731211, 43480.565194347655, 35935.281294373446, 38067.80966095152, 38210.617893821815, 38276.80861595674, 38365.232695346494, 28141.437171256868, 28620.856895715388, 28816.711832881403, 29308.56037159487, 29355.706442935298], "value": 21561.567891672446, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.7881059470265, 434.3853447371969, 434.7478462491755, 447.1303315052223, 454.3843092609516, 18947.905260473697, 19627.411177664675, 19726.112324945378, 20508.28221012265, 20553.999999999964, 19164.0, 19548.804511954877, 20028.799712002874, 20476.59046819063, 20483.0, 5754.65472071675, 5876.7355469004, 5886.823395298148, 5925.259342582177, 5936.792212272571, 5781.682007489587, 5795.652260864341, 5851.4148585141575, 5903.763849446039, 5920.911186332209, 5873.030949893263, 5887.116932460144, 5894.0, 5901.380355062721, 5904.645721256738, 5726.19833223347, 5896.115582662614, 5993.850153746148, 6014.218151640281, 6018.608790428608, 5648.632838865502, 5812.389699081577, 5817.272840894888, 5818.679972601505, 5828.0, 14594.34325455357, 14901.92549037248, 14949.327280272413, 14951.102933824006, 18529.44411667652, 2188.2997440818945, 2384.141708984764, 2490.3525083478426, 2690.8520031398266, 3073.9077827664983, 10338.9483052585, 10429.269951103426, 10584.153267738562, 10939.617113401073, 10989.450527473595, 1998.7701414337391, 2145.463634091477, 2193.824494040473, 2198.560287942402, 2266.7619899910437, 64207.67896160536, 74100.25899741042, 84685.0, 86366.0, 97732.0, 17628.383006594708, 18618.720719189223, 19021.809781902004, 19180.424587262405, 19204.903975480032, 28842.278943026173, 33940.321193576485, 34605.480917786255, 34833.47749783755, 34861.30277394438, 9909.157721593601, 10008.0, 10099.394036357842, 10436.112930400848, 10446.0, 8074.555899425471, 8456.179170529014, 8457.23884850354, 8468.407211495136, 8836.116388361112, 9250.398724082914, 9421.24630029596, 9442.716718498456, 9678.806423871623, 9794.461304628172, 3600.2017871145667, 3676.841794834672, 3739.289534988398, 3790.3556395412847, 3839.30892439358, 1056.9101626361692, 1076.3810808784824, 1086.4079076903106, 1124.5726623882872, 1140.5950887434842, 20007.899960500104, 20129.0, 20159.596808063758, 20217.0, 20346.89826550858, 1055.7255113670346, 1096.1340540972542, 1111.699841042914, 1117.1621284036971, 1182.8166634171716, 11087.833682494607, 11482.311061336346, 11492.655220343404, 11809.0, 11853.88146118545, 474253.0, 474836.0, 482213.0, 484673.0, 500884.0, 55301.0, 69656.303436966, 97257.51371243098, 98557.50721246492, 99259.50370248103, 1044.4203467075695, 1052.7578656909106, 1054.1935419404163, 1071.3625392891217, 1077.385890042668, 17871.553211169823, 17979.550511237325, 18037.0, 18102.728459073387, 18188.454346369632, 29595.85202073976, 37356.25287494289, 37812.0, 40046.59953400429, 40470.392944106454, 44241.33637995368, 46029.309560357295, 46846.76576617095, 49154.0, 50469.74765126151, 8428.873566896624, 9180.219681326895, 9201.30990175726, 9346.299027572823, 9467.289953253396, 12464.314462704639, 12907.677308067374, 13089.934550327187, 13259.071864969359, 13282.53511127106, 7648.617569121633, 9123.726288211365, 10895.782084358427, 10909.236353455182, 11271.718207044889, 11853.466594003117, 11855.466504007512, 11872.643820685393, 12211.633650990485, 12224.938875305566, 12057.81913271284, 12133.939330303292, 12190.634280971584, 12336.0, 12366.072544559014, 9011.369204155646, 9126.817463650557, 9291.6283348668, 9354.906450935403, 9416.858747118935, 12362.567310144103, 12432.191907525981, 12445.688857778629, 12495.875041249472, 12699.746005080031], "value": 10083.590453229333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.916485010899, 1567.349549936776, 1585.714571377152, 1764.2237415537163, 1907.95230119247, 7907.960460197698, 8206.466579672324, 8244.42289039766, 8250.504969701822, 8258.83482330353, 7920.366370690345, 8144.837103257946, 8160.755177344674, 8174.550399728013, 8181.140980197083, 2048.160254295739, 2324.244620498336, 2368.3368656776042, 2407.6268178432283, 2436.1838784007377, 2392.7966122879575, 2399.1483023526657, 2404.2907342333965, 2407.3981504623844, 2408.8795560222006, 2322.094383190557, 2401.519696060786, 2404.2426635609813, 2405.0860672944254, 2405.386626410263, 2037.195307853397, 2327.325075728037, 2390.6772585701056, 2393.497365553236, 2396.484755777499, 2252.1329288223988, 2256.131389415087, 2261.4007288068674, 2263.4567703751145, 2294.8393612447135, 8219.178082191793, 8255.133211012817, 8275.213854683796, 8298.70954516589, 8307.003159620825, 5107.770150343244, 5270.6574072685535, 5272.57819374453, 5278.2346559749, 5365.409804921455, 3328.0, 3447.241606846515, 3459.2735525539665, 3570.8214589270433, 3604.567451905762, 1127.0814286356592, 1167.9080060143792, 1176.9646910592696, 1181.3030312115807, 1197.8742232065595, 11662.825057624143, 11663.591774287945, 11678.76642467146, 11706.946374826306, 11713.590024349107, 4736.739479328601, 4763.428388593391, 4798.2802579613335, 4817.933307773155, 4879.463259041502, 9837.80324393508, 9862.55618497155, 9864.654737084167, 9867.901320986843, 9878.308518403786, 4624.583787459145, 5327.440618734977, 5337.733113344318, 5341.305630268118, 5342.973285133549, 4764.523547645208, 4842.055799119208, 4863.0, 4872.415310162733, 4876.463589005278, 3894.844206231719, 3936.5472970608175, 3979.4428779970826, 4022.1352409232154, 4045.8179381927885, 1320.5576131995697, 1324.1856258401206, 1355.9389827457787, 1364.5769811358512, 1366.077897419238, 1085.733995171188, 1092.126298960844, 1100.9614663486739, 1104.4808939798281, 1122.1527746551465, 5878.882422351529, 6011.338752737195, 6114.419130182714, 6191.318954915046, 6203.751849925952, 455.58996902787754, 462.2557682131765, 463.5225717510969, 468.2274247491598, 469.9342092107108, 4278.358246263086, 4362.869113926524, 4460.665450091256, 4471.150481408522, 4474.440694913136, 9845.261605379625, 10206.234532410099, 10350.378977261389, 10388.272820902615, 10414.47927603632, 10278.17774578032, 10287.639932602473, 10289.639862604772, 10297.897021029843, 10300.124489418484, 7623.847523049508, 7983.2415920487465, 7991.760247192594, 7992.24073713008, 8004.51972881629, 5910.202122713378, 6031.819045428644, 6065.150878876872, 6069.119419787465, 6132.41742034506, 9389.10803473669, 9570.425774453555, 9668.951655241679, 9688.515574221401, 9699.563519641491, 9183.724488265363, 9643.807123857623, 9929.255305851946, 9988.05113514215, 10000.149987250874, 6758.2903795102775, 7235.059442272471, 7354.191038985814, 7422.925770742223, 7588.165301816906, 7940.523568585903, 7961.442699011013, 7999.880001800094, 8000.079990801132, 8024.919750802417, 7127.7148914042855, 7373.0, 7387.667554960147, 7389.408847292311, 7533.924660753322, 7110.7511237106455, 7214.567125972459, 7228.746993855189, 7251.56490610565, 7338.743143989935, 6668.866622667617, 6732.596044237362, 6767.0, 6778.186617605918, 6784.457243420416, 5209.557187639093, 5245.239440281214, 5301.125314323093, 5368.0, 5506.201600767947, 5193.221016847503, 5201.271821944855, 5262.657927234717, 5374.785008599612, 5389.083855744454], "value": 4518.7997472944235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.06971734056776, 359.8556590043662, 362.2808724681507, 366.8679275460832, 370.4942753141961, 5266.710330931797, 5483.698396588186, 5540.612157149001, 5549.694766787825, 5565.0817615093465, 5320.9467905320935, 5415.783368665258, 5423.376311724153, 5431.809886653968, 5438.945610543893, 4056.411820286057, 4080.8571699990357, 4239.618434340909, 4265.573442655733, 4291.506476755167, 4227.534971153171, 4228.703990720651, 4245.150969806035, 4246.617804397619, 4249.766262855542, 4202.642775364097, 4202.8232095013445, 4233.6824738144605, 4238.936415953764, 4239.342901850218, 3880.2239552089695, 3989.2221016901717, 4126.154138401651, 4185.727927684706, 4242.193983143224, 3713.3316003119426, 3724.962750372516, 3742.831572579213, 3744.6255374462608, 3765.3222419964395, 5210.218467229908, 5228.503292187236, 5239.21411788231, 5239.973800131013, 5254.737263136828, 4633.189191891405, 4873.659743570511, 4874.731889746063, 4883.487233840467, 4885.462599114095, 5678.801241956553, 5687.744051517691, 5702.486776190121, 5727.684977326246, 5736.913946290809, 4164.292070348048, 4173.373993900909, 4187.078842654611, 4188.32986722123, 4192.496900371944, 5841.824745257649, 5922.940770592325, 5924.170616113747, 5925.822225333247, 5966.880662386728, 4114.860272658201, 4121.876343709693, 4139.482564679415, 4143.91712165755, 4150.232207041707, 4206.57934206583, 4211.389348544505, 4214.473190851144, 4214.599613036757, 4224.070704445017, 4602.654800889946, 4736.431628204568, 4777.378940737682, 4794.568488836021, 4814.903701926012, 5678.829635110871, 5684.801031963944, 5698.6580805151825, 5729.140628905614, 5746.798862039808, 1540.2991638804317, 1570.685862827439, 1572.9134897580514, 1573.0090043272894, 1573.9291731872092, 988.6292640259902, 1005.7535903703639, 1036.0209601926258, 1045.46158728255, 1055.646358469921, 5332.173513105475, 5507.9430915842095, 5523.011715782315, 5531.3915469298345, 5546.972265138648, 5864.824055278348, 5874.1776151338845, 5880.588358814927, 5882.617629854046, 5900.940990590039, 5300.893982120413, 5449.700266485302, 5476.370217424967, 5479.643823151562, 5510.586705997066, 3337.7162941150273, 3346.832658367072, 3358.529805827186, 3360.428727116396, 3365.730741540671, 5690.374058853523, 6342.587731797418, 6346.143270658495, 6357.777477788255, 6370.6814659267775, 5963.314218864886, 5978.850528736816, 6004.7598096076545, 6018.21763170785, 6028.969855150696, 5923.229980102559, 5930.8813823723285, 5947.434993675594, 5957.553183511167, 5961.850953726105, 5707.400722924033, 5903.468687818117, 5911.467967882912, 5934.673592952429, 5936.762529498772, 5858.384869588632, 5862.589618726648, 5878.559108066826, 5878.823635290948, 5882.794102206402, 5829.417058294137, 5895.29256489224, 5962.582619216613, 5978.641281523122, 5983.491403230774, 4315.913681726411, 4333.848315308949, 4380.824767009284, 4393.648508119279, 4406.735595864258, 5833.220867635242, 5849.883002339844, 5853.795117170878, 5859.619124756877, 5906.616069955439, 5432.511074003359, 5611.607187496836, 5711.628744131618, 5717.113847353667, 5743.368229494668, 5623.437656234508, 5631.1271752878365, 5689.6586204827845, 5706.971465142814, 5712.714364281686, 5353.804939974591, 5404.162354835007, 5611.298587676541, 5628.85927851807, 5639.605227634026, 5720.541261978169, 5742.827715168552, 5762.798302059407, 5769.105788602773, 5797.652140871562, 5624.1563765433875, 5643.209950607001, 5647.491725744702, 5650.604457687922, 5652.915206271991], "value": 4387.321314459474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.5310105992234, 882.6469412235107, 889.719738282441, 892.2861710631494, 909.8217807938714, 13669.658258543528, 13884.23636699981, 14056.64858378541, 14104.50634227801, 14113.364898579563, 13812.861871381283, 13863.514776982807, 13909.652258693537, 13915.791263131036, 13916.0, 755.5919803306208, 891.8751374807532, 908.7728067982988, 915.6703586708779, 919.3748251189189, 912.4616476278994, 918.1277786103228, 919.2278486071698, 920.9907900920972, 921.5162039929024, 974.8878878928907, 975.6926568131051, 976.9413835169912, 977.3451787302489, 977.7213494154167, 778.6029125146159, 920.8020275640736, 946.6118891254622, 946.6260826973341, 947.6967370441462, 692.8510370270366, 696.7596179318127, 697.5117417807555, 697.9965100174518, 700.4361489001352, 13354.732905341838, 13401.66495837602, 13404.396802143927, 13406.061575689706, 13426.261555614437, 13299.335033248395, 13379.86620133796, 13615.115017523829, 13707.246101464416, 14163.291835408188, 12042.939785301105, 12073.0, 12131.696707582381, 12135.575254866126, 12279.570215042431, 3779.0, 3800.1069748609057, 3800.923981520354, 3835.0, 4029.174019326031, 52105.739471302404, 52188.47811521874, 52259.0, 52276.0, 52723.0, 10389.688309350731, 10735.355878647306, 10805.297655652357, 10896.073833724222, 11110.666679999613, 19036.42890713281, 19169.904150479157, 19502.414927552196, 19502.804971950383, 19604.313849015216, 8018.0, 8072.152423995396, 8074.878876816852, 8092.595370231465, 8237.0527389351, 4684.508126646721, 4777.283407488904, 4874.683145595525, 4886.096072226578, 4915.582175515121, 3420.504026916133, 3517.595476520182, 3524.4184709523156, 3528.68241858234, 3532.2405682778326, 677.4884961853769, 683.6171743823475, 698.1866125963196, 699.422976044765, 701.3722718167314, 673.5251647588439, 683.5351960666695, 736.8452624948713, 742.8551432470616, 763.5533213070305, 9495.287853410884, 10140.391576505433, 10158.492075396201, 10273.178145748323, 10408.0, 671.4695390641339, 726.2483329753679, 764.4152223548989, 766.2031487253306, 793.3375631347889, 4335.978320108379, 4373.540778218241, 4413.117376524708, 4441.200583894865, 4511.684182107221, 61915.690421548505, 67530.0, 69136.308636914, 69345.3065469349, 69529.30470695229, 54291.72854135783, 60206.0, 60396.0, 61421.692891535255, 62172.68913655403, 52899.20651190158, 55707.721461393245, 55810.162847557316, 56351.43648563544, 57318.713406432704, 9529.571169297536, 9542.809143817223, 9570.760730981781, 9666.516674166123, 9695.418274903528, 35612.0, 36163.457548137325, 36640.26719465649, 36758.26483470262, 37109.628903711695, 45252.0, 47444.7627761859, 50352.74823625859, 50757.74621127018, 51076.74461627668, 12420.937895310466, 12695.682607934876, 12879.742405152032, 12892.48430062787, 13322.0, 14095.436182552994, 14338.928305358406, 14603.853961460249, 14613.707725845636, 14728.189949552878, 13114.803277950648, 13322.333883305602, 13520.459181632625, 13553.661158470723, 14036.78944815808, 12650.177738446971, 12965.870341296844, 12971.870281297444, 13308.534201303295, 13387.397567109309, 13517.864821352054, 13543.187408755506, 13594.320283985564, 13862.584122476343, 14005.649858753613, 11099.0, 11141.944290278496, 11457.713557161138, 11759.0, 12186.0, 11405.828912566152, 11481.253718508271, 11520.596779112691, 11550.075993920635, 11656.242344247597], "value": 7089.41286958929, "unit": "score"}], "name": "dromaeo_css", "value": 7426.532510570336, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 105.0, 110.0, 111.0, 112.0, 99.0, 110.0, 110.0, 110.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 156.0, 181.0, 132.0, 158.0, 152.0, 128.0, 186.0, 126.0], "value": 149.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 161.0, 159.0, 160.0, 161.0, 165.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 76.0, 170.0, 79.0, 77.0, 74.0, 77.0, 166.0, 76.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 156.0, 93.0, 93.0, 93.0, 153.0, 93.0, 93.0, 147.0], "value": 110.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 144.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 55.0, 55.0, 53.0, 56.0, 76.0, 54.0, 53.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 58.0, 57.0, 58.0], "value": 57.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 74.0, 74.0, 80.0, 73.0, 74.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 115.0, 114.0, 120.0, 116.0, 116.0, 116.0, 149.0, 118.0], "value": 120.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 168.0, 168.0, 162.0, 162.0, 162.0, 160.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 72.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1519.8999999999999, "alertThreshold": 2.0}]} 09:20:26 INFO - Return code: 0 09:20:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:26 ERROR - # TBPL SUCCESS # 09:20:26 INFO - Running post-action listener: _resource_record_post_action 09:20:26 INFO - Running post-run listener: _resource_record_post_run 09:20:27 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 500736000; Read time: 8; Write time: 544900 09:20:27 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 221966336; Read time: 0; Write time: 369244 09:20:27 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251768832; Read time: 8; Write time: 159208 09:20:27 INFO - Running post-run listener: _upload_blobber_files 09:20:27 INFO - Blob upload gear active. 09:20:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:27 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:20:27 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:20:27 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:20:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:28 INFO - (blobuploader) - INFO - Done attempting. 09:20:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:29 INFO - (blobuploader) - INFO - Done attempting. 09:20:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:29 INFO - Return code: 0 09:20:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70cccabf15374d19d016c649010621c9c444a63fd18679232bb2416e71097c5cf98783a546a5c9695a247bc0ca53196a392357712c29747f3181a0ca3f8dc88c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb6485a9307edf32ec6d26fad89c29cc47dd446b0b4cadeda9b51de9c7046ba3352cd26b300a4e5f612cef65cf0bde83d2109454f3edcb95d5c0b0082cd20726"} 09:20:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:29 INFO - Contents: 09:20:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70cccabf15374d19d016c649010621c9c444a63fd18679232bb2416e71097c5cf98783a546a5c9695a247bc0ca53196a392357712c29747f3181a0ca3f8dc88c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb6485a9307edf32ec6d26fad89c29cc47dd446b0b4cadeda9b51de9c7046ba3352cd26b300a4e5f612cef65cf0bde83d2109454f3edcb95d5c0b0082cd20726"} 09:20:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:29 INFO - Copying logs to upload dir... 09:20:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.309405 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-26 09:20:28.513456) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:28.521185) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 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/70cccabf15374d19d016c649010621c9c444a63fd18679232bb2416e71097c5cf98783a546a5c9695a247bc0ca53196a392357712c29747f3181a0ca3f8dc88c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb6485a9307edf32ec6d26fad89c29cc47dd446b0b4cadeda9b51de9c7046ba3352cd26b300a4e5f612cef65cf0bde83d2109454f3edcb95d5c0b0082cd20726"} build_url:https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/ZiPQa71WSP-SD0XstXuXXg/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/70cccabf15374d19d016c649010621c9c444a63fd18679232bb2416e71097c5cf98783a546a5c9695a247bc0ca53196a392357712c29747f3181a0ca3f8dc88c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb6485a9307edf32ec6d26fad89c29cc47dd446b0b4cadeda9b51de9c7046ba3352cd26b300a4e5f612cef65cf0bde83d2109454f3edcb95d5c0b0082cd20726"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:28.548695) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:28.549060) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461685309.432090-527420774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:28.631514) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:28.634932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:28.635288) ========= ========= Total master_lag: 0.22 =========