builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1469893507.32 results: success (0) buildid: 20160730060525 builduid: 25e1b537a1d1458e81ef0e0376463e07 revision: fb90485f11a590834aaa80481d61f8a4c11368e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.319419) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.320390) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.320891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.336726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.337151) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 08:45:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.5M=0s 2016-07-30 08:45:07 (38.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.458104) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.458462) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018567 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:07.510053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 08:45:07.510767) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fb90485f11a590834aaa80481d61f8a4c11368e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fb90485f11a590834aaa80481d61f8a4c11368e2 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 08:45:08,026 truncating revision to first 12 chars 2016-07-30 08:45:08,027 Setting DEBUG logging. 2016-07-30 08:45:08,027 attempt 1/10 2016-07-30 08:45:08,027 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fb90485f11a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 08:45:09,374 unpacking tar archive at: mozilla-aurora-fb90485f11a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.775881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 08:45:09.304999) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:09.305717) ========= script_repo_revision: fb90485f11a590834aaa80481d61f8a4c11368e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:09.306635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:09.307313) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 08:45:09.318732) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-30 08:45:09.319351) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:45:09 INFO - MultiFileLogger online at 20160730 08:45:09 in /builds/slave/test 08:45:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:45:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:09 INFO - {'append_to_log': False, 08:45:09 INFO - 'base_work_dir': '/builds/slave/test', 08:45:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:45:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:09 INFO - 'branch': 'Mozilla-Aurora', 08:45:09 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:09 INFO - 'config_files': ('talos/linux_config.py',), 08:45:09 INFO - 'default_actions': ('clobber', 08:45:09 INFO - 'read-buildbot-config', 08:45:09 INFO - 'download-and-extract', 08:45:09 INFO - 'populate-webroot', 08:45:09 INFO - 'create-virtualenv', 08:45:09 INFO - 'install', 08:45:09 INFO - 'run-tests'), 08:45:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:09 INFO - 'download_minidump_stackwalk': True, 08:45:09 INFO - 'download_symbols': 'ondemand', 08:45:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:09 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:09 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:09 INFO - 'installer_path': 'installer.exe', 08:45:09 INFO - 'log_level': 'info', 08:45:09 INFO - 'log_name': 'talos', 08:45:09 INFO - 'log_to_console': True, 08:45:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:45:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:45:09 INFO - 'opt_config_files': (), 08:45:09 INFO - 'pip_index': False, 08:45:09 INFO - 'sps_profile': False, 08:45:09 INFO - 'sps_profile_interval': 0, 08:45:09 INFO - 'suite': 'dromaeojs', 08:45:09 INFO - 'system_bits': '32', 08:45:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:09 INFO - 'title': 'talos-linux64-ix-041', 08:45:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:09 INFO - 'use_talos_json': True, 08:45:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:09 INFO - 'work_dir': 'build'} 08:45:09 INFO - ##### 08:45:09 INFO - ##### Running clobber step. 08:45:09 INFO - ##### 08:45:09 INFO - Running pre-action listener: _resource_record_pre_action 08:45:09 INFO - Running main action method: clobber 08:45:09 INFO - rmtree: /builds/slave/test/build 08:45:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:10 INFO - Running post-action listener: _resource_record_post_action 08:45:10 INFO - ##### 08:45:10 INFO - ##### Finished clobber step (success) 08:45:10 INFO - ##### 08:45:10 INFO - ##### 08:45:10 INFO - ##### Running read-buildbot-config step. 08:45:10 INFO - ##### 08:45:10 INFO - Running pre-action listener: _resource_record_pre_action 08:45:10 INFO - Running main action method: read_buildbot_config 08:45:10 INFO - Using buildbot properties: 08:45:10 INFO - { 08:45:10 INFO - "project": "", 08:45:10 INFO - "product": "firefox", 08:45:10 INFO - "script_repo_revision": "production", 08:45:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:45:10 INFO - "repository": "", 08:45:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:45:10 INFO - "buildid": "20160730060525", 08:45:10 INFO - "pgo_build": "True", 08:45:10 INFO - "basedir": "/builds/slave/test", 08:45:10 INFO - "buildnumber": 398, 08:45:10 INFO - "slavename": "talos-linux64-ix-041", 08:45:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:45:10 INFO - "platform": "ubuntu64_hw", 08:45:10 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:45:10 INFO - "branch": "mozilla-aurora", 08:45:10 INFO - "repo_path": "releases/mozilla-aurora", 08:45:10 INFO - "revision": "fb90485f11a590834aaa80481d61f8a4c11368e2", 08:45:10 INFO - "stage_platform": "linux64-pgo", 08:45:10 INFO - "builduid": "25e1b537a1d1458e81ef0e0376463e07", 08:45:10 INFO - "slavebuilddir": "test" 08:45:10 INFO - } 08:45:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:45:10 INFO - Running post-action listener: _resource_record_post_action 08:45:10 INFO - ##### 08:45:10 INFO - ##### Finished read-buildbot-config step (success) 08:45:10 INFO - ##### 08:45:10 INFO - ##### 08:45:10 INFO - ##### Running download-and-extract step. 08:45:10 INFO - ##### 08:45:10 INFO - Running pre-action listener: _resource_record_pre_action 08:45:10 INFO - Running main action method: download_and_extract 08:45:10 INFO - mkdir: /builds/slave/test/build/tests 08:45: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')]} 08:45:10 INFO - https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:45:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:45:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:45:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:45:10 INFO - Downloaded 1361 bytes. 08:45:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:45:10 INFO - Using the following test package requirements: 08:45:10 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:45:10 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:45:10 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:45:10 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'jsshell-linux-x86_64.zip'], 08:45:10 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:45:10 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:45:10 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:45:10 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:45:10 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:45:10 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:45:10 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:45: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')]} 08:45:10 INFO - https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:45:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:45:11 INFO - Downloaded 24496468 bytes. 08:45:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:45:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:45:13 INFO - Return code: 0 08:45:13 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:45: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')]} 08:45:13 INFO - https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:45:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:45:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:45:14 INFO - Downloaded 24496468 bytes. 08:45:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:45:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:45:15 INFO - Return code: 0 08:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:15 INFO - https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:45:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:45:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:45:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:45:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:45:16 INFO - Downloaded 10833369 bytes. 08:45:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:45:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:45:16 INFO - Return code: 0 08:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:16 INFO - https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:45:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:45:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:45:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:45:18 INFO - Downloaded 63639880 bytes. 08:45:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:45:18 INFO - mkdir: /builds/slave/test/properties 08:45:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:45:18 INFO - Writing to file /builds/slave/test/properties/build_url 08:45:18 INFO - Contents: 08:45:18 INFO - build_url:https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:45:18 INFO - Running post-action listener: _resource_record_post_action 08:45:18 INFO - Running post-action listener: set_extra_try_arguments 08:45:18 INFO - ##### 08:45:18 INFO - ##### Finished download-and-extract step (success) 08:45:18 INFO - ##### 08:45:18 INFO - ##### 08:45:18 INFO - ##### Running populate-webroot step. 08:45:18 INFO - ##### 08:45:18 INFO - Running pre-action listener: _resource_record_pre_action 08:45:18 INFO - Running main action method: populate_webroot 08:45:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:45:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:45:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:45:18 INFO - '--tppagecycles', 08:45:18 INFO - '7'], 08:45:18 INFO - 'tests': ['tsvgm']}}, 08:45:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:45:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:45:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:45:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:45:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:45:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:45:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['damp', 'tps']}, 08:45:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'tests': ['damp', 'tps']}, 08:45:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['dromaeo_dom']}, 08:45:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:45:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'other-e10s': {'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['a11yr', 08:45:18 INFO - 'ts_paint', 08:45:18 INFO - 'tpaint', 08:45:18 INFO - 'sessionrestore', 08:45:18 INFO - 'sessionrestore_no_auto_restore', 08:45:18 INFO - 'tabpaint']}, 08:45:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['tsvgx', 08:45:18 INFO - 'tsvgr_opacity', 08:45:18 INFO - 'tart', 08:45:18 INFO - 'tscrollx', 08:45:18 INFO - 'cart']}, 08:45:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:45:18 INFO - 'tsvgr_opacity', 08:45:18 INFO - 'tart', 08:45:18 INFO - 'tscrollx', 08:45:18 INFO - 'cart']}, 08:45:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'talos_options': ['--disable-e10s'], 08:45:18 INFO - 'tests': ['tp5o']}, 08:45:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'tests': ['tp5o']}, 08:45:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'talos_options': ['--disable-e10s', 08:45:18 INFO - '--xperf_path', 08:45:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:45:18 INFO - 'tests': ['tp5n']}, 08:45:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:45:18 INFO - 'talos_options': ['--xperf_path', 08:45:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:45:18 INFO - 'tests': ['tp5n']}}, 08:45:18 INFO - 'talos.zip': {'path': '', 08:45:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:45:18 INFO - Running post-action listener: _resource_record_post_action 08:45:18 INFO - ##### 08:45:18 INFO - ##### Finished populate-webroot step (success) 08:45:18 INFO - ##### 08:45:18 INFO - ##### 08:45:18 INFO - ##### Running create-virtualenv step. 08:45:18 INFO - ##### 08:45:18 INFO - Running pre-action listener: _resource_record_pre_action 08:45:18 INFO - Running main action method: create_virtualenv 08:45:18 INFO - Creating virtualenv /builds/slave/test/build/venv 08:45:18 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 08:45:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:45:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:45:19 INFO - Using real prefix '/usr' 08:45:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:45:19 INFO - Installing distribute.............................................................................................................................................................................................done. 08:45:21 INFO - Installing pip.................done. 08:45:21 INFO - Return code: 0 08:45:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45: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', 'pip>=1.5'] in /builds/slave/test/build 08:45: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 pip>=1.5 08:45:21 INFO - Using env: {'DISPLAY': ':0', 08:45:21 INFO - 'HOME': '/home/cltbld', 08:45:21 INFO - 'LANG': 'en_US.UTF-8', 08:45:21 INFO - 'LANGUAGE': 'en_US:en', 08:45:21 INFO - 'LOGNAME': 'cltbld', 08:45:21 INFO - 'MAIL': '/var/mail/cltbld', 08:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:21 INFO - 'MOZ_NO_REMOTE': '1', 08:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:21 INFO - 'NO_EM_RESTART': '1', 08:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:21 INFO - 'PWD': '/builds/slave/test', 08:45:21 INFO - 'SHELL': '/bin/bash', 08:45:21 INFO - 'SHLVL': '1', 08:45:21 INFO - 'TERM': 'linux', 08:45:21 INFO - 'TMOUT': '86400', 08:45:21 INFO - 'USER': 'cltbld', 08:45:21 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:21 INFO - '_': '/tools/buildbot/bin/python'} 08:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:21 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 08:45:21 INFO - Cleaning up... 08:45:21 INFO - Return code: 0 08:45:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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', '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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:45: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 psutil>=3.1.1 08:45:21 INFO - Using env: {'DISPLAY': ':0', 08:45:21 INFO - 'HOME': '/home/cltbld', 08:45:21 INFO - 'LANG': 'en_US.UTF-8', 08:45:21 INFO - 'LANGUAGE': 'en_US:en', 08:45:21 INFO - 'LOGNAME': 'cltbld', 08:45:21 INFO - 'MAIL': '/var/mail/cltbld', 08:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:21 INFO - 'MOZ_NO_REMOTE': '1', 08:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:21 INFO - 'NO_EM_RESTART': '1', 08:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:21 INFO - 'PWD': '/builds/slave/test', 08:45:21 INFO - 'SHELL': '/bin/bash', 08:45:21 INFO - 'SHLVL': '1', 08:45:21 INFO - 'TERM': 'linux', 08:45:21 INFO - 'TMOUT': '86400', 08:45:21 INFO - 'USER': 'cltbld', 08:45:21 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:21 INFO - '_': '/tools/buildbot/bin/python'} 08:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:21 INFO - Downloading/unpacking psutil>=3.1.1 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:45:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:23 INFO - Installing collected packages: psutil 08:45:23 INFO - Running setup.py install for psutil 08:45:24 INFO - building 'psutil._psutil_linux' extension 08:45:24 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 08:45:24 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 08:45:24 INFO - building 'psutil._psutil_posix' extension 08:45:24 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 08:45:24 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 08:45:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:24 INFO - Successfully installed psutil 08:45:24 INFO - Cleaning up... 08:45:24 INFO - Return code: 0 08:45:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:45:24 INFO - Using env: {'DISPLAY': ':0', 08:45:24 INFO - 'HOME': '/home/cltbld', 08:45:24 INFO - 'LANG': 'en_US.UTF-8', 08:45:24 INFO - 'LANGUAGE': 'en_US:en', 08:45:24 INFO - 'LOGNAME': 'cltbld', 08:45:24 INFO - 'MAIL': '/var/mail/cltbld', 08:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:24 INFO - 'MOZ_NO_REMOTE': '1', 08:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:24 INFO - 'NO_EM_RESTART': '1', 08:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:24 INFO - 'PWD': '/builds/slave/test', 08:45:24 INFO - 'SHELL': '/bin/bash', 08:45:24 INFO - 'SHLVL': '1', 08:45:24 INFO - 'TERM': 'linux', 08:45:24 INFO - 'TMOUT': '86400', 08:45:24 INFO - 'USER': 'cltbld', 08:45:24 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:24 INFO - '_': '/tools/buildbot/bin/python'} 08:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:45:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:45:26 INFO - Installing collected packages: mozsystemmonitor 08:45:26 INFO - Running setup.py install for mozsystemmonitor 08:45:26 INFO - Successfully installed mozsystemmonitor 08:45:26 INFO - Cleaning up... 08:45:26 INFO - Return code: 0 08:45:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:45:26 INFO - Using env: {'DISPLAY': ':0', 08:45:26 INFO - 'HOME': '/home/cltbld', 08:45:26 INFO - 'LANG': 'en_US.UTF-8', 08:45:26 INFO - 'LANGUAGE': 'en_US:en', 08:45:26 INFO - 'LOGNAME': 'cltbld', 08:45:26 INFO - 'MAIL': '/var/mail/cltbld', 08:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:26 INFO - 'MOZ_NO_REMOTE': '1', 08:45:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:26 INFO - 'NO_EM_RESTART': '1', 08:45:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:26 INFO - 'PWD': '/builds/slave/test', 08:45:26 INFO - 'SHELL': '/bin/bash', 08:45:26 INFO - 'SHLVL': '1', 08:45:26 INFO - 'TERM': 'linux', 08:45:26 INFO - 'TMOUT': '86400', 08:45:26 INFO - 'USER': 'cltbld', 08:45:26 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:26 INFO - '_': '/tools/buildbot/bin/python'} 08:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:27 INFO - Downloading/unpacking jsonschema==2.5.1 08:45:27 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 08:45:27 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 08:45:27 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 08:45:27 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 08:45:27 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 08:45:27 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 08:45:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:29 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:29 INFO - warning: no files found matching '*.txt' 08:45:29 INFO - no previously-included directories found matching 'build' 08:45:29 INFO - no previously-included directories found matching 'dist' 08:45:29 INFO - no previously-included directories found matching '.git*' 08:45:29 INFO - Installing collected packages: jsonschema, functools32 08:45:29 INFO - Running setup.py install for functools32 08:45:29 INFO - warning: no files found matching '*.txt' 08:45:29 INFO - no previously-included directories found matching 'build' 08:45:29 INFO - no previously-included directories found matching 'dist' 08:45:29 INFO - no previously-included directories found matching '.git*' 08:45:29 INFO - Successfully installed jsonschema functools32 08:45:29 INFO - Cleaning up... 08:45:29 INFO - Return code: 0 08:45:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:29 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:29 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 08:45:29 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 08:45:29 INFO - Using env: {'DISPLAY': ':0', 08:45:29 INFO - 'HOME': '/home/cltbld', 08:45:29 INFO - 'LANG': 'en_US.UTF-8', 08:45:29 INFO - 'LANGUAGE': 'en_US:en', 08:45:29 INFO - 'LOGNAME': 'cltbld', 08:45:29 INFO - 'MAIL': '/var/mail/cltbld', 08:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:29 INFO - 'MOZ_NO_REMOTE': '1', 08:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:29 INFO - 'NO_EM_RESTART': '1', 08:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:29 INFO - 'PWD': '/builds/slave/test', 08:45:29 INFO - 'SHELL': '/bin/bash', 08:45:29 INFO - 'SHLVL': '1', 08:45:29 INFO - 'TERM': 'linux', 08:45:29 INFO - 'TMOUT': '86400', 08:45:29 INFO - 'USER': 'cltbld', 08:45:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:29 INFO - '_': '/tools/buildbot/bin/python'} 08:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:45:29 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 08:45:29 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 08:45:29 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 08:45:29 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 08:45:29 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 08:45:29 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 08:45:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:45:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:45:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:32 INFO - Downloading docopt-0.6.1.tar.gz 08:45:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:45:32 INFO - Installing collected packages: blobuploader, requests, docopt 08:45:32 INFO - Running setup.py install for blobuploader 08:45:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:45:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:45:32 INFO - Running setup.py install for requests 08:45:32 INFO - Running setup.py install for docopt 08:45:32 INFO - Successfully installed blobuploader requests docopt 08:45:32 INFO - Cleaning up... 08:45:32 INFO - Return code: 0 08:45:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:32 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:32 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 08:45:32 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 08:45:32 INFO - Using env: {'DISPLAY': ':0', 08:45:32 INFO - 'HOME': '/home/cltbld', 08:45:32 INFO - 'LANG': 'en_US.UTF-8', 08:45:32 INFO - 'LANGUAGE': 'en_US:en', 08:45:32 INFO - 'LOGNAME': 'cltbld', 08:45:32 INFO - 'MAIL': '/var/mail/cltbld', 08:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:32 INFO - 'MOZ_NO_REMOTE': '1', 08:45:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:32 INFO - 'NO_EM_RESTART': '1', 08:45:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:32 INFO - 'PWD': '/builds/slave/test', 08:45:32 INFO - 'SHELL': '/bin/bash', 08:45:32 INFO - 'SHLVL': '1', 08:45:32 INFO - 'TERM': 'linux', 08:45:32 INFO - 'TMOUT': '86400', 08:45:32 INFO - 'USER': 'cltbld', 08:45:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:32 INFO - '_': '/tools/buildbot/bin/python'} 08:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:33 INFO - Running setup.py (path:/tmp/pip-1WOjbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:33 INFO - Running setup.py (path:/tmp/pip-MMzNKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:33 INFO - Running setup.py (path:/tmp/pip-s0ELhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:33 INFO - Running setup.py (path:/tmp/pip-Nt1SDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:33 INFO - Running setup.py (path:/tmp/pip-ykhIXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:33 INFO - Running setup.py (path:/tmp/pip-doBDgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:33 INFO - Running setup.py (path:/tmp/pip-3GVdLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:33 INFO - Running setup.py (path:/tmp/pip-G_JFT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:33 INFO - Running setup.py (path:/tmp/pip-JPKdAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:33 INFO - Running setup.py (path:/tmp/pip-mBhh8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:33 INFO - Running setup.py (path:/tmp/pip-iEstGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:33 INFO - Running setup.py (path:/tmp/pip-InfQTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:33 INFO - Running setup.py (path:/tmp/pip-TsC4DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:34 INFO - Running setup.py (path:/tmp/pip-QEcQHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:34 INFO - Running setup.py (path:/tmp/pip-c9ip0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:34 INFO - Running setup.py (path:/tmp/pip-4l53wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:34 INFO - Running setup.py (path:/tmp/pip-hTroPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:34 INFO - Running setup.py install for manifestparser 08:45:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:34 INFO - Running setup.py install for mozcrash 08:45:34 INFO - Running setup.py install for mozdebug 08:45:34 INFO - Running setup.py install for mozdevice 08:45:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:34 INFO - Running setup.py install for mozfile 08:45:34 INFO - Running setup.py install for mozhttpd 08:45:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:34 INFO - Running setup.py install for mozinfo 08:45:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for mozInstall 08:45:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for mozleak 08:45:35 INFO - Running setup.py install for mozlog 08:45:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for moznetwork 08:45:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for mozprocess 08:45:35 INFO - Running setup.py install for mozprofile 08:45:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for mozrunner 08:45:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:35 INFO - Running setup.py install for mozscreenshot 08:45:35 INFO - Running setup.py install for moztest 08:45:36 INFO - Running setup.py install for mozversion 08:45:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:36 INFO - Cleaning up... 08:45:36 INFO - Return code: 0 08:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:36 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:36 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 08:45:36 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 08:45:36 INFO - Using env: {'DISPLAY': ':0', 08:45:36 INFO - 'HOME': '/home/cltbld', 08:45:36 INFO - 'LANG': 'en_US.UTF-8', 08:45:36 INFO - 'LANGUAGE': 'en_US:en', 08:45:36 INFO - 'LOGNAME': 'cltbld', 08:45:36 INFO - 'MAIL': '/var/mail/cltbld', 08:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:36 INFO - 'MOZ_NO_REMOTE': '1', 08:45:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:36 INFO - 'NO_EM_RESTART': '1', 08:45:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:36 INFO - 'PWD': '/builds/slave/test', 08:45:36 INFO - 'SHELL': '/bin/bash', 08:45:36 INFO - 'SHLVL': '1', 08:45:36 INFO - 'TERM': 'linux', 08:45:36 INFO - 'TMOUT': '86400', 08:45:36 INFO - 'USER': 'cltbld', 08:45:36 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:36 INFO - '_': '/tools/buildbot/bin/python'} 08:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:36 INFO - Running setup.py (path:/tmp/pip-ym1XnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:36 INFO - Running setup.py (path:/tmp/pip-vReHub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:36 INFO - Running setup.py (path:/tmp/pip-UGIooz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:36 INFO - Running setup.py (path:/tmp/pip-hlWaDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:36 INFO - Running setup.py (path:/tmp/pip-onJT3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:36 INFO - Running setup.py (path:/tmp/pip-viqnSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:36 INFO - Running setup.py (path:/tmp/pip-XQemLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:36 INFO - Running setup.py (path:/tmp/pip-CxLjtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:36 INFO - Running setup.py (path:/tmp/pip-tzUdrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:36 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)) 08:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:37 INFO - Running setup.py (path:/tmp/pip-dQCvbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:37 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:37 INFO - Running setup.py (path:/tmp/pip-tjCoWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:37 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:37 INFO - Running setup.py (path:/tmp/pip-HqxVJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:37 INFO - Running setup.py (path:/tmp/pip-YygRxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:37 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:37 INFO - Running setup.py (path:/tmp/pip-fhkeK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:37 INFO - Running setup.py (path:/tmp/pip-w5IxxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:37 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:37 INFO - Running setup.py (path:/tmp/pip-Azr7GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:37 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)) 08:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:37 INFO - Running setup.py (path:/tmp/pip-mWRYSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:37 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)) 08:45:37 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)) 08:45:37 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)) 08:45:37 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)) 08:45:37 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)) 08:45:37 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)) 08:45:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:37 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 08:45:37 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 08:45:37 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 08:45:37 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 08:45:37 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 08:45:37 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 08:45:39 INFO - Downloading blessings-1.6.tar.gz 08:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:39 INFO - Installing collected packages: blessings 08:45:39 INFO - Running setup.py install for blessings 08:45:39 INFO - Successfully installed blessings 08:45:39 INFO - Cleaning up... 08:45:39 INFO - Return code: 0 08:45:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:39 INFO - Reading from file tmpfile_stdout 08:45:39 INFO - Current package versions: 08:45:39 INFO - argparse == 1.2.1 08:45:39 INFO - blessings == 1.6 08:45:39 INFO - blobuploader == 1.2.4 08:45:39 INFO - docopt == 0.6.1 08:45:39 INFO - functools32 == 3.2.3-2 08:45:39 INFO - jsonschema == 2.5.1 08:45:39 INFO - manifestparser == 1.1 08:45:39 INFO - mozInstall == 1.12 08:45:39 INFO - mozcrash == 0.17 08:45:39 INFO - mozdebug == 0.1 08:45:39 INFO - mozdevice == 0.48 08:45:39 INFO - mozfile == 1.2 08:45:39 INFO - mozhttpd == 0.7 08:45:39 INFO - mozinfo == 0.9 08:45:39 INFO - mozleak == 0.1 08:45:39 INFO - mozlog == 3.2 08:45:39 INFO - moznetwork == 0.27 08:45:39 INFO - mozprocess == 0.23 08:45:39 INFO - mozprofile == 0.28 08:45:39 INFO - mozrunner == 6.12 08:45:39 INFO - mozscreenshot == 0.1 08:45:39 INFO - mozsystemmonitor == 0.3 08:45:39 INFO - moztest == 0.7 08:45:39 INFO - mozversion == 1.4 08:45:39 INFO - psutil == 3.1.1 08:45:39 INFO - requests == 1.2.3 08:45:39 INFO - wsgiref == 0.1.2 08:45:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:39 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:39 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 08:45:39 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 08:45:39 INFO - Using env: {'DISPLAY': ':0', 08:45:39 INFO - 'HOME': '/home/cltbld', 08:45:39 INFO - 'LANG': 'en_US.UTF-8', 08:45:39 INFO - 'LANGUAGE': 'en_US:en', 08:45:39 INFO - 'LOGNAME': 'cltbld', 08:45:39 INFO - 'MAIL': '/var/mail/cltbld', 08:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:39 INFO - 'MOZ_NO_REMOTE': '1', 08:45:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:39 INFO - 'NO_EM_RESTART': '1', 08:45:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:39 INFO - 'PWD': '/builds/slave/test', 08:45:39 INFO - 'SHELL': '/bin/bash', 08:45:39 INFO - 'SHLVL': '1', 08:45:39 INFO - 'TERM': 'linux', 08:45:39 INFO - 'TMOUT': '86400', 08:45:39 INFO - 'USER': 'cltbld', 08:45:39 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:39 INFO - '_': '/tools/buildbot/bin/python'} 08:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 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)) 08:45:40 INFO - Cleaning up... 08:45:40 INFO - Return code: 0 08:45:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:40 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:40 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 08:45:40 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 08:45:40 INFO - Using env: {'DISPLAY': ':0', 08:45:40 INFO - 'HOME': '/home/cltbld', 08:45:40 INFO - 'LANG': 'en_US.UTF-8', 08:45:40 INFO - 'LANGUAGE': 'en_US:en', 08:45:40 INFO - 'LOGNAME': 'cltbld', 08:45:40 INFO - 'MAIL': '/var/mail/cltbld', 08:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:40 INFO - 'MOZ_NO_REMOTE': '1', 08:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:40 INFO - 'NO_EM_RESTART': '1', 08:45:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:40 INFO - 'PWD': '/builds/slave/test', 08:45:40 INFO - 'SHELL': '/bin/bash', 08:45:40 INFO - 'SHLVL': '1', 08:45:40 INFO - 'TERM': 'linux', 08:45:40 INFO - 'TMOUT': '86400', 08:45:40 INFO - 'USER': 'cltbld', 08:45:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:40 INFO - '_': '/tools/buildbot/bin/python'} 08:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:40 INFO - Cleaning up... 08:45:40 INFO - Return code: 0 08:45:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:40 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 0x7f171741ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', '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 08:45:40 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 08:45:40 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 08:45:40 INFO - Using env: {'DISPLAY': ':0', 08:45:40 INFO - 'HOME': '/home/cltbld', 08:45:40 INFO - 'LANG': 'en_US.UTF-8', 08:45:40 INFO - 'LANGUAGE': 'en_US:en', 08:45:40 INFO - 'LOGNAME': 'cltbld', 08:45:40 INFO - 'MAIL': '/var/mail/cltbld', 08:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:40 INFO - 'MOZ_NO_REMOTE': '1', 08:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:40 INFO - 'NO_EM_RESTART': '1', 08:45:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:40 INFO - 'PWD': '/builds/slave/test', 08:45:40 INFO - 'SHELL': '/bin/bash', 08:45:40 INFO - 'SHLVL': '1', 08:45:40 INFO - 'TERM': 'linux', 08:45:40 INFO - 'TMOUT': '86400', 08:45:40 INFO - 'USER': 'cltbld', 08:45:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:40 INFO - '_': '/tools/buildbot/bin/python'} 08:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:40 INFO - Downloading/unpacking flake8 08:45:40 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 08:45:40 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 08:45:40 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 08:45:40 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 08:45:40 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 08:45:40 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 08:45:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:45:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:45:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:42 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 08:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:45:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:43 INFO - no previously-included directories found matching 'docs/_build' 08:45:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:45:43 INFO - Running setup.py install for pep8 08:45:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:43 INFO - no previously-included directories found matching 'docs/_build' 08:45:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:45:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:45:43 INFO - Cleaning up... 08:45:43 INFO - Return code: 0 08:45:43 INFO - Running post-action listener: _resource_record_post_action 08:45:43 INFO - Running post-action listener: _start_resource_monitoring 08:45:43 INFO - Starting resource monitoring. 08:45:43 INFO - ##### 08:45:43 INFO - ##### Finished create-virtualenv step (success) 08:45:43 INFO - ##### 08:45:43 INFO - ##### 08:45:43 INFO - ##### Running install step. 08:45:43 INFO - ##### 08:45:43 INFO - Running pre-action listener: _resource_record_pre_action 08:45:43 INFO - Running main action method: install 08:45:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:43 INFO - Reading from file tmpfile_stdout 08:45:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:43 INFO - Reading from file tmpfile_stdout 08:45:43 INFO - Output received: 08:45:43 INFO - Usage: mozinstall [options] installer 08:45:43 INFO - Options: 08:45:43 INFO - -h, --help show this help message and exit 08:45:43 INFO - -d DEST, --destination=DEST 08:45:43 INFO - Directory to install application into. [default: 08:45:43 INFO - "/builds/slave/test"] 08:45:43 INFO - --app=APP Application being installed. [default: firefox] 08:45:43 INFO - mkdir: /builds/slave/test/build/application 08:45:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:45:59 INFO - Reading from file tmpfile_stdout 08:45:59 INFO - Output received: 08:45:59 INFO - /builds/slave/test/build/application/firefox/firefox 08:45:59 INFO - Running post-action listener: _resource_record_post_action 08:45:59 INFO - ##### 08:45:59 INFO - ##### Finished install step (success) 08:45:59 INFO - ##### 08:45:59 INFO - ##### 08:45:59 INFO - ##### Running run-tests step. 08:45:59 INFO - ##### 08:45:59 INFO - Running pre-action listener: _resource_record_pre_action 08:45:59 INFO - Running main action method: run_tests 08:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:59 INFO - Python 2.7.3 08:45:59 INFO - Return code: 0 08:45:59 INFO - grabbing minidump binary from tooltool 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:59 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 0x16592e0>, '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 0x15c5140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c55d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:59 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 08:45:59 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 08:45:59 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 08:45:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:59 INFO - Return code: 0 08:45:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:45:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:45:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:46:00 INFO - Return code: 0 08:46:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 08:46:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 08:46:00 INFO - Using env: {'DISPLAY': ':0', 08:46:00 INFO - 'HOME': '/home/cltbld', 08:46:00 INFO - 'LANG': 'en_US.UTF-8', 08:46:00 INFO - 'LANGUAGE': 'en_US:en', 08:46:00 INFO - 'LOGNAME': 'cltbld', 08:46:00 INFO - 'MAIL': '/var/mail/cltbld', 08:46:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:46:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:00 INFO - 'MOZ_NO_REMOTE': '1', 08:46:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:46:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:00 INFO - 'NO_EM_RESTART': '1', 08:46:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:00 INFO - 'PWD': '/builds/slave/test', 08:46:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:46:00 INFO - 'SHELL': '/bin/bash', 08:46:00 INFO - 'SHLVL': '1', 08:46:00 INFO - 'TERM': 'linux', 08:46:00 INFO - 'TMOUT': '86400', 08:46:00 INFO - 'USER': 'cltbld', 08:46:00 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774', 08:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:00 INFO - '_': '/tools/buildbot/bin/python'} 08:46:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 08:46:00 INFO - mozversion application_buildid: 20160730060525 08:46:00 INFO - mozversion application_changeset: fb90485f11a590834aaa80481d61f8a4c11368e2 08:46:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:46:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:46:00 INFO - mozversion application_name: Firefox 08:46:00 INFO - mozversion application_remotingname: firefox-dev 08:46:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:46:00 INFO - mozversion application_vendor: Mozilla 08:46:00 INFO - mozversion application_version: 49.0a2 08:46:00 INFO - mozversion platform_buildid: 20160730060525 08:46:00 INFO - mozversion platform_changeset: fb90485f11a590834aaa80481d61f8a4c11368e2 08:46:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:46:00 INFO - mozversion platform_version: 49.0a2 08:46:00 INFO - using testdate: 1469893560 08:46:00 INFO - actual date: 1469893560 08:46:00 INFO - starting webserver on 'localhost:48148' 08:46:00 INFO - SUITE-START | Running 2 tests 08:46:00 INFO - TEST-START | dromaeo_css 08:46:00 INFO - operating with platform_type : linux_ 08:46:00 INFO - Initialising browser for dromaeo_css test... 08:46:00 INFO - TEST-INFO | started process 26807 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt0wdm0/profile http://localhost:48148/getInfo.html) 08:46:04 INFO - PROCESS | 26807 | __metrics Screen width/height:1600/1200 08:46:04 INFO - PROCESS | 26807 | colorDepth:24 08:46:04 INFO - PROCESS | 26807 | Browser inner width/height: 1024/697 08:46:04 INFO - PROCESS | 26807 | __metrics 08:46:05 INFO - PROCESS | 26807 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:46:05 ERROR - PROCESS | 26807 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:46:06 INFO - TEST-INFO | 26807: exit 0 08:46:06 INFO - Browser initialized. 08:46:06 INFO - Running cycle 1/1 for dromaeo_css test... 08:46:06 INFO - TEST-INFO | started process 26897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt0wdm0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:18 INFO - PROCESS | 26897 | RSS: Main: 145764352 08:46:18 INFO - PROCESS | 26897 | 08:47:09 INFO - PROCESS | 26897 | 1469893629671 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] 08:49:17 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48148/tests/dromaeo/cssquery-ext.html) 08:49:18 INFO - PROCESS | 26897 | RSS: Main: 252600320 08:49:18 INFO - PROCESS | 26897 | 08:52:21 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-ext.html (next: http://localhost:48148/tests/dromaeo/cssquery-jquery.html) 08:52:21 INFO - PROCESS | 26897 | RSS: Main: 223571968 08:52:21 INFO - PROCESS | 26897 | 08:55:23 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48148/tests/dromaeo/cssquery-mootools.html) 08:55:24 INFO - PROCESS | 26897 | RSS: Main: 220016640 08:55:24 INFO - PROCESS | 26897 | 08:58:26 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48148/tests/dromaeo/cssquery-prototype.html) 08:58:26 INFO - PROCESS | 26897 | RSS: Main: 213061632 08:58:26 INFO - PROCESS | 26897 | 09:01:29 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48148/tests/dromaeo/cssquery-yui.html) 09:01:29 INFO - PROCESS | 26897 | RSS: Main: 206557184 09:01:29 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | Cycle 1(1): loaded http://localhost:48148/tests/dromaeo/cssquery-yui.html (next: http://localhost:48148/tests/dromaeo/cssquery-dojo.html) 09:04:32 INFO - PROCESS | 26897 | RSS: Main: 248655872 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | __start_tp_report 09:04:32 INFO - PROCESS | 26897 | _x_x_mozilla_page_load 09:04:32 INFO - PROCESS | 26897 | _x_x_mozilla_page_load_details 09:04:32 INFO - PROCESS | 26897 | |i|pagename|runs| 09:04:32 INFO - PROCESS | 26897 | |0;dojo.html;3266.8856590019345;3296.060622722523;3301.8514166862496;3322.2857085726596;3338.582677165354;5345.465453454653;5427.538659213967;5442.591805614575;5456.454354564542;5458.890822183564;5337.14605663094;5354.357477102743;5394.514493695567;5403.70279634621;5403.891922161554;4795.2087905495555;4876.926846097312;4897.387826521667;4916.311716359694;4960.206366981283;4784.186688263004;4910.2143657014885;4944.60443164546;4951.851444456672;4957.058158949806;4940.357753492059;4949.331840201581;4954.826581069663;4987.625928055392;4992.101421744084;5040.470750571192;5050.191969284897;5064.11378008847;5070.6957582545165;5100.5409513143995;4790.281457781326;4796.400449943757;4823.831165909176;4912.140375434285;4954.554090131869;5174.404943431516;5181.3782346118705;5213.504717051912;5235.554977826851;5240.973795131038;5704.657720536781;5781.884362312772;5783.624064435799;5783.739731712083;5788.42115788422;5742.224799652077;5796.768129274824;5797.855053623651;5804.187413762077;5818.156367326714;5422.53908417781;5465.398806131322;5469.781208751645;5470.370907345627;5474.808381706661;5745.253117094751;5938.6436813791315;5948;5956.344802071769;5957.195778569838;5607.383187849333;5642.576806739511;5663.490285874291;5682.119271512922;5701.942980570141;5743.655380677173;5744.51171650406;5772.913406298909;5775.48020678141;5784.190213370048;5526.806561770319;5622;5762.279715035621;5771.68255745938;5777.162311464814;5659.066254068115;5687.544996400278;5690;5698.401667824819;5727.198192253088;4408.889777755582;4417.315316126005;4419.381286619876;4420.469543654782;4447.066116115588;3917.666998305176;3930.8624198153498;3932.7050471214775;3974.7813870237414;3984.601539845992;5380.112281473591;5391.946080539223;5411.864703382368;5430.8913821723345;5453.072977593819;5725.055365864586;5731.627444216113;5733.455321744427;5736.024875771129;5766.596338256281;5338.359396872323;5443.782248710087;5466.453354664502;5500.312460942382;5524.1989911462615;4983.02830947969;5000.424951130666;5009.799608015712;5014.7994080236385;5037.219231019198;6005.579609427386;6006.729697163638;6022.819315420456;6054.636721796706;6057.091436284504;5610.074337734228;5668.971655141863;5691.914621280767;5706.087026075808;5713.371529131875;5955.851103722442;5960.225170727777;5968.582199246011;5969.985102532667;5971.552133589911;5539.335279766293;5623.775048997994;5623.943760562342;5631.746571404215;5635.633683810539;5615.6630602164005;5660.179274005164;5713.257276554022;5747.712614369349;5747.971260143672;5611.410801865745;5620.381758006698;5622.803201887914;5623.971880140573;5624.437556244343;5481.451854814487;5531.225628412106;5616.522595579258;5627.099664053731;5628;5478.178273259043;5562.610617256915;5568.777248909998;5573.860653483695;5589.077802162597;5644.463775941279;5658.49073583363;5662.85842853932;5693.345265294379;5718.656880587178;5407.188921661625;5466.80866169698;5496.1755736639825;5594.244776955059;5618.157276408571 09:04:32 INFO - PROCESS | 26897 | |1;ext.html;595.6902410746412;633.9017452294894;634.6287421858215;648.8280605639503;659.2451642868912;31865.203369915766;34931.47602785957;35133.82433087837;44524;45499;46221.76889115558;46731.29903051457;46814.06371872569;46875;46970.53029469713;9052.45685258885;9179.816403671923;9181.862272065924;9185.908140918606;9197.95401022994;8306.584670766468;10675.092617127553;10940.726481837939;10952.397618130999;11261.774764504706;11041.779164416706;11120.499577518991;11127;11138.610148644799;11159.832602510969;8377.6230069647;10838.783224335548;10884.401357925311;10952.397618130999;10958.99999999996;10588;10672.893271067267;10923.289986150954;10994.615188468366;11002.889971100347;44114;44636.55363446389;44750.55249447496;44790.77604611956;44898.55101448976;3732.5147730794824;3816.5229346331707;4013.418054382098;4048.67610591152;4295.570442955679;33616;34005.82997084999;34029.82985085084;34106.48840267398;35663.10842228939;4594.540545945379;4605.30920361945;4611.123886461562;4697.4832768395445;4780.617550595944;350793;352232;353313;354565;354921;38055.619443805204;39386.80306598449;69789;70641;70746.29253707397;40707.592924070974;40747.59252407497;40788.18423631511;40979.385309220386;41101.58898411037;17676.558086047953;17685.55786105332;17754;17893.37373191932;17900.731489027676;13193.538226162038;14244.715105697827;14534.709305814036;14982.925085374503;15068.54794356171;9141;9213.631454741735;9273.119053689888;9274.860877086849;9360.90639093614;3532;3592.550931133608;3636.472711456824;3664.04734768962;3664.157243833933;1111.9777604447866;1133.880942501042;1150.3040660400504;1180.911431642613;1243.583399561139;33002;33405.16487087794;33443.33113337768;33704.831475842955;34402.48396274058;1148.833419154218;1153.9538418463335;1154.8267759836092;1191.773563023023;1258.2950102611014;15088.698226035638;17097.829021709622;17475.57422858149;17783.911080444774;17915.283388664306;536505;537132;538127;538929;539064;59216.70391648014;59360.40639593635;59368;60057;60308;1397.5178563395816;1460.8685218330188;1482.9406823727145;1517.719221943944;1529.8546638069365;31839.36321273608;32825.83587082049;32906;33593.83203084067;33760.15599610029;49280.507194927595;49533.50466495289;49616.503834961186;49789.50210497993;49800;400654;405692;405826;407819;407906;25694.486110278063;46425.76787116043;46593.301100482844;46944.29583556181;47144.29283560681;47036.52963470321;47141.52858471371;47524;51838.481615183366;52095.218571720696;43608.78195609002;43929.78035109804;44336.11327773362;44551;44880.551194488944;42612;42697.57302426936;42700.359494606986;43710.56289437065;44135.55864441315;43046.78476607597;43520.34719479274;43873.78063109664;44111;44569.33146002878;38184.42723359096;38823;39424.40863387109;39452.01369965659;39734;29157.7084229155;29333.7066629331;29414.411711765217;29549;29655.555166672948 09:04:32 INFO - PROCESS | 26897 | |2;jquery.html;424.53513402823904;426.4818245831314;432.16591977483444;438.6885311428885;438.9978050109749;18465.353712620043;19363.225470981142;20031.198752049917;20575.794242057575;20777.376678699624;18810.435686929413;19224.711629325535;19330.51673708158;20122.89938550309;20213.797862021376;5607.355154157263;5785;5791.623544469617;5820.476157145856;5826.504747096502;4177.707560470768;4314.395984562164;5737.913931291034;5807;6447.387498187664;6541.607503549779;6560.376764207416;6563.901541476882;6567.244766851802;6570.507211959122;6262.937370626281;6494.5778524396;6500.479961603108;6507.284198738135;6528.543001989862;3892.2020985697864;4074.9592504074876;5300.416954135042;5732.856678583069;5761.625494342897;21744.673829892407;22931.656025159635;23347.182848600387;23907.40231494209;24054.398640033964;2362.137819695816;2419.2379400488967;2450.6324051392257;2725.536658768014;2809.8454584997817;14628.488002919847;14727.852721472862;14849.406023759037;14869.182194979272;14884.479043233436;2034.6439373109795;2036.1244664794133;2056.856020078595;2111.524906895936;2172.2071443923014;63061.68469157671;65743.67128164376;69227.30772692259;76788.99999999945;81675;11040.503177357039;11516.769664606662;11566.42167891602;11640;11725.648230553097;16430.75353869693;16441.91779041097;16525.504234872973;16527.421540246276;16532.75200871988;8722.25860801839;8930.598123084477;8942.68700595476;9015.233705135137;9042.18620324174;8333.541655209021;8375.246227839523;8509.27671147947;8582.613782379807;8832.77918052041;4964.776585053682;5027.874303142377;5049.26785616091;5081.390233172043;5142.845714628567;2010.326540608883;2013.3456626596414;2094.549671820566;2102.5111387188463;2119.660854263341;478.16798770139707;480.56508859482227;974.7027156717128;1024.4365598920565;1125.6566747142037;11546.307221566565;12218.633440996784;12341.197822141532;12446.128770986124;12609.873901261053;481.4222932481037;487.05025200858466;488;497.0655168283604;504.13037510294447;6641.369069938348;6658.667066646648;6698.330166983263;6703.966480167665;6714.664266786739;531138;532782;536765;545825;549495;95358.5232073849;96198;97521.51239243758;98060;98085;500.5170010939461;506.94677058908286;507.2036902063798;509.788437798317;509.9056674515227;11312.773744525228;16633;17524.386646467312;17557.56106097307;18871.622567548846;37796.24407511889;41778.79110604428;43670.781646091564;44271.33592996172;44691.55308447004;54268;59155;59361.703191483764;60768.39231607627;60870.69564652148;7731.497452665559;7740.458167928191;7914.525128492309;8338.666453341799;8483.10927352619;8821.382503224713;11348.773024539629;12732.045096617605;12863.099583029472;13201.60395188146;11186.83219751688;11456.140789440658;11632.767344653228;11910.880891191324;11968.880311196777;11113.833292500458;11172.664820055412;11349.262297950938;11563.710907227387;11591.6522504325;11624.476898539417;11716.94141529287;11927.76144477123;11964.700882478008;12127.211731237794;7448.366888814511;7823.413244006609;7880.56656883854;8584.570771461527;8768.430052046597;8330.333573314241;10483.633072842415;11065.446727663746;11100.833487497532;11173.715022772485 09:04:32 INFO - PROCESS | 26897 | |3;mootools.html;1172.8885755853191;1596.329541592531;1598.2967494302502;1810.9456716298507;1891.7866096666899;7972.800679982996;8869;9145.542722863864;9146.268298536117;9367.203787678056;8992.41549299295;9121.224695900857;9139.360244782867;9143.177114059734;9145.222656074242;2073.0463986566124;2118.661014237722;2131.9680204796864;2403.2549909528016;2436.5857804173334;2374.608189648706;2449.155041510676;2456.5578195924727;2459.4712136890566;2465;2321.129576408847;2323.6282194848827;2326.150954901466;2331.3821837213154;2375.2636682628354;2047.6518991771434;2116.153538584563;2431.63525471179;2469.1111199968086;2470.0984140788573;2110.757262914769;2323.2913961241816;2381.357033600918;2392.7487613800477;2408.4580969281947;9020.18818306349;9063.59213835379;9068.591913363915;9074.410163339362;9075.773105672344;5260.447652996419;5328.706921119337;5331.813386531491;5336.38631557372;5337.839864804062;3262.804231746079;3329.8335083245743;3385.086026772782;3473.7915725056578;3474.270403215328;1159.4898244772357;1165.4755360087995;1193.6598069550223;1198.19720787072;1228.7542491501647;12572.874271257262;12589;12602.180858244184;12611.306378149106;12630.621081367573;4724.495308712941;4777.1878780606685;4809.711417314902;4821.734804585782;4890.168671325812;10279.537420816081;10380;10383.740406489898;10391;10428.322159059637;5325.4940780625775;5330.440303768049;5330.786768529293;5337.2794672719465;5385.030694475032;4404.141192467502;4532.524084971031;4905.828296009622;5039.899202015939;5114.207297868837;4145.129522800185;4160.9375859362135;4173.728707633994;4185.037441388497;4201.705880588391;1323.4639970811836;1327.1904138475522;1336.8195293635429;1339.3102552185633;1341.081359268904;1059.2691043180334;1061.6549621373085;1062.9627963021258;1068.423051552153;1075.8117329467311;6199.845003874939;6293.118963345135;6316.610345723933;6392.680365981682;6397.296297407282;485.0614061790447;490.1275729202026;493.11485882839673;496.5779087775434;498.429298453269;4310.52584215736;4403.933940990888;4405.339199120158;4489.169503641837;4558.361829343895;11207.887921120684;11562.364069976233;11623.186376953701;11633;11652.592159274385;11023.228374013903;11525.538978440936;11540.653780386434;11552.422378881023;11553.768924621461;8618.741437756877;8711.390202685878;8810.163034511712;8810.64757409722;8817.29461643067;6290.591111577916;6478.1578394808375;6512.544121911421;6530.183727034121;6610.305917878746;10610.8408373876;10674.73313167177;10711.464426778786;11016.228863979444;11022.669319920415;10752.623658171926;11006.559737610405;11008.064314533034;11073.723156921142;11085;7236.710531578678;8112.797180070545;8281;8342.624581893951;8396.454230475;9170;9184.954075229849;9197.448153110834;9213.355065145644;9244.861327079965;7973.083095444098;8166.550839703873;8302.626381812954;8317.95841020791;8474.491530508189;7942;7992;8042.3968202383885;8077.232662897015;8108.716194933385;7469.141048779243;7510.136334321404;7521.548707077593;7548.773536793905;7604;5526.91709624364;5559.916601251065;5585.2180694701965;5665.603407761417;5692;5481.972590137024;5511.090670039399;5624.381318055092;5655.179998900218;5760.279965004374 09:04:32 INFO - PROCESS | 26897 | |4;prototype.html;340.91136304560814;343.1181862613084;354.3250108543226;361.23959066165736;362.35139101009196;5443.646162999402;5477.917831232529;5499.285092937917;5500.6424582402215;5533.391326954032;5083.847484575459;5184;5253.6322457428;5281.551068159201;5442.700651464178;4129.648979836717;4181.996320882989;4283.207606592775;4305.655547556188;4338.8047537860875;4234.470691163605;4277.957220427803;4285.400043993844;4293.076988447483;4324.091940692442;4121.649659778923;4270.231358355495;4271.295236286009;4290.442242508485;4292.699511034229;4041.3937909313545;4146.502419709624;4217.36739489076;4228.830846766125;4231.259529582311;3767.8869633911027;3771.679407250387;3773.2076263984595;3782.394816829322;3805.162864169878;5170.138750049979;5189.66267192635;5189.792408303663;5199.934013527218;5218.6953261684575;4722.598579120779;4833.806647734086;4852.0591763294615;4875.171220892456;4885.828995985159;5557.666540007613;5594.860128496779;5621.763212093374;5647.943520564783;5673.517750991171;4167.478870212381;4193.693468592262;4197.727147735387;4206.455094667713;4227.957720422787;5625.353084395306;5827.825165245049;5892.646441213498;5929.644221346733;6001.549883758736;4249.38383934334;4265.232258193555;4277.764722940268;4281.957180428156;4311.892202694958;4343.218220720238;4349.891252718708;4354.238008348526;4359.564043595615;4363.498197707304;4623.791929363187;4792.226876055812;4816.54242846929;4878.975605122023;4880.829170978998;5673.489385955201;5693.60144789869;5694.6583205007;5719.914201286984;5723.914141287884;1448.891333150018;1455.3305479479345;1455.745244582215;1455.8689717925438;1475.5425817996456;895.6193617712446;929.5120061967386;931.5575101826713;933.4912472702393;935.4013431403907;4967.751612419364;4971;5000.024990128879;5065.442801291855;5093.159628661229;5111.386633603917;5156.458571849977;5213.765380557808;5250.238715386247;5289.947100528945;5024.346834911511;5040.722760248222;5114.795408183631;5401.270828438189;5614.838287259435;3119.5008798592116;3135.435621588136;3140.3885531638343;3142.71715545602;3146.3235404388165;6101.908471372933;6114.38856114385;6116.48009919153;6117.174181485531;6162.414570615784;5623.6906970116215;6028.336882942873;6050.183225264621;6095.542834287447;6097.603655762361;6159.6140868305165;6172.9074063889075;6209.298954954609;6219.96890015538;6237.594556353823;5664.801731939362;5980.252468441445;5988.790392336247;5994.340622531431;6015.308239552507;5840.386759390372;5870.882582348415;5871.647701137775;5895.94104058954;5909.970450147722;5913.526917846648;6177.320494745664;6187.381261873777;6205.045410695617;6211.9068213975925;4165.4584903962295;4190.371444283382;4193.517745459311;4194.5948107384365;4216.388623649493;5928.970355148197;5943.554233432424;5944.702764861653;5960.731767070405;6002.189704389852;5631.943680563142;5739.139129130664;5786.363500015079;5829.970850145722;5832.475077243069;5738.167965644875;5739.8852022960145;5740.512056475246;5752.482276595127;5765.91351129725;5600.607957443103;5638.577106716931;5656.2929633795775;5669.837683275001;5689.601727879174;5537.355173791386;5726.599138060463;5775.075987841925;5778.642019125418;5785.392533783892;5680.460356266148;5685.829425117253;5688.288963879515;5696.857578560403;5759.39526349744 09:04:32 INFO - PROCESS | 26897 | |5;yui.html;831.3349320543564;852.2883392367373;854.1117238072404;865.5369377383101;889.4263200235848;14685.853141468582;15316.234188290582;15350.53948381549;15358.23208839559;15390.30743616537;14498.927505362459;14593.124412535255;14611.415543378278;14617.78073328901;14628.049176803526;636.5003472274266;793.2067932067932;821.5481485183155;840.6805413942693;849.2441726863096;776.9572673502955;784.654751909161;788.3771820262015;791.0270367448043;791.1020991175029;805.1666525146475;814.7189219719218;814.9796255093611;815.1196707555855;924.0943875002501;683.1938312790904;892.9284858170189;926.4487629860222;927.4574373991223;935.7052528453515;612.0543759890979;612.0696541257264;612.3723183736705;612.6018088242633;612.681405669054;15482.922585387114;15604.219789010504;15609.843901561066;15620.375184992587;15638.609034774108;14489.927550362181;14787;14787.11277323364;14879.404823807035;15105.773413398807;13378.13042152257;13671;13760.65598360039;13809.378577964017;14182.503612373517;4646.558576935186;4684.5549672781035;4767.451743049559;4791.448983366888;4796.904061918777;74079.25920740831;75047.62476187584;75099.62450187768;75151;75610.6219468899;12057.397130143458;12166.391680415943;12240.816387754012;12275.631731048083;12680.112392132647;21372.893135534534;21374.251901183383;21409.678854816877;21415.250466233607;21422.892885535784;8288.58557072144;8602.91397086021;8605.225529702357;8630.956845215735;8641.956790216134;4678.391809064799;4803.231482962709;4807.495213002654;4809.0862736080035;4864.78108485119;3489.1451594359546;3578.033930838711;3596.1189508570046;3602.2075143468396;3603.747737658339;622.3061286665336;625.1903784598956;629.6285191736897;631.8831016262006;636.9522285828581;642.9196350456193;670.7283550162192;723.5333210079574;757.5341165183372;765.2997507280887;9621.374610650286;9724.659636912816;9737.172340351008;9813.264005199495;9906.653267135616;675.6824292582478;682.2698822342844;724.0840336973732;726.7746998430504;760.7831767946109;4856.68431551948;4934.309196712463;5138.768755406016;5153.330067091254;5274.947250527523;80779;94171;118661;123638;128657.35671321584;98790;102801;105607.4719626397;106391.46804266084;109886;91866.54066729624;98327;98996;99767;100870;9285.35002549815;9533.332666713264;9603.087706667855;9625.229981601595;9678.70963871085;45169;45788;48366.516334836204;51531.48468515267;56913;67235.3276467229;68212.3178768206;68682;72872;73289.326192552;13282.734345313233;13840.653983650489;14040.227787471787;14172.078814877;14256.857431425968;11353;15797.68404631878;16339.183040847673;16388.426405075767;16508.174591270148;10082.29423940317;13267.336633168496;13276.800847987095;13413.061085723906;14095.154290742588;14748.360383082016;14762;15095.698086038437;15209.847901521285;15518.146501942501;14281.357338919566;14301.92849035748;14438;14538.345774439964;15013.399464021317;11551.768964620493;11558.884411156117;11709;11770.17608767378;12095.516179353079;11716.228076699925;11811.763764724828;11846.526138954345;12090.395480225778;12164.878351216375 09:04:32 INFO - PROCESS | 26897 | __end_tp_report 09:04:32 INFO - PROCESS | 26897 | __start_cc_report 09:04:32 INFO - PROCESS | 26897 | _x_x_mozilla_cycle_collect,909 09:04:32 INFO - PROCESS | 26897 | __end_cc_report 09:04:32 INFO - PROCESS | 26897 | __startTimestamp1469894672595__endTimestamp 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | ------- Summary: start ------- 09:04:32 INFO - PROCESS | 26897 | Number of tests: 6 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#0] dojo.html Cycles:165 Average:5353.11 Median:5539.34 stddev:576.20 (10.4%) stddev-sans-first:554.24 09:04:32 INFO - PROCESS | 26897 | Values: 3266.9 3296.1 3301.9 3322.3 3338.6 5345.5 5427.5 5442.6 5456.5 5458.9 5337.1 5354.4 5394.5 5403.7 5403.9 4795.2 4876.9 4897.4 4916.3 4960.2 4784.2 4910.2 4944.6 4951.9 4957.1 4940.4 4949.3 4954.8 4987.6 4992.1 5040.5 5050.2 5064.1 5070.7 5100.5 4790.3 4796.4 4823.8 4912.1 4954.6 5174.4 5181.4 5213.5 5235.6 5241.0 5704.7 5781.9 5783.6 5783.7 5788.4 5742.2 5796.8 5797.9 5804.2 5818.2 5422.5 5465.4 5469.8 5470.4 5474.8 5745.3 5938.6 5948.0 5956.3 5957.2 5607.4 5642.6 5663.5 5682.1 5701.9 5743.7 5744.5 5772.9 5775.5 5784.2 5526.8 5622.0 5762.3 5771.7 5777.2 5659.1 5687.5 5690.0 5698.4 5727.2 4408.9 4417.3 4419.4 4420.5 4447.1 3917.7 3930.9 3932.7 3974.8 3984.6 5380.1 5391.9 5411.9 5430.9 5453.1 5725.1 5731.6 5733.5 5736.0 5766.6 5338.4 5443.8 5466.5 5500.3 5524.2 4983.0 5000.4 5009.8 5014.8 5037.2 6005.6 6006.7 6022.8 6054.6 6057.1 5610.1 5669.0 5691.9 5706.1 5713.4 5955.9 5960.2 5968.6 5970.0 5971.6 5539.3 5623.8 5623.9 5631.7 5635.6 5615.7 5660.2 5713.3 5747.7 5748.0 5611.4 5620.4 5622.8 5624.0 5624.4 5481.5 5531.2 5616.5 5627.1 5628.0 5478.2 5562.6 5568.8 5573.9 5589.1 5644.5 5658.5 5662.9 5693.3 5718.7 5407.2 5466.8 5496.2 5594.2 5618.2 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#1] ext.html Cycles:180 Average:59828.40 Median:220194.00 stddev:116278.85 (52.8%) stddev-sans-first:116519.99 09:04:32 INFO - PROCESS | 26897 | Values: 595.7 633.9 634.6 648.8 659.2 31865.2 34931.5 35133.8 44524.0 45499.0 46221.8 46731.3 46814.1 46875.0 46970.5 9052.5 9179.8 9181.9 9185.9 9198.0 8306.6 10675.1 10940.7 10952.4 11261.8 11041.8 11120.5 11127.0 11138.6 11159.8 8377.6 10838.8 10884.4 10952.4 10959.0 10588.0 10672.9 10923.3 10994.6 11002.9 44114.0 44636.6 44750.6 44790.8 44898.6 3732.5 3816.5 4013.4 4048.7 4295.6 33616.0 34005.8 34029.8 34106.5 35663.1 4594.5 4605.3 4611.1 4697.5 4780.6 350793.0 352232.0 353313.0 354565.0 354921.0 38055.6 39386.8 69789.0 70641.0 70746.3 40707.6 40747.6 40788.2 40979.4 41101.6 17676.6 17685.6 17754.0 17893.4 17900.7 13193.5 14244.7 14534.7 14982.9 15068.5 9141.0 9213.6 9273.1 9274.9 9360.9 3532.0 3592.6 3636.5 3664.0 3664.2 1112.0 1133.9 1150.3 1180.9 1243.6 33002.0 33405.2 33443.3 33704.8 34402.5 1148.8 1154.0 1154.8 1191.8 1258.3 15088.7 17097.8 17475.6 17783.9 17915.3 536505.0 537132.0 538127.0 538929.0 539064.0 59216.7 59360.4 59368.0 60057.0 60308.0 1397.5 1460.9 1482.9 1517.7 1529.9 31839.4 32825.8 32906.0 33593.8 33760.2 49280.5 49533.5 49616.5 49789.5 49800.0 400654.0 405692.0 405826.0 407819.0 407906.0 25694.5 46425.8 46593.3 46944.3 47144.3 47036.5 47141.5 47524.0 51838.5 52095.2 43608.8 43929.8 44336.1 44551.0 44880.6 42612.0 42697.6 42700.4 43710.6 44135.6 43046.8 43520.3 43873.8 44111.0 44569.3 38184.4 38823.0 39424.4 39452.0 39734.0 29157.7 29333.7 29414.4 29549.0 29655.6 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#2] jquery.html Cycles:180 Average:30090.38 Median:438.84 stddev:88688.23 (20209.6%) stddev-sans-first:88909.05 09:04:32 INFO - PROCESS | 26897 | Values: 424.5 426.5 432.2 438.7 439.0 18465.4 19363.2 20031.2 20575.8 20777.4 18810.4 19224.7 19330.5 20122.9 20213.8 5607.4 5785.0 5791.6 5820.5 5826.5 4177.7 4314.4 5737.9 5807.0 6447.4 6541.6 6560.4 6563.9 6567.2 6570.5 6262.9 6494.6 6500.5 6507.3 6528.5 3892.2 4075.0 5300.4 5732.9 5761.6 21744.7 22931.7 23347.2 23907.4 24054.4 2362.1 2419.2 2450.6 2725.5 2809.8 14628.5 14727.9 14849.4 14869.2 14884.5 2034.6 2036.1 2056.9 2111.5 2172.2 63061.7 65743.7 69227.3 76789.0 81675.0 11040.5 11516.8 11566.4 11640.0 11725.6 16430.8 16441.9 16525.5 16527.4 16532.8 8722.3 8930.6 8942.7 9015.2 9042.2 8333.5 8375.2 8509.3 8582.6 8832.8 4964.8 5027.9 5049.3 5081.4 5142.8 2010.3 2013.3 2094.5 2102.5 2119.7 478.2 480.6 974.7 1024.4 1125.7 11546.3 12218.6 12341.2 12446.1 12609.9 481.4 487.1 488.0 497.1 504.1 6641.4 6658.7 6698.3 6704.0 6714.7 531138.0 532782.0 536765.0 545825.0 549495.0 95358.5 96198.0 97521.5 98060.0 98085.0 500.5 506.9 507.2 509.8 509.9 11312.8 16633.0 17524.4 17557.6 18871.6 37796.2 41778.8 43670.8 44271.3 44691.6 54268.0 59155.0 59361.7 60768.4 60870.7 7731.5 7740.5 7914.5 8338.7 8483.1 8821.4 11348.8 12732.0 12863.1 13201.6 11186.8 11456.1 11632.8 11910.9 11968.9 11113.8 11172.7 11349.3 11563.7 11591.7 11624.5 11716.9 11927.8 11964.7 12127.2 7448.4 7823.4 7880.6 8584.6 8768.4 8330.3 10483.6 11065.4 11100.8 11173.7 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#3] mootools.html Cycles:180 Average:6062.72 Median:4545.44 stddev:3489.88 (76.8%) stddev-sans-first:3480.31 09:04:32 INFO - PROCESS | 26897 | Values: 1172.9 1596.3 1598.3 1810.9 1891.8 7972.8 8869.0 9145.5 9146.3 9367.2 8992.4 9121.2 9139.4 9143.2 9145.2 2073.0 2118.7 2132.0 2403.3 2436.6 2374.6 2449.2 2456.6 2459.5 2465.0 2321.1 2323.6 2326.2 2331.4 2375.3 2047.7 2116.2 2431.6 2469.1 2470.1 2110.8 2323.3 2381.4 2392.7 2408.5 9020.2 9063.6 9068.6 9074.4 9075.8 5260.4 5328.7 5331.8 5336.4 5337.8 3262.8 3329.8 3385.1 3473.8 3474.3 1159.5 1165.5 1193.7 1198.2 1228.8 12572.9 12589.0 12602.2 12611.3 12630.6 4724.5 4777.2 4809.7 4821.7 4890.2 10279.5 10380.0 10383.7 10391.0 10428.3 5325.5 5330.4 5330.8 5337.3 5385.0 4404.1 4532.5 4905.8 5039.9 5114.2 4145.1 4160.9 4173.7 4185.0 4201.7 1323.5 1327.2 1336.8 1339.3 1341.1 1059.3 1061.7 1063.0 1068.4 1075.8 6199.8 6293.1 6316.6 6392.7 6397.3 485.1 490.1 493.1 496.6 498.4 4310.5 4403.9 4405.3 4489.2 4558.4 11207.9 11562.4 11623.2 11633.0 11652.6 11023.2 11525.5 11540.7 11552.4 11553.8 8618.7 8711.4 8810.2 8810.6 8817.3 6290.6 6478.2 6512.5 6530.2 6610.3 10610.8 10674.7 10711.5 11016.2 11022.7 10752.6 11006.6 11008.1 11073.7 11085.0 7236.7 8112.8 8281.0 8342.6 8396.5 9170.0 9185.0 9197.4 9213.4 9244.9 7973.1 8166.6 8302.6 8318.0 8474.5 7942.0 7992.0 8042.4 8077.2 8108.7 7469.1 7510.1 7521.5 7548.8 7604.0 5526.9 5559.9 5585.2 5665.6 5692.0 5482.0 5511.1 5624.4 5655.2 5760.3 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#4] prototype.html Cycles:180 Average:4795.02 Median:5285.75 stddev:1409.85 (26.7%) stddev-sans-first:1373.60 09:04:32 INFO - PROCESS | 26897 | Values: 340.9 343.1 354.3 361.2 362.4 5443.6 5477.9 5499.3 5500.6 5533.4 5083.8 5184.0 5253.6 5281.6 5442.7 4129.6 4182.0 4283.2 4305.7 4338.8 4234.5 4278.0 4285.4 4293.1 4324.1 4121.6 4270.2 4271.3 4290.4 4292.7 4041.4 4146.5 4217.4 4228.8 4231.3 3767.9 3771.7 3773.2 3782.4 3805.2 5170.1 5189.7 5189.8 5199.9 5218.7 4722.6 4833.8 4852.1 4875.2 4885.8 5557.7 5594.9 5621.8 5647.9 5673.5 4167.5 4193.7 4197.7 4206.5 4228.0 5625.4 5827.8 5892.6 5929.6 6001.5 4249.4 4265.2 4277.8 4282.0 4311.9 4343.2 4349.9 4354.2 4359.6 4363.5 4623.8 4792.2 4816.5 4879.0 4880.8 5673.5 5693.6 5694.7 5719.9 5723.9 1448.9 1455.3 1455.7 1455.9 1475.5 895.6 929.5 931.6 933.5 935.4 4967.8 4971.0 5000.0 5065.4 5093.2 5111.4 5156.5 5213.8 5250.2 5289.9 5024.3 5040.7 5114.8 5401.3 5614.8 3119.5 3135.4 3140.4 3142.7 3146.3 6101.9 6114.4 6116.5 6117.2 6162.4 5623.7 6028.3 6050.2 6095.5 6097.6 6159.6 6172.9 6209.3 6220.0 6237.6 5664.8 5980.3 5988.8 5994.3 6015.3 5840.4 5870.9 5871.6 5895.9 5910.0 5913.5 6177.3 6187.4 6205.0 6211.9 4165.5 4190.4 4193.5 4194.6 4216.4 5929.0 5943.6 5944.7 5960.7 6002.2 5631.9 5739.1 5786.4 5830.0 5832.5 5738.2 5739.9 5740.5 5752.5 5765.9 5600.6 5638.6 5656.3 5669.8 5689.6 5537.4 5726.6 5775.1 5778.6 5785.4 5680.5 5685.8 5688.3 5696.9 5759.4 09:04:32 INFO - PROCESS | 26897 | 09:04:32 INFO - PROCESS | 26897 | [#5] yui.html Cycles:180 Average:21172.68 Median:4800.07 stddev:30402.19 (633.4%) stddev-sans-first:30449.11 09:04:32 INFO - PROCESS | 26897 | Values: 831.3 852.3 854.1 865.5 889.4 14685.9 15316.2 15350.5 15358.2 15390.3 14498.9 14593.1 14611.4 14617.8 14628.0 636.5 793.2 821.5 840.7 849.2 777.0 784.7 788.4 791.0 791.1 805.2 814.7 815.0 815.1 924.1 683.2 892.9 926.4 927.5 935.7 612.1 612.1 612.4 612.6 612.7 15482.9 15604.2 15609.8 15620.4 15638.6 14489.9 14787.0 14787.1 14879.4 15105.8 13378.1 13671.0 13760.7 13809.4 14182.5 4646.6 4684.6 4767.5 4791.4 4796.9 74079.3 75047.6 75099.6 75151.0 75610.6 12057.4 12166.4 12240.8 12275.6 12680.1 21372.9 21374.3 21409.7 21415.3 21422.9 8288.6 8602.9 8605.2 8631.0 8642.0 4678.4 4803.2 4807.5 4809.1 4864.8 3489.1 3578.0 3596.1 3602.2 3603.7 622.3 625.2 629.6 631.9 637.0 642.9 670.7 723.5 757.5 765.3 9621.4 9724.7 9737.2 9813.3 9906.7 675.7 682.3 724.1 726.8 760.8 4856.7 4934.3 5138.8 5153.3 5274.9 80779.0 94171.0 118661.0 123638.0 128657.4 98790.0 102801.0 105607.5 106391.5 109886.0 91866.5 98327.0 98996.0 99767.0 100870.0 9285.4 9533.3 9603.1 9625.2 9678.7 45169.0 45788.0 48366.5 51531.5 56913.0 67235.3 68212.3 68682.0 72872.0 73289.3 13282.7 13840.7 14040.2 14172.1 14256.9 11353.0 15797.7 16339.2 16388.4 16508.2 10082.3 13267.3 13276.8 13413.1 14095.2 14748.4 14762.0 15095.7 15209.8 15518.1 14281.4 14301.9 14438.0 14538.3 15013.4 11551.8 11558.9 11709.0 11770.2 12095.5 11716.2 11811.8 11846.5 12090.4 12164.9 09:04:32 INFO - PROCESS | 26897 | -------- Summary: end -------- 09:04:32 INFO - PROCESS | 26897 | 09:04:33 INFO - TEST-INFO | 26897: exit 0 09:04:33 INFO - TEST-OK | dromaeo_css | took 1112490ms 09:04:33 INFO - TEST-START | kraken 09:04:33 INFO - operating with platform_type : linux_ 09:04:33 INFO - Initialising browser for kraken test... 09:04:33 INFO - TEST-INFO | started process 27095 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpruFkIm/profile http://localhost:48148/getInfo.html) 09:04:37 INFO - PROCESS | 27095 | __metrics Screen width/height:1600/1200 09:04:37 INFO - PROCESS | 27095 | colorDepth:24 09:04:37 INFO - PROCESS | 27095 | Browser inner width/height: 1024/697 09:04:37 INFO - PROCESS | 27095 | __metrics 09:04:38 INFO - PROCESS | 27095 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:04:38 ERROR - PROCESS | 27095 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:04:39 INFO - TEST-INFO | 27095: exit 0 09:04:39 INFO - Browser initialized. 09:04:39 INFO - Running cycle 1/1 for kraken test... 09:04:39 INFO - TEST-INFO | started process 27184 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpruFkIm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:51 INFO - PROCESS | 27184 | RSS: Main: 145440768 09:04:51 INFO - PROCESS | 27184 | 09:04:53 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48148/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:54 INFO - PROCESS | 27184 | RSS: Main: 194969600 09:04:54 INFO - PROCESS | 27184 | 09:04:56 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48148/tests/kraken/driver.html?testName=audio-dft) 09:04:57 INFO - PROCESS | 27184 | RSS: Main: 190775296 09:04:57 INFO - PROCESS | 27184 | 09:05:00 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48148/tests/kraken/driver.html?testName=audio-fft) 09:05:00 INFO - PROCESS | 27184 | RSS: Main: 277049344 09:05:00 INFO - PROCESS | 27184 | 09:05:03 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48148/tests/kraken/driver.html?testName=audio-oscillator) 09:05:03 INFO - PROCESS | 27184 | RSS: Main: 186179584 09:05:03 INFO - PROCESS | 27184 | 09:05:05 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48148/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:05:06 INFO - PROCESS | 27184 | RSS: Main: 185344000 09:05:06 INFO - PROCESS | 27184 | 09:05:09 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48148/tests/kraken/driver.html?testName=imaging-darkroom) 09:05:09 INFO - PROCESS | 27184 | RSS: Main: 220737536 09:05:09 INFO - PROCESS | 27184 | 09:05:13 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48148/tests/kraken/driver.html?testName=imaging-desaturate) 09:05:13 INFO - PROCESS | 27184 | RSS: Main: 220786688 09:05:13 INFO - PROCESS | 27184 | 09:05:16 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48148/tests/kraken/driver.html?testName=json-parse-financial) 09:05:17 INFO - PROCESS | 27184 | RSS: Main: 220172288 09:05:17 INFO - PROCESS | 27184 | 09:05:18 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48148/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:05:19 INFO - PROCESS | 27184 | RSS: Main: 200036352 09:05:19 INFO - PROCESS | 27184 | 09:05:21 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:05:21 INFO - PROCESS | 27184 | RSS: Main: 202088448 09:05:21 INFO - PROCESS | 27184 | 09:05:23 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:05:24 INFO - PROCESS | 27184 | RSS: Main: 210358272 09:05:24 INFO - PROCESS | 27184 | 09:05:26 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:27 INFO - PROCESS | 27184 | RSS: Main: 215130112 09:05:27 INFO - PROCESS | 27184 | 09:05:30 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:30 INFO - PROCESS | 27184 | RSS: Main: 213827584 09:05:30 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | Cycle 1(1): loaded http://localhost:48148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48148/tests/kraken/driver.html?testName=ai-astar) 09:05:32 INFO - PROCESS | 27184 | RSS: Main: 256626688 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | __start_tp_report 09:05:32 INFO - PROCESS | 27184 | _x_x_mozilla_page_load 09:05:32 INFO - PROCESS | 27184 | _x_x_mozilla_page_load_details 09:05:32 INFO - PROCESS | 27184 | |i|pagename|runs| 09:05:32 INFO - PROCESS | 27184 | |0;ai-astar;112;105;112;110;110;110;109;110;110;111 09:05:32 INFO - PROCESS | 27184 | |1;audio-beat-detection;153;123;131;177;131;124;153;124;160;126 09:05:32 INFO - PROCESS | 27184 | |2;audio-dft;161;160;161;160;160;161;160;159;160;161 09:05:32 INFO - PROCESS | 27184 | |3;audio-fft;76;75;75;105;76;77;76;76;115;76 09:05:32 INFO - PROCESS | 27184 | |4;audio-oscillator;85;83;114;84;85;85;113;84;85;128 09:05:32 INFO - PROCESS | 27184 | |5;imaging-gaussian-blur;117;117;117;116;117;116;116;116;117;116 09:05:32 INFO - PROCESS | 27184 | |6;imaging-darkroom;146;145;146;145;146;146;146;145;145;145 09:05:32 INFO - PROCESS | 27184 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;94 09:05:32 INFO - PROCESS | 27184 | |8;json-parse-financial;52;51;53;52;54;52;52;52;53;53 09:05:32 INFO - PROCESS | 27184 | |9;json-stringify-tinderbox;73;60;65;60;60;60;59;60;60;60 09:05:32 INFO - PROCESS | 27184 | |10;stanford-crypto-aes;78;78;80;78;77;77;76;77;102;78 09:05:32 INFO - PROCESS | 27184 | |11;stanford-crypto-ccm;118;119;124;120;117;117;121;129;138;132 09:05:32 INFO - PROCESS | 27184 | |12;stanford-crypto-pbkdf2;164;161;157;158;166;158;159;162;155;157 09:05:32 INFO - PROCESS | 27184 | |13;stanford-crypto-sha256-iterative;63;63;63;61;61;60;61;60;60;60 09:05:32 INFO - PROCESS | 27184 | __end_tp_report 09:05:32 INFO - PROCESS | 27184 | __start_cc_report 09:05:32 INFO - PROCESS | 27184 | _x_x_mozilla_cycle_collect,1547 09:05:32 INFO - PROCESS | 27184 | __end_cc_report 09:05:32 INFO - PROCESS | 27184 | __startTimestamp1469894732678__endTimestamp 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | ------- Summary: start ------- 09:05:32 INFO - PROCESS | 27184 | Number of tests: 14 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.00 stddev:1.97 (1.8%) stddev-sans-first:1.94 09:05:32 INFO - PROCESS | 27184 | Values: 112.0 105.0 112.0 110.0 110.0 110.0 109.0 110.0 110.0 111.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:142.00 stddev:19.05 (13.4%) stddev-sans-first:19.63 09:05:32 INFO - PROCESS | 27184 | Values: 153.0 123.0 131.0 177.0 131.0 124.0 153.0 124.0 160.0 126.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 09:05:32 INFO - PROCESS | 27184 | Values: 161.0 160.0 161.0 160.0 160.0 161.0 160.0 159.0 160.0 161.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:14.59 (19.2%) stddev-sans-first:15.27 09:05:32 INFO - PROCESS | 27184 | Values: 76.0 75.0 75.0 105.0 76.0 77.0 76.0 76.0 115.0 76.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.50 stddev:16.86 (20.0%) stddev-sans-first:17.52 09:05:32 INFO - PROCESS | 27184 | Values: 85.0 83.0 114.0 84.0 85.0 85.0 113.0 84.0 85.0 128.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:05:32 INFO - PROCESS | 27184 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 116.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:05:32 INFO - PROCESS | 27184 | Values: 146.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:05:32 INFO - PROCESS | 27184 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:0.84 (1.6%) stddev-sans-first:0.88 09:05:32 INFO - PROCESS | 27184 | Values: 52.0 51.0 53.0 52.0 54.0 52.0 52.0 52.0 53.0 53.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:60.00 stddev:4.30 (7.2%) stddev-sans-first:1.74 09:05:32 INFO - PROCESS | 27184 | Values: 73.0 60.0 65.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:78.00 stddev:7.77 (10.0%) stddev-sans-first:8.20 09:05:32 INFO - PROCESS | 27184 | Values: 78.0 78.0 80.0 78.0 77.0 77.0 76.0 77.0 102.0 78.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:122.50 stddev:7.20 (5.9%) stddev-sans-first:7.36 09:05:32 INFO - PROCESS | 27184 | Values: 118.0 119.0 124.0 120.0 117.0 117.0 121.0 129.0 138.0 132.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.00 stddev:3.47 (2.2%) stddev-sans-first:3.31 09:05:32 INFO - PROCESS | 27184 | Values: 164.0 161.0 157.0 158.0 166.0 158.0 159.0 162.0 155.0 157.0 09:05:32 INFO - PROCESS | 27184 | 09:05:32 INFO - PROCESS | 27184 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.32 (2.2%) stddev-sans-first:1.22 09:05:32 INFO - PROCESS | 27184 | Values: 63.0 63.0 63.0 61.0 61.0 60.0 61.0 60.0 60.0 60.0 09:05:32 INFO - PROCESS | 27184 | -------- Summary: end -------- 09:05:32 INFO - PROCESS | 27184 | 09:05:33 INFO - TEST-INFO | 27184: exit 0 09:05:33 INFO - TEST-OK | kraken | took 60380ms 09:05:33 INFO - SUITE-END | took 1172s 09:05:33 INFO - Completed test suite (00:19:33) 09:05:33 INFO - javascript benchmark 09:05:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.8856590019345, 3296.060622722523, 3301.8514166862496, 3322.2857085726596, 3338.582677165354, 5345.465453454653, 5427.538659213967, 5442.591805614575, 5456.454354564542, 5458.890822183564, 5337.14605663094, 5354.357477102743, 5394.514493695567, 5403.70279634621, 5403.891922161554, 4795.2087905495555, 4876.926846097312, 4897.387826521667, 4916.311716359694, 4960.206366981283, 4784.186688263004, 4910.2143657014885, 4944.60443164546, 4951.851444456672, 4957.058158949806, 4940.357753492059, 4949.331840201581, 4954.826581069663, 4987.625928055392, 4992.101421744084, 5040.470750571192, 5050.191969284897, 5064.11378008847, 5070.6957582545165, 5100.5409513143995, 4790.281457781326, 4796.400449943757, 4823.831165909176, 4912.140375434285, 4954.554090131869, 5174.404943431516, 5181.3782346118705, 5213.504717051912, 5235.554977826851, 5240.973795131038, 5704.657720536781, 5781.884362312772, 5783.624064435799, 5783.739731712083, 5788.42115788422, 5742.224799652077, 5796.768129274824, 5797.855053623651, 5804.187413762077, 5818.156367326714, 5422.53908417781, 5465.398806131322, 5469.781208751645, 5470.370907345627, 5474.808381706661, 5745.253117094751, 5938.6436813791315, 5948.0, 5956.344802071769, 5957.195778569838, 5607.383187849333, 5642.576806739511, 5663.490285874291, 5682.119271512922, 5701.942980570141, 5743.655380677173, 5744.51171650406, 5772.913406298909, 5775.48020678141, 5784.190213370048, 5526.806561770319, 5622.0, 5762.279715035621, 5771.68255745938, 5777.162311464814, 5659.066254068115, 5687.544996400278, 5690.0, 5698.401667824819, 5727.198192253088, 4408.889777755582, 4417.315316126005, 4419.381286619876, 4420.469543654782, 4447.066116115588, 3917.666998305176, 3930.8624198153498, 3932.7050471214775, 3974.7813870237414, 3984.601539845992, 5380.112281473591, 5391.946080539223, 5411.864703382368, 5430.8913821723345, 5453.072977593819, 5725.055365864586, 5731.627444216113, 5733.455321744427, 5736.024875771129, 5766.596338256281, 5338.359396872323, 5443.782248710087, 5466.453354664502, 5500.312460942382, 5524.1989911462615, 4983.02830947969, 5000.424951130666, 5009.799608015712, 5014.7994080236385, 5037.219231019198, 6005.579609427386, 6006.729697163638, 6022.819315420456, 6054.636721796706, 6057.091436284504, 5610.074337734228, 5668.971655141863, 5691.914621280767, 5706.087026075808, 5713.371529131875, 5955.851103722442, 5960.225170727777, 5968.582199246011, 5969.985102532667, 5971.552133589911, 5539.335279766293, 5623.775048997994, 5623.943760562342, 5631.746571404215, 5635.633683810539, 5615.6630602164005, 5660.179274005164, 5713.257276554022, 5747.712614369349, 5747.971260143672, 5611.410801865745, 5620.381758006698, 5622.803201887914, 5623.971880140573, 5624.437556244343, 5481.451854814487, 5531.225628412106, 5616.522595579258, 5627.099664053731, 5628.0, 5478.178273259043, 5562.610617256915, 5568.777248909998, 5573.860653483695, 5589.077802162597, 5644.463775941279, 5658.49073583363, 5662.85842853932, 5693.345265294379, 5718.656880587178, 5407.188921661625, 5466.80866169698, 5496.1755736639825, 5594.244776955059, 5618.157276408571], "value": 5317.009261981241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.6902410746412, 633.9017452294894, 634.6287421858215, 648.8280605639503, 659.2451642868912, 31865.203369915766, 34931.47602785957, 35133.82433087837, 44524.0, 45499.0, 46221.76889115558, 46731.29903051457, 46814.06371872569, 46875.0, 46970.53029469713, 9052.45685258885, 9179.816403671923, 9181.862272065924, 9185.908140918606, 9197.95401022994, 8306.584670766468, 10675.092617127553, 10940.726481837939, 10952.397618130999, 11261.774764504706, 11041.779164416706, 11120.499577518991, 11127.0, 11138.610148644799, 11159.832602510969, 8377.6230069647, 10838.783224335548, 10884.401357925311, 10952.397618130999, 10958.99999999996, 10588.0, 10672.893271067267, 10923.289986150954, 10994.615188468366, 11002.889971100347, 44114.0, 44636.55363446389, 44750.55249447496, 44790.77604611956, 44898.55101448976, 3732.5147730794824, 3816.5229346331707, 4013.418054382098, 4048.67610591152, 4295.570442955679, 33616.0, 34005.82997084999, 34029.82985085084, 34106.48840267398, 35663.10842228939, 4594.540545945379, 4605.30920361945, 4611.123886461562, 4697.4832768395445, 4780.617550595944, 350793.0, 352232.0, 353313.0, 354565.0, 354921.0, 38055.619443805204, 39386.80306598449, 69789.0, 70641.0, 70746.29253707397, 40707.592924070974, 40747.59252407497, 40788.18423631511, 40979.385309220386, 41101.58898411037, 17676.558086047953, 17685.55786105332, 17754.0, 17893.37373191932, 17900.731489027676, 13193.538226162038, 14244.715105697827, 14534.709305814036, 14982.925085374503, 15068.54794356171, 9141.0, 9213.631454741735, 9273.119053689888, 9274.860877086849, 9360.90639093614, 3532.0, 3592.550931133608, 3636.472711456824, 3664.04734768962, 3664.157243833933, 1111.9777604447866, 1133.880942501042, 1150.3040660400504, 1180.911431642613, 1243.583399561139, 33002.0, 33405.16487087794, 33443.33113337768, 33704.831475842955, 34402.48396274058, 1148.833419154218, 1153.9538418463335, 1154.8267759836092, 1191.773563023023, 1258.2950102611014, 15088.698226035638, 17097.829021709622, 17475.57422858149, 17783.911080444774, 17915.283388664306, 536505.0, 537132.0, 538127.0, 538929.0, 539064.0, 59216.70391648014, 59360.40639593635, 59368.0, 60057.0, 60308.0, 1397.5178563395816, 1460.8685218330188, 1482.9406823727145, 1517.719221943944, 1529.8546638069365, 31839.36321273608, 32825.83587082049, 32906.0, 33593.83203084067, 33760.15599610029, 49280.507194927595, 49533.50466495289, 49616.503834961186, 49789.50210497993, 49800.0, 400654.0, 405692.0, 405826.0, 407819.0, 407906.0, 25694.486110278063, 46425.76787116043, 46593.301100482844, 46944.29583556181, 47144.29283560681, 47036.52963470321, 47141.52858471371, 47524.0, 51838.481615183366, 52095.218571720696, 43608.78195609002, 43929.78035109804, 44336.11327773362, 44551.0, 44880.551194488944, 42612.0, 42697.57302426936, 42700.359494606986, 43710.56289437065, 44135.55864441315, 43046.78476607597, 43520.34719479274, 43873.78063109664, 44111.0, 44569.33146002878, 38184.42723359096, 38823.0, 39424.40863387109, 39452.01369965659, 39734.0, 29157.7084229155, 29333.7066629331, 29414.411711765217, 29549.0, 29655.555166672948], "value": 20394.186377429167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.53513402823904, 426.4818245831314, 432.16591977483444, 438.6885311428885, 438.9978050109749, 18465.353712620043, 19363.225470981142, 20031.198752049917, 20575.794242057575, 20777.376678699624, 18810.435686929413, 19224.711629325535, 19330.51673708158, 20122.89938550309, 20213.797862021376, 5607.355154157263, 5785.0, 5791.623544469617, 5820.476157145856, 5826.504747096502, 4177.707560470768, 4314.395984562164, 5737.913931291034, 5807.0, 6447.387498187664, 6541.607503549779, 6560.376764207416, 6563.901541476882, 6567.244766851802, 6570.507211959122, 6262.937370626281, 6494.5778524396, 6500.479961603108, 6507.284198738135, 6528.543001989862, 3892.2020985697864, 4074.9592504074876, 5300.416954135042, 5732.856678583069, 5761.625494342897, 21744.673829892407, 22931.656025159635, 23347.182848600387, 23907.40231494209, 24054.398640033964, 2362.137819695816, 2419.2379400488967, 2450.6324051392257, 2725.536658768014, 2809.8454584997817, 14628.488002919847, 14727.852721472862, 14849.406023759037, 14869.182194979272, 14884.479043233436, 2034.6439373109795, 2036.1244664794133, 2056.856020078595, 2111.524906895936, 2172.2071443923014, 63061.68469157671, 65743.67128164376, 69227.30772692259, 76788.99999999945, 81675.0, 11040.503177357039, 11516.769664606662, 11566.42167891602, 11640.0, 11725.648230553097, 16430.75353869693, 16441.91779041097, 16525.504234872973, 16527.421540246276, 16532.75200871988, 8722.25860801839, 8930.598123084477, 8942.68700595476, 9015.233705135137, 9042.18620324174, 8333.541655209021, 8375.246227839523, 8509.27671147947, 8582.613782379807, 8832.77918052041, 4964.776585053682, 5027.874303142377, 5049.26785616091, 5081.390233172043, 5142.845714628567, 2010.326540608883, 2013.3456626596414, 2094.549671820566, 2102.5111387188463, 2119.660854263341, 478.16798770139707, 480.56508859482227, 974.7027156717128, 1024.4365598920565, 1125.6566747142037, 11546.307221566565, 12218.633440996784, 12341.197822141532, 12446.128770986124, 12609.873901261053, 481.4222932481037, 487.05025200858466, 488.0, 497.0655168283604, 504.13037510294447, 6641.369069938348, 6658.667066646648, 6698.330166983263, 6703.966480167665, 6714.664266786739, 531138.0, 532782.0, 536765.0, 545825.0, 549495.0, 95358.5232073849, 96198.0, 97521.51239243758, 98060.0, 98085.0, 500.5170010939461, 506.94677058908286, 507.2036902063798, 509.788437798317, 509.9056674515227, 11312.773744525228, 16633.0, 17524.386646467312, 17557.56106097307, 18871.622567548846, 37796.24407511889, 41778.79110604428, 43670.781646091564, 44271.33592996172, 44691.55308447004, 54268.0, 59155.0, 59361.703191483764, 60768.39231607627, 60870.69564652148, 7731.497452665559, 7740.458167928191, 7914.525128492309, 8338.666453341799, 8483.10927352619, 8821.382503224713, 11348.773024539629, 12732.045096617605, 12863.099583029472, 13201.60395188146, 11186.83219751688, 11456.140789440658, 11632.767344653228, 11910.880891191324, 11968.880311196777, 11113.833292500458, 11172.664820055412, 11349.262297950938, 11563.710907227387, 11591.6522504325, 11624.476898539417, 11716.94141529287, 11927.76144477123, 11964.700882478008, 12127.211731237794, 7448.366888814511, 7823.413244006609, 7880.56656883854, 8584.570771461527, 8768.430052046597, 8330.333573314241, 10483.633072842415, 11065.446727663746, 11100.833487497532, 11173.715022772485], "value": 8787.037825162539, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1172.8885755853191, 1596.329541592531, 1598.2967494302502, 1810.9456716298507, 1891.7866096666899, 7972.800679982996, 8869.0, 9145.542722863864, 9146.268298536117, 9367.203787678056, 8992.41549299295, 9121.224695900857, 9139.360244782867, 9143.177114059734, 9145.222656074242, 2073.0463986566124, 2118.661014237722, 2131.9680204796864, 2403.2549909528016, 2436.5857804173334, 2374.608189648706, 2449.155041510676, 2456.5578195924727, 2459.4712136890566, 2465.0, 2321.129576408847, 2323.6282194848827, 2326.150954901466, 2331.3821837213154, 2375.2636682628354, 2047.6518991771434, 2116.153538584563, 2431.63525471179, 2469.1111199968086, 2470.0984140788573, 2110.757262914769, 2323.2913961241816, 2381.357033600918, 2392.7487613800477, 2408.4580969281947, 9020.18818306349, 9063.59213835379, 9068.591913363915, 9074.410163339362, 9075.773105672344, 5260.447652996419, 5328.706921119337, 5331.813386531491, 5336.38631557372, 5337.839864804062, 3262.804231746079, 3329.8335083245743, 3385.086026772782, 3473.7915725056578, 3474.270403215328, 1159.4898244772357, 1165.4755360087995, 1193.6598069550223, 1198.19720787072, 1228.7542491501647, 12572.874271257262, 12589.0, 12602.180858244184, 12611.306378149106, 12630.621081367573, 4724.495308712941, 4777.1878780606685, 4809.711417314902, 4821.734804585782, 4890.168671325812, 10279.537420816081, 10380.0, 10383.740406489898, 10391.0, 10428.322159059637, 5325.4940780625775, 5330.440303768049, 5330.786768529293, 5337.2794672719465, 5385.030694475032, 4404.141192467502, 4532.524084971031, 4905.828296009622, 5039.899202015939, 5114.207297868837, 4145.129522800185, 4160.9375859362135, 4173.728707633994, 4185.037441388497, 4201.705880588391, 1323.4639970811836, 1327.1904138475522, 1336.8195293635429, 1339.3102552185633, 1341.081359268904, 1059.2691043180334, 1061.6549621373085, 1062.9627963021258, 1068.423051552153, 1075.8117329467311, 6199.845003874939, 6293.118963345135, 6316.610345723933, 6392.680365981682, 6397.296297407282, 485.0614061790447, 490.1275729202026, 493.11485882839673, 496.5779087775434, 498.429298453269, 4310.52584215736, 4403.933940990888, 4405.339199120158, 4489.169503641837, 4558.361829343895, 11207.887921120684, 11562.364069976233, 11623.186376953701, 11633.0, 11652.592159274385, 11023.228374013903, 11525.538978440936, 11540.653780386434, 11552.422378881023, 11553.768924621461, 8618.741437756877, 8711.390202685878, 8810.163034511712, 8810.64757409722, 8817.29461643067, 6290.591111577916, 6478.1578394808375, 6512.544121911421, 6530.183727034121, 6610.305917878746, 10610.8408373876, 10674.73313167177, 10711.464426778786, 11016.228863979444, 11022.669319920415, 10752.623658171926, 11006.559737610405, 11008.064314533034, 11073.723156921142, 11085.0, 7236.710531578678, 8112.797180070545, 8281.0, 8342.624581893951, 8396.454230475, 9170.0, 9184.954075229849, 9197.448153110834, 9213.355065145644, 9244.861327079965, 7973.083095444098, 8166.550839703873, 8302.626381812954, 8317.95841020791, 8474.491530508189, 7942.0, 7992.0, 8042.3968202383885, 8077.232662897015, 8108.716194933385, 7469.141048779243, 7510.136334321404, 7521.548707077593, 7548.773536793905, 7604.0, 5526.91709624364, 5559.916601251065, 5585.2180694701965, 5665.603407761417, 5692.0, 5481.972590137024, 5511.090670039399, 5624.381318055092, 5655.179998900218, 5760.279965004374], "value": 4754.340684544262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.91136304560814, 343.1181862613084, 354.3250108543226, 361.23959066165736, 362.35139101009196, 5443.646162999402, 5477.917831232529, 5499.285092937917, 5500.6424582402215, 5533.391326954032, 5083.847484575459, 5184.0, 5253.6322457428, 5281.551068159201, 5442.700651464178, 4129.648979836717, 4181.996320882989, 4283.207606592775, 4305.655547556188, 4338.8047537860875, 4234.470691163605, 4277.957220427803, 4285.400043993844, 4293.076988447483, 4324.091940692442, 4121.649659778923, 4270.231358355495, 4271.295236286009, 4290.442242508485, 4292.699511034229, 4041.3937909313545, 4146.502419709624, 4217.36739489076, 4228.830846766125, 4231.259529582311, 3767.8869633911027, 3771.679407250387, 3773.2076263984595, 3782.394816829322, 3805.162864169878, 5170.138750049979, 5189.66267192635, 5189.792408303663, 5199.934013527218, 5218.6953261684575, 4722.598579120779, 4833.806647734086, 4852.0591763294615, 4875.171220892456, 4885.828995985159, 5557.666540007613, 5594.860128496779, 5621.763212093374, 5647.943520564783, 5673.517750991171, 4167.478870212381, 4193.693468592262, 4197.727147735387, 4206.455094667713, 4227.957720422787, 5625.353084395306, 5827.825165245049, 5892.646441213498, 5929.644221346733, 6001.549883758736, 4249.38383934334, 4265.232258193555, 4277.764722940268, 4281.957180428156, 4311.892202694958, 4343.218220720238, 4349.891252718708, 4354.238008348526, 4359.564043595615, 4363.498197707304, 4623.791929363187, 4792.226876055812, 4816.54242846929, 4878.975605122023, 4880.829170978998, 5673.489385955201, 5693.60144789869, 5694.6583205007, 5719.914201286984, 5723.914141287884, 1448.891333150018, 1455.3305479479345, 1455.745244582215, 1455.8689717925438, 1475.5425817996456, 895.6193617712446, 929.5120061967386, 931.5575101826713, 933.4912472702393, 935.4013431403907, 4967.751612419364, 4971.0, 5000.024990128879, 5065.442801291855, 5093.159628661229, 5111.386633603917, 5156.458571849977, 5213.765380557808, 5250.238715386247, 5289.947100528945, 5024.346834911511, 5040.722760248222, 5114.795408183631, 5401.270828438189, 5614.838287259435, 3119.5008798592116, 3135.435621588136, 3140.3885531638343, 3142.71715545602, 3146.3235404388165, 6101.908471372933, 6114.38856114385, 6116.48009919153, 6117.174181485531, 6162.414570615784, 5623.6906970116215, 6028.336882942873, 6050.183225264621, 6095.542834287447, 6097.603655762361, 6159.6140868305165, 6172.9074063889075, 6209.298954954609, 6219.96890015538, 6237.594556353823, 5664.801731939362, 5980.252468441445, 5988.790392336247, 5994.340622531431, 6015.308239552507, 5840.386759390372, 5870.882582348415, 5871.647701137775, 5895.94104058954, 5909.970450147722, 5913.526917846648, 6177.320494745664, 6187.381261873777, 6205.045410695617, 6211.9068213975925, 4165.4584903962295, 4190.371444283382, 4193.517745459311, 4194.5948107384365, 4216.388623649493, 5928.970355148197, 5943.554233432424, 5944.702764861653, 5960.731767070405, 6002.189704389852, 5631.943680563142, 5739.139129130664, 5786.363500015079, 5829.970850145722, 5832.475077243069, 5738.167965644875, 5739.8852022960145, 5740.512056475246, 5752.482276595127, 5765.91351129725, 5600.607957443103, 5638.577106716931, 5656.2929633795775, 5669.837683275001, 5689.601727879174, 5537.355173791386, 5726.599138060463, 5775.075987841925, 5778.642019125418, 5785.392533783892, 5680.460356266148, 5685.829425117253, 5688.288963879515, 5696.857578560403, 5759.39526349744], "value": 4346.677133570172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.3349320543564, 852.2883392367373, 854.1117238072404, 865.5369377383101, 889.4263200235848, 14685.853141468582, 15316.234188290582, 15350.53948381549, 15358.23208839559, 15390.30743616537, 14498.927505362459, 14593.124412535255, 14611.415543378278, 14617.78073328901, 14628.049176803526, 636.5003472274266, 793.2067932067932, 821.5481485183155, 840.6805413942693, 849.2441726863096, 776.9572673502955, 784.654751909161, 788.3771820262015, 791.0270367448043, 791.1020991175029, 805.1666525146475, 814.7189219719218, 814.9796255093611, 815.1196707555855, 924.0943875002501, 683.1938312790904, 892.9284858170189, 926.4487629860222, 927.4574373991223, 935.7052528453515, 612.0543759890979, 612.0696541257264, 612.3723183736705, 612.6018088242633, 612.681405669054, 15482.922585387114, 15604.219789010504, 15609.843901561066, 15620.375184992587, 15638.609034774108, 14489.927550362181, 14787.0, 14787.11277323364, 14879.404823807035, 15105.773413398807, 13378.13042152257, 13671.0, 13760.65598360039, 13809.378577964017, 14182.503612373517, 4646.558576935186, 4684.5549672781035, 4767.451743049559, 4791.448983366888, 4796.904061918777, 74079.25920740831, 75047.62476187584, 75099.62450187768, 75151.0, 75610.6219468899, 12057.397130143458, 12166.391680415943, 12240.816387754012, 12275.631731048083, 12680.112392132647, 21372.893135534534, 21374.251901183383, 21409.678854816877, 21415.250466233607, 21422.892885535784, 8288.58557072144, 8602.91397086021, 8605.225529702357, 8630.956845215735, 8641.956790216134, 4678.391809064799, 4803.231482962709, 4807.495213002654, 4809.0862736080035, 4864.78108485119, 3489.1451594359546, 3578.033930838711, 3596.1189508570046, 3602.2075143468396, 3603.747737658339, 622.3061286665336, 625.1903784598956, 629.6285191736897, 631.8831016262006, 636.9522285828581, 642.9196350456193, 670.7283550162192, 723.5333210079574, 757.5341165183372, 765.2997507280887, 9621.374610650286, 9724.659636912816, 9737.172340351008, 9813.264005199495, 9906.653267135616, 675.6824292582478, 682.2698822342844, 724.0840336973732, 726.7746998430504, 760.7831767946109, 4856.68431551948, 4934.309196712463, 5138.768755406016, 5153.330067091254, 5274.947250527523, 80779.0, 94171.0, 118661.0, 123638.0, 128657.35671321584, 98790.0, 102801.0, 105607.4719626397, 106391.46804266084, 109886.0, 91866.54066729624, 98327.0, 98996.0, 99767.0, 100870.0, 9285.35002549815, 9533.332666713264, 9603.087706667855, 9625.229981601595, 9678.70963871085, 45169.0, 45788.0, 48366.516334836204, 51531.48468515267, 56913.0, 67235.3276467229, 68212.3178768206, 68682.0, 72872.0, 73289.326192552, 13282.734345313233, 13840.653983650489, 14040.227787471787, 14172.078814877, 14256.857431425968, 11353.0, 15797.68404631878, 16339.183040847673, 16388.426405075767, 16508.174591270148, 10082.29423940317, 13267.336633168496, 13276.800847987095, 13413.061085723906, 14095.154290742588, 14748.360383082016, 14762.0, 15095.698086038437, 15209.847901521285, 15518.146501942501, 14281.357338919566, 14301.92849035748, 14438.0, 14538.345774439964, 15013.399464021317, 11551.768964620493, 11558.884411156117, 11709.0, 11770.17608767378, 12095.516179353079, 11716.228076699925, 11811.763764724828, 11846.526138954345, 12090.395480225778, 12164.878351216375], "value": 7770.093041551058, "unit": "score"}], "name": "dromaeo_css", "value": 7313.449052229522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 131.0, 177.0, 131.0, 124.0, 153.0, 124.0, 160.0, 126.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 159.0, 160.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 105.0, 76.0, 77.0, 76.0, 76.0, 115.0, 76.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 114.0, 84.0, 85.0, 85.0, 113.0, 84.0, 85.0, 128.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 52.0, 54.0, 52.0, 52.0, 52.0, 53.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 60.0, 65.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 80.0, 78.0, 77.0, 77.0, 76.0, 77.0, 102.0, 78.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 124.0, 120.0, 117.0, 117.0, 121.0, 129.0, 138.0, 132.0], "value": 123.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 157.0, 158.0, 166.0, 158.0, 159.0, 162.0, 155.0, 157.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1483.1, "alertThreshold": 2.0}]} 09:05:33 INFO - Return code: 0 09:05:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:33 ERROR - # TBPL SUCCESS # 09:05:33 INFO - Running post-action listener: _resource_record_post_action 09:05:33 INFO - ##### 09:05:33 INFO - ##### Finished run-tests step (success) 09:05:33 INFO - ##### 09:05:33 INFO - Running post-run listener: _resource_record_post_run 09:05:34 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506191872; Read time: 8; Write time: 446284 09:05:34 INFO - TinderboxPrint: CPU usage
13.1% 09:05:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:05:34 INFO - TinderboxPrint: I/O write bytes / time
506,191,872 / 446,284 09:05:34 INFO - TinderboxPrint: CPU idle
8,256.4 (86.9%) 09:05:34 INFO - TinderboxPrint: CPU user
1,196.2 (12.6%) 09:05:34 INFO - TinderboxPrint: Swap in / out
0 / 0 09:05:34 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252768256; Read time: 0; Write time: 281856 09:05:34 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252837888; Read time: 8; Write time: 164420 09:05:34 INFO - Running post-run listener: _upload_blobber_files 09:05:34 INFO - Blob upload gear active. 09:05:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:05:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:38 INFO - (blobuploader) - INFO - Done attempting. 09:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:39 INFO - (blobuploader) - INFO - Done attempting. 09:05:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:39 INFO - (blobuploader) - INFO - Done attempting. 09:05:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:39 INFO - Return code: 0 09:05:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1305401570247c219fd0d4d0c574bdd5ccc119e98379d32cc92a4ba9be81269c5e8d1735089424701b92c10144b59f957a0e4cc0b971aac6decbc5aa9e8ad8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab34d91a6f50d7604b20dd266fd61705295c43a0573b857f806afc840d8a9e2a66ff5ee7dad71d41410d09c50638d3b0cec9673dd1d7bf9d061f76f93494a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1c37d1b29cfa3916d359d542ea9c6585368158c0131356d58d116e617eaf6694df577f86c232ab2669f581c8b88a7d9fe89f416c5fe07aa5dcbb78748b9420"} 09:05:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:39 INFO - Contents: 09:05:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1305401570247c219fd0d4d0c574bdd5ccc119e98379d32cc92a4ba9be81269c5e8d1735089424701b92c10144b59f957a0e4cc0b971aac6decbc5aa9e8ad8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab34d91a6f50d7604b20dd266fd61705295c43a0573b857f806afc840d8a9e2a66ff5ee7dad71d41410d09c50638d3b0cec9673dd1d7bf9d061f76f93494a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1c37d1b29cfa3916d359d542ea9c6585368158c0131356d58d116e617eaf6694df577f86c232ab2669f581c8b88a7d9fe89f416c5fe07aa5dcbb78748b9420"} 09:05:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:39 INFO - Copying logs to upload dir... 09:05:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.931354 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-30 09:05:39.269770) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 09:05:39.273679) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1305401570247c219fd0d4d0c574bdd5ccc119e98379d32cc92a4ba9be81269c5e8d1735089424701b92c10144b59f957a0e4cc0b971aac6decbc5aa9e8ad8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab34d91a6f50d7604b20dd266fd61705295c43a0573b857f806afc840d8a9e2a66ff5ee7dad71d41410d09c50638d3b0cec9673dd1d7bf9d061f76f93494a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1c37d1b29cfa3916d359d542ea9c6585368158c0131356d58d116e617eaf6694df577f86c232ab2669f581c8b88a7d9fe89f416c5fe07aa5dcbb78748b9420"} build_url:https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/esAWQ2lFSraoDwHJdtj43w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1305401570247c219fd0d4d0c574bdd5ccc119e98379d32cc92a4ba9be81269c5e8d1735089424701b92c10144b59f957a0e4cc0b971aac6decbc5aa9e8ad8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab34d91a6f50d7604b20dd266fd61705295c43a0573b857f806afc840d8a9e2a66ff5ee7dad71d41410d09c50638d3b0cec9673dd1d7bf9d061f76f93494a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf1c37d1b29cfa3916d359d542ea9c6585368158c0131356d58d116e617eaf6694df577f86c232ab2669f581c8b88a7d9fe89f416c5fe07aa5dcbb78748b9420"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 09:05:39.302557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 09:05:39.303275) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1469893507.116216-1438609774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 09:05:39.386961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 09:05:39.387604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 09:05:39.388230) ========= ========= Total master_lag: 0.18 =========