builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1465223088.01 results: success (0) buildid: 20160606043046 builduid: f6829da16dd848098aa876aec41b6ec5 revision: 0a3b6e2df6567d845f31c000c68dd67816c6153d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.007628) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.008142) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.008476) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.023857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.024307) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 07:24:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-06-06 07:24:49 (18.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.126388) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.127001) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017901 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.176883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.177294) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0a3b6e2df6567d845f31c000c68dd67816c6153d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0a3b6e2df6567d845f31c000c68dd67816c6153d --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=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 07:24:50,060 truncating revision to first 12 chars 2016-06-06 07:24:50,060 Setting DEBUG logging. 2016-06-06 07:24:50,060 attempt 1/10 2016-06-06 07:24:50,060 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0a3b6e2df656?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 07:24:50,399 unpacking tar archive at: mozilla-central-0a3b6e2df656/testing/mozharness/ program finished with exit code 0 elapsedTime=0.699331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.894644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.895168) ========= script_repo_revision: 0a3b6e2df6567d845f31c000c68dd67816c6153d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.895602) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.896408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 07:24:48.907930) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-06 07:24:48.908262) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:24:50 INFO - MultiFileLogger online at 20160606 07:24:50 in /builds/slave/test 07:24:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:24:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:24:50 INFO - {'append_to_log': False, 07:24:50 INFO - 'base_work_dir': '/builds/slave/test', 07:24:50 INFO - 'blob_upload_branch': 'Firefox', 07:24:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:24:50 INFO - 'branch': 'Firefox', 07:24:50 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:50 INFO - 'config_files': ('talos/linux_config.py',), 07:24:50 INFO - 'default_actions': ('clobber', 07:24:50 INFO - 'read-buildbot-config', 07:24:50 INFO - 'download-and-extract', 07:24:50 INFO - 'populate-webroot', 07:24:50 INFO - 'create-virtualenv', 07:24:50 INFO - 'install', 07:24:50 INFO - 'run-tests'), 07:24:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:50 INFO - 'download_minidump_stackwalk': True, 07:24:50 INFO - 'download_symbols': 'ondemand', 07:24:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:50 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:50 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:50 INFO - 'installer_path': 'installer.exe', 07:24:50 INFO - 'log_level': 'info', 07:24:50 INFO - 'log_name': 'talos', 07:24:50 INFO - 'log_to_console': True, 07:24:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:24:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:24:50 INFO - 'opt_config_files': (), 07:24:50 INFO - 'pip_index': False, 07:24:50 INFO - 'sps_profile': False, 07:24:50 INFO - 'sps_profile_interval': 0, 07:24:50 INFO - 'suite': 'dromaeojs', 07:24:50 INFO - 'system_bits': '32', 07:24:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:50 INFO - 'title': 'talos-linux64-ix-067', 07:24:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:50 INFO - 'use_talos_json': True, 07:24:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:24:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:50 INFO - 'work_dir': 'build'} 07:24:50 INFO - ##### 07:24:50 INFO - ##### Running clobber step. 07:24:50 INFO - ##### 07:24:50 INFO - Running pre-action listener: _resource_record_pre_action 07:24:50 INFO - Running main action method: clobber 07:24:50 INFO - rmtree: /builds/slave/test/build 07:24:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:24:51 INFO - Running post-action listener: _resource_record_post_action 07:24:51 INFO - ##### 07:24:51 INFO - ##### Finished clobber step (success) 07:24:51 INFO - ##### 07:24:51 INFO - ##### 07:24:51 INFO - ##### Running read-buildbot-config step. 07:24:51 INFO - ##### 07:24:51 INFO - Running pre-action listener: _resource_record_pre_action 07:24:51 INFO - Running main action method: read_buildbot_config 07:24:51 INFO - Using buildbot properties: 07:24:51 INFO - { 07:24:51 INFO - "project": "", 07:24:51 INFO - "product": "firefox", 07:24:51 INFO - "script_repo_revision": "production", 07:24:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:24:51 INFO - "repository": "", 07:24:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:24:51 INFO - "buildid": "20160606043046", 07:24:51 INFO - "pgo_build": "True", 07:24:51 INFO - "basedir": "/builds/slave/test", 07:24:51 INFO - "buildnumber": 780, 07:24:51 INFO - "slavename": "talos-linux64-ix-067", 07:24:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:24:51 INFO - "platform": "ubuntu64_hw", 07:24:51 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:24:51 INFO - "branch": "mozilla-central", 07:24:51 INFO - "repo_path": "mozilla-central", 07:24:51 INFO - "revision": "0a3b6e2df6567d845f31c000c68dd67816c6153d", 07:24:51 INFO - "stage_platform": "linux64-pgo", 07:24:51 INFO - "builduid": "f6829da16dd848098aa876aec41b6ec5", 07:24:51 INFO - "slavebuilddir": "test" 07:24:51 INFO - } 07:24:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:24:51 INFO - Running post-action listener: _resource_record_post_action 07:24:51 INFO - ##### 07:24:51 INFO - ##### Finished read-buildbot-config step (success) 07:24:51 INFO - ##### 07:24:51 INFO - ##### 07:24:51 INFO - ##### Running download-and-extract step. 07:24:51 INFO - ##### 07:24:51 INFO - Running pre-action listener: _resource_record_pre_action 07:24:51 INFO - Running main action method: download_and_extract 07:24:51 INFO - mkdir: /builds/slave/test/build/tests 07:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:51 INFO - https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:24:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:24:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:24:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:24:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:24:52 INFO - Downloaded 1361 bytes. 07:24:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:24:52 INFO - Using the following test package requirements: 07:24:52 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:24:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:24:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:24:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'jsshell-linux-x86_64.zip'], 07:24:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:24:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:24:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:24:52 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:24:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:24:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:52 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:24:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:52 INFO - https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:24:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:24:53 INFO - Downloaded 24443701 bytes. 07:24:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:24:55 INFO - Return code: 0 07:24:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:55 INFO - https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:24:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:24:56 INFO - Downloaded 24443701 bytes. 07:24:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:24:57 INFO - Return code: 0 07:24:57 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')]} 07:24:57 INFO - https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:24:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:24:58 INFO - Downloaded 10833369 bytes. 07:24:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:24:59 INFO - Return code: 0 07:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:59 INFO - https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:24:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:24:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:24:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:24:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:25:00 INFO - Downloaded 59195941 bytes. 07:25:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:25:00 INFO - mkdir: /builds/slave/test/properties 07:25:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:25:00 INFO - Writing to file /builds/slave/test/properties/build_url 07:25:00 INFO - Contents: 07:25:00 INFO - build_url:https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:25:01 INFO - Running post-action listener: _resource_record_post_action 07:25:01 INFO - Running post-action listener: set_extra_try_arguments 07:25:01 INFO - ##### 07:25:01 INFO - ##### Finished download-and-extract step (success) 07:25:01 INFO - ##### 07:25:01 INFO - ##### 07:25:01 INFO - ##### Running populate-webroot step. 07:25:01 INFO - ##### 07:25:01 INFO - Running pre-action listener: _resource_record_pre_action 07:25:01 INFO - Running main action method: populate_webroot 07:25:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:25:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:25:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:25:01 INFO - '--tppagecycles', 07:25:01 INFO - '7'], 07:25:01 INFO - 'tests': ['tsvgm']}}, 07:25:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:25:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:25:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:25:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:25:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:25:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['damp', 'tps']}, 07:25:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'tests': ['damp', 'tps']}, 07:25:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['dromaeo_dom']}, 07:25:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:25:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'other-e10s': {'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['a11yr', 07:25:01 INFO - 'ts_paint', 07:25:01 INFO - 'tpaint', 07:25:01 INFO - 'sessionrestore', 07:25:01 INFO - 'sessionrestore_no_auto_restore', 07:25:01 INFO - 'tabpaint']}, 07:25:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['tsvgx', 07:25:01 INFO - 'tsvgr_opacity', 07:25:01 INFO - 'tart', 07:25:01 INFO - 'tscrollx', 07:25:01 INFO - 'cart']}, 07:25:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:25:01 INFO - 'tsvgr_opacity', 07:25:01 INFO - 'tart', 07:25:01 INFO - 'tscrollx', 07:25:01 INFO - 'cart']}, 07:25:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'talos_options': ['--disable-e10s'], 07:25:01 INFO - 'tests': ['tp5o']}, 07:25:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'tests': ['tp5o']}, 07:25:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'talos_options': ['--disable-e10s', 07:25:01 INFO - '--xperf_path', 07:25:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:01 INFO - 'tests': ['tp5n']}, 07:25:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:25:01 INFO - 'talos_options': ['--xperf_path', 07:25:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:25:01 INFO - 'tests': ['tp5n']}}, 07:25:01 INFO - 'talos.zip': {'path': '', 07:25:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:25:01 INFO - Running post-action listener: _resource_record_post_action 07:25:01 INFO - ##### 07:25:01 INFO - ##### Finished populate-webroot step (success) 07:25:01 INFO - ##### 07:25:01 INFO - ##### 07:25:01 INFO - ##### Running create-virtualenv step. 07:25:01 INFO - ##### 07:25:01 INFO - Running pre-action listener: _resource_record_pre_action 07:25:01 INFO - Running main action method: create_virtualenv 07:25:01 INFO - Creating virtualenv /builds/slave/test/build/venv 07:25:01 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 07:25:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:25:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:25:01 INFO - Using real prefix '/usr' 07:25:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:25:02 INFO - Installing distribute.............................................................................................................................................................................................done. 07:25:04 INFO - Installing pip.................done. 07:25:04 INFO - Return code: 0 07:25:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:25:04 INFO - Using env: {'DISPLAY': ':0', 07:25:04 INFO - 'HOME': '/home/cltbld', 07:25:04 INFO - 'LANG': 'en_US.UTF-8', 07:25:04 INFO - 'LANGUAGE': 'en_US:en', 07:25:04 INFO - 'LOGNAME': 'cltbld', 07:25:04 INFO - 'MAIL': '/var/mail/cltbld', 07:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:04 INFO - 'MOZ_NO_REMOTE': '1', 07:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:04 INFO - 'NO_EM_RESTART': '1', 07:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:04 INFO - 'PWD': '/builds/slave/test', 07:25:04 INFO - 'SHELL': '/bin/bash', 07:25:04 INFO - 'SHLVL': '1', 07:25:04 INFO - 'TERM': 'linux', 07:25:04 INFO - 'TMOUT': '86400', 07:25:04 INFO - 'USER': 'cltbld', 07:25:04 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:04 INFO - '_': '/tools/buildbot/bin/python'} 07:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:04 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 07:25:04 INFO - Cleaning up... 07:25:04 INFO - Return code: 0 07:25:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:25:04 INFO - Using env: {'DISPLAY': ':0', 07:25:04 INFO - 'HOME': '/home/cltbld', 07:25:04 INFO - 'LANG': 'en_US.UTF-8', 07:25:04 INFO - 'LANGUAGE': 'en_US:en', 07:25:04 INFO - 'LOGNAME': 'cltbld', 07:25:04 INFO - 'MAIL': '/var/mail/cltbld', 07:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:04 INFO - 'MOZ_NO_REMOTE': '1', 07:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:04 INFO - 'NO_EM_RESTART': '1', 07:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:04 INFO - 'PWD': '/builds/slave/test', 07:25:04 INFO - 'SHELL': '/bin/bash', 07:25:04 INFO - 'SHLVL': '1', 07:25:04 INFO - 'TERM': 'linux', 07:25:04 INFO - 'TMOUT': '86400', 07:25:04 INFO - 'USER': 'cltbld', 07:25:04 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:04 INFO - '_': '/tools/buildbot/bin/python'} 07:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:04 INFO - Downloading/unpacking psutil>=3.1.1 07:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:25:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:06 INFO - Installing collected packages: psutil 07:25:06 INFO - Running setup.py install for psutil 07:25:06 INFO - building 'psutil._psutil_linux' extension 07:25:06 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 07:25:07 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 07:25:07 INFO - building 'psutil._psutil_posix' extension 07:25:07 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 07:25:07 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 07:25:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:25:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:25:07 INFO - Successfully installed psutil 07:25:07 INFO - Cleaning up... 07:25:07 INFO - Return code: 0 07:25:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:25:07 INFO - Using env: {'DISPLAY': ':0', 07:25:07 INFO - 'HOME': '/home/cltbld', 07:25:07 INFO - 'LANG': 'en_US.UTF-8', 07:25:07 INFO - 'LANGUAGE': 'en_US:en', 07:25:07 INFO - 'LOGNAME': 'cltbld', 07:25:07 INFO - 'MAIL': '/var/mail/cltbld', 07:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:07 INFO - 'MOZ_NO_REMOTE': '1', 07:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:07 INFO - 'NO_EM_RESTART': '1', 07:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:07 INFO - 'PWD': '/builds/slave/test', 07:25:07 INFO - 'SHELL': '/bin/bash', 07:25:07 INFO - 'SHLVL': '1', 07:25:07 INFO - 'TERM': 'linux', 07:25:07 INFO - 'TMOUT': '86400', 07:25:07 INFO - 'USER': 'cltbld', 07:25:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:07 INFO - '_': '/tools/buildbot/bin/python'} 07:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:25:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:25:09 INFO - Installing collected packages: mozsystemmonitor 07:25:09 INFO - Running setup.py install for mozsystemmonitor 07:25:09 INFO - Successfully installed mozsystemmonitor 07:25:09 INFO - Cleaning up... 07:25:09 INFO - Return code: 0 07:25:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:25: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')]} 07:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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')]} 07:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:09 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:09 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==2.5.1'] in /builds/slave/test/build 07:25:09 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==2.5.1 07:25:09 INFO - Using env: {'DISPLAY': ':0', 07:25:09 INFO - 'HOME': '/home/cltbld', 07:25:09 INFO - 'LANG': 'en_US.UTF-8', 07:25:09 INFO - 'LANGUAGE': 'en_US:en', 07:25:09 INFO - 'LOGNAME': 'cltbld', 07:25:09 INFO - 'MAIL': '/var/mail/cltbld', 07:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:09 INFO - 'MOZ_NO_REMOTE': '1', 07:25:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:09 INFO - 'NO_EM_RESTART': '1', 07:25:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:09 INFO - 'PWD': '/builds/slave/test', 07:25:09 INFO - 'SHELL': '/bin/bash', 07:25:09 INFO - 'SHLVL': '1', 07:25:09 INFO - 'TERM': 'linux', 07:25:09 INFO - 'TMOUT': '86400', 07:25:09 INFO - 'USER': 'cltbld', 07:25:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:09 INFO - '_': '/tools/buildbot/bin/python'} 07:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:25:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:25:12 INFO - warning: no files found matching '*.txt' 07:25:12 INFO - no previously-included directories found matching 'build' 07:25:12 INFO - no previously-included directories found matching 'dist' 07:25:12 INFO - no previously-included directories found matching '.git*' 07:25:12 INFO - Installing collected packages: jsonschema, functools32 07:25:12 INFO - Running setup.py install for functools32 07:25:12 INFO - warning: no files found matching '*.txt' 07:25:12 INFO - no previously-included directories found matching 'build' 07:25:12 INFO - no previously-included directories found matching 'dist' 07:25:12 INFO - no previously-included directories found matching '.git*' 07:25:12 INFO - Successfully installed jsonschema functools32 07:25:12 INFO - Cleaning up... 07:25:12 INFO - Return code: 0 07:25:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:25:12 INFO - Using env: {'DISPLAY': ':0', 07:25:12 INFO - 'HOME': '/home/cltbld', 07:25:12 INFO - 'LANG': 'en_US.UTF-8', 07:25:12 INFO - 'LANGUAGE': 'en_US:en', 07:25:12 INFO - 'LOGNAME': 'cltbld', 07:25:12 INFO - 'MAIL': '/var/mail/cltbld', 07:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:12 INFO - 'MOZ_NO_REMOTE': '1', 07:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:12 INFO - 'NO_EM_RESTART': '1', 07:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:12 INFO - 'PWD': '/builds/slave/test', 07:25:12 INFO - 'SHELL': '/bin/bash', 07:25:12 INFO - 'SHLVL': '1', 07:25:12 INFO - 'TERM': 'linux', 07:25:12 INFO - 'TMOUT': '86400', 07:25:12 INFO - 'USER': 'cltbld', 07:25:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:12 INFO - '_': '/tools/buildbot/bin/python'} 07:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:12 INFO - Downloading/unpacking blobuploader==1.2.4 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:12 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 07:25:15 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:15 INFO - Downloading docopt-0.6.1.tar.gz 07:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:16 INFO - Running setup.py install for blobuploader 07:25:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:16 INFO - Running setup.py install for requests 07:25:16 INFO - Running setup.py install for docopt 07:25:16 INFO - Successfully installed blobuploader requests docopt 07:25:16 INFO - Cleaning up... 07:25:16 INFO - Return code: 0 07:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:16 INFO - Using env: {'DISPLAY': ':0', 07:25:16 INFO - 'HOME': '/home/cltbld', 07:25:16 INFO - 'LANG': 'en_US.UTF-8', 07:25:16 INFO - 'LANGUAGE': 'en_US:en', 07:25:16 INFO - 'LOGNAME': 'cltbld', 07:25:16 INFO - 'MAIL': '/var/mail/cltbld', 07:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:16 INFO - 'MOZ_NO_REMOTE': '1', 07:25:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:16 INFO - 'NO_EM_RESTART': '1', 07:25:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:16 INFO - 'PWD': '/builds/slave/test', 07:25:16 INFO - 'SHELL': '/bin/bash', 07:25:16 INFO - 'SHLVL': '1', 07:25:16 INFO - 'TERM': 'linux', 07:25:16 INFO - 'TMOUT': '86400', 07:25:16 INFO - 'USER': 'cltbld', 07:25:16 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:16 INFO - '_': '/tools/buildbot/bin/python'} 07:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:16 INFO - Running setup.py (path:/tmp/pip-43HLlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:16 INFO - Running setup.py (path:/tmp/pip-_SfR2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:16 INFO - Running setup.py (path:/tmp/pip-Vahyli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:17 INFO - Running setup.py (path:/tmp/pip-lJg6Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:17 INFO - Running setup.py (path:/tmp/pip-kRiVI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:17 INFO - Running setup.py (path:/tmp/pip-EQ7ZeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:17 INFO - Running setup.py (path:/tmp/pip-XHPAQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:17 INFO - Running setup.py (path:/tmp/pip-Hmfe9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:17 INFO - Running setup.py (path:/tmp/pip-i_pqjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:17 INFO - Running setup.py (path:/tmp/pip-nV5pe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:17 INFO - Running setup.py (path:/tmp/pip-wBK2eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:17 INFO - Running setup.py (path:/tmp/pip-Q8grL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:17 INFO - Running setup.py (path:/tmp/pip-V2Zvk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:17 INFO - Running setup.py (path:/tmp/pip-DwZT86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:17 INFO - Running setup.py (path:/tmp/pip-ZdMUWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:17 INFO - Running setup.py (path:/tmp/pip-q_GfIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:17 INFO - Running setup.py (path:/tmp/pip-gLA5y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:18 INFO - Running setup.py install for manifestparser 07:25:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Running setup.py install for mozcrash 07:25:18 INFO - Running setup.py install for mozdebug 07:25:18 INFO - Running setup.py install for mozdevice 07:25:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Running setup.py install for mozfile 07:25:18 INFO - Running setup.py install for mozhttpd 07:25:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Running setup.py install for mozinfo 07:25:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Running setup.py install for mozInstall 07:25:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:25:18 INFO - Running setup.py install for mozleak 07:25:18 INFO - Running setup.py install for mozlog 07:25:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Running setup.py install for moznetwork 07:25:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Running setup.py install for mozprocess 07:25:19 INFO - Running setup.py install for mozprofile 07:25:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Running setup.py install for mozrunner 07:25:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Running setup.py install for mozscreenshot 07:25:19 INFO - Running setup.py install for moztest 07:25:19 INFO - Running setup.py install for mozversion 07:25:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:25:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:25:19 INFO - Cleaning up... 07:25:19 INFO - Return code: 0 07:25:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:19 INFO - Using env: {'DISPLAY': ':0', 07:25:19 INFO - 'HOME': '/home/cltbld', 07:25:19 INFO - 'LANG': 'en_US.UTF-8', 07:25:19 INFO - 'LANGUAGE': 'en_US:en', 07:25:19 INFO - 'LOGNAME': 'cltbld', 07:25:19 INFO - 'MAIL': '/var/mail/cltbld', 07:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:19 INFO - 'MOZ_NO_REMOTE': '1', 07:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:19 INFO - 'NO_EM_RESTART': '1', 07:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:19 INFO - 'PWD': '/builds/slave/test', 07:25:19 INFO - 'SHELL': '/bin/bash', 07:25:19 INFO - 'SHLVL': '1', 07:25:19 INFO - 'TERM': 'linux', 07:25:19 INFO - 'TMOUT': '86400', 07:25:19 INFO - 'USER': 'cltbld', 07:25:19 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:19 INFO - '_': '/tools/buildbot/bin/python'} 07:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:20 INFO - Running setup.py (path:/tmp/pip-snMtGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:20 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)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:20 INFO - Running setup.py (path:/tmp/pip-ImvpNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:20 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)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:20 INFO - Running setup.py (path:/tmp/pip-wHwNm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:20 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)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:20 INFO - Running setup.py (path:/tmp/pip-cHxAFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:20 INFO - Running setup.py (path:/tmp/pip-6LqlEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:20 INFO - Running setup.py (path:/tmp/pip-YVEx6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:20 INFO - Running setup.py (path:/tmp/pip-HXCItv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:20 INFO - Running setup.py (path:/tmp/pip-zbd7ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:20 INFO - Running setup.py (path:/tmp/pip-mEAKDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:20 INFO - Running setup.py (path:/tmp/pip-btYuHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:20 INFO - Running setup.py (path:/tmp/pip-hDC6ZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:20 INFO - Running setup.py (path:/tmp/pip-DvU1H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:20 INFO - Running setup.py (path:/tmp/pip-4napM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:20 INFO - Running setup.py (path:/tmp/pip-MbLsPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:21 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)) 07:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:21 INFO - Running setup.py (path:/tmp/pip-HZwBm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:21 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)) 07:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:21 INFO - Running setup.py (path:/tmp/pip-Za2AyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:21 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)) 07:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:21 INFO - Running setup.py (path:/tmp/pip-p2BwYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:21 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)) 07:25:21 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)) 07:25:21 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)) 07:25:21 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)) 07:25:21 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)) 07:25:21 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)) 07:25:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:23 INFO - Downloading blessings-1.6.tar.gz 07:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:23 INFO - Installing collected packages: blessings 07:25:23 INFO - Running setup.py install for blessings 07:25:23 INFO - Successfully installed blessings 07:25:23 INFO - Cleaning up... 07:25:23 INFO - Return code: 0 07:25:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:23 INFO - Reading from file tmpfile_stdout 07:25:23 INFO - Current package versions: 07:25:23 INFO - argparse == 1.2.1 07:25:23 INFO - blessings == 1.6 07:25:23 INFO - blobuploader == 1.2.4 07:25:23 INFO - docopt == 0.6.1 07:25:23 INFO - functools32 == 3.2.3-2 07:25:23 INFO - jsonschema == 2.5.1 07:25:23 INFO - manifestparser == 1.1 07:25:23 INFO - mozInstall == 1.12 07:25:23 INFO - mozcrash == 0.17 07:25:23 INFO - mozdebug == 0.1 07:25:23 INFO - mozdevice == 0.48 07:25:23 INFO - mozfile == 1.2 07:25:23 INFO - mozhttpd == 0.7 07:25:23 INFO - mozinfo == 0.9 07:25:23 INFO - mozleak == 0.1 07:25:23 INFO - mozlog == 3.2 07:25:23 INFO - moznetwork == 0.27 07:25:23 INFO - mozprocess == 0.22 07:25:23 INFO - mozprofile == 0.28 07:25:23 INFO - mozrunner == 6.11 07:25:23 INFO - mozscreenshot == 0.1 07:25:23 INFO - mozsystemmonitor == 0.3 07:25:23 INFO - moztest == 0.7 07:25:23 INFO - mozversion == 1.4 07:25:23 INFO - psutil == 3.1.1 07:25:23 INFO - requests == 1.2.3 07:25:23 INFO - wsgiref == 0.1.2 07:25:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:25:23 INFO - Using env: {'DISPLAY': ':0', 07:25:23 INFO - 'HOME': '/home/cltbld', 07:25:23 INFO - 'LANG': 'en_US.UTF-8', 07:25:23 INFO - 'LANGUAGE': 'en_US:en', 07:25:23 INFO - 'LOGNAME': 'cltbld', 07:25:23 INFO - 'MAIL': '/var/mail/cltbld', 07:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:23 INFO - 'MOZ_NO_REMOTE': '1', 07:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:23 INFO - 'NO_EM_RESTART': '1', 07:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:23 INFO - 'PWD': '/builds/slave/test', 07:25:23 INFO - 'SHELL': '/bin/bash', 07:25:23 INFO - 'SHLVL': '1', 07:25:23 INFO - 'TERM': 'linux', 07:25:23 INFO - 'TMOUT': '86400', 07:25:23 INFO - 'USER': 'cltbld', 07:25:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:23 INFO - '_': '/tools/buildbot/bin/python'} 07:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:25:23 INFO - Cleaning up... 07:25:23 INFO - Return code: 0 07:25:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:25:23 INFO - Using env: {'DISPLAY': ':0', 07:25:23 INFO - 'HOME': '/home/cltbld', 07:25:23 INFO - 'LANG': 'en_US.UTF-8', 07:25:23 INFO - 'LANGUAGE': 'en_US:en', 07:25:23 INFO - 'LOGNAME': 'cltbld', 07:25:23 INFO - 'MAIL': '/var/mail/cltbld', 07:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:23 INFO - 'MOZ_NO_REMOTE': '1', 07:25:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:23 INFO - 'NO_EM_RESTART': '1', 07:25:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:23 INFO - 'PWD': '/builds/slave/test', 07:25:23 INFO - 'SHELL': '/bin/bash', 07:25:23 INFO - 'SHLVL': '1', 07:25:23 INFO - 'TERM': 'linux', 07:25:23 INFO - 'TMOUT': '86400', 07:25:23 INFO - 'USER': 'cltbld', 07:25:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:23 INFO - '_': '/tools/buildbot/bin/python'} 07:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:24 INFO - Cleaning up... 07:25:24 INFO - Return code: 0 07:25:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:25: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')]} 07:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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')]} 07:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:24 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 0x7fe4c6bcef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', '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 07:25:24 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 07:25:24 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 07:25:24 INFO - Using env: {'DISPLAY': ':0', 07:25:24 INFO - 'HOME': '/home/cltbld', 07:25:24 INFO - 'LANG': 'en_US.UTF-8', 07:25:24 INFO - 'LANGUAGE': 'en_US:en', 07:25:24 INFO - 'LOGNAME': 'cltbld', 07:25:24 INFO - 'MAIL': '/var/mail/cltbld', 07:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:24 INFO - 'MOZ_NO_REMOTE': '1', 07:25:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:24 INFO - 'NO_EM_RESTART': '1', 07:25:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:24 INFO - 'PWD': '/builds/slave/test', 07:25:24 INFO - 'SHELL': '/bin/bash', 07:25:24 INFO - 'SHLVL': '1', 07:25:24 INFO - 'TERM': 'linux', 07:25:24 INFO - 'TMOUT': '86400', 07:25:24 INFO - 'USER': 'cltbld', 07:25:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:24 INFO - '_': '/tools/buildbot/bin/python'} 07:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:24 INFO - Downloading/unpacking flake8 07:25:24 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 07:25:24 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 07:25:24 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 07:25:24 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 07:25:24 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 07:25:24 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 07:25:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:25:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:25:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:25:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:25:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:25:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:25:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:25:27 INFO - no previously-included directories found matching 'docs/_build' 07:25:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:25:27 INFO - Running setup.py install for pep8 07:25:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:25:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:25:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:25:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:25:27 INFO - no previously-included directories found matching 'docs/_build' 07:25:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:25:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:25:27 INFO - Cleaning up... 07:25:27 INFO - Return code: 0 07:25:27 INFO - Running post-action listener: _resource_record_post_action 07:25:27 INFO - Running post-action listener: _start_resource_monitoring 07:25:27 INFO - Starting resource monitoring. 07:25:27 INFO - ##### 07:25:27 INFO - ##### Finished create-virtualenv step (success) 07:25:27 INFO - ##### 07:25:27 INFO - ##### 07:25:27 INFO - ##### Running install step. 07:25:27 INFO - ##### 07:25:27 INFO - Running pre-action listener: _resource_record_pre_action 07:25:27 INFO - Running main action method: install 07:25:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:27 INFO - Reading from file tmpfile_stdout 07:25:27 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:27 INFO - Reading from file tmpfile_stdout 07:25:27 INFO - Output received: 07:25:27 INFO - Usage: mozinstall [options] installer 07:25:27 INFO - Options: 07:25:27 INFO - -h, --help show this help message and exit 07:25:27 INFO - -d DEST, --destination=DEST 07:25:27 INFO - Directory to install application into. [default: 07:25:27 INFO - "/builds/slave/test"] 07:25:27 INFO - --app=APP Application being installed. [default: firefox] 07:25:27 INFO - mkdir: /builds/slave/test/build/application 07:25:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:25:44 INFO - Reading from file tmpfile_stdout 07:25:44 INFO - Output received: 07:25:44 INFO - /builds/slave/test/build/application/firefox/firefox 07:25:44 INFO - Running post-action listener: _resource_record_post_action 07:25:44 INFO - ##### 07:25:44 INFO - ##### Finished install step (success) 07:25:44 INFO - ##### 07:25:44 INFO - ##### 07:25:44 INFO - ##### Running run-tests step. 07:25:44 INFO - ##### 07:25:44 INFO - Running pre-action listener: _resource_record_pre_action 07:25:44 INFO - Running main action method: run_tests 07:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:25:44 INFO - Python 2.7.3 07:25:44 INFO - Return code: 0 07:25:44 INFO - grabbing minidump binary from tooltool 07:25: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')]} 07:25:44 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114d1c0>, '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 0x114e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x114e550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:25:44 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 07:25:44 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 07:25:44 INFO - Calling ['/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'] with output_timeout 600 07:25:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:44 INFO - Return code: 0 07:25:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:25:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:25:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:25:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:25:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:25:45 INFO - Return code: 0 07:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 07:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 07:25:45 INFO - Using env: {'DISPLAY': ':0', 07:25:45 INFO - 'HOME': '/home/cltbld', 07:25:45 INFO - 'LANG': 'en_US.UTF-8', 07:25:45 INFO - 'LANGUAGE': 'en_US:en', 07:25:45 INFO - 'LOGNAME': 'cltbld', 07:25:45 INFO - 'MAIL': '/var/mail/cltbld', 07:25:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:25:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:45 INFO - 'MOZ_NO_REMOTE': '1', 07:25:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:25:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:45 INFO - 'NO_EM_RESTART': '1', 07:25:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:45 INFO - 'PWD': '/builds/slave/test', 07:25:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:25:45 INFO - 'SHELL': '/bin/bash', 07:25:45 INFO - 'SHLVL': '1', 07:25:45 INFO - 'TERM': 'linux', 07:25:45 INFO - 'TMOUT': '86400', 07:25:45 INFO - 'USER': 'cltbld', 07:25:45 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555', 07:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:45 INFO - '_': '/tools/buildbot/bin/python'} 07:25:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 07:25:45 INFO - mozversion application_buildid: 20160606043046 07:25:45 INFO - mozversion application_changeset: 0a3b6e2df6567d845f31c000c68dd67816c6153d 07:25:45 INFO - mozversion application_display_name: Nightly 07:25:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:45 INFO - mozversion application_name: Firefox 07:25:45 INFO - mozversion application_remotingname: firefox 07:25:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:25:45 INFO - mozversion application_vendor: Mozilla 07:25:45 INFO - mozversion application_version: 49.0a1 07:25:45 INFO - mozversion platform_buildid: 20160606043046 07:25:45 INFO - mozversion platform_changeset: 0a3b6e2df6567d845f31c000c68dd67816c6153d 07:25:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:25:45 INFO - mozversion platform_version: 49.0a1 07:25:45 INFO - using testdate: 1465223145 07:25:45 INFO - actual date: 1465223145 07:25:45 INFO - starting webserver on 'localhost:50654' 07:25:45 INFO - SUITE-START | Running 2 tests 07:25:45 INFO - TEST-START | dromaeo_css 07:25:45 INFO - operating with platform_type : linux_ 07:25:45 INFO - Initialising browser for dromaeo_css test... 07:25:45 INFO - TEST-INFO | started process 27732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm1K7A3/profile http://localhost:50654/getInfo.html) 07:25:49 INFO - PROCESS | 27732 | __metrics Screen width/height:1600/1200 07:25:49 INFO - PROCESS | 27732 | colorDepth:24 07:25:49 INFO - PROCESS | 27732 | Browser inner width/height: 1024/697 07:25:49 INFO - PROCESS | 27732 | __metrics 07:25:49 ERROR - PROCESS | 27732 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:25:50 INFO - PROCESS | 27732 | console.error: PushService: 07:25:50 INFO - PROCESS | 27732 | stateChangeProcessEnqueue: Error transitioning state 07:25:50 INFO - PROCESS | 27732 | UnknownError 07:25:50 INFO - TEST-INFO | 27732: exit 0 07:25:50 INFO - Browser initialized. 07:25:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:25:50 INFO - TEST-INFO | started process 27812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm1K7A3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:02 INFO - PROCESS | 27812 | RSS: Main: 156131328 07:26:02 INFO - PROCESS | 27812 | 07:26:53 INFO - PROCESS | 27812 | 1465223213918 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] 07:29:01 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50654/tests/dromaeo/cssquery-ext.html) 07:29:02 INFO - PROCESS | 27812 | RSS: Main: 242454528 07:29:02 INFO - PROCESS | 27812 | 07:32:04 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-ext.html (next: http://localhost:50654/tests/dromaeo/cssquery-jquery.html) 07:32:05 INFO - PROCESS | 27812 | RSS: Main: 235646976 07:32:05 INFO - PROCESS | 27812 | 07:35:07 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50654/tests/dromaeo/cssquery-mootools.html) 07:35:08 INFO - PROCESS | 27812 | RSS: Main: 238092288 07:35:08 INFO - PROCESS | 27812 | 07:38:10 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50654/tests/dromaeo/cssquery-prototype.html) 07:38:10 INFO - PROCESS | 27812 | RSS: Main: 238673920 07:38:10 INFO - PROCESS | 27812 | 07:41:13 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50654/tests/dromaeo/cssquery-yui.html) 07:41:13 INFO - PROCESS | 27812 | RSS: Main: 206512128 07:41:13 INFO - PROCESS | 27812 | 07:44:16 INFO - PROCESS | 27812 | Cycle 1(1): loaded http://localhost:50654/tests/dromaeo/cssquery-yui.html (next: http://localhost:50654/tests/dromaeo/cssquery-dojo.html) 07:44:17 INFO - PROCESS | 27812 | RSS: Main: 272420864 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | __start_tp_report 07:44:17 INFO - PROCESS | 27812 | _x_x_mozilla_page_load 07:44:17 INFO - PROCESS | 27812 | _x_x_mozilla_page_load_details 07:44:17 INFO - PROCESS | 27812 | |i|pagename|runs| 07:44:17 INFO - PROCESS | 27812 | |0;dojo.html;3120.625524937008;3135.2475405902583;3150.1022208670524;3162.841857907104;3185.5380969759385;5328.973355133219;5368.946310536893;5390.002849472846;5454.181872719089;5481.616286859921;5317.9202311965355;5324.41431442541;5339.332583427072;5357.4374690657505;5382.91925621116;4758.714477131374;4847.830325938593;4851.563359297663;4860.708357498562;5117.513836185575;5046.192609182532;5065.797368105272;5098.158803797369;5118.129917913963;5130.4356520782685;4850.345203397549;4869.707817530942;4890.364252647133;4928.630352723524;4985.451600323961;4936.210206367;4936.925946110811;4951.009798040371;4992.600591952635;5019.64862459628;4958.752062396902;4973.079980203674;4987.62592805541;5002.324686167393;5024.924626130611;5211.765470553796;5264.657797243167;5337.3061502004875;5443.346798384179;5484.670919744828;5754.597178197528;5826.737796799154;5834.2998840139035;5839.8540036499;5847.239858818369;5837.708114594296;5874.089516125007;5878.059510478346;5893.116032595102;5897.026990546555;5284.5243928046275;5472.151816468454;5485.506304432581;5516.779328826842;5525.723713814293;6077.494294546232;6080.167618667567;6080.726367313393;6080.98042526151;6120.724567394389;5869.20765696634;5880.705964701833;5886.676232807151;5889.911651325234;5896.882062358814;5627.915581266284;5800.24596802413;5862.677552734641;5865.706714664249;5898.2332296802;5841.386654401312;5858.677772722456;5866.266716660418;5866.384029676908;5872.471477566954;5642.435756424325;5821.970890145522;5876.08920617305;5895;5895.380984996599;4400.933985990213;4421.049474363027;4443.555644435531;4455.554444555519;4459.1973444779605;3941.8029098544957;3959.940600890989;3977.025628720982;3980.5621381647998;3999.8000099995465;5341.038613049688;5364.383095943938;5482.643628164156;5526.364468086222;5535.03136951032;5828.737706803204;5907.1139329100115;5907.940920590739;5918.23063001807;5944.702764861826;5476.205950137208;5510.559155267569;5530.55755539564;5536.197251398525;5540.722963851791;4893.363862697897;5013.849584512471;5075.162598171334;5085.898282034339;5130.769115389817;6067.544934129958;6109.556495504338;6112.321110680095;6112.41229444207;6113.141862489121;5922.111683247547;5927.525797936241;5942.792002280073;5945.346011938596;5979.79070732531;5795.304563452244;5806.216160818235;5828.271466066572;6098.024316109579;6107.328193898578;5649.68926709035;5662.745176467151;5670.376258611632;5670.943290567042;5724.856878578069;5709.600327977168;5766.452187042224;5768.94231057684;5770.913436298543;5772.105323674838;5387.892242155213;5582.720863956867;5601.7759289629585;5632.464915832989;5663.037283661705;5676.091825308096;5684.573656975826;5686;5688.033034384247;5693.28833895763;5569.248151499496;5588.413216612197;5590.972045139748;5593.776248949996;5594.13290939905;5544.085225937675;5584.664920104807;5654.293213348332;5654.971725141348;5661.433856614306;5485.232067510631;5486;5496.120620700669;5540.307461567304;5556.027695153412 07:44:17 INFO - PROCESS | 27812 | |1;ext.html;590.3181824992133;612.8988716861717;624.4380057947847;632.2444678609063;640.6988715303811;31525;43222.35166472505;43529.564704352946;43772.78113609427;43896.12207755843;43463;43787;43876.56123438773;43930;44036.559634403646;8696.17386348296;8867.955660221689;8883.333749968746;8898.199162075412;8900.554972251393;8645.092265312145;8771.12288771124;8784.07767184446;8787.560621968907;8793.824123517526;8783.47299162049;8832.558372081403;8835.77910552238;8839.204471597555;8840.38117331787;8717.912820871805;8769.7807554811;8772.254358379481;8775;8775.473471591693;8619.1811777881;8729.214370706666;8730.650773969097;8734;8734.082921293237;41960.79019604882;42452;42485.57514424847;42522.57477425217;42579.7871010643;3259.820709860957;3397.6262611112757;3423.7774544654767;3791.9431208531896;4298.806553705091;32310.35379292401;32359;32553.51169732456;32586.674133258603;33082;4274.123804620045;4340.826366945318;4442.444694413199;4478.552144785495;4500.144972455223;324745;325974;327733;328694;328986;52094;55838.72080639652;55890.16164757532;56425.71787141038;57422.425775742544;60118.69940650356;60640.69679651574;60921.39078609246;61024.69487652622;61054.38945610487;16380.754288685679;16407.671846563;16478.75281870773;16504.17479126039;16507.752383714254;12129.272243665408;12681.873181268254;12759.744805104032;12775.488980440865;12780.04149688777;15860.365585376687;15927.840721592867;16136.112513811853;16299.837001630069;16471.423500177436;5885.411458854081;5904.76380944766;6041;6061.54538409621;6079.118527813443;967.7628980899584;1043.2905624175633;1057.825458799305;1083.756154865149;1115.4255558387438;32631.184220394207;32787.67212327894;32892.67107328944;32917.835410822794;33032.3393532128;1184.9170558060853;1187.9109066819851;1194.8386967759416;1208.9300968642815;1260.9180403273758;17093.658126837643;17538.210780514906;18096.18567164481;18112.366067187584;18839;566255;571128;579345;942211;943227;53512;54453;54506.72746636241;54536.454635453134;54594.72702636461;1264.3362234826789;1379.799929010288;1390.5133203378737;1409.7885317202297;1422.5732280315865;30381.544276836306;32307.192320191243;32420.35159296848;32664.34671306608;32920.67079329176;48309;48695;48704.75647621739;48782.268265975326;48810;335231;337272;337660;338107;338133;24465.75534244706;24621.384465387793;24680.13619523308;24793.504129917663;26016.479670406865;46429.3035604473;47070;47858.28212576884;49200.50799491959;51816.74091629669;40598.79700601478;41432;41600;41867.581324186365;42615.36076958786;40587.1882562353;40963;41100;41120;41265.58734412737;41408.792956035024;42244.788776055924;42403;42447.787761060994;44789.552104478535;36063.819680901426;39241.60758392494;39382.60617393904;39920.2015959685;40004.1999160021;28100.156995290174;28524.144275670933;28745.568816467352;28747.712522874503;28907.566386503797 07:44:17 INFO - PROCESS | 27812 | |2;jquery.html;380.72017067455414;389.9707521935854;392.57993946477274;397.34636522919817;404.57721680843514;18122.456326310214;20142.798572014275;20704.28003942534;20948;20970.89514552429;18555.81444185561;19214.231430742788;19460.4161875144;19471.805281947178;19497.61004779907;5561.610687251894;5612.298462692163;5723.456271654186;5734.856628584298;5750.367459579443;5491.2312276281355;5632.831015069554;5635.436456354352;5639.125935480007;5660.745266462998;5486.780528778844;5614.803481878135;5618.381977982439;5766.192733017381;5769.653820770746;5792.131180322943;5793.362730099686;5794.594378393493;5809.912851307192;5834.883302333951;5388.218708287276;5649.887002259973;5657.179708942222;5657.3494048184575;5658.547316214693;12696.746065078647;12905.935470322682;13724.45102195911;22870.313890583144;23601.173958911528;2170.533335332911;2200.3069033254633;2229.297771202066;2237.395324711587;2252.8197744180425;9448.622055117787;9645.180159686435;9745.366551174151;9755.170810481117;9903.752406189831;1852.6757817381904;1941.417574727576;1968.3602829080605;1977;2019.8687085339552;76189.61905190439;76499.61750191213;77633.61183194048;78285.60857195735;80376.59811700962;10139.442330671884;10223.386596804216;10227.982745090216;10449.164066874631;10688.091512221548;14046.85953140476;14105.576832695035;14132.717345653235;14178.14931104137;14247.358868850926;7991.520508769492;8039.035315762029;8089.514629122272;8089.635966381527;8280.875786863202;7935.246151615587;7962.522248665098;8127.187281271944;8375.49747015169;8386.748397548083;4295.8281668733525;4323.740575565476;4326.956730432656;4384.539623339567;4415.602595766396;1781.1628534588722;1820.335577514198;1824.6715591193713;1853.793985157658;1908.570571621374;957.9281553883347;1013.2755080117604;1021.3004092196869;1043.1915265669015;1054.362786260492;18446;19130.52173695641;19369.12838922252;19421.71249888691;19618.11718472672;972.2173650211506;988.7033889833021;989.3865803202061;1010.3230835340339;1012.6607586458618;10657.307275027099;10751.51618177184;10978.286411383235;11214.215004949738;11379.349994250788;473558;474590;492494;499059;500639;90270.54864725635;91825;92130.53934730418;95271;95678.52160739152;1052.1687866585312;1092.4756117063853;1116.5366372955295;1130.2257953302014;1140.6463996161217;17697.292108315523;17700.29198832032;18129.36547220841;18146.27414903442;18391.90804045971;41191.588084119976;42788;43911;44011;44469.55530444654;60439.09341359971;61536.69231653813;61957;62217.68891155515;62870;7278.308560686726;7620.618969051636;8222.876656850272;8485.406021578678;8561.058283588676;13016.869831301567;13086.803697944348;13531.458741650618;13733.107348022346;13861.306934653025;11917.82123268169;11950.163488555718;12054.638360849189;12070;12070.396480176132;11877.584284550345;11943.462544185359;11972.922436980714;12086.818697719717;12096.818547721616;11521.308721476738;12325.260484370967;12340.197887137307;12463.501459941863;12475.313857737912;7603.885941710768;7655.885161722467;7857.685692572233;7906.011748531434;7920.801979950547;8756.649734010569;11075.944620276847;11755.764884702428;11777.587784427504;12194.878051219373 07:44:17 INFO - PROCESS | 27812 | |3;mootools.html;1262.0912942681266;1435.7343891380096;1509.1397903195177;1605.8474444927729;1837.2375464182364;8202.466839655419;8381.958090209548;8407.453515521487;8673.869891951626;8677.652893884237;8472.491650500975;8630.611622476988;8650.913490865105;8652.30781537477;8653.177948094937;2273.5566564519927;2294.7475777664445;2296.9425764355856;2297.1844995026777;2298.9885050574726;2301.6432452969734;2326.93019209424;2337.1586228957563;2341.1220792202926;2343.0627748900406;2320;2323.93028209154;2341.4497593065616;2343.5195784864145;2355.305184970438;2282.9895961894013;2283.9885800571055;2312.7340355859037;2319.5592837361014;2328.161861729776;2249.1228420915854;2258.9548209035893;2263.1400067974228;2264.4225722440674;2272.204728345066;8267;8434.40959132861;8465.492070475791;8509.446885952455;8517.318614510825;5035.295058691786;5203.505666961671;5211.895762084775;5215.087359712035;5216.947830521684;3286.96713032869;3291.308825146722;3303.174206448388;3362.96637033629;3369.8652053917813;1187.590281352932;1192.0999645267855;1196.6170825335896;1203.1938601137258;1215.7993931001374;11774;12004.399780010965;12025.518979240907;12026.15816892818;12031.157818952677;4350.32569951658;4395.1868904252815;4520.16376970255;4526.094781043805;4538.773061346919;10259.230557708053;10262.076413122855;10289;10289.22830787694;10298.742531436774;5122.359705036871;5127.41034781005;5130.820421285237;5131.666441681279;5135.768890399941;4134.565870583606;4492.3710680504755;4594.977025114853;4628.490866004737;4644.86065418038;3826.8660596878976;3900.3759398496104;3929.6070392960473;3972.3445631471063;4018.7588744675413;1192.4719594459627;1192.856857177127;1238.68413554543;1245.339969815999;1248.975020499603;741.7255615422329;811.9553424561706;935.8736570563022;966.2077096780572;1010.4190090697731;5561.694106824163;5588.608797384225;5603.7478313475995;5655.491005809415;5766.596338256366;482.55363788495504;484.1817328714461;489.2661008487269;503.2777963622214;653.9705713242915;3950.6246906543834;4099.795010249535;4142.316517774594;4148.56440073794;4164.375343698409;10675.092617127475;10756.946215268874;10827.296225745302;10981.17641176915;11005.339679619248;10772.892271077346;10864.293820901616;10917.39954302505;10933.617323393642;10933.671989840317;7724.7682569523;7984.121746607869;8005.319547838382;8007.159248278964;8186.877196842052;5879.147523609138;5882.705864706833;5999.4900433463645;6024.3975602439405;6053.243344581927;9815.607375704893;9887.406755594688;10018.298719089593;10028.147607453157;10028.147607453448;9958.601655933682;10097.242706797166;10129.157425187457;10150.54322555472;10229.386236825814;6680.164979377578;6853;6858.279880612663;7674.155842857393;7821.804454888445;8068.556229407204;8262.752117436487;8275.089740128702;8500.70247541333;8507.361947853811;7063.611501367474;7394.519356241827;7531.887021694789;7578.696852126074;7676.501027433199;7248.855022899618;7549.547027178387;7568.508046977149;7625.6568454418575;7660.616969151409;6699.799006029827;6719.059331693469;6755.121834161528;6766.729330826712;6767.89848152288;4760.4763476016915;4784.856454306377;4799.568038876518;4886.975565122151;4900.166971614763;4980.9633588320285;5026.673266237683;5049.798008079636;5095.974520127375;5111.923321150261 07:44:17 INFO - PROCESS | 27812 | |4;prototype.html;311.44407233088924;324.4095745742748;327.9688429599188;330.18609128498275;333.36161251203947;5216.556592689626;5290.227773695811;5354.464553544649;5405.729593570705;5425.37608175059;5318.893622127555;5320.856015956569;5340.172273297635;5343.438938911417;5354.866128346784;4164.292070348033;4169.4162817205615;4173.4157217989505;4181.163767246548;4188.429338997876;4064.309067458539;4169.854055108071;4171.249175148472;4198.118395137025;4244.514419953022;4085.673146148301;4125.195586860564;4147.751134931898;4159.750414975096;4174.812133454002;4152.916941661165;4154.418381426588;4166.416701661769;4178.8537401190815;4180.268453020709;3667.6332366763377;3672.6510981456718;3686.557613086447;3699.8890033299044;3703.2038111805823;5070.72111033893;5127.182840283875;5140.228965655144;5171.456997015297;5188.558972487305;4701.623870090384;4729.976350118227;4739.19433696269;4739.928901066452;4756.500567440404;5561.360443549003;5592.105263157916;5602.635828671164;5618.129189975561;5619.690916999563;3809.771413715186;3963.94955336836;3967.444557761916;3976.70174736893;3976.960230397688;5476.890462190774;5687.516561092353;5817.941820581782;5821.388754180834;5832.445917637818;3932.744371615836;4017.3371393720295;4035.1929614077303;4060.9847538115473;4061.8578349757618;3868.2843673920415;3993.9201215975518;4032.9798351008058;4048.109415928491;4066.7356621819486;4654.930176047362;4655.976720116378;4675.532446755297;4695.624350051988;4712.256465107932;5625.296837895263;5630.296212973379;5648.265725455664;5660.150977353348;5672.546196304285;1359.8368195816367;1411.3084588551742;1414.1161773891317;1415.1721243072918;1419.5954153066327;875.8642410426394;881.9284569248395;883.9039590907229;889.4174315823146;896.7759008952863;4721.315409265637;4755.001449695534;4796.784144713426;4804.492720314191;4853.538913803183;5073.619478539051;5097.64316497849;5101.770420331094;5123.3339665843205;5123.974380128076;4483.273939533303;4776.21192503233;4803;4808.2787581863;4834.226523756252;2704.8512331821544;2712.22701530063;2720.8231464954715;2726.8363898165776;2728.4133911209187;5244.160934250502;5252.973735131376;5267.736613169326;5278.788848446096;5281.973590132025;4868.78090485929;5185.662931909414;5187.792488300502;5204;5247.688077980505;5102.877366979333;5107.438181799999;5207.869803254873;5211.895762084813;5249.370075590953;5175.482451754794;5211;5229.398619158694;5236.554892834151;5642.291699984113;5412.566994640498;5606.523445507178;5681.914771278517;5693.459121383462;5701.971490142523;5892.145638882424;5908.025175846109;5915.615484993462;5926.67403292823;5956.225690660186;3853.7090074825246;4040.6767458602653;4050.6556942660204;4062.9593704063764;4072.775997320176;5789.2763404574425;5801;5815.836832633575;5824.796132135355;5842.773017666168;5706.743196556082;5720.39935806751;5727.341355744143;5734.799282025276;5744.396838332083;5485.177223416519;5628.971855140698;5631.155326701028;5632.9436705632415;5688.459596338341;5512.263636954346;5524.889502210013;5536.20769772707;5542.334919809648;5546.533416614616;5613.550915926798;5638.492535671645;5678.488935995781;5689.203511508475;5692.174634678031;5421.484958928895;5520.36515800672;5540.418256083214;5569.526590239875;5571.303587051619 07:44:17 INFO - PROCESS | 27812 | |5;yui.html;719.0329007484934;740.7444431671072;784.4744021505591;813.4631143445328;853.7652145659943;15818.446354377598;15907.12510811905;16087.356505739786;16138;16147.354105835753;12246.510139594406;14797.852021479783;14871.256437178125;14929.402823887032;14943.178125203109;788.2157253532743;791.4736700094445;793.9642716077761;797.2744802229972;809.8825670277806;790.7864876483346;796.2753893956501;799.1848314719012;799.2686691677137;799.2726618776915;857.2499063319596;857.8241460500583;862.1766213266316;862.3403096631082;862.5600943518788;696.7770313499682;821.5112008355019;825.2984962781647;835.2399316621876;854.7136709202425;623.5666211982674;626.1922120464595;626.3923993726124;626.677261210477;627.2567008095422;14253.073550219202;14272.785908211385;14303.427862885472;14310.928445357707;14325.64185895361;14464.783028254584;14783.70432591353;14838.406463741438;14852.702945941128;14874.405023799036;13280.933595332059;13324.533641322507;13376.063675542717;13392.665183370396;13437.865621343759;4548.499665036843;4579.862604121849;4581.289900065495;4734.124187025412;4798.592119669832;72895.27104728938;74071.62964185198;74073;74106.25893741101;76065.61967190183;11671.241369310968;11705.531778728755;11707.882921170849;11863;12091.093168012434;21991.670124948138;22143.77856221417;22195.66706499404;22199.22302719397;22389.440263993205;8732.782644306177;8766.21104100634;8773;8793.736187914374;8866.601002954883;4669.276262179367;4710.434747830213;4730.550597693214;4819.445763737215;4981.626378021663;3209.8074115552677;3304.2069903223055;3305.6198537168534;3307.669233076673;3312.271300313927;526.7313670028236;566.6996491859321;582.7202942587581;603.9577229593887;623.7255607532671;617.607819034916;708.6598432752307;724.5906063074347;748.8895264287751;769.0195001373294;9699.757506062264;9856.655017074369;9946.80106397868;9990.500474976223;10088.192944564416;643.8937575300117;699.964053201265;729.6133049483782;731.6707481633287;789.3448437796615;4439.378487011821;4706.317583950308;4710.4347478302825;4721.811127554859;4881.267809828554;112609;113927;118162.40918795351;118303;121589;63219;86225;94861.5256923711;96643.51678241705;102292.48853755833;89611.55194223986;89771.55114224387;91285;91494.08505914989;91567.54216228877;8549.401541892008;8690.478571285743;8833.514156721185;8944.731658050268;9114.680986165453;48081;51879.481205188975;52804.73597631987;52847.735761320946;53334;74919.62540187481;77778.2222177771;78004;78327.82069089482;80381.59809200917;11266.7746645065;11361.829572556584;12103.455344509694;12241.632751017127;12658.240505569695;13582.79625805594;14136.575902722934;14611.488597899022;15334.76997845011;15485;13657.726845463234;13773.931130344285;14122.105053224477;14172;14744.705105898036;13777.242251676254;13926.658836176928;14159.858401415853;14289.42842286297;14875.181864997528;12322.383880806103;12347.62957111288;12757.681057973625;12762.553310634083;13200.20798752078;10888.945555272174;11304.321740695585;11393.031592314746;11849.585264515701;11990.460429280878;10963.287386319864;10979.670609881716;11091.682986291555;11096.445177741241;11225.270357426742 07:44:17 INFO - PROCESS | 27812 | __end_tp_report 07:44:17 INFO - PROCESS | 27812 | __start_cc_report 07:44:17 INFO - PROCESS | 27812 | _x_x_mozilla_cycle_collect,1041 07:44:17 INFO - PROCESS | 27812 | __end_cc_report 07:44:17 INFO - PROCESS | 27812 | __startTimestamp1465224257031__endTimestamp 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | ------- Summary: start ------- 07:44:17 INFO - PROCESS | 27812 | Number of tests: 6 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#0] dojo.html Cycles:165 Average:5406.58 Median:5569.25 stddev:621.27 (11.2%) stddev-sans-first:596.73 07:44:17 INFO - PROCESS | 27812 | Values: 3120.6 3135.2 3150.1 3162.8 3185.5 5329.0 5368.9 5390.0 5454.2 5481.6 5317.9 5324.4 5339.3 5357.4 5382.9 4758.7 4847.8 4851.6 4860.7 5117.5 5046.2 5065.8 5098.2 5118.1 5130.4 4850.3 4869.7 4890.4 4928.6 4985.5 4936.2 4936.9 4951.0 4992.6 5019.6 4958.8 4973.1 4987.6 5002.3 5024.9 5211.8 5264.7 5337.3 5443.3 5484.7 5754.6 5826.7 5834.3 5839.9 5847.2 5837.7 5874.1 5878.1 5893.1 5897.0 5284.5 5472.2 5485.5 5516.8 5525.7 6077.5 6080.2 6080.7 6081.0 6120.7 5869.2 5880.7 5886.7 5889.9 5896.9 5627.9 5800.2 5862.7 5865.7 5898.2 5841.4 5858.7 5866.3 5866.4 5872.5 5642.4 5822.0 5876.1 5895.0 5895.4 4400.9 4421.0 4443.6 4455.6 4459.2 3941.8 3959.9 3977.0 3980.6 3999.8 5341.0 5364.4 5482.6 5526.4 5535.0 5828.7 5907.1 5907.9 5918.2 5944.7 5476.2 5510.6 5530.6 5536.2 5540.7 4893.4 5013.8 5075.2 5085.9 5130.8 6067.5 6109.6 6112.3 6112.4 6113.1 5922.1 5927.5 5942.8 5945.3 5979.8 5795.3 5806.2 5828.3 6098.0 6107.3 5649.7 5662.7 5670.4 5670.9 5724.9 5709.6 5766.5 5768.9 5770.9 5772.1 5387.9 5582.7 5601.8 5632.5 5663.0 5676.1 5684.6 5686.0 5688.0 5693.3 5569.2 5588.4 5591.0 5593.8 5594.1 5544.1 5584.7 5654.3 5655.0 5661.4 5485.2 5486.0 5496.1 5540.3 5556.0 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#1] ext.html Cycles:180 Average:61687.26 Median:42323.89 stddev:136655.66 (322.9%) stddev-sans-first:136962.02 07:44:17 INFO - PROCESS | 27812 | Values: 590.3 612.9 624.4 632.2 640.7 31525.0 43222.4 43529.6 43772.8 43896.1 43463.0 43787.0 43876.6 43930.0 44036.6 8696.2 8868.0 8883.3 8898.2 8900.6 8645.1 8771.1 8784.1 8787.6 8793.8 8783.5 8832.6 8835.8 8839.2 8840.4 8717.9 8769.8 8772.3 8775.0 8775.5 8619.2 8729.2 8730.7 8734.0 8734.1 41960.8 42452.0 42485.6 42522.6 42579.8 3259.8 3397.6 3423.8 3791.9 4298.8 32310.4 32359.0 32553.5 32586.7 33082.0 4274.1 4340.8 4442.4 4478.6 4500.1 324745.0 325974.0 327733.0 328694.0 328986.0 52094.0 55838.7 55890.2 56425.7 57422.4 60118.7 60640.7 60921.4 61024.7 61054.4 16380.8 16407.7 16478.8 16504.2 16507.8 12129.3 12681.9 12759.7 12775.5 12780.0 15860.4 15927.8 16136.1 16299.8 16471.4 5885.4 5904.8 6041.0 6061.5 6079.1 967.8 1043.3 1057.8 1083.8 1115.4 32631.2 32787.7 32892.7 32917.8 33032.3 1184.9 1187.9 1194.8 1208.9 1260.9 17093.7 17538.2 18096.2 18112.4 18839.0 566255.0 571128.0 579345.0 942211.0 943227.0 53512.0 54453.0 54506.7 54536.5 54594.7 1264.3 1379.8 1390.5 1409.8 1422.6 30381.5 32307.2 32420.4 32664.3 32920.7 48309.0 48695.0 48704.8 48782.3 48810.0 335231.0 337272.0 337660.0 338107.0 338133.0 24465.8 24621.4 24680.1 24793.5 26016.5 46429.3 47070.0 47858.3 49200.5 51816.7 40598.8 41432.0 41600.0 41867.6 42615.4 40587.2 40963.0 41100.0 41120.0 41265.6 41408.8 42244.8 42403.0 42447.8 44789.6 36063.8 39241.6 39382.6 39920.2 40004.2 28100.2 28524.1 28745.6 28747.7 28907.6 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#2] jquery.html Cycles:180 Average:28834.30 Median:385.35 stddev:80559.54 (20905.8%) stddev-sans-first:80757.20 07:44:17 INFO - PROCESS | 27812 | Values: 380.7 390.0 392.6 397.3 404.6 18122.5 20142.8 20704.3 20948.0 20970.9 18555.8 19214.2 19460.4 19471.8 19497.6 5561.6 5612.3 5723.5 5734.9 5750.4 5491.2 5632.8 5635.4 5639.1 5660.7 5486.8 5614.8 5618.4 5766.2 5769.7 5792.1 5793.4 5794.6 5809.9 5834.9 5388.2 5649.9 5657.2 5657.3 5658.5 12696.7 12905.9 13724.5 22870.3 23601.2 2170.5 2200.3 2229.3 2237.4 2252.8 9448.6 9645.2 9745.4 9755.2 9903.8 1852.7 1941.4 1968.4 1977.0 2019.9 76189.6 76499.6 77633.6 78285.6 80376.6 10139.4 10223.4 10228.0 10449.2 10688.1 14046.9 14105.6 14132.7 14178.1 14247.4 7991.5 8039.0 8089.5 8089.6 8280.9 7935.2 7962.5 8127.2 8375.5 8386.7 4295.8 4323.7 4327.0 4384.5 4415.6 1781.2 1820.3 1824.7 1853.8 1908.6 957.9 1013.3 1021.3 1043.2 1054.4 18446.0 19130.5 19369.1 19421.7 19618.1 972.2 988.7 989.4 1010.3 1012.7 10657.3 10751.5 10978.3 11214.2 11379.3 473558.0 474590.0 492494.0 499059.0 500639.0 90270.5 91825.0 92130.5 95271.0 95678.5 1052.2 1092.5 1116.5 1130.2 1140.6 17697.3 17700.3 18129.4 18146.3 18391.9 41191.6 42788.0 43911.0 44011.0 44469.6 60439.1 61536.7 61957.0 62217.7 62870.0 7278.3 7620.6 8222.9 8485.4 8561.1 13016.9 13086.8 13531.5 13733.1 13861.3 11917.8 11950.2 12054.6 12070.0 12070.4 11877.6 11943.5 11972.9 12086.8 12096.8 11521.3 12325.3 12340.2 12463.5 12475.3 7603.9 7655.9 7857.7 7906.0 7920.8 8756.6 11075.9 11755.8 11777.6 12194.9 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#3] mootools.html Cycles:180 Average:5660.52 Median:4611.73 stddev:3251.93 (70.5%) stddev-sans-first:3244.25 07:44:17 INFO - PROCESS | 27812 | Values: 1262.1 1435.7 1509.1 1605.8 1837.2 8202.5 8382.0 8407.5 8673.9 8677.7 8472.5 8630.6 8650.9 8652.3 8653.2 2273.6 2294.7 2296.9 2297.2 2299.0 2301.6 2326.9 2337.2 2341.1 2343.1 2320.0 2323.9 2341.4 2343.5 2355.3 2283.0 2284.0 2312.7 2319.6 2328.2 2249.1 2259.0 2263.1 2264.4 2272.2 8267.0 8434.4 8465.5 8509.4 8517.3 5035.3 5203.5 5211.9 5215.1 5216.9 3287.0 3291.3 3303.2 3363.0 3369.9 1187.6 1192.1 1196.6 1203.2 1215.8 11774.0 12004.4 12025.5 12026.2 12031.2 4350.3 4395.2 4520.2 4526.1 4538.8 10259.2 10262.1 10289.0 10289.2 10298.7 5122.4 5127.4 5130.8 5131.7 5135.8 4134.6 4492.4 4595.0 4628.5 4644.9 3826.9 3900.4 3929.6 3972.3 4018.8 1192.5 1192.9 1238.7 1245.3 1249.0 741.7 812.0 935.9 966.2 1010.4 5561.7 5588.6 5603.7 5655.5 5766.6 482.6 484.2 489.3 503.3 654.0 3950.6 4099.8 4142.3 4148.6 4164.4 10675.1 10756.9 10827.3 10981.2 11005.3 10772.9 10864.3 10917.4 10933.6 10933.7 7724.8 7984.1 8005.3 8007.2 8186.9 5879.1 5882.7 5999.5 6024.4 6053.2 9815.6 9887.4 10018.3 10028.1 10028.1 9958.6 10097.2 10129.2 10150.5 10229.4 6680.2 6853.0 6858.3 7674.2 7821.8 8068.6 8262.8 8275.1 8500.7 8507.4 7063.6 7394.5 7531.9 7578.7 7676.5 7248.9 7549.5 7568.5 7625.7 7660.6 6699.8 6719.1 6755.1 6766.7 6767.9 4760.5 4784.9 4799.6 4887.0 4900.2 4981.0 5026.7 5049.8 5096.0 5111.9 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#4] prototype.html Cycles:180 Average:4576.98 Median:5155.84 stddev:1348.02 (26.1%) stddev-sans-first:1313.23 07:44:17 INFO - PROCESS | 27812 | Values: 311.4 324.4 328.0 330.2 333.4 5216.6 5290.2 5354.5 5405.7 5425.4 5318.9 5320.9 5340.2 5343.4 5354.9 4164.3 4169.4 4173.4 4181.2 4188.4 4064.3 4169.9 4171.2 4198.1 4244.5 4085.7 4125.2 4147.8 4159.8 4174.8 4152.9 4154.4 4166.4 4178.9 4180.3 3667.6 3672.7 3686.6 3699.9 3703.2 5070.7 5127.2 5140.2 5171.5 5188.6 4701.6 4730.0 4739.2 4739.9 4756.5 5561.4 5592.1 5602.6 5618.1 5619.7 3809.8 3963.9 3967.4 3976.7 3977.0 5476.9 5687.5 5817.9 5821.4 5832.4 3932.7 4017.3 4035.2 4061.0 4061.9 3868.3 3993.9 4033.0 4048.1 4066.7 4654.9 4656.0 4675.5 4695.6 4712.3 5625.3 5630.3 5648.3 5660.2 5672.5 1359.8 1411.3 1414.1 1415.2 1419.6 875.9 881.9 883.9 889.4 896.8 4721.3 4755.0 4796.8 4804.5 4853.5 5073.6 5097.6 5101.8 5123.3 5124.0 4483.3 4776.2 4803.0 4808.3 4834.2 2704.9 2712.2 2720.8 2726.8 2728.4 5244.2 5253.0 5267.7 5278.8 5282.0 4868.8 5185.7 5187.8 5204.0 5247.7 5102.9 5107.4 5207.9 5211.9 5249.4 5175.5 5211.0 5229.4 5236.6 5642.3 5412.6 5606.5 5681.9 5693.5 5702.0 5892.1 5908.0 5915.6 5926.7 5956.2 3853.7 4040.7 4050.7 4063.0 4072.8 5789.3 5801.0 5815.8 5824.8 5842.8 5706.7 5720.4 5727.3 5734.8 5744.4 5485.2 5629.0 5631.2 5632.9 5688.5 5512.3 5524.9 5536.2 5542.3 5546.5 5613.6 5638.5 5678.5 5689.2 5692.2 5421.5 5520.4 5540.4 5569.5 5571.3 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - PROCESS | 27812 | [#5] yui.html Cycles:180 Average:20797.96 Median:4726.18 stddev:29833.67 (631.2%) stddev-sans-first:29879.27 07:44:17 INFO - PROCESS | 27812 | Values: 719.0 740.7 784.5 813.5 853.8 15818.4 15907.1 16087.4 16138.0 16147.4 12246.5 14797.9 14871.3 14929.4 14943.2 788.2 791.5 794.0 797.3 809.9 790.8 796.3 799.2 799.3 799.3 857.2 857.8 862.2 862.3 862.6 696.8 821.5 825.3 835.2 854.7 623.6 626.2 626.4 626.7 627.3 14253.1 14272.8 14303.4 14310.9 14325.6 14464.8 14783.7 14838.4 14852.7 14874.4 13280.9 13324.5 13376.1 13392.7 13437.9 4548.5 4579.9 4581.3 4734.1 4798.6 72895.3 74071.6 74073.0 74106.3 76065.6 11671.2 11705.5 11707.9 11863.0 12091.1 21991.7 22143.8 22195.7 22199.2 22389.4 8732.8 8766.2 8773.0 8793.7 8866.6 4669.3 4710.4 4730.6 4819.4 4981.6 3209.8 3304.2 3305.6 3307.7 3312.3 526.7 566.7 582.7 604.0 623.7 617.6 708.7 724.6 748.9 769.0 9699.8 9856.7 9946.8 9990.5 10088.2 643.9 700.0 729.6 731.7 789.3 4439.4 4706.3 4710.4 4721.8 4881.3 112609.0 113927.0 118162.4 118303.0 121589.0 63219.0 86225.0 94861.5 96643.5 102292.5 89611.6 89771.6 91285.0 91494.1 91567.5 8549.4 8690.5 8833.5 8944.7 9114.7 48081.0 51879.5 52804.7 52847.7 53334.0 74919.6 77778.2 78004.0 78327.8 80381.6 11266.8 11361.8 12103.5 12241.6 12658.2 13582.8 14136.6 14611.5 15334.8 15485.0 13657.7 13773.9 14122.1 14172.0 14744.7 13777.2 13926.7 14159.9 14289.4 14875.2 12322.4 12347.6 12757.7 12762.6 13200.2 10888.9 11304.3 11393.0 11849.6 11990.5 10963.3 10979.7 11091.7 11096.4 11225.3 07:44:17 INFO - PROCESS | 27812 | -------- Summary: end -------- 07:44:17 INFO - PROCESS | 27812 | 07:44:17 INFO - TEST-INFO | 27812: exit 0 07:44:17 INFO - TEST-OK | dromaeo_css | took 1112043ms 07:44:17 INFO - TEST-START | kraken 07:44:17 INFO - operating with platform_type : linux_ 07:44:17 INFO - Initialising browser for kraken test... 07:44:17 INFO - TEST-INFO | started process 28033 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYj8B6u/profile http://localhost:50654/getInfo.html) 07:44:21 INFO - PROCESS | 28033 | __metrics Screen width/height:1600/1200 07:44:21 INFO - PROCESS | 28033 | colorDepth:24 07:44:21 INFO - PROCESS | 28033 | Browser inner width/height: 1024/697 07:44:21 INFO - PROCESS | 28033 | __metrics 07:44:22 ERROR - PROCESS | 28033 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:44:22 INFO - PROCESS | 28033 | console.error: PushService: 07:44:22 INFO - PROCESS | 28033 | stateChangeProcessEnqueue: Error transitioning state 07:44:22 INFO - PROCESS | 28033 | UnknownError 07:44:23 INFO - TEST-INFO | 28033: exit 0 07:44:23 INFO - Browser initialized. 07:44:23 INFO - Running cycle 1/1 for kraken test... 07:44:23 INFO - TEST-INFO | started process 28115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYj8B6u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:34 INFO - PROCESS | 28115 | RSS: Main: 156483584 07:44:34 INFO - PROCESS | 28115 | 07:44:37 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50654/tests/kraken/driver.html?testName=audio-beat-detection) 07:44:38 INFO - PROCESS | 28115 | RSS: Main: 214704128 07:44:38 INFO - PROCESS | 28115 | 07:44:40 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50654/tests/kraken/driver.html?testName=audio-dft) 07:44:41 INFO - PROCESS | 28115 | RSS: Main: 197771264 07:44:41 INFO - PROCESS | 28115 | 07:44:44 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50654/tests/kraken/driver.html?testName=audio-fft) 07:44:45 INFO - PROCESS | 28115 | RSS: Main: 268709888 07:44:45 INFO - PROCESS | 28115 | 07:44:47 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50654/tests/kraken/driver.html?testName=audio-oscillator) 07:44:47 INFO - PROCESS | 28115 | RSS: Main: 179560448 07:44:47 INFO - PROCESS | 28115 | 07:44:50 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50654/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:44:50 INFO - PROCESS | 28115 | RSS: Main: 183873536 07:44:50 INFO - PROCESS | 28115 | 07:44:53 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50654/tests/kraken/driver.html?testName=imaging-darkroom) 07:44:54 INFO - PROCESS | 28115 | RSS: Main: 217636864 07:44:54 INFO - PROCESS | 28115 | 07:44:57 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50654/tests/kraken/driver.html?testName=imaging-desaturate) 07:44:58 INFO - PROCESS | 28115 | RSS: Main: 220246016 07:44:58 INFO - PROCESS | 28115 | 07:45:00 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50654/tests/kraken/driver.html?testName=json-parse-financial) 07:45:01 INFO - PROCESS | 28115 | RSS: Main: 220704768 07:45:01 INFO - PROCESS | 28115 | 07:45:03 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50654/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:45:03 INFO - PROCESS | 28115 | RSS: Main: 206843904 07:45:03 INFO - PROCESS | 28115 | 07:45:05 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:06 INFO - PROCESS | 28115 | RSS: Main: 210563072 07:45:06 INFO - PROCESS | 28115 | 07:45:08 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:08 INFO - PROCESS | 28115 | RSS: Main: 216928256 07:45:08 INFO - PROCESS | 28115 | 07:45:11 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:45:11 INFO - PROCESS | 28115 | RSS: Main: 222650368 07:45:11 INFO - PROCESS | 28115 | 07:45:14 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:45:15 INFO - PROCESS | 28115 | RSS: Main: 223150080 07:45:15 INFO - PROCESS | 28115 | 07:45:16 INFO - PROCESS | 28115 | Cycle 1(1): loaded http://localhost:50654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50654/tests/kraken/driver.html?testName=ai-astar) 07:45:17 INFO - PROCESS | 28115 | RSS: Main: 265338880 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | __start_tp_report 07:45:17 INFO - PROCESS | 28115 | _x_x_mozilla_page_load 07:45:17 INFO - PROCESS | 28115 | _x_x_mozilla_page_load_details 07:45:17 INFO - PROCESS | 28115 | |i|pagename|runs| 07:45:17 INFO - PROCESS | 28115 | |0;ai-astar;110;106;111;110;110;111;109;109;109;110 07:45:17 INFO - PROCESS | 28115 | |1;audio-beat-detection;158;124;127;177;127;125;149;128;178;134 07:45:17 INFO - PROCESS | 28115 | |2;audio-dft;161;160;160;161;160;160;161;163;162;161 07:45:17 INFO - PROCESS | 28115 | |3;audio-fft;75;74;76;113;75;76;74;74;100;75 07:45:17 INFO - PROCESS | 28115 | |4;audio-oscillator;84;84;123;84;84;85;121;84;85;127 07:45:17 INFO - PROCESS | 28115 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 07:45:17 INFO - PROCESS | 28115 | |6;imaging-darkroom;149;148;148;150;148;150;149;149;146;148 07:45:17 INFO - PROCESS | 28115 | |7;imaging-desaturate;95;95;96;95;94;94;95;95;95;95 07:45:17 INFO - PROCESS | 28115 | |8;json-parse-financial;53;55;56;54;53;55;55;55;53;55 07:45:17 INFO - PROCESS | 28115 | |9;json-stringify-tinderbox;62;63;63;63;64;63;63;63;63;63 07:45:17 INFO - PROCESS | 28115 | |10;stanford-crypto-aes;78;78;83;81;79;79;80;82;117;80 07:45:17 INFO - PROCESS | 28115 | |11;stanford-crypto-ccm;127;126;140;117;121;120;121;121;138;138 07:45:17 INFO - PROCESS | 28115 | |12;stanford-crypto-pbkdf2;164;162;162;160;160;158;166;163;156;157 07:45:17 INFO - PROCESS | 28115 | |13;stanford-crypto-sha256-iterative;62;63;63;63;62;62;63;62;66;66 07:45:17 INFO - PROCESS | 28115 | __end_tp_report 07:45:17 INFO - PROCESS | 28115 | __start_cc_report 07:45:17 INFO - PROCESS | 28115 | _x_x_mozilla_cycle_collect,1614 07:45:17 INFO - PROCESS | 28115 | __end_cc_report 07:45:17 INFO - PROCESS | 28115 | __startTimestamp1465224317219__endTimestamp 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | ------- Summary: start ------- 07:45:17 INFO - PROCESS | 28115 | Number of tests: 14 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 07:45:17 INFO - PROCESS | 28115 | Values: 110.0 106.0 111.0 110.0 110.0 111.0 109.0 109.0 109.0 110.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:141.50 stddev:21.46 (15.2%) stddev-sans-first:22.03 07:45:17 INFO - PROCESS | 28115 | Values: 158.0 124.0 127.0 177.0 127.0 125.0 149.0 128.0 178.0 134.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 07:45:17 INFO - PROCESS | 28115 | Values: 161.0 160.0 160.0 161.0 160.0 160.0 161.0 163.0 162.0 161.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:13.70 (18.3%) stddev-sans-first:14.35 07:45:17 INFO - PROCESS | 28115 | Values: 75.0 74.0 76.0 113.0 75.0 76.0 74.0 74.0 100.0 75.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:19.08 (22.7%) stddev-sans-first:19.73 07:45:17 INFO - PROCESS | 28115 | Values: 84.0 84.0 123.0 84.0 84.0 85.0 121.0 84.0 85.0 127.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:45:17 INFO - PROCESS | 28115 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:1.18 (0.8%) stddev-sans-first:1.24 07:45:17 INFO - PROCESS | 28115 | Values: 149.0 148.0 148.0 150.0 148.0 150.0 149.0 149.0 146.0 148.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:45:17 INFO - PROCESS | 28115 | Values: 95.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:1.01 07:45:17 INFO - PROCESS | 28115 | Values: 53.0 55.0 56.0 54.0 53.0 55.0 55.0 55.0 53.0 55.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 07:45:17 INFO - PROCESS | 28115 | Values: 62.0 63.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:80.00 stddev:11.81 (14.8%) stddev-sans-first:12.35 07:45:17 INFO - PROCESS | 28115 | Values: 78.0 78.0 83.0 81.0 79.0 79.0 80.0 82.0 117.0 80.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:126.50 stddev:8.62 (6.8%) stddev-sans-first:9.14 07:45:17 INFO - PROCESS | 28115 | Values: 127.0 126.0 140.0 117.0 121.0 120.0 121.0 121.0 138.0 138.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:162.00 stddev:3.19 (2.0%) stddev-sans-first:3.17 07:45:17 INFO - PROCESS | 28115 | Values: 164.0 162.0 162.0 160.0 160.0 158.0 166.0 163.0 156.0 157.0 07:45:17 INFO - PROCESS | 28115 | 07:45:17 INFO - PROCESS | 28115 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.55 (2.5%) stddev-sans-first:1.58 07:45:17 INFO - PROCESS | 28115 | Values: 62.0 63.0 63.0 63.0 62.0 62.0 63.0 62.0 66.0 66.0 07:45:17 INFO - PROCESS | 28115 | -------- Summary: end -------- 07:45:17 INFO - PROCESS | 28115 | 07:45:18 INFO - TEST-INFO | 28115: exit 0 07:45:18 INFO - TEST-OK | kraken | took 60467ms 07:45:18 INFO - SUITE-END | took 1172s 07:45:18 INFO - Completed test suite (00:19:32) 07:45:18 INFO - javascript benchmark 07:45:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.625524937008, 3135.2475405902583, 3150.1022208670524, 3162.841857907104, 3185.5380969759385, 5328.973355133219, 5368.946310536893, 5390.002849472846, 5454.181872719089, 5481.616286859921, 5317.9202311965355, 5324.41431442541, 5339.332583427072, 5357.4374690657505, 5382.91925621116, 4758.714477131374, 4847.830325938593, 4851.563359297663, 4860.708357498562, 5117.513836185575, 5046.192609182532, 5065.797368105272, 5098.158803797369, 5118.129917913963, 5130.4356520782685, 4850.345203397549, 4869.707817530942, 4890.364252647133, 4928.630352723524, 4985.451600323961, 4936.210206367, 4936.925946110811, 4951.009798040371, 4992.600591952635, 5019.64862459628, 4958.752062396902, 4973.079980203674, 4987.62592805541, 5002.324686167393, 5024.924626130611, 5211.765470553796, 5264.657797243167, 5337.3061502004875, 5443.346798384179, 5484.670919744828, 5754.597178197528, 5826.737796799154, 5834.2998840139035, 5839.8540036499, 5847.239858818369, 5837.708114594296, 5874.089516125007, 5878.059510478346, 5893.116032595102, 5897.026990546555, 5284.5243928046275, 5472.151816468454, 5485.506304432581, 5516.779328826842, 5525.723713814293, 6077.494294546232, 6080.167618667567, 6080.726367313393, 6080.98042526151, 6120.724567394389, 5869.20765696634, 5880.705964701833, 5886.676232807151, 5889.911651325234, 5896.882062358814, 5627.915581266284, 5800.24596802413, 5862.677552734641, 5865.706714664249, 5898.2332296802, 5841.386654401312, 5858.677772722456, 5866.266716660418, 5866.384029676908, 5872.471477566954, 5642.435756424325, 5821.970890145522, 5876.08920617305, 5895.0, 5895.380984996599, 4400.933985990213, 4421.049474363027, 4443.555644435531, 4455.554444555519, 4459.1973444779605, 3941.8029098544957, 3959.940600890989, 3977.025628720982, 3980.5621381647998, 3999.8000099995465, 5341.038613049688, 5364.383095943938, 5482.643628164156, 5526.364468086222, 5535.03136951032, 5828.737706803204, 5907.1139329100115, 5907.940920590739, 5918.23063001807, 5944.702764861826, 5476.205950137208, 5510.559155267569, 5530.55755539564, 5536.197251398525, 5540.722963851791, 4893.363862697897, 5013.849584512471, 5075.162598171334, 5085.898282034339, 5130.769115389817, 6067.544934129958, 6109.556495504338, 6112.321110680095, 6112.41229444207, 6113.141862489121, 5922.111683247547, 5927.525797936241, 5942.792002280073, 5945.346011938596, 5979.79070732531, 5795.304563452244, 5806.216160818235, 5828.271466066572, 6098.024316109579, 6107.328193898578, 5649.68926709035, 5662.745176467151, 5670.376258611632, 5670.943290567042, 5724.856878578069, 5709.600327977168, 5766.452187042224, 5768.94231057684, 5770.913436298543, 5772.105323674838, 5387.892242155213, 5582.720863956867, 5601.7759289629585, 5632.464915832989, 5663.037283661705, 5676.091825308096, 5684.573656975826, 5686.0, 5688.033034384247, 5693.28833895763, 5569.248151499496, 5588.413216612197, 5590.972045139748, 5593.776248949996, 5594.13290939905, 5544.085225937675, 5584.664920104807, 5654.293213348332, 5654.971725141348, 5661.433856614306, 5485.232067510631, 5486.0, 5496.120620700669, 5540.307461567304, 5556.027695153412], "value": 5364.459383842872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.3181824992133, 612.8988716861717, 624.4380057947847, 632.2444678609063, 640.6988715303811, 31525.0, 43222.35166472505, 43529.564704352946, 43772.78113609427, 43896.12207755843, 43463.0, 43787.0, 43876.56123438773, 43930.0, 44036.559634403646, 8696.17386348296, 8867.955660221689, 8883.333749968746, 8898.199162075412, 8900.554972251393, 8645.092265312145, 8771.12288771124, 8784.07767184446, 8787.560621968907, 8793.824123517526, 8783.47299162049, 8832.558372081403, 8835.77910552238, 8839.204471597555, 8840.38117331787, 8717.912820871805, 8769.7807554811, 8772.254358379481, 8775.0, 8775.473471591693, 8619.1811777881, 8729.214370706666, 8730.650773969097, 8734.0, 8734.082921293237, 41960.79019604882, 42452.0, 42485.57514424847, 42522.57477425217, 42579.7871010643, 3259.820709860957, 3397.6262611112757, 3423.7774544654767, 3791.9431208531896, 4298.806553705091, 32310.35379292401, 32359.0, 32553.51169732456, 32586.674133258603, 33082.0, 4274.123804620045, 4340.826366945318, 4442.444694413199, 4478.552144785495, 4500.144972455223, 324745.0, 325974.0, 327733.0, 328694.0, 328986.0, 52094.0, 55838.72080639652, 55890.16164757532, 56425.71787141038, 57422.425775742544, 60118.69940650356, 60640.69679651574, 60921.39078609246, 61024.69487652622, 61054.38945610487, 16380.754288685679, 16407.671846563, 16478.75281870773, 16504.17479126039, 16507.752383714254, 12129.272243665408, 12681.873181268254, 12759.744805104032, 12775.488980440865, 12780.04149688777, 15860.365585376687, 15927.840721592867, 16136.112513811853, 16299.837001630069, 16471.423500177436, 5885.411458854081, 5904.76380944766, 6041.0, 6061.54538409621, 6079.118527813443, 967.7628980899584, 1043.2905624175633, 1057.825458799305, 1083.756154865149, 1115.4255558387438, 32631.184220394207, 32787.67212327894, 32892.67107328944, 32917.835410822794, 33032.3393532128, 1184.9170558060853, 1187.9109066819851, 1194.8386967759416, 1208.9300968642815, 1260.9180403273758, 17093.658126837643, 17538.210780514906, 18096.18567164481, 18112.366067187584, 18839.0, 566255.0, 571128.0, 579345.0, 942211.0, 943227.0, 53512.0, 54453.0, 54506.72746636241, 54536.454635453134, 54594.72702636461, 1264.3362234826789, 1379.799929010288, 1390.5133203378737, 1409.7885317202297, 1422.5732280315865, 30381.544276836306, 32307.192320191243, 32420.35159296848, 32664.34671306608, 32920.67079329176, 48309.0, 48695.0, 48704.75647621739, 48782.268265975326, 48810.0, 335231.0, 337272.0, 337660.0, 338107.0, 338133.0, 24465.75534244706, 24621.384465387793, 24680.13619523308, 24793.504129917663, 26016.479670406865, 46429.3035604473, 47070.0, 47858.28212576884, 49200.50799491959, 51816.74091629669, 40598.79700601478, 41432.0, 41600.0, 41867.581324186365, 42615.36076958786, 40587.1882562353, 40963.0, 41100.0, 41120.0, 41265.58734412737, 41408.792956035024, 42244.788776055924, 42403.0, 42447.787761060994, 44789.552104478535, 36063.819680901426, 39241.60758392494, 39382.60617393904, 39920.2015959685, 40004.1999160021, 28100.156995290174, 28524.144275670933, 28745.568816467352, 28747.712522874503, 28907.566386503797], "value": 19924.11517445653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.72017067455414, 389.9707521935854, 392.57993946477274, 397.34636522919817, 404.57721680843514, 18122.456326310214, 20142.798572014275, 20704.28003942534, 20948.0, 20970.89514552429, 18555.81444185561, 19214.231430742788, 19460.4161875144, 19471.805281947178, 19497.61004779907, 5561.610687251894, 5612.298462692163, 5723.456271654186, 5734.856628584298, 5750.367459579443, 5491.2312276281355, 5632.831015069554, 5635.436456354352, 5639.125935480007, 5660.745266462998, 5486.780528778844, 5614.803481878135, 5618.381977982439, 5766.192733017381, 5769.653820770746, 5792.131180322943, 5793.362730099686, 5794.594378393493, 5809.912851307192, 5834.883302333951, 5388.218708287276, 5649.887002259973, 5657.179708942222, 5657.3494048184575, 5658.547316214693, 12696.746065078647, 12905.935470322682, 13724.45102195911, 22870.313890583144, 23601.173958911528, 2170.533335332911, 2200.3069033254633, 2229.297771202066, 2237.395324711587, 2252.8197744180425, 9448.622055117787, 9645.180159686435, 9745.366551174151, 9755.170810481117, 9903.752406189831, 1852.6757817381904, 1941.417574727576, 1968.3602829080605, 1977.0, 2019.8687085339552, 76189.61905190439, 76499.61750191213, 77633.61183194048, 78285.60857195735, 80376.59811700962, 10139.442330671884, 10223.386596804216, 10227.982745090216, 10449.164066874631, 10688.091512221548, 14046.85953140476, 14105.576832695035, 14132.717345653235, 14178.14931104137, 14247.358868850926, 7991.520508769492, 8039.035315762029, 8089.514629122272, 8089.635966381527, 8280.875786863202, 7935.246151615587, 7962.522248665098, 8127.187281271944, 8375.49747015169, 8386.748397548083, 4295.8281668733525, 4323.740575565476, 4326.956730432656, 4384.539623339567, 4415.602595766396, 1781.1628534588722, 1820.335577514198, 1824.6715591193713, 1853.793985157658, 1908.570571621374, 957.9281553883347, 1013.2755080117604, 1021.3004092196869, 1043.1915265669015, 1054.362786260492, 18446.0, 19130.52173695641, 19369.12838922252, 19421.71249888691, 19618.11718472672, 972.2173650211506, 988.7033889833021, 989.3865803202061, 1010.3230835340339, 1012.6607586458618, 10657.307275027099, 10751.51618177184, 10978.286411383235, 11214.215004949738, 11379.349994250788, 473558.0, 474590.0, 492494.0, 499059.0, 500639.0, 90270.54864725635, 91825.0, 92130.53934730418, 95271.0, 95678.52160739152, 1052.1687866585312, 1092.4756117063853, 1116.5366372955295, 1130.2257953302014, 1140.6463996161217, 17697.292108315523, 17700.29198832032, 18129.36547220841, 18146.27414903442, 18391.90804045971, 41191.588084119976, 42788.0, 43911.0, 44011.0, 44469.55530444654, 60439.09341359971, 61536.69231653813, 61957.0, 62217.68891155515, 62870.0, 7278.308560686726, 7620.618969051636, 8222.876656850272, 8485.406021578678, 8561.058283588676, 13016.869831301567, 13086.803697944348, 13531.458741650618, 13733.107348022346, 13861.306934653025, 11917.82123268169, 11950.163488555718, 12054.638360849189, 12070.0, 12070.396480176132, 11877.584284550345, 11943.462544185359, 11972.922436980714, 12086.818697719717, 12096.818547721616, 11521.308721476738, 12325.260484370967, 12340.197887137307, 12463.501459941863, 12475.313857737912, 7603.885941710768, 7655.885161722467, 7857.685692572233, 7906.011748531434, 7920.801979950547, 8756.649734010569, 11075.944620276847, 11755.764884702428, 11777.587784427504, 12194.878051219373], "value": 9097.096380743258, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.0912942681266, 1435.7343891380096, 1509.1397903195177, 1605.8474444927729, 1837.2375464182364, 8202.466839655419, 8381.958090209548, 8407.453515521487, 8673.869891951626, 8677.652893884237, 8472.491650500975, 8630.611622476988, 8650.913490865105, 8652.30781537477, 8653.177948094937, 2273.5566564519927, 2294.7475777664445, 2296.9425764355856, 2297.1844995026777, 2298.9885050574726, 2301.6432452969734, 2326.93019209424, 2337.1586228957563, 2341.1220792202926, 2343.0627748900406, 2320.0, 2323.93028209154, 2341.4497593065616, 2343.5195784864145, 2355.305184970438, 2282.9895961894013, 2283.9885800571055, 2312.7340355859037, 2319.5592837361014, 2328.161861729776, 2249.1228420915854, 2258.9548209035893, 2263.1400067974228, 2264.4225722440674, 2272.204728345066, 8267.0, 8434.40959132861, 8465.492070475791, 8509.446885952455, 8517.318614510825, 5035.295058691786, 5203.505666961671, 5211.895762084775, 5215.087359712035, 5216.947830521684, 3286.96713032869, 3291.308825146722, 3303.174206448388, 3362.96637033629, 3369.8652053917813, 1187.590281352932, 1192.0999645267855, 1196.6170825335896, 1203.1938601137258, 1215.7993931001374, 11774.0, 12004.399780010965, 12025.518979240907, 12026.15816892818, 12031.157818952677, 4350.32569951658, 4395.1868904252815, 4520.16376970255, 4526.094781043805, 4538.773061346919, 10259.230557708053, 10262.076413122855, 10289.0, 10289.22830787694, 10298.742531436774, 5122.359705036871, 5127.41034781005, 5130.820421285237, 5131.666441681279, 5135.768890399941, 4134.565870583606, 4492.3710680504755, 4594.977025114853, 4628.490866004737, 4644.86065418038, 3826.8660596878976, 3900.3759398496104, 3929.6070392960473, 3972.3445631471063, 4018.7588744675413, 1192.4719594459627, 1192.856857177127, 1238.68413554543, 1245.339969815999, 1248.975020499603, 741.7255615422329, 811.9553424561706, 935.8736570563022, 966.2077096780572, 1010.4190090697731, 5561.694106824163, 5588.608797384225, 5603.7478313475995, 5655.491005809415, 5766.596338256366, 482.55363788495504, 484.1817328714461, 489.2661008487269, 503.2777963622214, 653.9705713242915, 3950.6246906543834, 4099.795010249535, 4142.316517774594, 4148.56440073794, 4164.375343698409, 10675.092617127475, 10756.946215268874, 10827.296225745302, 10981.17641176915, 11005.339679619248, 10772.892271077346, 10864.293820901616, 10917.39954302505, 10933.617323393642, 10933.671989840317, 7724.7682569523, 7984.121746607869, 8005.319547838382, 8007.159248278964, 8186.877196842052, 5879.147523609138, 5882.705864706833, 5999.4900433463645, 6024.3975602439405, 6053.243344581927, 9815.607375704893, 9887.406755594688, 10018.298719089593, 10028.147607453157, 10028.147607453448, 9958.601655933682, 10097.242706797166, 10129.157425187457, 10150.54322555472, 10229.386236825814, 6680.164979377578, 6853.0, 6858.279880612663, 7674.155842857393, 7821.804454888445, 8068.556229407204, 8262.752117436487, 8275.089740128702, 8500.70247541333, 8507.361947853811, 7063.611501367474, 7394.519356241827, 7531.887021694789, 7578.696852126074, 7676.501027433199, 7248.855022899618, 7549.547027178387, 7568.508046977149, 7625.6568454418575, 7660.616969151409, 6699.799006029827, 6719.059331693469, 6755.121834161528, 6766.729330826712, 6767.89848152288, 4760.4763476016915, 4784.856454306377, 4799.568038876518, 4886.975565122151, 4900.166971614763, 4980.9633588320285, 5026.673266237683, 5049.798008079636, 5095.974520127375, 5111.923321150261], "value": 4471.327948185783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.44407233088924, 324.4095745742748, 327.9688429599188, 330.18609128498275, 333.36161251203947, 5216.556592689626, 5290.227773695811, 5354.464553544649, 5405.729593570705, 5425.37608175059, 5318.893622127555, 5320.856015956569, 5340.172273297635, 5343.438938911417, 5354.866128346784, 4164.292070348033, 4169.4162817205615, 4173.4157217989505, 4181.163767246548, 4188.429338997876, 4064.309067458539, 4169.854055108071, 4171.249175148472, 4198.118395137025, 4244.514419953022, 4085.673146148301, 4125.195586860564, 4147.751134931898, 4159.750414975096, 4174.812133454002, 4152.916941661165, 4154.418381426588, 4166.416701661769, 4178.8537401190815, 4180.268453020709, 3667.6332366763377, 3672.6510981456718, 3686.557613086447, 3699.8890033299044, 3703.2038111805823, 5070.72111033893, 5127.182840283875, 5140.228965655144, 5171.456997015297, 5188.558972487305, 4701.623870090384, 4729.976350118227, 4739.19433696269, 4739.928901066452, 4756.500567440404, 5561.360443549003, 5592.105263157916, 5602.635828671164, 5618.129189975561, 5619.690916999563, 3809.771413715186, 3963.94955336836, 3967.444557761916, 3976.70174736893, 3976.960230397688, 5476.890462190774, 5687.516561092353, 5817.941820581782, 5821.388754180834, 5832.445917637818, 3932.744371615836, 4017.3371393720295, 4035.1929614077303, 4060.9847538115473, 4061.8578349757618, 3868.2843673920415, 3993.9201215975518, 4032.9798351008058, 4048.109415928491, 4066.7356621819486, 4654.930176047362, 4655.976720116378, 4675.532446755297, 4695.624350051988, 4712.256465107932, 5625.296837895263, 5630.296212973379, 5648.265725455664, 5660.150977353348, 5672.546196304285, 1359.8368195816367, 1411.3084588551742, 1414.1161773891317, 1415.1721243072918, 1419.5954153066327, 875.8642410426394, 881.9284569248395, 883.9039590907229, 889.4174315823146, 896.7759008952863, 4721.315409265637, 4755.001449695534, 4796.784144713426, 4804.492720314191, 4853.538913803183, 5073.619478539051, 5097.64316497849, 5101.770420331094, 5123.3339665843205, 5123.974380128076, 4483.273939533303, 4776.21192503233, 4803.0, 4808.2787581863, 4834.226523756252, 2704.8512331821544, 2712.22701530063, 2720.8231464954715, 2726.8363898165776, 2728.4133911209187, 5244.160934250502, 5252.973735131376, 5267.736613169326, 5278.788848446096, 5281.973590132025, 4868.78090485929, 5185.662931909414, 5187.792488300502, 5204.0, 5247.688077980505, 5102.877366979333, 5107.438181799999, 5207.869803254873, 5211.895762084813, 5249.370075590953, 5175.482451754794, 5211.0, 5229.398619158694, 5236.554892834151, 5642.291699984113, 5412.566994640498, 5606.523445507178, 5681.914771278517, 5693.459121383462, 5701.971490142523, 5892.145638882424, 5908.025175846109, 5915.615484993462, 5926.67403292823, 5956.225690660186, 3853.7090074825246, 4040.6767458602653, 4050.6556942660204, 4062.9593704063764, 4072.775997320176, 5789.2763404574425, 5801.0, 5815.836832633575, 5824.796132135355, 5842.773017666168, 5706.743196556082, 5720.39935806751, 5727.341355744143, 5734.799282025276, 5744.396838332083, 5485.177223416519, 5628.971855140698, 5631.155326701028, 5632.9436705632415, 5688.459596338341, 5512.263636954346, 5524.889502210013, 5536.20769772707, 5542.334919809648, 5546.533416614616, 5613.550915926798, 5638.492535671645, 5678.488935995781, 5689.203511508475, 5692.174634678031, 5421.484958928895, 5520.36515800672, 5540.418256083214, 5569.526590239875, 5571.303587051619], "value": 4144.101494150057, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.0329007484934, 740.7444431671072, 784.4744021505591, 813.4631143445328, 853.7652145659943, 15818.446354377598, 15907.12510811905, 16087.356505739786, 16138.0, 16147.354105835753, 12246.510139594406, 14797.852021479783, 14871.256437178125, 14929.402823887032, 14943.178125203109, 788.2157253532743, 791.4736700094445, 793.9642716077761, 797.2744802229972, 809.8825670277806, 790.7864876483346, 796.2753893956501, 799.1848314719012, 799.2686691677137, 799.2726618776915, 857.2499063319596, 857.8241460500583, 862.1766213266316, 862.3403096631082, 862.5600943518788, 696.7770313499682, 821.5112008355019, 825.2984962781647, 835.2399316621876, 854.7136709202425, 623.5666211982674, 626.1922120464595, 626.3923993726124, 626.677261210477, 627.2567008095422, 14253.073550219202, 14272.785908211385, 14303.427862885472, 14310.928445357707, 14325.64185895361, 14464.783028254584, 14783.70432591353, 14838.406463741438, 14852.702945941128, 14874.405023799036, 13280.933595332059, 13324.533641322507, 13376.063675542717, 13392.665183370396, 13437.865621343759, 4548.499665036843, 4579.862604121849, 4581.289900065495, 4734.124187025412, 4798.592119669832, 72895.27104728938, 74071.62964185198, 74073.0, 74106.25893741101, 76065.61967190183, 11671.241369310968, 11705.531778728755, 11707.882921170849, 11863.0, 12091.093168012434, 21991.670124948138, 22143.77856221417, 22195.66706499404, 22199.22302719397, 22389.440263993205, 8732.782644306177, 8766.21104100634, 8773.0, 8793.736187914374, 8866.601002954883, 4669.276262179367, 4710.434747830213, 4730.550597693214, 4819.445763737215, 4981.626378021663, 3209.8074115552677, 3304.2069903223055, 3305.6198537168534, 3307.669233076673, 3312.271300313927, 526.7313670028236, 566.6996491859321, 582.7202942587581, 603.9577229593887, 623.7255607532671, 617.607819034916, 708.6598432752307, 724.5906063074347, 748.8895264287751, 769.0195001373294, 9699.757506062264, 9856.655017074369, 9946.80106397868, 9990.500474976223, 10088.192944564416, 643.8937575300117, 699.964053201265, 729.6133049483782, 731.6707481633287, 789.3448437796615, 4439.378487011821, 4706.317583950308, 4710.4347478302825, 4721.811127554859, 4881.267809828554, 112609.0, 113927.0, 118162.40918795351, 118303.0, 121589.0, 63219.0, 86225.0, 94861.5256923711, 96643.51678241705, 102292.48853755833, 89611.55194223986, 89771.55114224387, 91285.0, 91494.08505914989, 91567.54216228877, 8549.401541892008, 8690.478571285743, 8833.514156721185, 8944.731658050268, 9114.680986165453, 48081.0, 51879.481205188975, 52804.73597631987, 52847.735761320946, 53334.0, 74919.62540187481, 77778.2222177771, 78004.0, 78327.82069089482, 80381.59809200917, 11266.7746645065, 11361.829572556584, 12103.455344509694, 12241.632751017127, 12658.240505569695, 13582.79625805594, 14136.575902722934, 14611.488597899022, 15334.76997845011, 15485.0, 13657.726845463234, 13773.931130344285, 14122.105053224477, 14172.0, 14744.705105898036, 13777.242251676254, 13926.658836176928, 14159.858401415853, 14289.42842286297, 14875.181864997528, 12322.383880806103, 12347.62957111288, 12757.681057973625, 12762.553310634083, 13200.20798752078, 10888.945555272174, 11304.321740695585, 11393.031592314746, 11849.585264515701, 11990.460429280878, 10963.287386319864, 10979.670609881716, 11091.682986291555, 11096.445177741241, 11225.270357426742], "value": 7578.016172175052, "unit": "score"}], "name": "dromaeo_css", "value": 7175.951119118549, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 111.0, 110.0, 110.0, 111.0, 109.0, 109.0, 109.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 127.0, 177.0, 127.0, 125.0, 149.0, 128.0, 178.0, 134.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 161.0, 163.0, 162.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 113.0, 75.0, 76.0, 74.0, 74.0, 100.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 123.0, 84.0, 84.0, 85.0, 121.0, 84.0, 85.0, 127.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 150.0, 148.0, 150.0, 149.0, 149.0, 146.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 56.0, 54.0, 53.0, 55.0, 55.0, 55.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 83.0, 81.0, 79.0, 79.0, 80.0, 82.0, 117.0, 80.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 126.0, 140.0, 117.0, 121.0, 120.0, 121.0, 121.0, 138.0, 138.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 160.0, 160.0, 158.0, 166.0, 163.0, 156.0, 157.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 66.0, 66.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1502.8, "alertThreshold": 2.0}]} 07:45:18 INFO - Return code: 0 07:45:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:45:18 ERROR - # TBPL SUCCESS # 07:45:18 INFO - Running post-action listener: _resource_record_post_action 07:45:18 INFO - ##### 07:45:18 INFO - ##### Finished run-tests step (success) 07:45:18 INFO - ##### 07:45:18 INFO - Running post-run listener: _resource_record_post_run 07:45:18 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 2322432; Write bytes: 545779712; Read time: 396; Write time: 439524 07:45:18 INFO - TinderboxPrint: CPU usage
13.2% 07:45:18 INFO - TinderboxPrint: I/O read bytes / time
2,322,432 / 396 07:45:18 INFO - TinderboxPrint: I/O write bytes / time
545,779,712 / 439,524 07:45:18 INFO - TinderboxPrint: CPU idle
8,248.9 (86.8%) 07:45:18 INFO - TinderboxPrint: CPU user
1,214.3 (12.8%) 07:45:18 INFO - TinderboxPrint: Swap in / out
0 / 0 07:45:18 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 248168448; Read time: 0; Write time: 229188 07:45:18 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 2322432; Write bytes: 297611264; Read time: 396; Write time: 210336 07:45:19 INFO - Running post-run listener: _upload_blobber_files 07:45:19 INFO - Blob upload gear active. 07:45:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:45:19 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 07:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:45:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:45:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:45:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:45:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:22 INFO - (blobuploader) - INFO - Done attempting. 07:45:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:45:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:45:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:23 INFO - (blobuploader) - INFO - Done attempting. 07:45:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:45:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:45:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:24 INFO - (blobuploader) - INFO - Done attempting. 07:45:24 INFO - (blobuploader) - INFO - Iteration through files over. 07:45:24 INFO - Return code: 0 07:45:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:45:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:45:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36efd6f2d5b75e6589642bb1e257373fc2c0d9dd7b98ae2506472476e86b5a1fbdabcd6014843ebe209fdcad66a952820a15efc59cef8ca45b636c13ac1d62cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe54e342f4bf826bfdaad70aa4469930b5b997aa66a437a09de7c0332b84489af9afe0046d8d605f532dbf5cd9f7ea62c09e4b5f218c57308459ca0ae56f3a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a8a1a69c5b2fe22550961540a18a37839eccd3648833b40137344f77d43e39585755e279b0358238bc885c1a471d89c656a0e1d3fdb3ec7eaeee08c1559558"} 07:45:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:45:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:45:24 INFO - Contents: 07:45:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36efd6f2d5b75e6589642bb1e257373fc2c0d9dd7b98ae2506472476e86b5a1fbdabcd6014843ebe209fdcad66a952820a15efc59cef8ca45b636c13ac1d62cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe54e342f4bf826bfdaad70aa4469930b5b997aa66a437a09de7c0332b84489af9afe0046d8d605f532dbf5cd9f7ea62c09e4b5f218c57308459ca0ae56f3a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a8a1a69c5b2fe22550961540a18a37839eccd3648833b40137344f77d43e39585755e279b0358238bc885c1a471d89c656a0e1d3fdb3ec7eaeee08c1559558"} 07:45:24 INFO - Running post-run listener: copy_logs_to_upload_dir 07:45:24 INFO - Copying logs to upload dir... 07:45:24 INFO - mkdir: /builds/slave/test/build/upload/logs 07:45:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.843249 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-06 07:45:22.756407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:45:22.760173) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36efd6f2d5b75e6589642bb1e257373fc2c0d9dd7b98ae2506472476e86b5a1fbdabcd6014843ebe209fdcad66a952820a15efc59cef8ca45b636c13ac1d62cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe54e342f4bf826bfdaad70aa4469930b5b997aa66a437a09de7c0332b84489af9afe0046d8d605f532dbf5cd9f7ea62c09e4b5f218c57308459ca0ae56f3a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a8a1a69c5b2fe22550961540a18a37839eccd3648833b40137344f77d43e39585755e279b0358238bc885c1a471d89c656a0e1d3fdb3ec7eaeee08c1559558"} build_url:https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011406 build_url: 'https://queue.taskcluster.net/v1/task/eGQrfRQbQzOP3xC5u6CP1Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36efd6f2d5b75e6589642bb1e257373fc2c0d9dd7b98ae2506472476e86b5a1fbdabcd6014843ebe209fdcad66a952820a15efc59cef8ca45b636c13ac1d62cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efe54e342f4bf826bfdaad70aa4469930b5b997aa66a437a09de7c0332b84489af9afe0046d8d605f532dbf5cd9f7ea62c09e4b5f218c57308459ca0ae56f3a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a8a1a69c5b2fe22550961540a18a37839eccd3648833b40137344f77d43e39585755e279b0358238bc885c1a471d89c656a0e1d3fdb3ec7eaeee08c1559558"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 07:45:22.786120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:45:22.786481) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1465223089.79426-1091037555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 07:45:22.869336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:45:22.869713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 07:45:22.870052) ========= ========= Total master_lag: 0.16 =========