builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1465646786.97 results: success (0) buildid: 20160611023005 builduid: c495368f2dc34a9aa9b6e4cb1351b203 revision: 9694e371363590c8dace6629dc4d57f1af7206f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:26.966329) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:26.966780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:26.967053) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.040922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.041264) ========= 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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-11 05:06:27-- 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% 45.9M=0s 2016-06-11 05:06:27 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085958 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.142734) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.143084) ========= 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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021942 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.192367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.192786) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9694e371363590c8dace6629dc4d57f1af7206f2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9694e371363590c8dace6629dc4d57f1af7206f2 --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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-11 05:06:27,551 truncating revision to first 12 chars 2016-06-11 05:06:27,551 Setting DEBUG logging. 2016-06-11 05:06:27,551 attempt 1/10 2016-06-11 05:06:27,551 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9694e3713635?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-11 05:06:27,853 unpacking tar archive at: fx-team-9694e3713635/testing/mozharness/ program finished with exit code 0 elapsedTime=0.680539 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.887193) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.887480) ========= script_repo_revision: 9694e371363590c8dace6629dc4d57f1af7206f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.887882) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.888145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-11 05:06:27.898640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-06-11 05:06:27.898957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:06:28 INFO - MultiFileLogger online at 20160611 05:06:28 in /builds/slave/test 05:06:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:06:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:28 INFO - {'append_to_log': False, 05:06:28 INFO - 'base_work_dir': '/builds/slave/test', 05:06:28 INFO - 'blob_upload_branch': 'Fx-Team', 05:06:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:28 INFO - 'branch': 'Fx-Team', 05:06:28 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:28 INFO - 'config_files': ('talos/linux_config.py',), 05:06:28 INFO - 'default_actions': ('clobber', 05:06:28 INFO - 'read-buildbot-config', 05:06:28 INFO - 'download-and-extract', 05:06:28 INFO - 'populate-webroot', 05:06:28 INFO - 'create-virtualenv', 05:06:28 INFO - 'install', 05:06:28 INFO - 'run-tests'), 05:06:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:28 INFO - 'download_minidump_stackwalk': True, 05:06:28 INFO - 'download_symbols': 'ondemand', 05:06:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:28 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:28 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:28 INFO - 'installer_path': 'installer.exe', 05:06:28 INFO - 'log_level': 'info', 05:06:28 INFO - 'log_name': 'talos', 05:06:28 INFO - 'log_to_console': True, 05:06:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:06:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:06:28 INFO - 'opt_config_files': (), 05:06:28 INFO - 'pip_index': False, 05:06:28 INFO - 'sps_profile': False, 05:06:28 INFO - 'sps_profile_interval': 0, 05:06:28 INFO - 'suite': 'dromaeojs', 05:06:28 INFO - 'system_bits': '32', 05:06:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:28 INFO - 'title': 'talos-linux64-ix-036', 05:06:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:28 INFO - 'use_talos_json': True, 05:06:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:28 INFO - 'work_dir': 'build'} 05:06:28 INFO - ##### 05:06:28 INFO - ##### Running clobber step. 05:06:28 INFO - ##### 05:06:28 INFO - Running pre-action listener: _resource_record_pre_action 05:06:28 INFO - Running main action method: clobber 05:06:28 INFO - rmtree: /builds/slave/test/build 05:06:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:28 INFO - Running post-action listener: _resource_record_post_action 05:06:28 INFO - ##### 05:06:28 INFO - ##### Finished clobber step (success) 05:06:28 INFO - ##### 05:06:28 INFO - ##### 05:06:28 INFO - ##### Running read-buildbot-config step. 05:06:28 INFO - ##### 05:06:28 INFO - Running pre-action listener: _resource_record_pre_action 05:06:28 INFO - Running main action method: read_buildbot_config 05:06:28 INFO - Using buildbot properties: 05:06:28 INFO - { 05:06:28 INFO - "project": "", 05:06:28 INFO - "product": "firefox", 05:06:28 INFO - "script_repo_revision": "production", 05:06:28 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:06:28 INFO - "repository": "", 05:06:28 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:06:28 INFO - "buildid": "20160611023005", 05:06:28 INFO - "pgo_build": "True", 05:06:28 INFO - "basedir": "/builds/slave/test", 05:06:28 INFO - "buildnumber": 180, 05:06:28 INFO - "slavename": "talos-linux64-ix-036", 05:06:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:06:28 INFO - "platform": "ubuntu64_hw", 05:06:28 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:06:28 INFO - "branch": "fx-team", 05:06:28 INFO - "repo_path": "integration/fx-team", 05:06:28 INFO - "revision": "9694e371363590c8dace6629dc4d57f1af7206f2", 05:06:28 INFO - "stage_platform": "linux64-pgo", 05:06:28 INFO - "builduid": "c495368f2dc34a9aa9b6e4cb1351b203", 05:06:28 INFO - "slavebuilddir": "test" 05:06:28 INFO - } 05:06:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:06:28 INFO - Running post-action listener: _resource_record_post_action 05:06:28 INFO - ##### 05:06:28 INFO - ##### Finished read-buildbot-config step (success) 05:06:28 INFO - ##### 05:06:28 INFO - ##### 05:06:28 INFO - ##### Running download-and-extract step. 05:06:28 INFO - ##### 05:06:28 INFO - Running pre-action listener: _resource_record_pre_action 05:06:28 INFO - Running main action method: download_and_extract 05:06:28 INFO - mkdir: /builds/slave/test/build/tests 05:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:28 INFO - https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:06:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:06:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:06:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:06:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:06:37 INFO - Downloaded 1361 bytes. 05:06:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:06:37 INFO - Using the following test package requirements: 05:06:37 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:06:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:06:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'jsshell-linux-x86_64.zip'], 05:06:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:06:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:06:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:06:37 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:06:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:06:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:06:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:06:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:06:37 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')]} 05:06:37 INFO - https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:06:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:06:52 INFO - Downloaded 24464076 bytes. 05:06:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:06:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:06:53 INFO - Return code: 0 05:06:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:53 INFO - https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:06:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:06:55 INFO - Downloaded 24464076 bytes. 05:06:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:06:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:06:56 INFO - Return code: 0 05:06:56 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')]} 05:06:56 INFO - https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:06:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:06:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:06:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:06:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:06:57 INFO - Downloaded 10833377 bytes. 05:06:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:06:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:06:57 INFO - Return code: 0 05:06: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')]} 05:06:57 INFO - https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:06:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:06:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:06:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:06:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:06:59 INFO - Downloaded 59360225 bytes. 05:06:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:06:59 INFO - mkdir: /builds/slave/test/properties 05:06:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:59 INFO - Contents: 05:06:59 INFO - build_url:https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:06:59 INFO - Running post-action listener: _resource_record_post_action 05:06:59 INFO - Running post-action listener: set_extra_try_arguments 05:06:59 INFO - ##### 05:06:59 INFO - ##### Finished download-and-extract step (success) 05:06:59 INFO - ##### 05:06:59 INFO - ##### 05:06:59 INFO - ##### Running populate-webroot step. 05:06:59 INFO - ##### 05:06:59 INFO - Running pre-action listener: _resource_record_pre_action 05:06:59 INFO - Running main action method: populate_webroot 05:06:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:59 INFO - '--tppagecycles', 05:06:59 INFO - '7'], 05:06:59 INFO - 'tests': ['tsvgm']}}, 05:06:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:59 INFO - 'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:59 INFO - 'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['damp', 'tps']}, 05:06:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:59 INFO - 'tests': ['damp', 'tps']}, 05:06:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['dromaeo_dom']}, 05:06:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['a11yr', 05:06:59 INFO - 'ts_paint', 05:06:59 INFO - 'tpaint', 05:06:59 INFO - 'sessionrestore', 05:06:59 INFO - 'sessionrestore_no_auto_restore', 05:06:59 INFO - 'tabpaint']}, 05:06:59 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:59 INFO - 'ts_paint', 05:06:59 INFO - 'tpaint', 05:06:59 INFO - 'sessionrestore', 05:06:59 INFO - 'sessionrestore_no_auto_restore', 05:06:59 INFO - 'tabpaint']}, 05:06:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:06:59 INFO - 'ts_paint', 05:06:59 INFO - 'tpaint', 05:06:59 INFO - 'sessionrestore', 05:06:59 INFO - 'sessionrestore_no_auto_restore', 05:06:59 INFO - 'tabpaint']}, 05:06:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:06:59 INFO - 'ts_paint', 05:06:59 INFO - 'tpaint', 05:06:59 INFO - 'sessionrestore', 05:06:59 INFO - 'sessionrestore_no_auto_restore', 05:06:59 INFO - 'tabpaint']}, 05:06:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:06:59 INFO - 'tests': ['a11yr', 05:06:59 INFO - 'ts_paint', 05:06:59 INFO - 'tpaint', 05:06:59 INFO - 'sessionrestore', 05:06:59 INFO - 'sessionrestore_no_auto_restore', 05:06:59 INFO - 'tabpaint']}, 05:06:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:07:00 INFO - 'tests': ['a11yr', 05:07:00 INFO - 'ts_paint', 05:07:00 INFO - 'tpaint', 05:07:00 INFO - 'sessionrestore', 05:07:00 INFO - 'sessionrestore_no_auto_restore', 05:07:00 INFO - 'tabpaint']}, 05:07:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:07:00 INFO - 'tests': ['tsvgx', 05:07:00 INFO - 'tsvgr_opacity', 05:07:00 INFO - 'tart', 05:07:00 INFO - 'tscrollx', 05:07:00 INFO - 'cart']}, 05:07:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:07:00 INFO - 'tsvgr_opacity', 05:07:00 INFO - 'tart', 05:07:00 INFO - 'tscrollx', 05:07:00 INFO - 'cart']}, 05:07:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:07:00 INFO - 'talos_options': ['--disable-e10s'], 05:07:00 INFO - 'tests': ['tp5o']}, 05:07:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:07:00 INFO - 'tests': ['tp5o']}, 05:07:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:07:00 INFO - 'talos_options': ['--disable-e10s', 05:07:00 INFO - '--xperf_path', 05:07:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:00 INFO - 'tests': ['tp5n']}, 05:07:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:07:00 INFO - 'talos_options': ['--xperf_path', 05:07:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:00 INFO - 'tests': ['tp5n']}}, 05:07:00 INFO - 'talos.zip': {'path': '', 05:07:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:07:00 INFO - Running post-action listener: _resource_record_post_action 05:07:00 INFO - ##### 05:07:00 INFO - ##### Finished populate-webroot step (success) 05:07:00 INFO - ##### 05:07:00 INFO - ##### 05:07:00 INFO - ##### Running create-virtualenv step. 05:07:00 INFO - ##### 05:07:00 INFO - Running pre-action listener: _resource_record_pre_action 05:07:00 INFO - Running main action method: create_virtualenv 05:07:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:07:00 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 05:07:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:07:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:07:00 INFO - Using real prefix '/usr' 05:07:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:07:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:07:02 INFO - Installing pip.................done. 05:07:02 INFO - Return code: 0 05:07:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:07:02 INFO - Using env: {'DISPLAY': ':0', 05:07:02 INFO - 'HOME': '/home/cltbld', 05:07:02 INFO - 'LANG': 'en_US.UTF-8', 05:07:02 INFO - 'LANGUAGE': 'en_US:en', 05:07:02 INFO - 'LOGNAME': 'cltbld', 05:07:02 INFO - 'MAIL': '/var/mail/cltbld', 05:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:02 INFO - 'MOZ_NO_REMOTE': '1', 05:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:02 INFO - 'NO_EM_RESTART': '1', 05:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:02 INFO - 'PWD': '/builds/slave/test', 05:07:02 INFO - 'SHELL': '/bin/bash', 05:07:02 INFO - 'SHLVL': '1', 05:07:02 INFO - 'TERM': 'linux', 05:07:02 INFO - 'TMOUT': '86400', 05:07:02 INFO - 'USER': 'cltbld', 05:07:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:02 INFO - '_': '/tools/buildbot/bin/python'} 05:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:02 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 05:07:02 INFO - Cleaning up... 05:07:02 INFO - Return code: 0 05:07:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:07:02 INFO - Using env: {'DISPLAY': ':0', 05:07:02 INFO - 'HOME': '/home/cltbld', 05:07:02 INFO - 'LANG': 'en_US.UTF-8', 05:07:02 INFO - 'LANGUAGE': 'en_US:en', 05:07:02 INFO - 'LOGNAME': 'cltbld', 05:07:02 INFO - 'MAIL': '/var/mail/cltbld', 05:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:02 INFO - 'MOZ_NO_REMOTE': '1', 05:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:02 INFO - 'NO_EM_RESTART': '1', 05:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:02 INFO - 'PWD': '/builds/slave/test', 05:07:02 INFO - 'SHELL': '/bin/bash', 05:07:02 INFO - 'SHLVL': '1', 05:07:02 INFO - 'TERM': 'linux', 05:07:02 INFO - 'TMOUT': '86400', 05:07:02 INFO - 'USER': 'cltbld', 05:07:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:02 INFO - '_': '/tools/buildbot/bin/python'} 05:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:02 INFO - Downloading/unpacking psutil>=3.1.1 05:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:07:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:04 INFO - Installing collected packages: psutil 05:07:04 INFO - Running setup.py install for psutil 05:07:05 INFO - building 'psutil._psutil_linux' extension 05:07:05 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 05:07:05 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 05:07:05 INFO - building 'psutil._psutil_posix' extension 05:07:05 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 05:07:05 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 05:07:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:05 INFO - Successfully installed psutil 05:07:05 INFO - Cleaning up... 05:07:05 INFO - Return code: 0 05:07:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:05 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:05 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 05:07:05 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 05:07:05 INFO - Using env: {'DISPLAY': ':0', 05:07:05 INFO - 'HOME': '/home/cltbld', 05:07:05 INFO - 'LANG': 'en_US.UTF-8', 05:07:05 INFO - 'LANGUAGE': 'en_US:en', 05:07:05 INFO - 'LOGNAME': 'cltbld', 05:07:05 INFO - 'MAIL': '/var/mail/cltbld', 05:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:05 INFO - 'MOZ_NO_REMOTE': '1', 05:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:05 INFO - 'NO_EM_RESTART': '1', 05:07:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:05 INFO - 'PWD': '/builds/slave/test', 05:07:05 INFO - 'SHELL': '/bin/bash', 05:07:05 INFO - 'SHLVL': '1', 05:07:05 INFO - 'TERM': 'linux', 05:07:05 INFO - 'TMOUT': '86400', 05:07:05 INFO - 'USER': 'cltbld', 05:07:05 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:05 INFO - '_': '/tools/buildbot/bin/python'} 05:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:07:07 INFO - Installing collected packages: mozsystemmonitor 05:07:07 INFO - Running setup.py install for mozsystemmonitor 05:07:07 INFO - Successfully installed mozsystemmonitor 05:07:07 INFO - Cleaning up... 05:07:07 INFO - Return code: 0 05:07:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:07: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')]} 05:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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')]} 05:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07: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', '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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:07: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 jsonschema==2.5.1 05:07:07 INFO - Using env: {'DISPLAY': ':0', 05:07:07 INFO - 'HOME': '/home/cltbld', 05:07:07 INFO - 'LANG': 'en_US.UTF-8', 05:07:07 INFO - 'LANGUAGE': 'en_US:en', 05:07:07 INFO - 'LOGNAME': 'cltbld', 05:07:07 INFO - 'MAIL': '/var/mail/cltbld', 05:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:07 INFO - 'MOZ_NO_REMOTE': '1', 05:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:07 INFO - 'NO_EM_RESTART': '1', 05:07:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:07 INFO - 'PWD': '/builds/slave/test', 05:07:07 INFO - 'SHELL': '/bin/bash', 05:07:07 INFO - 'SHLVL': '1', 05:07:07 INFO - 'TERM': 'linux', 05:07:07 INFO - 'TMOUT': '86400', 05:07:07 INFO - 'USER': 'cltbld', 05:07:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:07 INFO - '_': '/tools/buildbot/bin/python'} 05:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:07:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:07:09 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 05:07:09 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 05:07:09 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 05:07:09 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 05:07:09 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 05:07:09 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 05:07:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:07:10 INFO - warning: no files found matching '*.txt' 05:07:10 INFO - no previously-included directories found matching 'build' 05:07:10 INFO - no previously-included directories found matching 'dist' 05:07:10 INFO - no previously-included directories found matching '.git*' 05:07:10 INFO - Installing collected packages: jsonschema, functools32 05:07:10 INFO - Running setup.py install for functools32 05:07:10 INFO - warning: no files found matching '*.txt' 05:07:10 INFO - no previously-included directories found matching 'build' 05:07:10 INFO - no previously-included directories found matching 'dist' 05:07:10 INFO - no previously-included directories found matching '.git*' 05:07:10 INFO - Successfully installed jsonschema functools32 05:07:10 INFO - Cleaning up... 05:07:10 INFO - Return code: 0 05:07:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:07:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:07:10 INFO - Using env: {'DISPLAY': ':0', 05:07:10 INFO - 'HOME': '/home/cltbld', 05:07:10 INFO - 'LANG': 'en_US.UTF-8', 05:07:10 INFO - 'LANGUAGE': 'en_US:en', 05:07:10 INFO - 'LOGNAME': 'cltbld', 05:07:10 INFO - 'MAIL': '/var/mail/cltbld', 05:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:10 INFO - 'MOZ_NO_REMOTE': '1', 05:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:10 INFO - 'NO_EM_RESTART': '1', 05:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:10 INFO - 'PWD': '/builds/slave/test', 05:07:10 INFO - 'SHELL': '/bin/bash', 05:07:10 INFO - 'SHLVL': '1', 05:07:10 INFO - 'TERM': 'linux', 05:07:10 INFO - 'TMOUT': '86400', 05:07:10 INFO - 'USER': 'cltbld', 05:07:10 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:10 INFO - '_': '/tools/buildbot/bin/python'} 05:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:10 INFO - Downloading/unpacking blobuploader==1.2.4 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07:12 INFO - Downloading blobuploader-1.2.4.tar.gz 05:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:07:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:07:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:13 INFO - Downloading docopt-0.6.1.tar.gz 05:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:07:13 INFO - Installing collected packages: blobuploader, requests, docopt 05:07:13 INFO - Running setup.py install for blobuploader 05:07:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:07:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:07:13 INFO - Running setup.py install for requests 05:07:13 INFO - Running setup.py install for docopt 05:07:13 INFO - Successfully installed blobuploader requests docopt 05:07:13 INFO - Cleaning up... 05:07:13 INFO - Return code: 0 05:07:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:13 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')]} 05:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:13 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')]} 05:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:13 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:13 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 05:07:13 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 05:07:13 INFO - Using env: {'DISPLAY': ':0', 05:07:13 INFO - 'HOME': '/home/cltbld', 05:07:13 INFO - 'LANG': 'en_US.UTF-8', 05:07:13 INFO - 'LANGUAGE': 'en_US:en', 05:07:13 INFO - 'LOGNAME': 'cltbld', 05:07:13 INFO - 'MAIL': '/var/mail/cltbld', 05:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:13 INFO - 'MOZ_NO_REMOTE': '1', 05:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:13 INFO - 'NO_EM_RESTART': '1', 05:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:13 INFO - 'PWD': '/builds/slave/test', 05:07:13 INFO - 'SHELL': '/bin/bash', 05:07:13 INFO - 'SHLVL': '1', 05:07:13 INFO - 'TERM': 'linux', 05:07:13 INFO - 'TMOUT': '86400', 05:07:13 INFO - 'USER': 'cltbld', 05:07:13 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:13 INFO - '_': '/tools/buildbot/bin/python'} 05:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:14 INFO - Running setup.py (path:/tmp/pip-eO9cyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:14 INFO - Running setup.py (path:/tmp/pip-f9HeOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:14 INFO - Running setup.py (path:/tmp/pip-VCaO1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:14 INFO - Running setup.py (path:/tmp/pip-VrRKmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:14 INFO - Running setup.py (path:/tmp/pip-MMqSGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:14 INFO - Running setup.py (path:/tmp/pip-zEQB12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:14 INFO - Running setup.py (path:/tmp/pip-is76El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:14 INFO - Running setup.py (path:/tmp/pip-soBHT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:14 INFO - Running setup.py (path:/tmp/pip-_bjO_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:14 INFO - Running setup.py (path:/tmp/pip-2iYhwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:14 INFO - Running setup.py (path:/tmp/pip-QfzOLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:14 INFO - Running setup.py (path:/tmp/pip-56hRQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:15 INFO - Running setup.py (path:/tmp/pip-HOEril-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:15 INFO - Running setup.py (path:/tmp/pip-aNUxDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:15 INFO - Running setup.py (path:/tmp/pip-WmQBYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:15 INFO - Running setup.py (path:/tmp/pip-k2frTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:15 INFO - Running setup.py (path:/tmp/pip-jd6U6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:07:15 INFO - Running setup.py install for manifestparser 05:07:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:07:15 INFO - Running setup.py install for mozcrash 05:07:15 INFO - Running setup.py install for mozdebug 05:07:15 INFO - Running setup.py install for mozdevice 05:07:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:07:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:07:15 INFO - Running setup.py install for mozfile 05:07:15 INFO - Running setup.py install for mozhttpd 05:07:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozinfo 05:07:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozInstall 05:07:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozleak 05:07:16 INFO - Running setup.py install for mozlog 05:07:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for moznetwork 05:07:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozprocess 05:07:16 INFO - Running setup.py install for mozprofile 05:07:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozrunner 05:07:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:07:16 INFO - Running setup.py install for mozscreenshot 05:07:16 INFO - Running setup.py install for moztest 05:07:17 INFO - Running setup.py install for mozversion 05:07:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:07:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:07:17 INFO - Cleaning up... 05:07:17 INFO - Return code: 0 05:07:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:17 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:17 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 05:07:17 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 05:07:17 INFO - Using env: {'DISPLAY': ':0', 05:07:17 INFO - 'HOME': '/home/cltbld', 05:07:17 INFO - 'LANG': 'en_US.UTF-8', 05:07:17 INFO - 'LANGUAGE': 'en_US:en', 05:07:17 INFO - 'LOGNAME': 'cltbld', 05:07:17 INFO - 'MAIL': '/var/mail/cltbld', 05:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:17 INFO - 'MOZ_NO_REMOTE': '1', 05:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:17 INFO - 'NO_EM_RESTART': '1', 05:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:17 INFO - 'PWD': '/builds/slave/test', 05:07:17 INFO - 'SHELL': '/bin/bash', 05:07:17 INFO - 'SHLVL': '1', 05:07:17 INFO - 'TERM': 'linux', 05:07:17 INFO - 'TMOUT': '86400', 05:07:17 INFO - 'USER': 'cltbld', 05:07:17 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:17 INFO - '_': '/tools/buildbot/bin/python'} 05:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:17 INFO - Running setup.py (path:/tmp/pip-I2XAd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:17 INFO - Running setup.py (path:/tmp/pip-jZ3lDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:17 INFO - Running setup.py (path:/tmp/pip-U2FeOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:17 INFO - Running setup.py (path:/tmp/pip-8vj13i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:17 INFO - Running setup.py (path:/tmp/pip-P9n7ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:17 INFO - Running setup.py (path:/tmp/pip-o0gDFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:17 INFO - Running setup.py (path:/tmp/pip-M8oNpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:17 INFO - Running setup.py (path:/tmp/pip-qv7Jn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:17 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)) 05:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:17 INFO - Running setup.py (path:/tmp/pip-lLNXo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:18 INFO - Running setup.py (path:/tmp/pip-j5Tty3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:18 INFO - Running setup.py (path:/tmp/pip-eMEnub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:18 INFO - Running setup.py (path:/tmp/pip-K86Dgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:18 INFO - Running setup.py (path:/tmp/pip-JuoKhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:18 INFO - Running setup.py (path:/tmp/pip-LS5BOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:18 INFO - Running setup.py (path:/tmp/pip-OACxAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:18 INFO - Running setup.py (path:/tmp/pip-enqZHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:18 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)) 05:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:18 INFO - Running setup.py (path:/tmp/pip-7sFNPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:18 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)) 05:07:18 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)) 05:07:18 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)) 05:07:18 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)) 05:07:18 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)) 05:07:18 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)) 05:07:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:20 INFO - Downloading blessings-1.6.tar.gz 05:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:07:20 INFO - Installing collected packages: blessings 05:07:20 INFO - Running setup.py install for blessings 05:07:20 INFO - Successfully installed blessings 05:07:20 INFO - Cleaning up... 05:07:20 INFO - Return code: 0 05:07:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:21 INFO - Reading from file tmpfile_stdout 05:07:21 INFO - Current package versions: 05:07:21 INFO - argparse == 1.2.1 05:07:21 INFO - blessings == 1.6 05:07:21 INFO - blobuploader == 1.2.4 05:07:21 INFO - docopt == 0.6.1 05:07:21 INFO - functools32 == 3.2.3-2 05:07:21 INFO - jsonschema == 2.5.1 05:07:21 INFO - manifestparser == 1.1 05:07:21 INFO - mozInstall == 1.12 05:07:21 INFO - mozcrash == 0.17 05:07:21 INFO - mozdebug == 0.1 05:07:21 INFO - mozdevice == 0.48 05:07:21 INFO - mozfile == 1.2 05:07:21 INFO - mozhttpd == 0.7 05:07:21 INFO - mozinfo == 0.9 05:07:21 INFO - mozleak == 0.1 05:07:21 INFO - mozlog == 3.2 05:07:21 INFO - moznetwork == 0.27 05:07:21 INFO - mozprocess == 0.22 05:07:21 INFO - mozprofile == 0.28 05:07:21 INFO - mozrunner == 6.11 05:07:21 INFO - mozscreenshot == 0.1 05:07:21 INFO - mozsystemmonitor == 0.3 05:07:21 INFO - moztest == 0.7 05:07:21 INFO - mozversion == 1.4 05:07:21 INFO - psutil == 3.1.1 05:07:21 INFO - requests == 1.2.3 05:07:21 INFO - wsgiref == 0.1.2 05:07:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:21 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 05:07:21 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 05:07:21 INFO - Using env: {'DISPLAY': ':0', 05:07:21 INFO - 'HOME': '/home/cltbld', 05:07:21 INFO - 'LANG': 'en_US.UTF-8', 05:07:21 INFO - 'LANGUAGE': 'en_US:en', 05:07:21 INFO - 'LOGNAME': 'cltbld', 05:07:21 INFO - 'MAIL': '/var/mail/cltbld', 05:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:21 INFO - 'MOZ_NO_REMOTE': '1', 05:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:21 INFO - 'NO_EM_RESTART': '1', 05:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:21 INFO - 'PWD': '/builds/slave/test', 05:07:21 INFO - 'SHELL': '/bin/bash', 05:07:21 INFO - 'SHLVL': '1', 05:07:21 INFO - 'TERM': 'linux', 05:07:21 INFO - 'TMOUT': '86400', 05:07:21 INFO - 'USER': 'cltbld', 05:07:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:21 INFO - '_': '/tools/buildbot/bin/python'} 05:07:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 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)) 05:07:21 INFO - Cleaning up... 05:07:21 INFO - Return code: 0 05:07:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:21 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 05:07:21 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 05:07:21 INFO - Using env: {'DISPLAY': ':0', 05:07:21 INFO - 'HOME': '/home/cltbld', 05:07:21 INFO - 'LANG': 'en_US.UTF-8', 05:07:21 INFO - 'LANGUAGE': 'en_US:en', 05:07:21 INFO - 'LOGNAME': 'cltbld', 05:07:21 INFO - 'MAIL': '/var/mail/cltbld', 05:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:21 INFO - 'MOZ_NO_REMOTE': '1', 05:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:21 INFO - 'NO_EM_RESTART': '1', 05:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:21 INFO - 'PWD': '/builds/slave/test', 05:07:21 INFO - 'SHELL': '/bin/bash', 05:07:21 INFO - 'SHLVL': '1', 05:07:21 INFO - 'TERM': 'linux', 05:07:21 INFO - 'TMOUT': '86400', 05:07:21 INFO - 'USER': 'cltbld', 05:07:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:21 INFO - '_': '/tools/buildbot/bin/python'} 05:07:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:07:21 INFO - Cleaning up... 05:07:21 INFO - Return code: 0 05:07:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:07:21 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 0x7f8e9c238f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bdb350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, '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': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', '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 05:07:21 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 05:07:21 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 05:07:21 INFO - Using env: {'DISPLAY': ':0', 05:07:21 INFO - 'HOME': '/home/cltbld', 05:07:21 INFO - 'LANG': 'en_US.UTF-8', 05:07:21 INFO - 'LANGUAGE': 'en_US:en', 05:07:21 INFO - 'LOGNAME': 'cltbld', 05:07:21 INFO - 'MAIL': '/var/mail/cltbld', 05:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:21 INFO - 'MOZ_NO_REMOTE': '1', 05:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:21 INFO - 'NO_EM_RESTART': '1', 05:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:21 INFO - 'PWD': '/builds/slave/test', 05:07:21 INFO - 'SHELL': '/bin/bash', 05:07:21 INFO - 'SHLVL': '1', 05:07:21 INFO - 'TERM': 'linux', 05:07:21 INFO - 'TMOUT': '86400', 05:07:21 INFO - 'USER': 'cltbld', 05:07:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:21 INFO - '_': '/tools/buildbot/bin/python'} 05:07:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:21 INFO - Downloading/unpacking flake8 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07: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 05:07:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:07:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:07:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:07:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:07:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:07:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:07:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:07:24 INFO - no previously-included directories found matching 'docs/_build' 05:07:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:07:24 INFO - Running setup.py install for pep8 05:07:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:07:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:07:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:07:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:07:24 INFO - no previously-included directories found matching 'docs/_build' 05:07:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:07:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:07:24 INFO - Cleaning up... 05:07:24 INFO - Return code: 0 05:07:24 INFO - Running post-action listener: _resource_record_post_action 05:07:24 INFO - Running post-action listener: _start_resource_monitoring 05:07:24 INFO - Starting resource monitoring. 05:07:24 INFO - ##### 05:07:24 INFO - ##### Finished create-virtualenv step (success) 05:07:24 INFO - ##### 05:07:24 INFO - ##### 05:07:24 INFO - ##### Running install step. 05:07:24 INFO - ##### 05:07:24 INFO - Running pre-action listener: _resource_record_pre_action 05:07:24 INFO - Running main action method: install 05:07:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:24 INFO - Reading from file tmpfile_stdout 05:07:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:07:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:07:24 INFO - Reading from file tmpfile_stdout 05:07:24 INFO - Output received: 05:07:24 INFO - Usage: mozinstall [options] installer 05:07:24 INFO - Options: 05:07:24 INFO - -h, --help show this help message and exit 05:07:24 INFO - -d DEST, --destination=DEST 05:07:24 INFO - Directory to install application into. [default: 05:07:24 INFO - "/builds/slave/test"] 05:07:24 INFO - --app=APP Application being installed. [default: firefox] 05:07:24 INFO - mkdir: /builds/slave/test/build/application 05:07:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:07:41 INFO - Reading from file tmpfile_stdout 05:07:41 INFO - Output received: 05:07:41 INFO - /builds/slave/test/build/application/firefox/firefox 05:07:41 INFO - Running post-action listener: _resource_record_post_action 05:07:41 INFO - ##### 05:07:41 INFO - ##### Finished install step (success) 05:07:41 INFO - ##### 05:07:41 INFO - ##### 05:07:41 INFO - ##### Running run-tests step. 05:07:41 INFO - ##### 05:07:41 INFO - Running pre-action listener: _resource_record_pre_action 05:07:41 INFO - Running main action method: run_tests 05:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:07:41 INFO - Python 2.7.3 05:07:41 INFO - Return code: 0 05:07:41 INFO - grabbing minidump binary from tooltool 05:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:41 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 0x1c6a1c0>, '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 0x1c6b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6b550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:07:41 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 05:07:41 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 05:07:41 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 05:07:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:07:41 INFO - Return code: 0 05:07:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:07:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:07:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:07:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:07:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:07:42 INFO - Return code: 0 05:07:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 05:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 05:07:42 INFO - Using env: {'DISPLAY': ':0', 05:07:42 INFO - 'HOME': '/home/cltbld', 05:07:42 INFO - 'LANG': 'en_US.UTF-8', 05:07:42 INFO - 'LANGUAGE': 'en_US:en', 05:07:42 INFO - 'LOGNAME': 'cltbld', 05:07:42 INFO - 'MAIL': '/var/mail/cltbld', 05:07:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:07:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:42 INFO - 'MOZ_NO_REMOTE': '1', 05:07:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:07:42 INFO - 'NO_EM_RESTART': '1', 05:07:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:42 INFO - 'PWD': '/builds/slave/test', 05:07:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:07:42 INFO - 'SHELL': '/bin/bash', 05:07:42 INFO - 'SHLVL': '1', 05:07:42 INFO - 'TERM': 'linux', 05:07:42 INFO - 'TMOUT': '86400', 05:07:42 INFO - 'USER': 'cltbld', 05:07:42 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642', 05:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:42 INFO - '_': '/tools/buildbot/bin/python'} 05:07:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 05:07:42 INFO - mozversion application_buildid: 20160611023005 05:07:42 INFO - mozversion application_changeset: 9694e371363590c8dace6629dc4d57f1af7206f2 05:07:42 INFO - mozversion application_display_name: Nightly 05:07:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:07:42 INFO - mozversion application_name: Firefox 05:07:42 INFO - mozversion application_remotingname: firefox 05:07:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:07:42 INFO - mozversion application_vendor: Mozilla 05:07:42 INFO - mozversion application_version: 50.0a1 05:07:42 INFO - mozversion platform_buildid: 20160611023005 05:07:42 INFO - mozversion platform_changeset: 9694e371363590c8dace6629dc4d57f1af7206f2 05:07:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:07:42 INFO - mozversion platform_version: 50.0a1 05:07:42 INFO - using testdate: 1465646862 05:07:42 INFO - actual date: 1465646862 05:07:42 INFO - starting webserver on 'localhost:55385' 05:07:42 INFO - SUITE-START | Running 2 tests 05:07:42 INFO - TEST-START | dromaeo_css 05:07:42 INFO - operating with platform_type : linux_ 05:07:42 INFO - Initialising browser for dromaeo_css test... 05:07:42 INFO - TEST-INFO | started process 29545 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1G49cp/profile http://localhost:55385/getInfo.html) 05:07:47 INFO - PROCESS | 29545 | __metrics Screen width/height:1600/1200 05:07:47 INFO - PROCESS | 29545 | colorDepth:24 05:07:47 INFO - PROCESS | 29545 | Browser inner width/height: 1024/697 05:07:47 INFO - PROCESS | 29545 | __metrics 05:07:48 INFO - PROCESS | 29545 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:48 INFO - PROCESS | 29545 | console.error: PushService: 05:07:48 INFO - PROCESS | 29545 | stateChangeProcessEnqueue: Error transitioning state 05:07:48 INFO - PROCESS | 29545 | UnknownError 05:07:49 INFO - TEST-INFO | 29545: exit 0 05:07:49 INFO - Browser initialized. 05:07:49 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:49 INFO - TEST-INFO | started process 29629 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1G49cp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:01 INFO - PROCESS | 29629 | RSS: Main: 173326336 05:08:01 INFO - PROCESS | 29629 | 05:08:52 INFO - PROCESS | 29629 | 1465646932819 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] 05:11:00 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55385/tests/dromaeo/cssquery-ext.html) 05:11:02 INFO - PROCESS | 29629 | RSS: Main: 260030464 05:11:02 INFO - PROCESS | 29629 | 05:14:04 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-ext.html (next: http://localhost:55385/tests/dromaeo/cssquery-jquery.html) 05:14:05 INFO - PROCESS | 29629 | RSS: Main: 262414336 05:14:05 INFO - PROCESS | 29629 | 05:17:07 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55385/tests/dromaeo/cssquery-mootools.html) 05:17:07 INFO - PROCESS | 29629 | RSS: Main: 260325376 05:17:07 INFO - PROCESS | 29629 | 05:20:09 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55385/tests/dromaeo/cssquery-prototype.html) 05:20:10 INFO - PROCESS | 29629 | RSS: Main: 258682880 05:20:10 INFO - PROCESS | 29629 | 05:23:13 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55385/tests/dromaeo/cssquery-yui.html) 05:23:13 INFO - PROCESS | 29629 | RSS: Main: 224305152 05:23:13 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | Cycle 1(1): loaded http://localhost:55385/tests/dromaeo/cssquery-yui.html (next: http://localhost:55385/tests/dromaeo/cssquery-dojo.html) 05:26:16 INFO - PROCESS | 29629 | RSS: Main: 290750464 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | __start_tp_report 05:26:16 INFO - PROCESS | 29629 | _x_x_mozilla_page_load 05:26:16 INFO - PROCESS | 29629 | _x_x_mozilla_page_load_details 05:26:16 INFO - PROCESS | 29629 | |i|pagename|runs| 05:26:16 INFO - PROCESS | 29629 | |0;dojo.html;3079.384123175364;3083.768717346199;3097.070878736379;3122.0633809857036;3140.5760222369972;5224.686518808874;5260.763265653045;5305.8938821223555;5348.572114230862;5382;5344.786208551663;5364.1149210380245;5364.544013758835;5390.973045134779;5394.541463975567;4775.3075804008395;4828.73441960692;4986.4514903360605;5035.748212589374;5080.923786143211;4980.701157930535;5017.397912250535;5030.773615187307;5047;5048.899022019557;4734.124187025394;4756.215224487973;4859.271109333593;4869.561739443467;4888.315635810989;4897.681650692729;4900.877478063041;4901.019796040806;4920.6309526785635;4963.3299504566785;4849.806007759686;4915.041566894457;4923.335349727777;4937.23472861707;4937.358143441365;5204.219367094928;5261.657992230493;5274.446183150752;5323.547498462635;5465.234867118606;5648.74580643872;5688.146777983336;5695.40198279183;5778.508826749731;5788.971055144697;5765.394633563457;5770.711464426804;5824.30108386992;5833.212516310286;5834.358220595731;5285.207218917155;5344.091504444254;5389.461053894618;5392.245085688007;5475.233467314579;5889.146073819358;5900.586958912835;5914.58597898143;5964.940350596526;5965.28416590012;5730.971345143247;5741.8564535886935;5742;5742.368339482654;5742.942570574324;5558.110702287696;5598.356189038232;5604.075327570987;5640.774369025193;5697.4302569742695;5668;5673.461021202979;5674.460926212003;5677.432256774372;5678.205051292823;5685.204071430003;5723.513501352349;5773.653580785166;5775.768969241183;5848.678322692208;4394.2749446340995;4425.336199570091;4436.134953684001;4465.531119232499;4471.6646251531265;3952.7628342299554;3991.7405368650943;3994.860179893748;3997.9000524986527;4007.4189242560246;5227.503387178212;5267.341582302212;5341.65279256847;5377.193420986805;5466.699331536804;5735.254416925856;5744.540436765049;5746.13807928814;5752.165935939265;5791.652500849962;5353.223782551586;5382.30030096085;5387.353517577916;5393.325834270716;5401.810936617199;4739.786709598076;4781.665283430196;4814.301926220748;4827.493113223131;4866.489018653061;5925.466707996215;5928.703564821742;5933.258342707161;5936.7328470218945;5943.791967281211;5787.565932554991;5792.971035144966;5801.332846722681;5805.303363596396;5805.8548536286935;5733.369329373682;5869.647821130746;5896.587238893237;5930.051191809463;6007.3992600739575;5701.230333904869;5702.714864256854;5703.287089113861;5708.971455142864;5756.366799651951;5762.7694892203845;5799.2750906136735;5807.448292412214;5809.970950145223;5818.650880947157;5447.564194864481;5561.054620714569;5597.356304025089;5616.045272303636;5650.943490565041;5624.803131890364;5625.8593535161945;5628.831135065954;5629.521490673339;5632.493075623213;5503.3395992481155;5550.722463876871;5565.860853478695;5566.52684521804;5567.415421380697;5497.120460726264;5565.499105080562;5612.7754889803955;5621.381648018796;5692.800751973661;5497.560195184456;5501.339839219319;5507.752151153288;5512.090505066619;5520.530754885719 05:26:16 INFO - PROCESS | 29629 | |1;ext.html;575.4648177195206;616.1958643969618;627.792828366639;634.3846468925141;649.7238673563737;29612.703872961265;41411.58588414115;42220.57779422205;42280.57719422805;42398.912130544304;43464.78267608657;44489.33266001013;44527.55098469583;44606.776966115205;44645.33032004523;8804.207621314097;9021.95489022554;9031.277497800193;9057.547122643875;9061.139191776803;8778.078301778318;8896.510691911943;8921.375503714742;8931.419457735226;8933.46399216046;8986.056464071276;9029.909700902972;9030.277577793793;9033.909660903406;9035.186833185013;8934.55327233639;8956.686515971975;8963.238124759404;8966.148215919477;8979.281657467387;8912.197902188835;8975.910240897572;8993.820123597492;9002.099790021011;9004.8649270261;42309;42658.360124598155;42794;42802.78598607018;42841.78579107085;3291.210109573709;3292.670732926712;3326.1352048467575;3563.768355056913;3600.891973240807;32169.6783032169;32708.345833083444;33015.17462063444;33276.334473310395;33601.49597756036;4232.873013809591;4233.491980962304;4254.446921900164;4255.468066491689;4347.22373408011;354988;355165;355479.2226038879;355643;360234;56084.439155608736;56560.71719641375;56640.716796415756;56732.4757652867;56768.14847777287;52037;52037.2194417084;52864.471355286725;53296.20055699167;58279.70860145757;14639.341229644691;14648.487302944346;14732;14732.779008314883;14741.778873316909;10607.15142788575;11092.223544351978;11094.22340436178;11094.389808560607;11101.111911047097;15349.846501535065;15600.375984960474;15650.921745391428;15672.138032408098;15879;6545.869082618321;6598.934010659832;6605.669716514155;6766;6792;968.3076600230869;1004.1916257412706;1033.8242498775226;1089.3518356577824;1108.695108845058;32245.35509289801;32567.837160814044;33119.834400828324;33198;33634.49548256778;1087.4617064553097;1096.7751610919743;1116.7431490757172;1131.4342828585707;1201.243216773427;16778.6644267114;17274.827251727573;17298.82701172972;17309.7403538947;17392.821361458966;157049;158668;158762;159573;160091;58417;58648;58719.41280587225;58880.705596471744;58895.70552147212;1196.3360335014152;1309.9803502947464;1331.074902942465;1344.5630170194727;1348.4067010515146;32859;34132.658673412945;34191.97424077282;34282;34489;49155.262671059245;49663.25505117499;49856.50143498664;49859;49924.25113623226;427694;435538;435703;439104;440441;34679;35861;36054;38215.80892095522;38636.80681596574;46205;49345.753271233414;49350.506494936024;50273.497265028345;50322;40993.38509922294;41442;41477;42350.788246058575;42619.78690106654;40416.79791601023;40593;40743.79628101959;41067;41352.58647413487;40683.796581016904;41234.175316494104;41497.37753933633;42121.36817947672;45063.32405013993;33292.500612490345;36562.8171859139;37591;37599.624003760706;37625;24752.381190470383;24823.875880621203;25146;25316.70882785226;25351.87324063368 05:26:16 INFO - PROCESS | 29629 | |2;jquery.html;385.82059342405773;388.44840326736033;398.5078428141247;405.68964741972394;416.68332067628614;14063.789043164361;15476.380944762195;17621.64756704865;19282.132304046318;19307.613847723056;14520.491782787605;17663.558411039732;18161.182746776394;18164.81835181651;18928.71606925894;5573.916391254134;5653.462921022497;5772.480476757091;5774.682392468433;5786.450287222729;4038.9394159087637;5505.779768809243;5686.31764188298;5725.026745453283;5995.160677505131;6288.088227207052;6363.872722545546;6387.361263873599;6395.712192951305;6406.167198264219;6259.21759780023;6351.650659213741;6396.584222025576;6448.419642232175;6450.548461607689;5642.943570564324;5651.4913657770585;5660.29246344207;5665.943340566624;5680.4319568043275;21134;21873.781262187335;22234;22313.330600082023;22332.106715731352;2138.3050508584774;2172.6415141501634;2208.3595757230382;2224.5662095891307;2268.5009297954425;9545.284103692182;9944.95027524865;9954.402735835873;10191.439470829102;12935.094543381967;1834.1196225811552;1850.4078694817663;1969.9015049247623;1972.615340008699;1976.2589029114083;82365.58817205936;82662;83296.58351708202;83297;85284;10432.68701938943;10482.423466709251;10593.205509586813;10631.84052239217;10862.131029517619;15044;15055.397784088515;15066.83596444737;15156.924215379073;15180.165090919885;6470.708818103197;7247.13034435871;7549.924500755032;7705.537667739954;7892.644830982619;7310.4151667866445;7407;7438.888416673754;7512.173660897297;7525.736599219001;4489.393931819162;4545.772711364419;4588.6099681526775;4589.908201835945;4795.448523419782;1866.4773863318292;1900;1906.8855868647925;1935.4967708395918;1936.7288579598867;864.1962974433837;965.1217392173089;987.3532835992437;995.4475266227169;1011.3325205364424;17869.285228590972;18798.248070077043;19012.334568290044;19099.61800763977;19339.90330048341;973.5764942250144;989.4310771306527;1013.1894484412588;1015.5785349080197;1042.0933787604831;10211.234157438073;10729.302595331279;10738.302010369458;10739.892601074045;10763.78472430547;432971;434086;449671;490103;498811;93758.53120734489;97534.51232743791;98284.50857745665;98668;99727.50136249272;965.4689920543673;976.3897564022628;1040.9479526023667;1047.6856942917095;1047.7171163785736;16606.588439982737;16606.66786664284;16727.330906763593;17021.65956680884;17288.308467661154;30891.848567020163;39739.80130099331;40604.79697601493;41365.172696546506;42688.35967460428;56578.7171064142;57330.42669573251;57540.42459575351;58638;58871;8355.248027677539;8389.370797190113;8393.286570641563;8647.481151130953;8677.609507572048;12582.24506529611;12944.482220711066;13078.53596099352;13500.392482338515;13661.726765464833;11735.706607334885;11748.118891083031;11863.940680296544;11990.400479975791;12017.339046352534;7661;11414.774119047039;11701.180917335705;11731.824022639837;11760.941195294312;11595.710107247387;11833.526658933546;11902.285862848257;11951.462184201559;12138.453769580214;8210.958945205235;8383.58082095905;8399.328053755562;8522.914770852212;8758.386912916034;11760.470778815145;11886.405679715806;11973.221740586834;11982.638195969077;11997.460114294703 05:26:16 INFO - PROCESS | 29629 | |3;mootools.html;1134.444122380034;1435;1477.3204326010034;1587.1350114187767;1725.5513566472714;8058.395620328477;8521;8523.744287671365;8750;8765.824683506326;8343.624536895839;8501.06488286288;8526.44578102423;8528.147185281468;8541.402101852873;2197.252934002435;2220.2784095169136;2222.3999520129555;2224.0214305705517;2225.2434172381386;2160.686700428437;2200;2244.472548950995;2247.6516139998325;2250.504888924434;2195.5169862630196;2210.16013914713;2231.9665205021856;2239.294622194012;2239.764824693408;2148.967765483519;2159.6220661384273;2168.718066651341;2173.9347819565437;2179.1065663078143;2108.5361220531613;2110.799474049963;2110.8944552772455;2111.2610586294827;2114.5770845830743;8534.658613655447;8663.610137543825;8713.651453941835;8719.564021798884;8796.604152813139;5011.899762004776;5123.462036486152;5129.153689641204;5132.435432102466;5132.589392848563;3251.252211991255;3275.0993476794074;3335.149536868083;3344.3980083584943;3351.7318614511023;1044.6918159143072;1054.620336678795;1062.5696592879897;1081.0432767001182;1087.276960821053;12175.817362739566;12287.385630718429;12328.445219965033;12332.260064396165;12334.568290109803;4121.5878412158545;4153.376993450946;4245.1934132514725;4261.062566235423;4262.680298977589;9708.563114659857;9709.757256068655;9726.076022777825;9732.172765315028;9735.41587504752;4893.951060489349;4923.729194894315;4926.852194434173;4930.679505832181;4932.334134891744;4086;4169.791510424528;4308.719933204332;4605.263157894721;4670.416197975253;3658.487811706363;3752.137008488063;3797.6012518685693;3801.5248093988253;3809.1619843634357;1045.0385645206277;1088.8584484017185;1104.679642903565;1109.2457129152253;1109.9778004440027;963.2294164668237;972.0473935543207;983.326421401328;983.4492684096929;985.0051448037534;5941.197938278277;5969.671668058299;5995.040793473024;6019.668918209541;6074.876147912651;918.0498184379136;957.3729207369184;982.2436723722693;985.2708995343394;1032.2309879139939;4090.304648209752;4137.13120244752;4179.665626749853;4202.70581059329;4205.894852628648;10857.131429485622;11146.944265278622;11207.943960280309;11244.887551124548;11257.774844503065;10975.121990240761;11070.169737269736;11071.391073491035;11076.224664273584;11106.167037472222;7914.564698941498;8286.8342633147;8304.128066553047;8467.941507311709;8489.95755021221;5955.43423374796;6222.533310001859;6311.116443697794;6332.430081292521;6385.521085918482;10124.34191777532;10322;10536.947315263682;10541.57833686644;10544.156467482737;10469.895301046892;10486.842697359698;10598;10598.364098154136;10618.893811061791;7904.960475197588;8133.430659853753;8145.592720364077;8169.468984516225;8175.918240817516;8465.619047143016;8594.097619750086;8602.18279263494;8620.310375169845;8635.179657932485;7699.653515591924;7762.456628035983;7797.142314345531;7822.374210063295;7889.13219545861;7496.662650180646;7646.311831935152;7652.502587331805;7664.693412263447;7688.577128258;7352.117745870316;7370.6314684266645;7434.702611895464;7477.476576639583;7493.962530187314;5316.6012549058205;5326.121190003606;5329.387120481194;5389.51494365509;5417.918731218956;5406.78372865081;5513.283273174462;5535.280413546213;5614.831555053355;5681.289838770153 05:26:16 INFO - PROCESS | 29629 | |4;prototype.html;317.9412556187894;322.6434789557539;324.1668910898991;329.4087113631032;330.9884154054608;5304.514735873957;5333.706646134461;5347.224652425396;5350.036993341197;5350.956563470115;5260.947390526094;5287.4976877196705;5294.205869119634;5322.574194064475;5333.4399888011785;3970.602939706035;3985.2228815381013;4107.54816970133;4136.772477513736;4155.812988415513;4119.6704263658985;4136.627703506684;4143.564925682806;4147.232761939036;4151.501819781631;3933.7836418996944;4081.8367265309353;4098.446709694198;4114.917701645965;4118.505779306487;3822.617738226183;4008.6592639625815;4055.290324193269;4105.2405305018665;4149.647279981205;3618.3486972344967;3633.6003039665616;3659.579148397915;3667.449882517617;3677.2461645362637;5141.460146684619;5172.146595811687;5192.8961420771375;5202.583793296527;5213.895722085537;4529.547045295477;4659.18464268755;4695.178343789823;4742.9525704742855;4752.5247475252545;5310.548603368718;5411.725905826957;5431.918521222185;5456.781728730846;5503.807366742185;3973.22522108189;3987.760734355948;3988.806156613851;3993.920121597581;4003.459532963042;5487.725613719298;5724.169995350692;5742.913856292159;5775.48020678141;5806.4193580641595;3973.165635216637;3990.4014397840556;3991.7405368650943;3993.1414745829784;4005.239004589119;4021.718479706451;4048.69634777387;4084.6732261419015;4084.6936479764136;4102.979485102615;4476.4556228101255;4721.474011403882;4723.220668589713;4726.527347265314;4749.691270067504;5552.750126244328;5611.270534830446;5612.494875461228;5613.7193140342815;5629.746661400247;1396.6717821311984;1401.201315250318;1408.6971301170297;1418.985810141906;1426.2298358886085;878.3598732344766;895.4000819450983;896.7040876510866;921.3596550397543;922.041077279635;5256.868578285497;5393.514583687487;5407.918881216785;5409.594280428905;5425.891482170414;5729;5731.168980497804;5745.2818397700285;5749.9712501437225;5770.769169233267;5170.121079416508;5307.336582927134;5386.461353864661;5438.592105592096;5440.727963601804;3010.909672709822;3058.5412188171954;3062.0096936991135;3079.6458407283003;3082.506798912208;5797.826065218051;5819.301683797887;5841.503472204825;5918.408159184047;5972.462478376967;5577.721113944286;5864.824055278348;5912.586118971717;5946.256717910262;5984.132300816357;5869.589128761031;5877.823665290048;5894.616849904743;5970.432808883149;5993.790217342459;5594.342777009803;5634.774609015593;5684.948284567368;5730.541556675539;5770.538356931352;5632.2678051853;5647.265855438766;5695.117256825199;5707.4577915098;5733.140028995684;5947.464728174485;5951.285845698544;5959.404059594006;5972.790952316822;5991.071383935497;3894.1308172076997;4030.3551431770775;4039.596040395937;4039.8182081805803;4074.6757303875766;5719.28508936383;5724.4561766632105;5728.341240757366;5742.444612147554;5746.482816546531;5336.946630533645;5596.132599447093;5607.495325420732;5610.382857885711;5612.831615051392;5511.724413779215;5523.502884740393;5534.944650553443;5538.501534861882;5549.1676248561425;5247.842564723064;5368.926214756986;5415.860735009728;5427.611850785642;5433.646812957145;5014.448410674896;5420.9186862197885;5453.945460545502;5457.471907865804;5558.221369161991;5426.728663566727;5459.590530710133;5510.641808282448;5526.336839579276;5527.972360138335 05:26:16 INFO - PROCESS | 29629 | |5;yui.html;727.5961841178147;768.2739810878721;771.9112302085261;787.5117427195138;816.5182542300042;14457.132572045672;16051;16347.918260408696;16372.83627163728;16405.589860253498;14924.850751492482;15043.849561504381;15114.6977060459;15166.696666066671;15225.314860831262;809.1988930958368;819.0335404223015;819.4018366592392;819.7294892685429;819.7950512371907;672.156443663202;693.9930600693979;694.6978064541916;779.7037125892153;801.9799505012393;841.3311417423163;856.9957150214241;857.2541888556933;857.2713193785295;857.3398483167957;850.5067061104545;852.722865068852;852.893388326453;853.2576658307281;858.9785255368635;513.6558505801084;529.7271904968944;537.749946274984;623.965681887496;624.0047124835879;12669.429875655618;14365;15050.32273547682;15056.774148387673;15599.376024958989;14179.716405671932;14463.059901106499;14484.493042743454;14559.92720036393;14769.556913292618;11720.238184517979;11759.647210583697;11780.293182409083;11990.16068875179;12024;3755.5493340799007;3794.4498047783195;3853.190829925719;3876.186000939806;4036.9192616147807;59682.10476842851;60226.39773602252;73454;75433;76012.61993690052;11106.111511079094;11274.269136353427;11309.75592684804;11601.709957250967;11624.88375116255;20799.1680332785;20820.687689684666;20840.687389689167;20850.58298834045;20890.686639700416;8267.669293228324;8455.069942306342;8465.238128568335;8628.309735221168;8727.694530691395;4893.4617192108835;5007.148784706608;5047.570956468668;5090.134677104827;5137.998090372416;3222.6132864056462;3338.399088164154;3344.9331013379597;3345;3346.748993825473;591.7899145803264;599.868029033612;602.7588964414182;606.6360183889631;624.4785604020693;756.2739769821034;761.1969372312153;767.4436033875508;797.752696664036;804.9597520123971;9641.566129524188;10092.293539452168;10224.744381390376;10237.897621023843;10311.432871192157;649.691396586627;702.8945658151216;709.1950636028146;720.8450183210634;745.1803016681715;4433.933490997638;4609.907801844011;4829.9275510867365;4857.611391088704;4860.3195552622665;69253.65373173203;103824.66994036837;106334.46832765942;110136.4493177529;111967;93949;94171.82054986905;94792.52603736937;95012;95844.52077739566;85091;85094;85966.14033859706;86269;88492.1150788484;9276.304277179104;9424.905750942677;9529.361354418912;9576.473293968898;9802.663961934695;50567.74716126396;51828;52043.73978130085;52975.470245298595;53393.46606533885;69427.65286173536;74149;75040;76112;76252.61873690596;12213.267203967791;12324.568640097554;12692.746145077232;12715.10994230395;13074.346282686018;12922.418491168108;13153.473861045451;13333.133346332457;13731.725365492435;14175.078619889675;13481.460741570227;13540.79688804649;13655.344655344656;13702.931485342908;13825.170489770646;12796.872031279569;13188.934055329662;13246.07277490605;13342.266175360448;13368.197908125543;12387.380630968595;12710.110292279453;12740.426680799572;13341.866581334063;13398.531051413154;10315;10626.574937002433;10661.318256922703;10771.407572583583;11292.604758833402;10699.502069710403;10873.836892446778;10954.780904382027;11078.501467433824;11238.438078095898 05:26:16 INFO - PROCESS | 29629 | __end_tp_report 05:26:16 INFO - PROCESS | 29629 | __start_cc_report 05:26:16 INFO - PROCESS | 29629 | _x_x_mozilla_cycle_collect,991 05:26:16 INFO - PROCESS | 29629 | __end_cc_report 05:26:16 INFO - PROCESS | 29629 | __startTimestamp1465647976596__endTimestamp 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | ------- Summary: start ------- 05:26:16 INFO - PROCESS | 29629 | Number of tests: 6 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#0] dojo.html Cycles:165 Average:5334.22 Median:5520.53 stddev:599.45 (10.9%) stddev-sans-first:574.60 05:26:16 INFO - PROCESS | 29629 | Values: 3079.4 3083.8 3097.1 3122.1 3140.6 5224.7 5260.8 5305.9 5348.6 5382.0 5344.8 5364.1 5364.5 5391.0 5394.5 4775.3 4828.7 4986.5 5035.7 5080.9 4980.7 5017.4 5030.8 5047.0 5048.9 4734.1 4756.2 4859.3 4869.6 4888.3 4897.7 4900.9 4901.0 4920.6 4963.3 4849.8 4915.0 4923.3 4937.2 4937.4 5204.2 5261.7 5274.4 5323.5 5465.2 5648.7 5688.1 5695.4 5778.5 5789.0 5765.4 5770.7 5824.3 5833.2 5834.4 5285.2 5344.1 5389.5 5392.2 5475.2 5889.1 5900.6 5914.6 5964.9 5965.3 5731.0 5741.9 5742.0 5742.4 5742.9 5558.1 5598.4 5604.1 5640.8 5697.4 5668.0 5673.5 5674.5 5677.4 5678.2 5685.2 5723.5 5773.7 5775.8 5848.7 4394.3 4425.3 4436.1 4465.5 4471.7 3952.8 3991.7 3994.9 3997.9 4007.4 5227.5 5267.3 5341.7 5377.2 5466.7 5735.3 5744.5 5746.1 5752.2 5791.7 5353.2 5382.3 5387.4 5393.3 5401.8 4739.8 4781.7 4814.3 4827.5 4866.5 5925.5 5928.7 5933.3 5936.7 5943.8 5787.6 5793.0 5801.3 5805.3 5805.9 5733.4 5869.6 5896.6 5930.1 6007.4 5701.2 5702.7 5703.3 5709.0 5756.4 5762.8 5799.3 5807.4 5810.0 5818.7 5447.6 5561.1 5597.4 5616.0 5650.9 5624.8 5625.9 5628.8 5629.5 5632.5 5503.3 5550.7 5565.9 5566.5 5567.4 5497.1 5565.5 5612.8 5621.4 5692.8 5497.6 5501.3 5507.8 5512.1 5520.5 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#1] ext.html Cycles:180 Average:49721.00 Median:41487.19 stddev:89384.05 (215.4%) stddev-sans-first:89558.63 05:26:16 INFO - PROCESS | 29629 | Values: 575.5 616.2 627.8 634.4 649.7 29612.7 41411.6 42220.6 42280.6 42398.9 43464.8 44489.3 44527.6 44606.8 44645.3 8804.2 9022.0 9031.3 9057.5 9061.1 8778.1 8896.5 8921.4 8931.4 8933.5 8986.1 9029.9 9030.3 9033.9 9035.2 8934.6 8956.7 8963.2 8966.1 8979.3 8912.2 8975.9 8993.8 9002.1 9004.9 42309.0 42658.4 42794.0 42802.8 42841.8 3291.2 3292.7 3326.1 3563.8 3600.9 32169.7 32708.3 33015.2 33276.3 33601.5 4232.9 4233.5 4254.4 4255.5 4347.2 354988.0 355165.0 355479.2 355643.0 360234.0 56084.4 56560.7 56640.7 56732.5 56768.1 52037.0 52037.2 52864.5 53296.2 58279.7 14639.3 14648.5 14732.0 14732.8 14741.8 10607.2 11092.2 11094.2 11094.4 11101.1 15349.8 15600.4 15650.9 15672.1 15879.0 6545.9 6598.9 6605.7 6766.0 6792.0 968.3 1004.2 1033.8 1089.4 1108.7 32245.4 32567.8 33119.8 33198.0 33634.5 1087.5 1096.8 1116.7 1131.4 1201.2 16778.7 17274.8 17298.8 17309.7 17392.8 157049.0 158668.0 158762.0 159573.0 160091.0 58417.0 58648.0 58719.4 58880.7 58895.7 1196.3 1310.0 1331.1 1344.6 1348.4 32859.0 34132.7 34192.0 34282.0 34489.0 49155.3 49663.3 49856.5 49859.0 49924.3 427694.0 435538.0 435703.0 439104.0 440441.0 34679.0 35861.0 36054.0 38215.8 38636.8 46205.0 49345.8 49350.5 50273.5 50322.0 40993.4 41442.0 41477.0 42350.8 42619.8 40416.8 40593.0 40743.8 41067.0 41352.6 40683.8 41234.2 41497.4 42121.4 45063.3 33292.5 36562.8 37591.0 37599.6 37625.0 24752.4 24823.9 25146.0 25316.7 25351.9 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#2] jquery.html Cycles:180 Average:28140.83 Median:20064.12 stddev:76545.06 (381.5%) stddev-sans-first:76731.42 05:26:16 INFO - PROCESS | 29629 | Values: 385.8 388.4 398.5 405.7 416.7 14063.8 15476.4 17621.6 19282.1 19307.6 14520.5 17663.6 18161.2 18164.8 18928.7 5573.9 5653.5 5772.5 5774.7 5786.5 4038.9 5505.8 5686.3 5725.0 5995.2 6288.1 6363.9 6387.4 6395.7 6406.2 6259.2 6351.7 6396.6 6448.4 6450.5 5642.9 5651.5 5660.3 5665.9 5680.4 21134.0 21873.8 22234.0 22313.3 22332.1 2138.3 2172.6 2208.4 2224.6 2268.5 9545.3 9945.0 9954.4 10191.4 12935.1 1834.1 1850.4 1969.9 1972.6 1976.3 82365.6 82662.0 83296.6 83297.0 85284.0 10432.7 10482.4 10593.2 10631.8 10862.1 15044.0 15055.4 15066.8 15156.9 15180.2 6470.7 7247.1 7549.9 7705.5 7892.6 7310.4 7407.0 7438.9 7512.2 7525.7 4489.4 4545.8 4588.6 4589.9 4795.4 1866.5 1900.0 1906.9 1935.5 1936.7 864.2 965.1 987.4 995.4 1011.3 17869.3 18798.2 19012.3 19099.6 19339.9 973.6 989.4 1013.2 1015.6 1042.1 10211.2 10729.3 10738.3 10739.9 10763.8 432971.0 434086.0 449671.0 490103.0 498811.0 93758.5 97534.5 98284.5 98668.0 99727.5 965.5 976.4 1040.9 1047.7 1047.7 16606.6 16606.7 16727.3 17021.7 17288.3 30891.8 39739.8 40604.8 41365.2 42688.4 56578.7 57330.4 57540.4 58638.0 58871.0 8355.2 8389.4 8393.3 8647.5 8677.6 12582.2 12944.5 13078.5 13500.4 13661.7 11735.7 11748.1 11863.9 11990.4 12017.3 7661.0 11414.8 11701.2 11731.8 11760.9 11595.7 11833.5 11902.3 11951.5 12138.5 8211.0 8383.6 8399.3 8522.9 8758.4 11760.5 11886.4 11973.2 11982.6 11997.5 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#3] mootools.html Cycles:180 Average:5784.05 Median:4782.18 stddev:3361.96 (70.3%) stddev-sans-first:3353.22 05:26:16 INFO - PROCESS | 29629 | Values: 1134.4 1435.0 1477.3 1587.1 1725.6 8058.4 8521.0 8523.7 8750.0 8765.8 8343.6 8501.1 8526.4 8528.1 8541.4 2197.3 2220.3 2222.4 2224.0 2225.2 2160.7 2200.0 2244.5 2247.7 2250.5 2195.5 2210.2 2232.0 2239.3 2239.8 2149.0 2159.6 2168.7 2173.9 2179.1 2108.5 2110.8 2110.9 2111.3 2114.6 8534.7 8663.6 8713.7 8719.6 8796.6 5011.9 5123.5 5129.2 5132.4 5132.6 3251.3 3275.1 3335.1 3344.4 3351.7 1044.7 1054.6 1062.6 1081.0 1087.3 12175.8 12287.4 12328.4 12332.3 12334.6 4121.6 4153.4 4245.2 4261.1 4262.7 9708.6 9709.8 9726.1 9732.2 9735.4 4894.0 4923.7 4926.9 4930.7 4932.3 4086.0 4169.8 4308.7 4605.3 4670.4 3658.5 3752.1 3797.6 3801.5 3809.2 1045.0 1088.9 1104.7 1109.2 1110.0 963.2 972.0 983.3 983.4 985.0 5941.2 5969.7 5995.0 6019.7 6074.9 918.0 957.4 982.2 985.3 1032.2 4090.3 4137.1 4179.7 4202.7 4205.9 10857.1 11146.9 11207.9 11244.9 11257.8 10975.1 11070.2 11071.4 11076.2 11106.2 7914.6 8286.8 8304.1 8467.9 8490.0 5955.4 6222.5 6311.1 6332.4 6385.5 10124.3 10322.0 10536.9 10541.6 10544.2 10469.9 10486.8 10598.0 10598.4 10618.9 7905.0 8133.4 8145.6 8169.5 8175.9 8465.6 8594.1 8602.2 8620.3 8635.2 7699.7 7762.5 7797.1 7822.4 7889.1 7496.7 7646.3 7652.5 7664.7 7688.6 7352.1 7370.6 7434.7 7477.5 7494.0 5316.6 5326.1 5329.4 5389.5 5417.9 5406.8 5513.3 5535.3 5614.8 5681.3 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#4] prototype.html Cycles:180 Average:4670.85 Median:5350.50 stddev:1386.68 (25.9%) stddev-sans-first:1351.54 05:26:16 INFO - PROCESS | 29629 | Values: 317.9 322.6 324.2 329.4 331.0 5304.5 5333.7 5347.2 5350.0 5351.0 5260.9 5287.5 5294.2 5322.6 5333.4 3970.6 3985.2 4107.5 4136.8 4155.8 4119.7 4136.6 4143.6 4147.2 4151.5 3933.8 4081.8 4098.4 4114.9 4118.5 3822.6 4008.7 4055.3 4105.2 4149.6 3618.3 3633.6 3659.6 3667.4 3677.2 5141.5 5172.1 5192.9 5202.6 5213.9 4529.5 4659.2 4695.2 4743.0 4752.5 5310.5 5411.7 5431.9 5456.8 5503.8 3973.2 3987.8 3988.8 3993.9 4003.5 5487.7 5724.2 5742.9 5775.5 5806.4 3973.2 3990.4 3991.7 3993.1 4005.2 4021.7 4048.7 4084.7 4084.7 4103.0 4476.5 4721.5 4723.2 4726.5 4749.7 5552.8 5611.3 5612.5 5613.7 5629.7 1396.7 1401.2 1408.7 1419.0 1426.2 878.4 895.4 896.7 921.4 922.0 5256.9 5393.5 5407.9 5409.6 5425.9 5729.0 5731.2 5745.3 5750.0 5770.8 5170.1 5307.3 5386.5 5438.6 5440.7 3010.9 3058.5 3062.0 3079.6 3082.5 5797.8 5819.3 5841.5 5918.4 5972.5 5577.7 5864.8 5912.6 5946.3 5984.1 5869.6 5877.8 5894.6 5970.4 5993.8 5594.3 5634.8 5684.9 5730.5 5770.5 5632.3 5647.3 5695.1 5707.5 5733.1 5947.5 5951.3 5959.4 5972.8 5991.1 3894.1 4030.4 4039.6 4039.8 4074.7 5719.3 5724.5 5728.3 5742.4 5746.5 5336.9 5596.1 5607.5 5610.4 5612.8 5511.7 5523.5 5534.9 5538.5 5549.2 5247.8 5368.9 5415.9 5427.6 5433.6 5014.4 5420.9 5453.9 5457.5 5558.2 5426.7 5459.6 5510.6 5526.3 5528.0 05:26:16 INFO - PROCESS | 29629 | 05:26:16 INFO - PROCESS | 29629 | [#5] yui.html Cycles:180 Average:19999.03 Median:4843.77 stddev:28174.94 (581.7%) stddev-sans-first:28216.81 05:26:16 INFO - PROCESS | 29629 | Values: 727.6 768.3 771.9 787.5 816.5 14457.1 16051.0 16347.9 16372.8 16405.6 14924.9 15043.8 15114.7 15166.7 15225.3 809.2 819.0 819.4 819.7 819.8 672.2 694.0 694.7 779.7 802.0 841.3 857.0 857.3 857.3 857.3 850.5 852.7 852.9 853.3 859.0 513.7 529.7 537.7 624.0 624.0 12669.4 14365.0 15050.3 15056.8 15599.4 14179.7 14463.1 14484.5 14559.9 14769.6 11720.2 11759.6 11780.3 11990.2 12024.0 3755.5 3794.4 3853.2 3876.2 4036.9 59682.1 60226.4 73454.0 75433.0 76012.6 11106.1 11274.3 11309.8 11601.7 11624.9 20799.2 20820.7 20840.7 20850.6 20890.7 8267.7 8455.1 8465.2 8628.3 8727.7 4893.5 5007.1 5047.6 5090.1 5138.0 3222.6 3338.4 3344.9 3345.0 3346.7 591.8 599.9 602.8 606.6 624.5 756.3 761.2 767.4 797.8 805.0 9641.6 10092.3 10224.7 10237.9 10311.4 649.7 702.9 709.2 720.8 745.2 4433.9 4609.9 4829.9 4857.6 4860.3 69253.7 103824.7 106334.5 110136.4 111967.0 93949.0 94171.8 94792.5 95012.0 95844.5 85091.0 85094.0 85966.1 86269.0 88492.1 9276.3 9424.9 9529.4 9576.5 9802.7 50567.7 51828.0 52043.7 52975.5 53393.5 69427.7 74149.0 75040.0 76112.0 76252.6 12213.3 12324.6 12692.7 12715.1 13074.3 12922.4 13153.5 13333.1 13731.7 14175.1 13481.5 13540.8 13655.3 13702.9 13825.2 12796.9 13188.9 13246.1 13342.3 13368.2 12387.4 12710.1 12740.4 13341.9 13398.5 10315.0 10626.6 10661.3 10771.4 11292.6 10699.5 10873.8 10954.8 11078.5 11238.4 05:26:16 INFO - PROCESS | 29629 | -------- Summary: end -------- 05:26:16 INFO - PROCESS | 29629 | 05:26:17 INFO - TEST-INFO | 29629: exit 0 05:26:17 INFO - TEST-OK | dromaeo_css | took 1114455ms 05:26:17 INFO - TEST-START | kraken 05:26:17 INFO - operating with platform_type : linux_ 05:26:17 INFO - Initialising browser for kraken test... 05:26:17 INFO - TEST-INFO | started process 29810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ7x5FB/profile http://localhost:55385/getInfo.html) 05:26:21 INFO - PROCESS | 29810 | __metrics Screen width/height:1600/1200 05:26:21 INFO - PROCESS | 29810 | colorDepth:24 05:26:21 INFO - PROCESS | 29810 | Browser inner width/height: 1024/697 05:26:21 INFO - PROCESS | 29810 | __metrics 05:26:22 INFO - PROCESS | 29810 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:26:22 INFO - PROCESS | 29810 | console.error: PushService: 05:26:22 INFO - PROCESS | 29810 | stateChangeProcessEnqueue: Error transitioning state 05:26:22 INFO - PROCESS | 29810 | UnknownError 05:26:23 INFO - TEST-INFO | 29810: exit 0 05:26:23 INFO - Browser initialized. 05:26:23 INFO - Running cycle 1/1 for kraken test... 05:26:23 INFO - TEST-INFO | started process 29889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ7x5FB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:35 INFO - PROCESS | 29889 | RSS: Main: 177078272 05:26:35 INFO - PROCESS | 29889 | 05:26:38 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55385/tests/kraken/driver.html?testName=audio-beat-detection) 05:26:38 INFO - PROCESS | 29889 | RSS: Main: 236568576 05:26:38 INFO - PROCESS | 29889 | 05:26:41 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55385/tests/kraken/driver.html?testName=audio-dft) 05:26:42 INFO - PROCESS | 29889 | RSS: Main: 224043008 05:26:42 INFO - PROCESS | 29889 | 05:26:45 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55385/tests/kraken/driver.html?testName=audio-fft) 05:26:45 INFO - PROCESS | 29889 | RSS: Main: 290467840 05:26:45 INFO - PROCESS | 29889 | 05:26:48 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55385/tests/kraken/driver.html?testName=audio-oscillator) 05:26:48 INFO - PROCESS | 29889 | RSS: Main: 201367552 05:26:48 INFO - PROCESS | 29889 | 05:26:50 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55385/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:51 INFO - PROCESS | 29889 | RSS: Main: 206028800 05:26:51 INFO - PROCESS | 29889 | 05:26:54 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55385/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:55 INFO - PROCESS | 29889 | RSS: Main: 242118656 05:26:55 INFO - PROCESS | 29889 | 05:26:58 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55385/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:59 INFO - PROCESS | 29889 | RSS: Main: 242892800 05:26:59 INFO - PROCESS | 29889 | 05:27:01 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55385/tests/kraken/driver.html?testName=json-parse-financial) 05:27:02 INFO - PROCESS | 29889 | RSS: Main: 242774016 05:27:02 INFO - PROCESS | 29889 | 05:27:03 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55385/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:27:04 INFO - PROCESS | 29889 | RSS: Main: 229068800 05:27:04 INFO - PROCESS | 29889 | 05:27:06 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:27:06 INFO - PROCESS | 29889 | RSS: Main: 232714240 05:27:06 INFO - PROCESS | 29889 | 05:27:09 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:27:09 INFO - PROCESS | 29889 | RSS: Main: 236306432 05:27:09 INFO - PROCESS | 29889 | 05:27:12 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:27:12 INFO - PROCESS | 29889 | RSS: Main: 240394240 05:27:12 INFO - PROCESS | 29889 | 05:27:15 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:27:16 INFO - PROCESS | 29889 | RSS: Main: 240021504 05:27:16 INFO - PROCESS | 29889 | 05:27:17 INFO - PROCESS | 29889 | Cycle 1(1): loaded http://localhost:55385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55385/tests/kraken/driver.html?testName=ai-astar) 05:27:18 INFO - PROCESS | 29889 | RSS: Main: 283205632 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | __start_tp_report 05:27:18 INFO - PROCESS | 29889 | _x_x_mozilla_page_load 05:27:18 INFO - PROCESS | 29889 | _x_x_mozilla_page_load_details 05:27:18 INFO - PROCESS | 29889 | |i|pagename|runs| 05:27:18 INFO - PROCESS | 29889 | |0;ai-astar;112;105;111;111;110;110;112;112;110;112 05:27:18 INFO - PROCESS | 29889 | |1;audio-beat-detection;163;124;126;178;129;133;150;135;193;126 05:27:18 INFO - PROCESS | 29889 | |2;audio-dft;161;161;161;160;162;164;163;164;164;161 05:27:18 INFO - PROCESS | 29889 | |3;audio-fft;75;75;74;126;75;76;74;74;101;76 05:27:18 INFO - PROCESS | 29889 | |4;audio-oscillator;84;85;113;85;84;85;124;86;85;120 05:27:18 INFO - PROCESS | 29889 | |5;imaging-gaussian-blur;117;117;117;117;118;118;117;117;116;116 05:27:18 INFO - PROCESS | 29889 | |6;imaging-darkroom;147;146;146;146;146;146;145;146;145;145 05:27:18 INFO - PROCESS | 29889 | |7;imaging-desaturate;95;94;95;95;94;95;94;95;95;95 05:27:18 INFO - PROCESS | 29889 | |8;json-parse-financial;55;65;53;54;54;53;54;53;52;55 05:27:18 INFO - PROCESS | 29889 | |9;json-stringify-tinderbox;64;63;63;72;64;63;63;64;64;64 05:27:18 INFO - PROCESS | 29889 | |10;stanford-crypto-aes;78;84;82;78;80;86;81;80;109;87 05:27:18 INFO - PROCESS | 29889 | |11;stanford-crypto-ccm;139;126;132;129;126;128;119;123;135;123 05:27:18 INFO - PROCESS | 29889 | |12;stanford-crypto-pbkdf2;165;170;161;162;162;162;160;169;163;167 05:27:18 INFO - PROCESS | 29889 | |13;stanford-crypto-sha256-iterative;63;62;63;63;66;63;64;63;62;66 05:27:18 INFO - PROCESS | 29889 | __end_tp_report 05:27:18 INFO - PROCESS | 29889 | __start_cc_report 05:27:18 INFO - PROCESS | 29889 | _x_x_mozilla_cycle_collect,1646 05:27:18 INFO - PROCESS | 29889 | __end_cc_report 05:27:18 INFO - PROCESS | 29889 | __startTimestamp1465648038146__endTimestamp 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | ------- Summary: start ------- 05:27:18 INFO - PROCESS | 29889 | Number of tests: 14 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.12 (1.9%) stddev-sans-first:2.18 05:27:18 INFO - PROCESS | 29889 | Values: 112.0 105.0 111.0 111.0 110.0 110.0 112.0 112.0 110.0 112.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:142.50 stddev:24.50 (17.2%) stddev-sans-first:25.17 05:27:18 INFO - PROCESS | 29889 | Values: 163.0 124.0 126.0 178.0 129.0 133.0 150.0 135.0 193.0 126.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.52 (0.9%) stddev-sans-first:1.56 05:27:18 INFO - PROCESS | 29889 | Values: 161.0 161.0 161.0 160.0 162.0 164.0 163.0 164.0 164.0 161.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:17.33 (23.1%) stddev-sans-first:18.17 05:27:18 INFO - PROCESS | 29889 | Values: 75.0 75.0 74.0 126.0 75.0 76.0 74.0 74.0 101.0 76.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:85.00 stddev:16.71 (19.7%) stddev-sans-first:17.23 05:27:18 INFO - PROCESS | 29889 | Values: 84.0 85.0 113.0 85.0 84.0 85.0 124.0 86.0 85.0 120.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:27:18 INFO - PROCESS | 29889 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 116.0 116.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 05:27:18 INFO - PROCESS | 29889 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:27:18 INFO - PROCESS | 29889 | Values: 95.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:3.71 (6.9%) stddev-sans-first:3.93 05:27:18 INFO - PROCESS | 29889 | Values: 55.0 65.0 53.0 54.0 54.0 53.0 54.0 53.0 52.0 55.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.00 stddev:2.72 (4.2%) stddev-sans-first:2.88 05:27:18 INFO - PROCESS | 29889 | Values: 64.0 63.0 63.0 72.0 64.0 63.0 63.0 64.0 64.0 64.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:81.50 stddev:9.14 (11.2%) stddev-sans-first:9.39 05:27:18 INFO - PROCESS | 29889 | Values: 78.0 84.0 82.0 78.0 80.0 86.0 81.0 80.0 109.0 87.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:128.50 stddev:6.02 (4.7%) stddev-sans-first:4.89 05:27:18 INFO - PROCESS | 29889 | Values: 139.0 126.0 132.0 129.0 126.0 128.0 119.0 123.0 135.0 123.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.00 stddev:3.48 (2.1%) stddev-sans-first:3.67 05:27:18 INFO - PROCESS | 29889 | Values: 165.0 170.0 161.0 162.0 162.0 162.0 160.0 169.0 163.0 167.0 05:27:18 INFO - PROCESS | 29889 | 05:27:18 INFO - PROCESS | 29889 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:1.43 (2.3%) stddev-sans-first:1.51 05:27:18 INFO - PROCESS | 29889 | Values: 63.0 62.0 63.0 63.0 66.0 63.0 64.0 63.0 62.0 66.0 05:27:18 INFO - PROCESS | 29889 | -------- Summary: end -------- 05:27:18 INFO - PROCESS | 29889 | 05:27:19 INFO - TEST-INFO | 29889: exit 0 05:27:19 INFO - TEST-OK | kraken | took 61835ms 05:27:19 INFO - SUITE-END | took 1176s 05:27:19 INFO - Completed test suite (00:19:36) 05:27:19 INFO - javascript benchmark 05:27:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.384123175364, 3083.768717346199, 3097.070878736379, 3122.0633809857036, 3140.5760222369972, 5224.686518808874, 5260.763265653045, 5305.8938821223555, 5348.572114230862, 5382.0, 5344.786208551663, 5364.1149210380245, 5364.544013758835, 5390.973045134779, 5394.541463975567, 4775.3075804008395, 4828.73441960692, 4986.4514903360605, 5035.748212589374, 5080.923786143211, 4980.701157930535, 5017.397912250535, 5030.773615187307, 5047.0, 5048.899022019557, 4734.124187025394, 4756.215224487973, 4859.271109333593, 4869.561739443467, 4888.315635810989, 4897.681650692729, 4900.877478063041, 4901.019796040806, 4920.6309526785635, 4963.3299504566785, 4849.806007759686, 4915.041566894457, 4923.335349727777, 4937.23472861707, 4937.358143441365, 5204.219367094928, 5261.657992230493, 5274.446183150752, 5323.547498462635, 5465.234867118606, 5648.74580643872, 5688.146777983336, 5695.40198279183, 5778.508826749731, 5788.971055144697, 5765.394633563457, 5770.711464426804, 5824.30108386992, 5833.212516310286, 5834.358220595731, 5285.207218917155, 5344.091504444254, 5389.461053894618, 5392.245085688007, 5475.233467314579, 5889.146073819358, 5900.586958912835, 5914.58597898143, 5964.940350596526, 5965.28416590012, 5730.971345143247, 5741.8564535886935, 5742.0, 5742.368339482654, 5742.942570574324, 5558.110702287696, 5598.356189038232, 5604.075327570987, 5640.774369025193, 5697.4302569742695, 5668.0, 5673.461021202979, 5674.460926212003, 5677.432256774372, 5678.205051292823, 5685.204071430003, 5723.513501352349, 5773.653580785166, 5775.768969241183, 5848.678322692208, 4394.2749446340995, 4425.336199570091, 4436.134953684001, 4465.531119232499, 4471.6646251531265, 3952.7628342299554, 3991.7405368650943, 3994.860179893748, 3997.9000524986527, 4007.4189242560246, 5227.503387178212, 5267.341582302212, 5341.65279256847, 5377.193420986805, 5466.699331536804, 5735.254416925856, 5744.540436765049, 5746.13807928814, 5752.165935939265, 5791.652500849962, 5353.223782551586, 5382.30030096085, 5387.353517577916, 5393.325834270716, 5401.810936617199, 4739.786709598076, 4781.665283430196, 4814.301926220748, 4827.493113223131, 4866.489018653061, 5925.466707996215, 5928.703564821742, 5933.258342707161, 5936.7328470218945, 5943.791967281211, 5787.565932554991, 5792.971035144966, 5801.332846722681, 5805.303363596396, 5805.8548536286935, 5733.369329373682, 5869.647821130746, 5896.587238893237, 5930.051191809463, 6007.3992600739575, 5701.230333904869, 5702.714864256854, 5703.287089113861, 5708.971455142864, 5756.366799651951, 5762.7694892203845, 5799.2750906136735, 5807.448292412214, 5809.970950145223, 5818.650880947157, 5447.564194864481, 5561.054620714569, 5597.356304025089, 5616.045272303636, 5650.943490565041, 5624.803131890364, 5625.8593535161945, 5628.831135065954, 5629.521490673339, 5632.493075623213, 5503.3395992481155, 5550.722463876871, 5565.860853478695, 5566.52684521804, 5567.415421380697, 5497.120460726264, 5565.499105080562, 5612.7754889803955, 5621.381648018796, 5692.800751973661, 5497.560195184456, 5501.339839219319, 5507.752151153288, 5512.090505066619, 5520.530754885719], "value": 5294.202957504494, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.4648177195206, 616.1958643969618, 627.792828366639, 634.3846468925141, 649.7238673563737, 29612.703872961265, 41411.58588414115, 42220.57779422205, 42280.57719422805, 42398.912130544304, 43464.78267608657, 44489.33266001013, 44527.55098469583, 44606.776966115205, 44645.33032004523, 8804.207621314097, 9021.95489022554, 9031.277497800193, 9057.547122643875, 9061.139191776803, 8778.078301778318, 8896.510691911943, 8921.375503714742, 8931.419457735226, 8933.46399216046, 8986.056464071276, 9029.909700902972, 9030.277577793793, 9033.909660903406, 9035.186833185013, 8934.55327233639, 8956.686515971975, 8963.238124759404, 8966.148215919477, 8979.281657467387, 8912.197902188835, 8975.910240897572, 8993.820123597492, 9002.099790021011, 9004.8649270261, 42309.0, 42658.360124598155, 42794.0, 42802.78598607018, 42841.78579107085, 3291.210109573709, 3292.670732926712, 3326.1352048467575, 3563.768355056913, 3600.891973240807, 32169.6783032169, 32708.345833083444, 33015.17462063444, 33276.334473310395, 33601.49597756036, 4232.873013809591, 4233.491980962304, 4254.446921900164, 4255.468066491689, 4347.22373408011, 354988.0, 355165.0, 355479.2226038879, 355643.0, 360234.0, 56084.439155608736, 56560.71719641375, 56640.716796415756, 56732.4757652867, 56768.14847777287, 52037.0, 52037.2194417084, 52864.471355286725, 53296.20055699167, 58279.70860145757, 14639.341229644691, 14648.487302944346, 14732.0, 14732.779008314883, 14741.778873316909, 10607.15142788575, 11092.223544351978, 11094.22340436178, 11094.389808560607, 11101.111911047097, 15349.846501535065, 15600.375984960474, 15650.921745391428, 15672.138032408098, 15879.0, 6545.869082618321, 6598.934010659832, 6605.669716514155, 6766.0, 6792.0, 968.3076600230869, 1004.1916257412706, 1033.8242498775226, 1089.3518356577824, 1108.695108845058, 32245.35509289801, 32567.837160814044, 33119.834400828324, 33198.0, 33634.49548256778, 1087.4617064553097, 1096.7751610919743, 1116.7431490757172, 1131.4342828585707, 1201.243216773427, 16778.6644267114, 17274.827251727573, 17298.82701172972, 17309.7403538947, 17392.821361458966, 157049.0, 158668.0, 158762.0, 159573.0, 160091.0, 58417.0, 58648.0, 58719.41280587225, 58880.705596471744, 58895.70552147212, 1196.3360335014152, 1309.9803502947464, 1331.074902942465, 1344.5630170194727, 1348.4067010515146, 32859.0, 34132.658673412945, 34191.97424077282, 34282.0, 34489.0, 49155.262671059245, 49663.25505117499, 49856.50143498664, 49859.0, 49924.25113623226, 427694.0, 435538.0, 435703.0, 439104.0, 440441.0, 34679.0, 35861.0, 36054.0, 38215.80892095522, 38636.80681596574, 46205.0, 49345.753271233414, 49350.506494936024, 50273.497265028345, 50322.0, 40993.38509922294, 41442.0, 41477.0, 42350.788246058575, 42619.78690106654, 40416.79791601023, 40593.0, 40743.79628101959, 41067.0, 41352.58647413487, 40683.796581016904, 41234.175316494104, 41497.37753933633, 42121.36817947672, 45063.32405013993, 33292.500612490345, 36562.8171859139, 37591.0, 37599.624003760706, 37625.0, 24752.381190470383, 24823.875880621203, 25146.0, 25316.70882785226, 25351.87324063368], "value": 19231.29165922265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.82059342405773, 388.44840326736033, 398.5078428141247, 405.68964741972394, 416.68332067628614, 14063.789043164361, 15476.380944762195, 17621.64756704865, 19282.132304046318, 19307.613847723056, 14520.491782787605, 17663.558411039732, 18161.182746776394, 18164.81835181651, 18928.71606925894, 5573.916391254134, 5653.462921022497, 5772.480476757091, 5774.682392468433, 5786.450287222729, 4038.9394159087637, 5505.779768809243, 5686.31764188298, 5725.026745453283, 5995.160677505131, 6288.088227207052, 6363.872722545546, 6387.361263873599, 6395.712192951305, 6406.167198264219, 6259.21759780023, 6351.650659213741, 6396.584222025576, 6448.419642232175, 6450.548461607689, 5642.943570564324, 5651.4913657770585, 5660.29246344207, 5665.943340566624, 5680.4319568043275, 21134.0, 21873.781262187335, 22234.0, 22313.330600082023, 22332.106715731352, 2138.3050508584774, 2172.6415141501634, 2208.3595757230382, 2224.5662095891307, 2268.5009297954425, 9545.284103692182, 9944.95027524865, 9954.402735835873, 10191.439470829102, 12935.094543381967, 1834.1196225811552, 1850.4078694817663, 1969.9015049247623, 1972.615340008699, 1976.2589029114083, 82365.58817205936, 82662.0, 83296.58351708202, 83297.0, 85284.0, 10432.68701938943, 10482.423466709251, 10593.205509586813, 10631.84052239217, 10862.131029517619, 15044.0, 15055.397784088515, 15066.83596444737, 15156.924215379073, 15180.165090919885, 6470.708818103197, 7247.13034435871, 7549.924500755032, 7705.537667739954, 7892.644830982619, 7310.4151667866445, 7407.0, 7438.888416673754, 7512.173660897297, 7525.736599219001, 4489.393931819162, 4545.772711364419, 4588.6099681526775, 4589.908201835945, 4795.448523419782, 1866.4773863318292, 1900.0, 1906.8855868647925, 1935.4967708395918, 1936.7288579598867, 864.1962974433837, 965.1217392173089, 987.3532835992437, 995.4475266227169, 1011.3325205364424, 17869.285228590972, 18798.248070077043, 19012.334568290044, 19099.61800763977, 19339.90330048341, 973.5764942250144, 989.4310771306527, 1013.1894484412588, 1015.5785349080197, 1042.0933787604831, 10211.234157438073, 10729.302595331279, 10738.302010369458, 10739.892601074045, 10763.78472430547, 432971.0, 434086.0, 449671.0, 490103.0, 498811.0, 93758.53120734489, 97534.51232743791, 98284.50857745665, 98668.0, 99727.50136249272, 965.4689920543673, 976.3897564022628, 1040.9479526023667, 1047.6856942917095, 1047.7171163785736, 16606.588439982737, 16606.66786664284, 16727.330906763593, 17021.65956680884, 17288.308467661154, 30891.848567020163, 39739.80130099331, 40604.79697601493, 41365.172696546506, 42688.35967460428, 56578.7171064142, 57330.42669573251, 57540.42459575351, 58638.0, 58871.0, 8355.248027677539, 8389.370797190113, 8393.286570641563, 8647.481151130953, 8677.609507572048, 12582.24506529611, 12944.482220711066, 13078.53596099352, 13500.392482338515, 13661.726765464833, 11735.706607334885, 11748.118891083031, 11863.940680296544, 11990.400479975791, 12017.339046352534, 7661.0, 11414.774119047039, 11701.180917335705, 11731.824022639837, 11760.941195294312, 11595.710107247387, 11833.526658933546, 11902.285862848257, 11951.462184201559, 12138.453769580214, 8210.958945205235, 8383.58082095905, 8399.328053755562, 8522.914770852212, 8758.386912916034, 11760.470778815145, 11886.405679715806, 11973.221740586834, 11982.638195969077, 11997.460114294703], "value": 9082.052053411619, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1134.444122380034, 1435.0, 1477.3204326010034, 1587.1350114187767, 1725.5513566472714, 8058.395620328477, 8521.0, 8523.744287671365, 8750.0, 8765.824683506326, 8343.624536895839, 8501.06488286288, 8526.44578102423, 8528.147185281468, 8541.402101852873, 2197.252934002435, 2220.2784095169136, 2222.3999520129555, 2224.0214305705517, 2225.2434172381386, 2160.686700428437, 2200.0, 2244.472548950995, 2247.6516139998325, 2250.504888924434, 2195.5169862630196, 2210.16013914713, 2231.9665205021856, 2239.294622194012, 2239.764824693408, 2148.967765483519, 2159.6220661384273, 2168.718066651341, 2173.9347819565437, 2179.1065663078143, 2108.5361220531613, 2110.799474049963, 2110.8944552772455, 2111.2610586294827, 2114.5770845830743, 8534.658613655447, 8663.610137543825, 8713.651453941835, 8719.564021798884, 8796.604152813139, 5011.899762004776, 5123.462036486152, 5129.153689641204, 5132.435432102466, 5132.589392848563, 3251.252211991255, 3275.0993476794074, 3335.149536868083, 3344.3980083584943, 3351.7318614511023, 1044.6918159143072, 1054.620336678795, 1062.5696592879897, 1081.0432767001182, 1087.276960821053, 12175.817362739566, 12287.385630718429, 12328.445219965033, 12332.260064396165, 12334.568290109803, 4121.5878412158545, 4153.376993450946, 4245.1934132514725, 4261.062566235423, 4262.680298977589, 9708.563114659857, 9709.757256068655, 9726.076022777825, 9732.172765315028, 9735.41587504752, 4893.951060489349, 4923.729194894315, 4926.852194434173, 4930.679505832181, 4932.334134891744, 4086.0, 4169.791510424528, 4308.719933204332, 4605.263157894721, 4670.416197975253, 3658.487811706363, 3752.137008488063, 3797.6012518685693, 3801.5248093988253, 3809.1619843634357, 1045.0385645206277, 1088.8584484017185, 1104.679642903565, 1109.2457129152253, 1109.9778004440027, 963.2294164668237, 972.0473935543207, 983.326421401328, 983.4492684096929, 985.0051448037534, 5941.197938278277, 5969.671668058299, 5995.040793473024, 6019.668918209541, 6074.876147912651, 918.0498184379136, 957.3729207369184, 982.2436723722693, 985.2708995343394, 1032.2309879139939, 4090.304648209752, 4137.13120244752, 4179.665626749853, 4202.70581059329, 4205.894852628648, 10857.131429485622, 11146.944265278622, 11207.943960280309, 11244.887551124548, 11257.774844503065, 10975.121990240761, 11070.169737269736, 11071.391073491035, 11076.224664273584, 11106.167037472222, 7914.564698941498, 8286.8342633147, 8304.128066553047, 8467.941507311709, 8489.95755021221, 5955.43423374796, 6222.533310001859, 6311.116443697794, 6332.430081292521, 6385.521085918482, 10124.34191777532, 10322.0, 10536.947315263682, 10541.57833686644, 10544.156467482737, 10469.895301046892, 10486.842697359698, 10598.0, 10598.364098154136, 10618.893811061791, 7904.960475197588, 8133.430659853753, 8145.592720364077, 8169.468984516225, 8175.918240817516, 8465.619047143016, 8594.097619750086, 8602.18279263494, 8620.310375169845, 8635.179657932485, 7699.653515591924, 7762.456628035983, 7797.142314345531, 7822.374210063295, 7889.13219545861, 7496.662650180646, 7646.311831935152, 7652.502587331805, 7664.693412263447, 7688.577128258, 7352.117745870316, 7370.6314684266645, 7434.702611895464, 7477.476576639583, 7493.962530187314, 5316.6012549058205, 5326.121190003606, 5329.387120481194, 5389.51494365509, 5417.918731218956, 5406.78372865081, 5513.283273174462, 5535.280413546213, 5614.831555053355, 5681.289838770153], "value": 4569.012519545588, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.9412556187894, 322.6434789557539, 324.1668910898991, 329.4087113631032, 330.9884154054608, 5304.514735873957, 5333.706646134461, 5347.224652425396, 5350.036993341197, 5350.956563470115, 5260.947390526094, 5287.4976877196705, 5294.205869119634, 5322.574194064475, 5333.4399888011785, 3970.602939706035, 3985.2228815381013, 4107.54816970133, 4136.772477513736, 4155.812988415513, 4119.6704263658985, 4136.627703506684, 4143.564925682806, 4147.232761939036, 4151.501819781631, 3933.7836418996944, 4081.8367265309353, 4098.446709694198, 4114.917701645965, 4118.505779306487, 3822.617738226183, 4008.6592639625815, 4055.290324193269, 4105.2405305018665, 4149.647279981205, 3618.3486972344967, 3633.6003039665616, 3659.579148397915, 3667.449882517617, 3677.2461645362637, 5141.460146684619, 5172.146595811687, 5192.8961420771375, 5202.583793296527, 5213.895722085537, 4529.547045295477, 4659.18464268755, 4695.178343789823, 4742.9525704742855, 4752.5247475252545, 5310.548603368718, 5411.725905826957, 5431.918521222185, 5456.781728730846, 5503.807366742185, 3973.22522108189, 3987.760734355948, 3988.806156613851, 3993.920121597581, 4003.459532963042, 5487.725613719298, 5724.169995350692, 5742.913856292159, 5775.48020678141, 5806.4193580641595, 3973.165635216637, 3990.4014397840556, 3991.7405368650943, 3993.1414745829784, 4005.239004589119, 4021.718479706451, 4048.69634777387, 4084.6732261419015, 4084.6936479764136, 4102.979485102615, 4476.4556228101255, 4721.474011403882, 4723.220668589713, 4726.527347265314, 4749.691270067504, 5552.750126244328, 5611.270534830446, 5612.494875461228, 5613.7193140342815, 5629.746661400247, 1396.6717821311984, 1401.201315250318, 1408.6971301170297, 1418.985810141906, 1426.2298358886085, 878.3598732344766, 895.4000819450983, 896.7040876510866, 921.3596550397543, 922.041077279635, 5256.868578285497, 5393.514583687487, 5407.918881216785, 5409.594280428905, 5425.891482170414, 5729.0, 5731.168980497804, 5745.2818397700285, 5749.9712501437225, 5770.769169233267, 5170.121079416508, 5307.336582927134, 5386.461353864661, 5438.592105592096, 5440.727963601804, 3010.909672709822, 3058.5412188171954, 3062.0096936991135, 3079.6458407283003, 3082.506798912208, 5797.826065218051, 5819.301683797887, 5841.503472204825, 5918.408159184047, 5972.462478376967, 5577.721113944286, 5864.824055278348, 5912.586118971717, 5946.256717910262, 5984.132300816357, 5869.589128761031, 5877.823665290048, 5894.616849904743, 5970.432808883149, 5993.790217342459, 5594.342777009803, 5634.774609015593, 5684.948284567368, 5730.541556675539, 5770.538356931352, 5632.2678051853, 5647.265855438766, 5695.117256825199, 5707.4577915098, 5733.140028995684, 5947.464728174485, 5951.285845698544, 5959.404059594006, 5972.790952316822, 5991.071383935497, 3894.1308172076997, 4030.3551431770775, 4039.596040395937, 4039.8182081805803, 4074.6757303875766, 5719.28508936383, 5724.4561766632105, 5728.341240757366, 5742.444612147554, 5746.482816546531, 5336.946630533645, 5596.132599447093, 5607.495325420732, 5610.382857885711, 5612.831615051392, 5511.724413779215, 5523.502884740393, 5534.944650553443, 5538.501534861882, 5549.1676248561425, 5247.842564723064, 5368.926214756986, 5415.860735009728, 5427.611850785642, 5433.646812957145, 5014.448410674896, 5420.9186862197885, 5453.945460545502, 5457.471907865804, 5558.221369161991, 5426.728663566727, 5459.590530710133, 5510.641808282448, 5526.336839579276, 5527.972360138335], "value": 4224.179024977997, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.5961841178147, 768.2739810878721, 771.9112302085261, 787.5117427195138, 816.5182542300042, 14457.132572045672, 16051.0, 16347.918260408696, 16372.83627163728, 16405.589860253498, 14924.850751492482, 15043.849561504381, 15114.6977060459, 15166.696666066671, 15225.314860831262, 809.1988930958368, 819.0335404223015, 819.4018366592392, 819.7294892685429, 819.7950512371907, 672.156443663202, 693.9930600693979, 694.6978064541916, 779.7037125892153, 801.9799505012393, 841.3311417423163, 856.9957150214241, 857.2541888556933, 857.2713193785295, 857.3398483167957, 850.5067061104545, 852.722865068852, 852.893388326453, 853.2576658307281, 858.9785255368635, 513.6558505801084, 529.7271904968944, 537.749946274984, 623.965681887496, 624.0047124835879, 12669.429875655618, 14365.0, 15050.32273547682, 15056.774148387673, 15599.376024958989, 14179.716405671932, 14463.059901106499, 14484.493042743454, 14559.92720036393, 14769.556913292618, 11720.238184517979, 11759.647210583697, 11780.293182409083, 11990.16068875179, 12024.0, 3755.5493340799007, 3794.4498047783195, 3853.190829925719, 3876.186000939806, 4036.9192616147807, 59682.10476842851, 60226.39773602252, 73454.0, 75433.0, 76012.61993690052, 11106.111511079094, 11274.269136353427, 11309.75592684804, 11601.709957250967, 11624.88375116255, 20799.1680332785, 20820.687689684666, 20840.687389689167, 20850.58298834045, 20890.686639700416, 8267.669293228324, 8455.069942306342, 8465.238128568335, 8628.309735221168, 8727.694530691395, 4893.4617192108835, 5007.148784706608, 5047.570956468668, 5090.134677104827, 5137.998090372416, 3222.6132864056462, 3338.399088164154, 3344.9331013379597, 3345.0, 3346.748993825473, 591.7899145803264, 599.868029033612, 602.7588964414182, 606.6360183889631, 624.4785604020693, 756.2739769821034, 761.1969372312153, 767.4436033875508, 797.752696664036, 804.9597520123971, 9641.566129524188, 10092.293539452168, 10224.744381390376, 10237.897621023843, 10311.432871192157, 649.691396586627, 702.8945658151216, 709.1950636028146, 720.8450183210634, 745.1803016681715, 4433.933490997638, 4609.907801844011, 4829.9275510867365, 4857.611391088704, 4860.3195552622665, 69253.65373173203, 103824.66994036837, 106334.46832765942, 110136.4493177529, 111967.0, 93949.0, 94171.82054986905, 94792.52603736937, 95012.0, 95844.52077739566, 85091.0, 85094.0, 85966.14033859706, 86269.0, 88492.1150788484, 9276.304277179104, 9424.905750942677, 9529.361354418912, 9576.473293968898, 9802.663961934695, 50567.74716126396, 51828.0, 52043.73978130085, 52975.470245298595, 53393.46606533885, 69427.65286173536, 74149.0, 75040.0, 76112.0, 76252.61873690596, 12213.267203967791, 12324.568640097554, 12692.746145077232, 12715.10994230395, 13074.346282686018, 12922.418491168108, 13153.473861045451, 13333.133346332457, 13731.725365492435, 14175.078619889675, 13481.460741570227, 13540.79688804649, 13655.344655344656, 13702.931485342908, 13825.170489770646, 12796.872031279569, 13188.934055329662, 13246.07277490605, 13342.266175360448, 13368.197908125543, 12387.380630968595, 12710.110292279453, 12740.426680799572, 13341.866581334063, 13398.531051413154, 10315.0, 10626.574937002433, 10661.318256922703, 10771.407572583583, 11292.604758833402, 10699.502069710403, 10873.836892446778, 10954.780904382027, 11078.501467433824, 11238.438078095898], "value": 7432.968951126966, "unit": "score"}], "name": "dromaeo_css", "value": 7141.57456675792, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 111.0, 111.0, 110.0, 110.0, 112.0, 112.0, 110.0, 112.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 124.0, 126.0, 178.0, 129.0, 133.0, 150.0, 135.0, 193.0, 126.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 162.0, 164.0, 163.0, 164.0, 164.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 126.0, 75.0, 76.0, 74.0, 74.0, 101.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 113.0, 85.0, 84.0, 85.0, 124.0, 86.0, 85.0, 120.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 116.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 65.0, 53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 52.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 72.0, 64.0, 63.0, 63.0, 64.0, 64.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 82.0, 78.0, 80.0, 86.0, 81.0, 80.0, 109.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 126.0, 132.0, 129.0, 126.0, 128.0, 119.0, 123.0, 135.0, 123.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 170.0, 161.0, 162.0, 162.0, 162.0, 160.0, 169.0, 163.0, 167.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 66.0, 63.0, 64.0, 63.0, 62.0, 66.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1512.8, "alertThreshold": 2.0}]} 05:27:19 INFO - Return code: 0 05:27:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:27:19 INFO - # TBPL SUCCESS # 05:27:19 INFO - Running post-action listener: _resource_record_post_action 05:27:19 INFO - ##### 05:27:19 INFO - ##### Finished run-tests step (success) 05:27:19 INFO - ##### 05:27:19 INFO - Running post-run listener: _resource_record_post_run 05:27:19 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 541814784; Read time: 232; Write time: 516540 05:27:19 INFO - TinderboxPrint: CPU usage
13.2% 05:27:19 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 232 05:27:19 INFO - TinderboxPrint: I/O write bytes / time
541,814,784 / 516,540 05:27:19 INFO - TinderboxPrint: CPU idle
8,269.7 (86.8%) 05:27:19 INFO - TinderboxPrint: CPU user
1,208.6 (12.7%) 05:27:19 INFO - TinderboxPrint: Swap in / out
0 / 0 05:27:19 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254484480; Read time: 0; Write time: 257532 05:27:19 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 8192; Write bytes: 287330304; Read time: 232; Write time: 259008 05:27:20 INFO - Running post-run listener: _upload_blobber_files 05:27:20 INFO - Blob upload gear active. 05:27:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:27:20 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 05:27:20 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:27:20 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:27:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:27:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:27:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:21 INFO - (blobuploader) - INFO - Done attempting. 05:27:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:27:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:27:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:21 INFO - (blobuploader) - INFO - Done attempting. 05:27:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:27:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:25 INFO - (blobuploader) - INFO - Done attempting. 05:27:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:27:25 INFO - Return code: 0 05:27:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:27:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:27:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7505957e3f38e112f5b11e9fde721ffbe6a3502735a07d45174903f6f038d6d873a67ba89356a53b18fbafc644fb77a9b923f45a3c23eb2fb3822bd30c48266a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/180c2891922eb201ec0a87ec747b7da96a3f670674c122e36d53e9415f5c8e7a0e35560cba03e767fbdd4201ec3eda67613f3adfd42e7ede5a81460a9f676937", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de231c1cce3635cdc7069e62daf0587238293dd051aed588a7385230ff8ad2654d4c933f9f498a4ab7f301cb42767426ca5c7e274734753f24d3341065308668"} 05:27:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:27:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:27:25 INFO - Contents: 05:27:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7505957e3f38e112f5b11e9fde721ffbe6a3502735a07d45174903f6f038d6d873a67ba89356a53b18fbafc644fb77a9b923f45a3c23eb2fb3822bd30c48266a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/180c2891922eb201ec0a87ec747b7da96a3f670674c122e36d53e9415f5c8e7a0e35560cba03e767fbdd4201ec3eda67613f3adfd42e7ede5a81460a9f676937", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de231c1cce3635cdc7069e62daf0587238293dd051aed588a7385230ff8ad2654d4c933f9f498a4ab7f301cb42767426ca5c7e274734753f24d3341065308668"} 05:27:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:27:25 INFO - Copying logs to upload dir... 05:27:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:27:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.663398 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-06-11 05:27:25.584311) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-11 05:27:25.588004) ========= 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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7505957e3f38e112f5b11e9fde721ffbe6a3502735a07d45174903f6f038d6d873a67ba89356a53b18fbafc644fb77a9b923f45a3c23eb2fb3822bd30c48266a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/180c2891922eb201ec0a87ec747b7da96a3f670674c122e36d53e9415f5c8e7a0e35560cba03e767fbdd4201ec3eda67613f3adfd42e7ede5a81460a9f676937", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de231c1cce3635cdc7069e62daf0587238293dd051aed588a7385230ff8ad2654d4c933f9f498a4ab7f301cb42767426ca5c7e274734753f24d3341065308668"} build_url:https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/SCGZxjF1TlOmibT9CXdxKw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7505957e3f38e112f5b11e9fde721ffbe6a3502735a07d45174903f6f038d6d873a67ba89356a53b18fbafc644fb77a9b923f45a3c23eb2fb3822bd30c48266a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/180c2891922eb201ec0a87ec747b7da96a3f670674c122e36d53e9415f5c8e7a0e35560cba03e767fbdd4201ec3eda67613f3adfd42e7ede5a81460a9f676937", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de231c1cce3635cdc7069e62daf0587238293dd051aed588a7385230ff8ad2654d4c933f9f498a4ab7f301cb42767426ca5c7e274734753f24d3341065308668"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-11 05:27:25.616627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:27:25.617232) ========= 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=14ef55db36f7623abb31b9cf000001d5-1465644790.861606-644824642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004460 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-11 05:27:25.702299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-11 05:27:25.703018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-11 05:27:25.703747) ========= ========= Total master_lag: 0.18 =========