builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-036 starttime: 1468218962.67 results: success (0) buildid: 20160710205425 builduid: 3c6658fada3745669c5a0f6b03495327 revision: 11c6cfe72f401b89242a92b9def96768f01b4dc3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:02.665713) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:02.666151) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-07-10 23:36:02.666439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-07-10 23:36:03.806293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:03.806644) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-10 23:36:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.4M=0s 2016-07-10 23:36:04 (89.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123649 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:03.944250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:03.944542) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016819 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:03.997457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 23:36:03.997816) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 11c6cfe72f401b89242a92b9def96768f01b4dc3 --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 11c6cfe72f401b89242a92b9def96768f01b4dc3 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-10 23:36:04,187 truncating revision to first 12 chars 2016-07-10 23:36:04,187 Setting DEBUG logging. 2016-07-10 23:36:04,187 attempt 1/10 2016-07-10 23:36:04,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/11c6cfe72f40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 23:36:05,066 unpacking tar archive at: mozilla-aurora-11c6cfe72f40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.309623 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 23:36:05.321077) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:05.321382) ========= script_repo_revision: 11c6cfe72f401b89242a92b9def96768f01b4dc3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:05.321944) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:05.322210) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 23:36:05.332734) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-10 23:36:05.333174) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:36:05 INFO - MultiFileLogger online at 20160710 23:36:05 in /builds/slave/test 23:36:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:36:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:36:05 INFO - {'append_to_log': False, 23:36:05 INFO - 'base_work_dir': '/builds/slave/test', 23:36:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:36:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:36:05 INFO - 'branch': 'Mozilla-Aurora', 23:36:05 INFO - 'buildbot_json_path': 'buildprops.json', 23:36:05 INFO - 'config_files': ('talos/linux_config.py',), 23:36:05 INFO - 'default_actions': ('clobber', 23:36:05 INFO - 'read-buildbot-config', 23:36:05 INFO - 'download-and-extract', 23:36:05 INFO - 'populate-webroot', 23:36:05 INFO - 'create-virtualenv', 23:36:05 INFO - 'install', 23:36:05 INFO - 'run-tests'), 23:36:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:36:05 INFO - 'download_minidump_stackwalk': True, 23:36:05 INFO - 'download_symbols': 'ondemand', 23:36:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:36:05 INFO - 'tooltool.py': '/tools/tooltool.py', 23:36:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:36:05 INFO - '/tools/misc-python/virtualenv.py')}, 23:36:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:36:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:36:05 INFO - 'installer_path': 'installer.exe', 23:36:05 INFO - 'log_level': 'info', 23:36:05 INFO - 'log_name': 'talos', 23:36:05 INFO - 'log_to_console': True, 23:36:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:36:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:36:05 INFO - 'opt_config_files': (), 23:36:05 INFO - 'pip_index': False, 23:36:05 INFO - 'sps_profile': False, 23:36:05 INFO - 'sps_profile_interval': 0, 23:36:05 INFO - 'suite': 'dromaeojs-e10s', 23:36:05 INFO - 'system_bits': '32', 23:36:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:36:05 INFO - 'title': 'talos-linux64-ix-036', 23:36:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:36:05 INFO - 'use_talos_json': True, 23:36:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:36:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:36:05 INFO - 'work_dir': 'build'} 23:36:05 INFO - ##### 23:36:05 INFO - ##### Running clobber step. 23:36:05 INFO - ##### 23:36:05 INFO - Running pre-action listener: _resource_record_pre_action 23:36:05 INFO - Running main action method: clobber 23:36:05 INFO - rmtree: /builds/slave/test/build 23:36:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:36:06 INFO - Running post-action listener: _resource_record_post_action 23:36:06 INFO - ##### 23:36:06 INFO - ##### Finished clobber step (success) 23:36:06 INFO - ##### 23:36:06 INFO - ##### 23:36:06 INFO - ##### Running read-buildbot-config step. 23:36:06 INFO - ##### 23:36:06 INFO - Running pre-action listener: _resource_record_pre_action 23:36:06 INFO - Running main action method: read_buildbot_config 23:36:06 INFO - Using buildbot properties: 23:36:06 INFO - { 23:36:06 INFO - "project": "", 23:36:06 INFO - "product": "firefox", 23:36:06 INFO - "script_repo_revision": "production", 23:36:06 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 23:36:06 INFO - "repository": "", 23:36:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 23:36:06 INFO - "buildid": "20160710205425", 23:36:06 INFO - "pgo_build": "True", 23:36:06 INFO - "basedir": "/builds/slave/test", 23:36:06 INFO - "buildnumber": 413, 23:36:06 INFO - "slavename": "talos-linux64-ix-036", 23:36:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:36:06 INFO - "platform": "ubuntu64_hw", 23:36:06 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:36:06 INFO - "branch": "mozilla-aurora", 23:36:06 INFO - "repo_path": "releases/mozilla-aurora", 23:36:06 INFO - "revision": "11c6cfe72f401b89242a92b9def96768f01b4dc3", 23:36:06 INFO - "stage_platform": "linux64-pgo", 23:36:06 INFO - "builduid": "3c6658fada3745669c5a0f6b03495327", 23:36:06 INFO - "slavebuilddir": "test" 23:36:06 INFO - } 23:36:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 23:36:06 INFO - Running post-action listener: _resource_record_post_action 23:36:06 INFO - ##### 23:36:06 INFO - ##### Finished read-buildbot-config step (success) 23:36:06 INFO - ##### 23:36:06 INFO - ##### 23:36:06 INFO - ##### Running download-and-extract step. 23:36:06 INFO - ##### 23:36:06 INFO - Running pre-action listener: _resource_record_pre_action 23:36:06 INFO - Running main action method: download_and_extract 23:36:06 INFO - mkdir: /builds/slave/test/build/tests 23:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:06 INFO - https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:36:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:36:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:36:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:15 INFO - Downloaded 1361 bytes. 23:36:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:36:15 INFO - Using the following test package requirements: 23:36:15 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 23:36:15 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:36:15 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:36:15 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'jsshell-linux-x86_64.zip'], 23:36:15 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:36:15 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 23:36:15 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:36:15 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:36:15 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:36:15 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:36:15 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:36:15 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:36: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')]} 23:36:15 INFO - https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:36:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:36:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:36:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:17 INFO - Downloaded 24526646 bytes. 23:36:17 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'] 23:36:17 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 23:36:19 INFO - Return code: 0 23:36:19 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 23:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:19 INFO - https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:36:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:36:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:36:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:22 INFO - Downloaded 24526646 bytes. 23:36:22 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'] 23:36:22 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 23:36:24 INFO - Return code: 0 23:36: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')]} 23:36:24 INFO - https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:36:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 23:36:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 23:36:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:25 INFO - Downloaded 10833369 bytes. 23:36:25 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'] 23:36:25 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 23:36:25 INFO - Return code: 0 23:36:25 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')]} 23:36:25 INFO - https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:36:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:36:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:36:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LmnGhLIySxaDUsqZTg7oXg/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 23:36:26 INFO - Downloaded 63638152 bytes. 23:36:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:36:26 INFO - mkdir: /builds/slave/test/properties 23:36:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:36:26 INFO - Writing to file /builds/slave/test/properties/build_url 23:36:26 INFO - Contents: 23:36:26 INFO - build_url:https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:36:27 INFO - Running post-action listener: _resource_record_post_action 23:36:27 INFO - Running post-action listener: set_extra_try_arguments 23:36:27 INFO - ##### 23:36:27 INFO - ##### Finished download-and-extract step (success) 23:36:27 INFO - ##### 23:36:27 INFO - ##### 23:36:27 INFO - ##### Running populate-webroot step. 23:36:27 INFO - ##### 23:36:27 INFO - Running pre-action listener: _resource_record_pre_action 23:36:27 INFO - Running main action method: populate_webroot 23:36:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:36:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:36:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:36:27 INFO - '--tppagecycles', 23:36:27 INFO - '7'], 23:36:27 INFO - 'tests': ['tsvgm']}}, 23:36:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:36:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:36:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:36:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:36:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:36:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:36:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['damp', 'tps']}, 23:36:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'tests': ['damp', 'tps']}, 23:36:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['dromaeo_dom']}, 23:36:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:36:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'other-e10s': {'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['a11yr', 23:36:27 INFO - 'ts_paint', 23:36:27 INFO - 'tpaint', 23:36:27 INFO - 'sessionrestore', 23:36:27 INFO - 'sessionrestore_no_auto_restore', 23:36:27 INFO - 'tabpaint']}, 23:36:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['tsvgx', 23:36:27 INFO - 'tsvgr_opacity', 23:36:27 INFO - 'tart', 23:36:27 INFO - 'tscrollx', 23:36:27 INFO - 'cart']}, 23:36:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:36:27 INFO - 'tsvgr_opacity', 23:36:27 INFO - 'tart', 23:36:27 INFO - 'tscrollx', 23:36:27 INFO - 'cart']}, 23:36:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'talos_options': ['--disable-e10s'], 23:36:27 INFO - 'tests': ['tp5o']}, 23:36:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'tests': ['tp5o']}, 23:36:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'talos_options': ['--disable-e10s', 23:36:27 INFO - '--xperf_path', 23:36:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:36:27 INFO - 'tests': ['tp5n']}, 23:36:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:27 INFO - 'talos_options': ['--xperf_path', 23:36:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:36:27 INFO - 'tests': ['tp5n']}}, 23:36:27 INFO - 'talos.zip': {'path': '', 23:36:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:36:27 INFO - Running post-action listener: _resource_record_post_action 23:36:27 INFO - ##### 23:36:27 INFO - ##### Finished populate-webroot step (success) 23:36:27 INFO - ##### 23:36:27 INFO - ##### 23:36:27 INFO - ##### Running create-virtualenv step. 23:36:27 INFO - ##### 23:36:27 INFO - Running pre-action listener: _resource_record_pre_action 23:36:27 INFO - Running main action method: create_virtualenv 23:36:27 INFO - Creating virtualenv /builds/slave/test/build/venv 23:36:27 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 23:36:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:36:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:36:27 INFO - Using real prefix '/usr' 23:36:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:36:28 INFO - Installing distribute.............................................................................................................................................................................................done. 23:36:30 INFO - Installing pip.................done. 23:36:30 INFO - Return code: 0 23:36:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:36:30 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')]} 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 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')]} 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:30 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 23:36:30 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 23:36:30 INFO - Using env: {'DISPLAY': ':0', 23:36:30 INFO - 'HOME': '/home/cltbld', 23:36:30 INFO - 'LANG': 'en_US.UTF-8', 23:36:30 INFO - 'LANGUAGE': 'en_US:en', 23:36:30 INFO - 'LOGNAME': 'cltbld', 23:36:30 INFO - 'MAIL': '/var/mail/cltbld', 23:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:30 INFO - 'MOZ_NO_REMOTE': '1', 23:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:30 INFO - 'NO_EM_RESTART': '1', 23:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:30 INFO - 'PWD': '/builds/slave/test', 23:36:30 INFO - 'SHELL': '/bin/bash', 23:36:30 INFO - 'SHLVL': '1', 23:36:30 INFO - 'TERM': 'linux', 23:36:30 INFO - 'TMOUT': '86400', 23:36:30 INFO - 'USER': 'cltbld', 23:36:30 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:30 INFO - '_': '/tools/buildbot/bin/python'} 23:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:30 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 23:36:30 INFO - Cleaning up... 23:36:30 INFO - Return code: 0 23:36:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:36:30 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')]} 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 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')]} 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:30 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 23:36:30 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 23:36:30 INFO - Using env: {'DISPLAY': ':0', 23:36:30 INFO - 'HOME': '/home/cltbld', 23:36:30 INFO - 'LANG': 'en_US.UTF-8', 23:36:30 INFO - 'LANGUAGE': 'en_US:en', 23:36:30 INFO - 'LOGNAME': 'cltbld', 23:36:30 INFO - 'MAIL': '/var/mail/cltbld', 23:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:30 INFO - 'MOZ_NO_REMOTE': '1', 23:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:30 INFO - 'NO_EM_RESTART': '1', 23:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:30 INFO - 'PWD': '/builds/slave/test', 23:36:30 INFO - 'SHELL': '/bin/bash', 23:36:30 INFO - 'SHLVL': '1', 23:36:30 INFO - 'TERM': 'linux', 23:36:30 INFO - 'TMOUT': '86400', 23:36:30 INFO - 'USER': 'cltbld', 23:36:30 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:30 INFO - '_': '/tools/buildbot/bin/python'} 23:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:30 INFO - Downloading/unpacking psutil>=3.1.1 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:36:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:36:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:36:32 INFO - Installing collected packages: psutil 23:36:32 INFO - Running setup.py install for psutil 23:36:32 INFO - building 'psutil._psutil_linux' extension 23:36:32 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 23:36:33 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 23:36:33 INFO - building 'psutil._psutil_posix' extension 23:36:33 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 23:36:33 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 23:36:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:36:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:36:33 INFO - Successfully installed psutil 23:36:33 INFO - Cleaning up... 23:36:34 INFO - Return code: 0 23:36:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:36:34 INFO - Using env: {'DISPLAY': ':0', 23:36:34 INFO - 'HOME': '/home/cltbld', 23:36:34 INFO - 'LANG': 'en_US.UTF-8', 23:36:34 INFO - 'LANGUAGE': 'en_US:en', 23:36:34 INFO - 'LOGNAME': 'cltbld', 23:36:34 INFO - 'MAIL': '/var/mail/cltbld', 23:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:34 INFO - 'MOZ_NO_REMOTE': '1', 23:36:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:34 INFO - 'NO_EM_RESTART': '1', 23:36:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:34 INFO - 'PWD': '/builds/slave/test', 23:36:34 INFO - 'SHELL': '/bin/bash', 23:36:34 INFO - 'SHLVL': '1', 23:36:34 INFO - 'TERM': 'linux', 23:36:34 INFO - 'TMOUT': '86400', 23:36:34 INFO - 'USER': 'cltbld', 23:36:34 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:34 INFO - '_': '/tools/buildbot/bin/python'} 23:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:36:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:36:36 INFO - Installing collected packages: mozsystemmonitor 23:36:36 INFO - Running setup.py install for mozsystemmonitor 23:36:36 INFO - Successfully installed mozsystemmonitor 23:36:36 INFO - Cleaning up... 23:36:36 INFO - Return code: 0 23:36:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:36 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:36 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 23:36:36 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 23:36:36 INFO - Using env: {'DISPLAY': ':0', 23:36:36 INFO - 'HOME': '/home/cltbld', 23:36:36 INFO - 'LANG': 'en_US.UTF-8', 23:36:36 INFO - 'LANGUAGE': 'en_US:en', 23:36:36 INFO - 'LOGNAME': 'cltbld', 23:36:36 INFO - 'MAIL': '/var/mail/cltbld', 23:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:36 INFO - 'MOZ_NO_REMOTE': '1', 23:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:36 INFO - 'NO_EM_RESTART': '1', 23:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:36 INFO - 'PWD': '/builds/slave/test', 23:36:36 INFO - 'SHELL': '/bin/bash', 23:36:36 INFO - 'SHLVL': '1', 23:36:36 INFO - 'TERM': 'linux', 23:36:36 INFO - 'TMOUT': '86400', 23:36:36 INFO - 'USER': 'cltbld', 23:36:36 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:36 INFO - '_': '/tools/buildbot/bin/python'} 23:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:36 INFO - Downloading/unpacking jsonschema==2.5.1 23:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:36:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:36:38 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 23:36:38 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 23:36:38 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 23:36:38 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 23:36:38 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 23:36:38 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 23:36:38 INFO - Downloading functools32-3.2.3-2.tar.gz 23:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:36:38 INFO - warning: no files found matching '*.txt' 23:36:38 INFO - no previously-included directories found matching 'build' 23:36:38 INFO - no previously-included directories found matching 'dist' 23:36:38 INFO - no previously-included directories found matching '.git*' 23:36:38 INFO - Installing collected packages: jsonschema, functools32 23:36:38 INFO - Running setup.py install for functools32 23:36:38 INFO - warning: no files found matching '*.txt' 23:36:38 INFO - no previously-included directories found matching 'build' 23:36:38 INFO - no previously-included directories found matching 'dist' 23:36:38 INFO - no previously-included directories found matching '.git*' 23:36:39 INFO - Successfully installed jsonschema functools32 23:36:39 INFO - Cleaning up... 23:36:39 INFO - Return code: 0 23:36:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:39 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:39 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 23:36:39 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 23:36:39 INFO - Using env: {'DISPLAY': ':0', 23:36:39 INFO - 'HOME': '/home/cltbld', 23:36:39 INFO - 'LANG': 'en_US.UTF-8', 23:36:39 INFO - 'LANGUAGE': 'en_US:en', 23:36:39 INFO - 'LOGNAME': 'cltbld', 23:36:39 INFO - 'MAIL': '/var/mail/cltbld', 23:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:39 INFO - 'MOZ_NO_REMOTE': '1', 23:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:39 INFO - 'NO_EM_RESTART': '1', 23:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:39 INFO - 'PWD': '/builds/slave/test', 23:36:39 INFO - 'SHELL': '/bin/bash', 23:36:39 INFO - 'SHLVL': '1', 23:36:39 INFO - 'TERM': 'linux', 23:36:39 INFO - 'TMOUT': '86400', 23:36:39 INFO - 'USER': 'cltbld', 23:36:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:39 INFO - '_': '/tools/buildbot/bin/python'} 23:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:39 INFO - Downloading/unpacking blobuploader==1.2.4 23:36:39 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 23:36:39 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 23:36:39 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 23:36:39 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 23:36:39 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 23:36:39 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 23:36:41 INFO - Downloading blobuploader-1.2.4.tar.gz 23:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:36:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:36:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 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 23:36:41 INFO - Downloading docopt-0.6.1.tar.gz 23:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:36:41 INFO - Installing collected packages: blobuploader, requests, docopt 23:36:41 INFO - Running setup.py install for blobuploader 23:36:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:36:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:36:42 INFO - Running setup.py install for requests 23:36:42 INFO - Running setup.py install for docopt 23:36:42 INFO - Successfully installed blobuploader requests docopt 23:36:42 INFO - Cleaning up... 23:36:42 INFO - Return code: 0 23:36:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:42 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')]} 23:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:42 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')]} 23:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:42 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:42 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 23:36:42 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 23:36:42 INFO - Using env: {'DISPLAY': ':0', 23:36:42 INFO - 'HOME': '/home/cltbld', 23:36:42 INFO - 'LANG': 'en_US.UTF-8', 23:36:42 INFO - 'LANGUAGE': 'en_US:en', 23:36:42 INFO - 'LOGNAME': 'cltbld', 23:36:42 INFO - 'MAIL': '/var/mail/cltbld', 23:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:42 INFO - 'MOZ_NO_REMOTE': '1', 23:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:42 INFO - 'NO_EM_RESTART': '1', 23:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:42 INFO - 'PWD': '/builds/slave/test', 23:36:42 INFO - 'SHELL': '/bin/bash', 23:36:42 INFO - 'SHLVL': '1', 23:36:42 INFO - 'TERM': 'linux', 23:36:42 INFO - 'TMOUT': '86400', 23:36:42 INFO - 'USER': 'cltbld', 23:36:42 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:42 INFO - '_': '/tools/buildbot/bin/python'} 23:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:42 INFO - Running setup.py (path:/tmp/pip-9Ln3Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:42 INFO - Running setup.py (path:/tmp/pip-GvYnMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:42 INFO - Running setup.py (path:/tmp/pip-xY1jnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:42 INFO - Running setup.py (path:/tmp/pip-yPf4So-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:43 INFO - Running setup.py (path:/tmp/pip-j7GXQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:43 INFO - Running setup.py (path:/tmp/pip-COx2hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:43 INFO - Running setup.py (path:/tmp/pip-KZe0ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:43 INFO - Running setup.py (path:/tmp/pip-PUdeBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:43 INFO - Running setup.py (path:/tmp/pip-0KtrUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:43 INFO - Running setup.py (path:/tmp/pip-36mp8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:43 INFO - Running setup.py (path:/tmp/pip-_k5tLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:43 INFO - Running setup.py (path:/tmp/pip-MUZ9Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:43 INFO - Running setup.py (path:/tmp/pip-gmkBhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:43 INFO - Running setup.py (path:/tmp/pip-hrNDxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:43 INFO - Running setup.py (path:/tmp/pip-3zmTF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:43 INFO - Running setup.py (path:/tmp/pip-oysIxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:43 INFO - Running setup.py (path:/tmp/pip-Lz_sNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:36:44 INFO - Running setup.py install for manifestparser 23:36:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Running setup.py install for mozcrash 23:36:44 INFO - Running setup.py install for mozdebug 23:36:44 INFO - Running setup.py install for mozdevice 23:36:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Running setup.py install for mozfile 23:36:44 INFO - Running setup.py install for mozhttpd 23:36:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Running setup.py install for mozinfo 23:36:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Running setup.py install for mozInstall 23:36:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:36:44 INFO - Running setup.py install for mozleak 23:36:44 INFO - Running setup.py install for mozlog 23:36:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Running setup.py install for moznetwork 23:36:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Running setup.py install for mozprocess 23:36:45 INFO - Running setup.py install for mozprofile 23:36:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Running setup.py install for mozrunner 23:36:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Running setup.py install for mozscreenshot 23:36:45 INFO - Running setup.py install for moztest 23:36:45 INFO - Running setup.py install for mozversion 23:36:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:36:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:36:45 INFO - Cleaning up... 23:36:45 INFO - Return code: 0 23:36:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:45 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')]} 23:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:45 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')]} 23:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:45 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:45 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 23:36:45 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 23:36:45 INFO - Using env: {'DISPLAY': ':0', 23:36:45 INFO - 'HOME': '/home/cltbld', 23:36:45 INFO - 'LANG': 'en_US.UTF-8', 23:36:45 INFO - 'LANGUAGE': 'en_US:en', 23:36:45 INFO - 'LOGNAME': 'cltbld', 23:36:45 INFO - 'MAIL': '/var/mail/cltbld', 23:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:45 INFO - 'MOZ_NO_REMOTE': '1', 23:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:45 INFO - 'NO_EM_RESTART': '1', 23:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:45 INFO - 'PWD': '/builds/slave/test', 23:36:45 INFO - 'SHELL': '/bin/bash', 23:36:45 INFO - 'SHLVL': '1', 23:36:45 INFO - 'TERM': 'linux', 23:36:45 INFO - 'TMOUT': '86400', 23:36:45 INFO - 'USER': 'cltbld', 23:36:45 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:45 INFO - '_': '/tools/buildbot/bin/python'} 23:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:46 INFO - Running setup.py (path:/tmp/pip-PpwMfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:46 INFO - Running setup.py (path:/tmp/pip-Y2Df1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:46 INFO - Running setup.py (path:/tmp/pip-pf_qkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:46 INFO - Running setup.py (path:/tmp/pip-SXoPf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:46 INFO - Running setup.py (path:/tmp/pip-_H4yvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:46 INFO - Running setup.py (path:/tmp/pip-KNLFoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:46 INFO - Running setup.py (path:/tmp/pip-ciSARP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:46 INFO - Running setup.py (path:/tmp/pip-4_3hSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:46 INFO - Running setup.py (path:/tmp/pip-mAmm_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:46 INFO - Running setup.py (path:/tmp/pip-38bJNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:46 INFO - Running setup.py (path:/tmp/pip-RZJUap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:46 INFO - Running setup.py (path:/tmp/pip-DZcLZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:46 INFO - Running setup.py (path:/tmp/pip-tZsQLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:46 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)) 23:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:46 INFO - Running setup.py (path:/tmp/pip-2BB6Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:47 INFO - Running setup.py (path:/tmp/pip-WKNYyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:47 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)) 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:47 INFO - Running setup.py (path:/tmp/pip-qKoU_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:47 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)) 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:47 INFO - Running setup.py (path:/tmp/pip-tgyK1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:47 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)) 23:36:47 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)) 23:36:47 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)) 23:36:47 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)) 23:36:47 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)) 23:36:47 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)) 23:36:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:36:47 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 23:36:47 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 23:36:47 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 23:36:47 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 23:36:47 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 23:36:47 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 23:36:49 INFO - Downloading blessings-1.6.tar.gz 23:36:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:36:49 INFO - Installing collected packages: blessings 23:36:49 INFO - Running setup.py install for blessings 23:36:49 INFO - Successfully installed blessings 23:36:49 INFO - Cleaning up... 23:36:49 INFO - Return code: 0 23:36:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:36:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:49 INFO - Reading from file tmpfile_stdout 23:36:49 INFO - Current package versions: 23:36:49 INFO - argparse == 1.2.1 23:36:49 INFO - blessings == 1.6 23:36:49 INFO - blobuploader == 1.2.4 23:36:49 INFO - docopt == 0.6.1 23:36:49 INFO - functools32 == 3.2.3-2 23:36:49 INFO - jsonschema == 2.5.1 23:36:49 INFO - manifestparser == 1.1 23:36:49 INFO - mozInstall == 1.12 23:36:49 INFO - mozcrash == 0.17 23:36:49 INFO - mozdebug == 0.1 23:36:49 INFO - mozdevice == 0.48 23:36:49 INFO - mozfile == 1.2 23:36:49 INFO - mozhttpd == 0.7 23:36:49 INFO - mozinfo == 0.9 23:36:49 INFO - mozleak == 0.1 23:36:49 INFO - mozlog == 3.2 23:36:49 INFO - moznetwork == 0.27 23:36:49 INFO - mozprocess == 0.22 23:36:49 INFO - mozprofile == 0.28 23:36:49 INFO - mozrunner == 6.11 23:36:49 INFO - mozscreenshot == 0.1 23:36:49 INFO - mozsystemmonitor == 0.3 23:36:49 INFO - moztest == 0.7 23:36:49 INFO - mozversion == 1.4 23:36:49 INFO - psutil == 3.1.1 23:36:49 INFO - requests == 1.2.3 23:36:49 INFO - wsgiref == 0.1.2 23:36:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:49 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')]} 23:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:49 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')]} 23:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:49 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:49 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 23:36:49 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 23:36:49 INFO - Using env: {'DISPLAY': ':0', 23:36:49 INFO - 'HOME': '/home/cltbld', 23:36:49 INFO - 'LANG': 'en_US.UTF-8', 23:36:49 INFO - 'LANGUAGE': 'en_US:en', 23:36:49 INFO - 'LOGNAME': 'cltbld', 23:36:49 INFO - 'MAIL': '/var/mail/cltbld', 23:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:49 INFO - 'MOZ_NO_REMOTE': '1', 23:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:49 INFO - 'NO_EM_RESTART': '1', 23:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:49 INFO - 'PWD': '/builds/slave/test', 23:36:49 INFO - 'SHELL': '/bin/bash', 23:36:49 INFO - 'SHLVL': '1', 23:36:49 INFO - 'TERM': 'linux', 23:36:49 INFO - 'TMOUT': '86400', 23:36:49 INFO - 'USER': 'cltbld', 23:36:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:49 INFO - '_': '/tools/buildbot/bin/python'} 23:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 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)) 23:36:49 INFO - Cleaning up... 23:36:49 INFO - Return code: 0 23:36:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:36:49 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')]} 23:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:49 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')]} 23:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:49 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:49 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 23:36:49 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 23:36:49 INFO - Using env: {'DISPLAY': ':0', 23:36:49 INFO - 'HOME': '/home/cltbld', 23:36:49 INFO - 'LANG': 'en_US.UTF-8', 23:36:49 INFO - 'LANGUAGE': 'en_US:en', 23:36:49 INFO - 'LOGNAME': 'cltbld', 23:36:49 INFO - 'MAIL': '/var/mail/cltbld', 23:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:49 INFO - 'MOZ_NO_REMOTE': '1', 23:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:49 INFO - 'NO_EM_RESTART': '1', 23:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:49 INFO - 'PWD': '/builds/slave/test', 23:36:49 INFO - 'SHELL': '/bin/bash', 23:36:49 INFO - 'SHLVL': '1', 23:36:49 INFO - 'TERM': 'linux', 23:36:49 INFO - 'TMOUT': '86400', 23:36:49 INFO - 'USER': 'cltbld', 23:36:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:49 INFO - '_': '/tools/buildbot/bin/python'} 23:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:36:50 INFO - Cleaning up... 23:36:50 INFO - Return code: 0 23:36:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:50 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 0x7fed86ae2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', '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 23:36:50 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 23:36:50 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 23:36:50 INFO - Using env: {'DISPLAY': ':0', 23:36:50 INFO - 'HOME': '/home/cltbld', 23:36:50 INFO - 'LANG': 'en_US.UTF-8', 23:36:50 INFO - 'LANGUAGE': 'en_US:en', 23:36:50 INFO - 'LOGNAME': 'cltbld', 23:36:50 INFO - 'MAIL': '/var/mail/cltbld', 23:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:50 INFO - 'MOZ_NO_REMOTE': '1', 23:36:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:50 INFO - 'NO_EM_RESTART': '1', 23:36:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:50 INFO - 'PWD': '/builds/slave/test', 23:36:50 INFO - 'SHELL': '/bin/bash', 23:36:50 INFO - 'SHLVL': '1', 23:36:50 INFO - 'TERM': 'linux', 23:36:50 INFO - 'TMOUT': '86400', 23:36:50 INFO - 'USER': 'cltbld', 23:36:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:50 INFO - '_': '/tools/buildbot/bin/python'} 23:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:50 INFO - Downloading/unpacking flake8 23:36:50 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 23:36:50 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 23:36:50 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 23:36:50 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 23:36:50 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 23:36:50 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 23:36:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:36:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:36:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 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 23:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:36:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:36:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:36:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:36:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:36:52 INFO - no previously-included directories found matching 'docs/_build' 23:36:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:36:52 INFO - Running setup.py install for pep8 23:36:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:36:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:36:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:36:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:36:53 INFO - no previously-included directories found matching 'docs/_build' 23:36:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:36:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:36:53 INFO - Cleaning up... 23:36:53 INFO - Return code: 0 23:36:53 INFO - Running post-action listener: _resource_record_post_action 23:36:53 INFO - Running post-action listener: _start_resource_monitoring 23:36:53 INFO - Starting resource monitoring. 23:36:53 INFO - ##### 23:36:53 INFO - ##### Finished create-virtualenv step (success) 23:36:53 INFO - ##### 23:36:53 INFO - ##### 23:36:53 INFO - ##### Running install step. 23:36:53 INFO - ##### 23:36:53 INFO - Running pre-action listener: _resource_record_pre_action 23:36:53 INFO - Running main action method: install 23:36:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:53 INFO - Reading from file tmpfile_stdout 23:36:53 INFO - Detecting whether we're running mozinstall >=1.0... 23:36:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:36:53 INFO - Reading from file tmpfile_stdout 23:36:53 INFO - Output received: 23:36:53 INFO - Usage: mozinstall [options] installer 23:36:53 INFO - Options: 23:36:53 INFO - -h, --help show this help message and exit 23:36:53 INFO - -d DEST, --destination=DEST 23:36:53 INFO - Directory to install application into. [default: 23:36:53 INFO - "/builds/slave/test"] 23:36:53 INFO - --app=APP Application being installed. [default: firefox] 23:36:53 INFO - mkdir: /builds/slave/test/build/application 23:36:53 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'] 23:36:53 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 23:37:09 INFO - Reading from file tmpfile_stdout 23:37:09 INFO - Output received: 23:37:09 INFO - /builds/slave/test/build/application/firefox/firefox 23:37:09 INFO - Running post-action listener: _resource_record_post_action 23:37:09 INFO - ##### 23:37:09 INFO - ##### Finished install step (success) 23:37:09 INFO - ##### 23:37:09 INFO - ##### 23:37:09 INFO - ##### Running run-tests step. 23:37:09 INFO - ##### 23:37:09 INFO - Running pre-action listener: _resource_record_pre_action 23:37:09 INFO - Running main action method: run_tests 23:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:37:09 INFO - Python 2.7.3 23:37:09 INFO - Return code: 0 23:37:09 INFO - grabbing minidump binary from tooltool 23:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:37:09 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 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:37:09 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 23:37:09 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 23:37:09 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 23:37:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:37:09 INFO - Return code: 0 23:37:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:37:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:37:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:37:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:37:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:37:10 INFO - Return code: 0 23:37:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:37:10 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:37:10 INFO - Using env: {'DISPLAY': ':0', 23:37:10 INFO - 'HOME': '/home/cltbld', 23:37:10 INFO - 'LANG': 'en_US.UTF-8', 23:37:10 INFO - 'LANGUAGE': 'en_US:en', 23:37:10 INFO - 'LOGNAME': 'cltbld', 23:37:10 INFO - 'MAIL': '/var/mail/cltbld', 23:37:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:37:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:10 INFO - 'MOZ_NO_REMOTE': '1', 23:37:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:37:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:37:10 INFO - 'NO_EM_RESTART': '1', 23:37:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:10 INFO - 'PWD': '/builds/slave/test', 23:37:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:37:10 INFO - 'SHELL': '/bin/bash', 23:37:10 INFO - 'SHLVL': '1', 23:37:10 INFO - 'TERM': 'linux', 23:37:10 INFO - 'TMOUT': '86400', 23:37:10 INFO - 'USER': 'cltbld', 23:37:10 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471', 23:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:10 INFO - '_': '/tools/buildbot/bin/python'} 23:37:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:37:10 INFO - mozversion application_buildid: 20160710205425 23:37:10 INFO - mozversion application_changeset: 11c6cfe72f401b89242a92b9def96768f01b4dc3 23:37:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:37:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:37:10 INFO - mozversion application_name: Firefox 23:37:10 INFO - mozversion application_remotingname: firefox-dev 23:37:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:37:10 INFO - mozversion application_vendor: Mozilla 23:37:10 INFO - mozversion application_version: 49.0a2 23:37:10 INFO - mozversion platform_buildid: 20160710205425 23:37:10 INFO - mozversion platform_changeset: 11c6cfe72f401b89242a92b9def96768f01b4dc3 23:37:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:37:10 INFO - mozversion platform_version: 49.0a2 23:37:10 INFO - using testdate: 1468219030 23:37:10 INFO - actual date: 1468219030 23:37:10 INFO - starting webserver on 'localhost:47730' 23:37:10 INFO - SUITE-START | Running 2 tests 23:37:10 INFO - TEST-START | dromaeo_css 23:37:10 INFO - operating with platform_type : linux_ 23:37:10 INFO - Initialising browser for dromaeo_css test... 23:37:10 INFO - TEST-INFO | started process 10785 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5I2MCA/profile http://localhost:47730/getInfo.html) 23:37:15 INFO - PROCESS | 10785 | __metrics Screen width/height:1600/1200 23:37:15 INFO - PROCESS | 10785 | colorDepth:24 23:37:15 INFO - PROCESS | 10785 | Browser inner width/height: 1024/697 23:37:15 INFO - PROCESS | 10785 | __metrics 23:37:16 ERROR - PROCESS | 10785 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 23:37:17 INFO - TEST-INFO | 10785: exit 0 23:37:17 INFO - Browser initialized. 23:37:17 INFO - Running cycle 1/1 for dromaeo_css test... 23:37:17 INFO - TEST-INFO | started process 10921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5I2MCA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:28 INFO - PROCESS | 10921 | RSS: Main: 155590656 23:37:28 INFO - PROCESS | 10921 | 23:38:18 INFO - PROCESS | 10921 | 1468219098587 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] 23:40:26 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47730/tests/dromaeo/cssquery-ext.html) 23:40:27 INFO - PROCESS | 10921 | RSS: Main: 159465472 23:40:27 INFO - PROCESS | 10921 | 23:43:29 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-ext.html (next: http://localhost:47730/tests/dromaeo/cssquery-jquery.html) 23:43:30 INFO - PROCESS | 10921 | RSS: Main: 158265344 23:43:30 INFO - PROCESS | 10921 | 23:46:32 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47730/tests/dromaeo/cssquery-mootools.html) 23:46:32 INFO - PROCESS | 10921 | RSS: Main: 157839360 23:46:32 INFO - PROCESS | 10921 | 23:49:34 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47730/tests/dromaeo/cssquery-prototype.html) 23:49:35 INFO - PROCESS | 10921 | RSS: Main: 156938240 23:49:35 INFO - PROCESS | 10921 | 23:52:37 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47730/tests/dromaeo/cssquery-yui.html) 23:52:38 INFO - PROCESS | 10921 | RSS: Main: 158863360 23:52:38 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | Cycle 1(1): loaded http://localhost:47730/tests/dromaeo/cssquery-yui.html (next: http://localhost:47730/tests/dromaeo/cssquery-dojo.html) 23:55:40 INFO - PROCESS | 10921 | RSS: Main: 159068160 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | __start_tp_report 23:55:40 INFO - PROCESS | 10921 | _x_x_mozilla_page_load 23:55:40 INFO - PROCESS | 10921 | _x_x_mozilla_page_load_details 23:55:40 INFO - PROCESS | 10921 | |i|pagename|runs| 23:55:40 INFO - PROCESS | 10921 | |0;dojo.html;3351.329734053189;3381.391349557082;3415.5730533683295;3417.7607567470272;3422.55506784118;5535.833924982247;5597.21638970544;5620.522255608273;5657.886842263152;5662.065759149746;5676.063449530833;5718.685472299022;5721.399253078429;5721.513671337931;5738.110592858103;5127.077126117298;5165.121929272014;5208.322918020652;5218.530332270114;5228.320318358627;4990.625703072266;4990.65065445419;5226.216067589873;5234.528892399683;5258.579313654917;5121.923171152417;5165.793368265265;5191.896162076756;5204.453532379102;5205.557527610139;5209.374875014984;5271.051210782039;5293.602979776532;5299.417064122943;5329.866753331159;4944.961558072773;5157.922631160535;5210.687358758487;5254.369475662945;5262.815801446931;5407.107827208506;5501.174823776426;5529.391766905677;5549.861253468655;5616.550675945955;6098.298695650013;6144.385561443864;6150.50795936324;6151.231096112986;6189.721462534197;6196.845078873019;6204.906926396107;6209.410106039919;6210.596311239755;6210.875782484325;5510.090835012218;5588.385277619459;5637.971810140964;5658.462446067617;5699.316082070095;6215.502759779207;6220.595661282002;6223.222097237845;6228.159198508143;6249.125122482856;5729.054705973468;5931.911021334683;5984.072468767348;5984.73068711909;6008.128821320884;6022.5483088768515;6040.426159514839;6092.573519853657;6145.10895920098;6147.293061297919;6047.092936059538;6113.480354169857;6118.3269840317525;6119.44924956756;6135.723892424851;5779.190913272145;6002.4897883679505;6121.081837724377;6144.8771024579255;6157.568970172135;4638.6752927294765;4700.106979673919;4708.693934894221;4710.905781884411;4713.505081966344;4209.915801683949;4229.809658565372;4230.7884605770205;4236.851710190129;4250.872473825791;5660.490555849993;5756.194132821408;5794.333651630117;5886.735096920649;5898.380670029671;5881.5000724938;6079.513638908877;6113.694315284218;6130.398136742002;6161.291451483136;5878.11828225761;5894.941050589525;5905.793297234576;5908.911366329508;5933.970330148321;4997.100521906019;5230.476952304815;5251.133562962144;5282.550983166397;5317.175837745156;6317.399847014526;6423.710933008026;6443.677816109176;6447.2907980122145;6459.903101453482;6170.10533472644;6191.9690401547705;6239.7504099837315;6243.063540468966;6245.437910588069;6054.939450605614;6143.38905964972;6206.968965155326;6256.562040657111;6418;5977.22296101504;5981.491573216152;5985.371535988833;5993.340732519507;6003.459688628044;6078.7264573093435;6086.878262434815;6095.664738439428;6103.664298463449;6114.847128821637;5692.800751973661;5942.61373010753;5944.167816505779;5992.850178745566;6065.120557519223;5931.13998470228;5964.373740757158;5994.580379373577;6023.5181185505935;6052.273727152769;5654.519365853784;5912.586118971631;5938.198343223609;5948.315943666534;5979.1031345296815;5933.199018132669;5947.345791962967;5961.4038596140035;6030.8793824122395;6040.335563087969;5921.6743079131065;5924.289085309791;5938.881222375442;5945.613535120204;5948.196993405835 23:55:40 INFO - PROCESS | 10921 | |1;ext.html;642.5951650460211;648.951328650351;664.2129077043702;669.323982777395;669.4577392312225;38085;39629;42690;42828.57171428285;42961.570384296145;44267.55732442674;45112.3233151503;45158.774206128925;45176;45384;8686.391952563306;8902.643894244222;8912.930448346207;8932.910670893272;8937.95531022344;8780.824383512358;8837.690680826172;8869.866951995726;8881.777955551099;8898.243649289818;8902.554872256362;8903.821923561525;8919.910800891972;8936.329775266848;8958.955205223965;8821.2942964563;8890.73327800167;8918;8931.64273429062;8937.195652391283;8754.868676969787;8809.955950220208;8811.735647930509;8836.072212417668;8853.645854165825;38505.80747096246;41275;41703.7914810424;41738.373924391155;41789;3465.9480107798404;3639.2357604903004;3657.8902632921054;3745.3445647011936;3748.456473811282;31522.684773152432;32449.6755032449;32528.837355813306;32639.204843733583;33215.169620759436;4448.666350023761;4457.910841783146;4469.642428605704;4483.39474170986;4499.932501012488;330735;330819;333169;333773.3311333452;334318;38189.66336178276;38379;38503.229935401134;38599.6140038596;38731.2253754929;39011.21977560433;41717.165656686695;41899.58100419018;41986.79006604947;42053.57946420558;17660.470185894443;17933.910330448525;17959.102044897703;17997.460076197734;18003.36988205433;14799.630009249855;14939;14940.477083302032;14944.103353798806;14955.102693838404;9257.722268331961;9385.15533601979;9404.670836520689;9443;9450.669226577174;3554.8578056877955;3568.3220188164164;3579.713622910161;3587.443946188345;3681.355762741563;1136.1308598921833;1141.2695874640237;1200.9099317551218;1218.4334284557724;1233.1614502138632;34245.82877085599;34252.486212706826;34291;34523.30953380918;34677;1198.3648666206925;1249.262934868432;1285.1646429820692;1351.6485713714308;1363.7068030373516;17906.55233619144;17964.461066168034;17967.371142009968;17983.73024404635;18237.7264341035;589194;590694;598374;658547;697944;60257.698711506164;65874.67062664752;66166;66359.33640663531;66411.33588664148;1500.3923411018554;1558.6648870492897;1574.9842501574838;1576.3852097681913;1598.1130472587824;32823;33665.66334336625;33981.83009084939;34051.4892276621;34435.13912152216;54851.17723234075;54894.72552637211;55036;55205.447945521635;55394;463299;467503;467549;471998;473665;45358;45736.31395529136;46638.53361466342;46804.06391872212;47343.28985065158;50810.237846433076;50990.74504627453;51252;51347;52232;41889;42801.785991069846;43198;43659.78170109129;44039.119217614825;41700.37449438321;42132;42570.787146064074;43452.78273608612;43597.56402436062;42929.35605965975;43766.56233437752;44140.33789493224;44393.778031109636;44474.55525444704;37074.814625926694;37273.81363093276;37409.69066082674;37886.62113378831;38448.80775596104;29270;29303.267418314714;29350.706492934798;29594.96417625459;29716.40567188601 23:55:40 INFO - PROCESS | 10921 | |2;jquery.html;405.8214385670305;408.3344149037069;408.4751094843127;420.222588211808;435.6645383055048;19141.808581914178;20237.39287821364;20485.283015094476;20686;21110.26114086007;19113.90443047786;19228.32700855467;19381.127849246783;19973.90013049936;19975.101120449577;5762.683052432115;5965.3736357682255;5990.161377407167;5995.52035837132;6002.339742628307;5867.88264234715;5914.408559144094;6022.638641681491;7170.462215333845;7190.388816950565;7002.894956575656;7166.928330716678;7260.165081015698;7317.926820731805;7351.301626345516;7121.715131394738;7236.240194779525;7241.203467618584;7272.927270727304;7377.077865266841;7362.042934418545;7380.225076367012;7411.48119631626;7425.74009909656;7460.738874139433;25895.870520647466;26708.86645566779;27433.588496172575;27568.72431275702;27708.445831083467;2422.5881600127846;2601.752833480816;2808.7893407994484;2970.287131088523;2974.5092059810104;16497;16797.496075117644;16863.747043794352;17106.657866842717;17145.571360716083;2090.5296308330658;2201.482651576878;2318.038014224095;2335.6963594732574;2337.5909215887323;77979;88103;92965.99999999933;101199;102905.48547257215;18069;18679.252829886926;18964.43106706801;19052.80947190538;19054.714179287323;35485.46771798425;35621.28757424837;36258.456123158176;36325;36376;10914.454277286104;11498.080153587693;11510.19428640004;11552.306861588331;11672.474738636613;9747.512624368896;9817.558209880572;9826.06652368024;9907.108360247612;9975.900240997642;9334.299927505464;9374.95312523433;9410.576524056434;9548;9580.66467673628;3470.774399664014;3604.387254166798;3755.282270552134;3789.696824254053;3926.960730392717;1064.7657515346611;1089.4661615808354;1167.6613782003124;1174.2015429508017;1176.7411169542686;17828.91085544564;19397.418077457416;19430.514237143903;19841.702374464396;20150.69773953392;1087.7987572298991;1089.9237053406343;1092.2627226621998;1095.6055819904827;1123.5955056179769;10966.835497467544;11031.503582338813;11070.833937490783;11163.83254251187;11209.327440353605;518550;529580;530860;539185;543081;58679.70660146672;99722.5013874926;104582.47708761407;105182;106847.46576267069;1053.920955928293;1070.9303895246785;1074.6883403812963;1100.5212732461405;1106.4025426269884;17577.29690812353;17807;17847.821521784615;18104.366347177787;18151.546211344823;41043.179136416504;42950.35574466448;43077.56922430861;43787;45032.324515132954;61783.691081544304;62154;62689.68655156695;62803.6859815698;63407;8548.829023419621;8759.343049271203;8796.384253102222;8881.156290152425;8973.775655608662;9920.454375009154;13025.674358141123;13299.867001329863;13342.93256539455;13854.376553054935;11872;11914.821277680669;12161.574344897886;12204.75590488203;12492.25046497213;8977.339192249454;11426.600068997544;11533.711657208636;11906.702332441411;11948.76102477963;12129.575464858688;12258.387080646111;12297.569585064837;12590.622281331574;12739.012664047883;8651.69719059855;9080.818383632422;9091.409058411182;9241.676541321021;9497.667581634609;9766;12246.387680615755;12276.202046866925;12393.318367489876;12484.56304029391 23:55:40 INFO - PROCESS | 10921 | |3;mootools.html;1574.7874037005004;1599.2803238542656;1820.563064864433;1905.2379048380656;1915.0329083812674;8114.269715725583;8389.580520973957;9073.818523629532;9696.224302055836;9764.414135151896;8879.91120088799;9258.120478554527;9437.81124377512;9692;9695.612175512988;2441.59713647248;2482.056818409002;2498.113169824713;2501.962470562943;2508.548461276969;2490.452100537888;2493.2146373892256;2518.44594189279;2522.823402361835;2528.292078218102;2432.963505547418;2457.7173625033083;2489.9253022409357;2492.53888030714;2514.8365356251784;2347.213683416058;2348.6829278047503;2354.8704821234824;2398.760123987605;2399.2802159352123;2454.6563481112657;2466.531359041794;2477.355887469253;2488.9751102488926;2506.8495890246645;9520.190783783386;9569.569369378325;9606.75983100421;9609.807803843954;9615.326927115104;5556.611037227396;5632.633878797865;5644.971775141139;5648.971755141239;5650.943490565083;3498.5451891253974;3551.6448355164534;3575.302815950891;3590.7486475946694;3665.505156803824;1258.3519487463964;1268.22448571999;1291.3026965438653;1297.5558711413466;1310.2073245686413;13408.798868016987;13421.66445838852;13481.865181348257;13510.864891351157;13583;4857.125717370836;4870.951290487121;4879.707217566886;4945.1346014448045;4952.678075925125;10509.579616815394;10569.101626361846;10639.468026598639;10668.679939601669;10672;5574.58190635704;5578.41426650204;5598.1322894951345;5622.437756224426;5627.396192085318;4509.2559727644575;5062.594992400599;5151.896962060738;5208.036513244986;5218.321618189611;4118.979405102956;4129.401236820644;4147.730397524211;4161.417401563784;4177.268977928911;1189.3636904256182;1192.3442106841203;1193.8089905615059;1197.9101567382481;1203.3862730007759;1034.8344264917578;1091.1598069486452;1099.5052226498108;1135.4549816088324;1142.9998751092794;6223.190985171989;6259.123722678829;6317.747290108436;6483.967580162069;6537.187113257973;1054.435876805905;1076.4832880217382;1082.09644946469;1108.174410064491;1124.6344937895217;4410.735355878657;4416.183006143874;4439.933400998988;4440.156370289635;4503.392042074343;11420.771584568261;11655.184137110491;11737.354445505409;11766.764664706658;11817.409129543488;11558.595449159238;11603.709907252387;11658.242214256046;11665;11709.297442153498;7933.682652693828;8161.428699990944;8243.629036693363;8336.708215212439;8362.331013518904;6296.244450665949;6646.102776125162;6659.267480577036;6765.2896445872475;6776.796696099125;10282.640107596199;10569.048785609331;10675.252732308663;10701.464926753475;10786.083182929538;10837.403942783223;10854.18593605467;10854.34873907568;10877.564897404016;10960.725981850517;7251.8187045324285;7788.143304236416;8107.594620268845;8189.549574773207;8259.339252859876;9086.45481271126;9158.358914875895;9172.35793494449;9225.49259790718;9232.399894006869;8083;8314.958425207835;8347.749567512741;8410.369222308229;8418.284445822173;8040.075391330072;8179.386546008954;8180.631871565674;8257.298129659046;8313;7596.620168991418;7695.615219239127;7701.037370328709;7734.574598397142;7745.22547745221;5655.915161272666;5725.914111288251;5735.71321433935;5775.5090817281;5864.354920958605;5882.823515294548;5938.376470470711;5953.613015154001;5962.19510366095;5993.70031498432 23:55:40 INFO - PROCESS | 10921 | |4;prototype.html;347.2655333968655;347.3851283228685;348.37988380682384;353.55628685999085;354.3409258778673;5762.366139724632;5831.7958871439505;5838.854028649281;5868.207791948085;5872.003518803606;5652.463016013482;5690.886182276352;5723.627964182325;5732.627379220347;5736.311642602883;4044.737092089012;4387.890302742425;4396.054848207635;4399.560043995591;4436.378906953029;4396.1427521633295;4430.468343798749;4435.647127626073;4453.710508816933;4455.866324010285;4311.655067594585;4339.913201735963;4340.978295108536;4357.324614684729;4362.650987920974;4226.564560350693;4351.129774045204;4390.604845563915;4391.429114215163;4402.427684401039;3819.4843696100947;3854.5567259765207;3869.7097717671286;3878.2243551289853;3883.650471457582;5496.340439147288;5498.257735205736;5504.4495550445035;5553.972230138864;5570.721463926788;4699.718016918996;4983.7757300921185;4989.376327959005;5000.499950005007;5014.874628134289;5866.237389139427;5909.6158749681135;5922.437368449991;5967.850803729898;5968.940310596882;4230;4240.448741663564;4251.404803327537;4254.936175957363;4259.041715613993;6044.365341639153;6110.411293063836;6135.5398345124295;6194.907076393858;6220.813375598739;4271.10306835568;4308.095299986975;4312.827486900552;4316.69783115179;4319.632831209319;4333.133373325348;4351.086271882877;4359.607635312837;4360.411344468456;4365.406626581275;4909.68087074339;4960.603151747851;5000.474950130184;5004.224345226496;5036.84889453317;5947.613405128653;5977.970110149421;6004.969975150097;6028.758849645965;6036.939630603725;1458.5405597236836;1495.3046833222604;1509.7357962356764;1512.4630756081654;1516.113073851787;950.4629884115452;957.8601464257512;1071.49104175516;1089.3899416326883;1091.2579445976653;5781.88436231273;5794.870000349892;5835.474807267281;5861.501772349398;5881.735321910524;6048.12302216185;6154.323024467305;6177.382261773877;6178.258608966863;6192.59748116371;5705.372409035002;5795.913061304083;5802;5803.738831752581;5813.500194916142;3256.120847371244;3259.1037464696924;3265.330607225513;3279.442494775894;3318.883839065621;6098.420650038239;6313.936860631335;6314.147590075281;6323.747050118036;6353.331600727705;6140.5701600887505;6194.907076393858;6241.282252541016;6294.559380843389;6318.778842740574;6166.475849552839;6274.874502510015;6364.268109167505;6410.294867564564;6411.96794016027;6037.45662890342;6047.879042419215;6084.421979911901;6090.756369745161;6141.232345956755;6033.637981721111;6066.18106555627;6074.90415806546;6135.202423684892;6145.525073982123;6098.634081955097;6331.51209465766;6424.751337032001;6444.677766111769;6454.838629034124;4457.487388950182;4461.375407443025;4470.7764611769935;4479.46246450428;4496.595306422438;5939.227900372924;6014.63912165254;6055.666938318434;6116.388361164026;6123.601965872204;5858.414158584278;5869.73586188614;5891.9116213255975;5915.40845915405;5948.762049517971;5922.838164069997;5931.5847890648965;5939.584229103922;5951.583389162717;5959.692370734284;5601.80393686219;5707.68607726579;5931.881362372815;5934.525237981037;5939.554533410098;6061.605995610271;6064.575479716377;6070.605410648293;6089.634621922699;6090.269167699905;5746.5402767779315;5935.228420305332;5936.732847021981;5950.613210141327;6021.675231449074 23:55:40 INFO - PROCESS | 10921 | |5;yui.html;790.2650535002449;813.032491335311;840.5041025794782;852.8635418333068;879.1735768377724;16498.42255521057;16975.74536381955;17103.315867365305;17112;17719.40525352718;15688.450904218354;15748.606284842885;15792.763108553352;15827.683446331066;15841.049537027757;866.3199388480044;875.1904488348268;894.9841929410102;898.24996128233;902.6705252582825;883.4390162246988;887.5384799904047;888.3159966825541;892.2193081054078;893.0176805513915;918.0314767919875;922.4695799915042;923.353652443289;927.0868194828089;931.7204838548442;913.9314551408638;915.0254978447931;916.4409710076848;916.5234078279324;916.6745805239144;675.6655455549535;677.2110491277656;679.741698154698;682.0349205873683;682.757621044532;16911;16943.40698075574;16985.06582137982;17011.149442527825;17020.91489542557;13610.455581776718;13948.511802086878;14297.642558936006;14424.639384015378;14804.40782368704;14558.199299038548;14659.63350916225;14866.108033518023;14870.553883383407;15322.15728134952;4773.474917759044;4842.564169224752;4844.10384078943;4845.927311090301;4856.465788763233;80395;81046.59476702579;81570;83547.5822620883;84740.99999999939;12457;12678.80981785274;12724.491020359266;12732.490700371882;12887;23373.766262337158;23796;23823.880880595487;23832.40418989539;23896.283111506684;9371.906280937239;9377.202937750231;9383.296252781069;9400.623975040922;9407.29445291606;4968;5122.282880396748;5132.255822905732;5135.948640513547;5216.347956505437;3709.387950988111;3823.0633494793396;3828;3833.2525157594;3837.4435706822355;645.4674893213117;645.7804346522205;654.201873714068;655.6951666184259;667.4160109903834;725.7060890339421;728.0244472407;729.274371999853;774.9651265693058;775.4261846233859;10574.101201397967;10992.835107473395;11019.72450688723;11235.943820280847;11281.548738050386;708.5890183693446;804.4489524675616;815.7104227999093;831.6382373667474;834.0741776627938;4425.579569940851;4476;4604.263317869192;5110.105731497048;5134.845954621367;73916;74382.25617743754;118724;121792;122128;74062;81221;99736.50131749295;105903;106804.46597766962;69338.87975041135;91058.08941910496;92556.53721731348;93375.53312233531;94019.52990235006;9672.70981870545;10014.148797352307;10241.743956400851;10278.948605256926;10307.433091180057;54515;55533.1670024958;55591.44408555863;56036;57400.42599574118;77175;80351.19648803437;81393.18606814093;81493.59253203696;81841.59079204565;12645.810312845131;12648.873511265137;12954;13020.21878687244;13507;13976.437618321428;14176.291185440481;14289.857101429268;14301.64245893861;14340.713185736435;12937.417816198482;13033.087683862417;13095.738085238432;13324.200547967153;13383.598492045254;13580.79628805549;13635.795463067863;13847.86152138506;14272;14277.214753188675;12968.546100886424;13477.932610336886;13511.189328640314;14326.57020289393;14403.711925761636;12518.874811251771;12540;12818.74362512726;13029;13082.411291491715;11526.94236528812;12054.156209065632;12228.633141005785;12768.170068945488;13221.93389033087 23:55:40 INFO - PROCESS | 10921 | __end_tp_report 23:55:40 INFO - PROCESS | 10921 | __start_cc_report 23:55:40 INFO - PROCESS | 10921 | _x_x_mozilla_cycle_collect,338 23:55:40 INFO - PROCESS | 10921 | __end_cc_report 23:55:40 INFO - PROCESS | 10921 | __startTimestamp1468220140599__endTimestamp 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | ------- Summary: start ------- 23:55:40 INFO - PROCESS | 10921 | Number of tests: 6 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#0] dojo.html Cycles:165 Average:5671.15 Median:5931.14 stddev:634.43 (10.7%) stddev-sans-first:609.71 23:55:40 INFO - PROCESS | 10921 | Values: 3351.3 3381.4 3415.6 3417.8 3422.6 5535.8 5597.2 5620.5 5657.9 5662.1 5676.1 5718.7 5721.4 5721.5 5738.1 5127.1 5165.1 5208.3 5218.5 5228.3 4990.6 4990.7 5226.2 5234.5 5258.6 5121.9 5165.8 5191.9 5204.5 5205.6 5209.4 5271.1 5293.6 5299.4 5329.9 4945.0 5157.9 5210.7 5254.4 5262.8 5407.1 5501.2 5529.4 5549.9 5616.6 6098.3 6144.4 6150.5 6151.2 6189.7 6196.8 6204.9 6209.4 6210.6 6210.9 5510.1 5588.4 5638.0 5658.5 5699.3 6215.5 6220.6 6223.2 6228.2 6249.1 5729.1 5931.9 5984.1 5984.7 6008.1 6022.5 6040.4 6092.6 6145.1 6147.3 6047.1 6113.5 6118.3 6119.4 6135.7 5779.2 6002.5 6121.1 6144.9 6157.6 4638.7 4700.1 4708.7 4710.9 4713.5 4209.9 4229.8 4230.8 4236.9 4250.9 5660.5 5756.2 5794.3 5886.7 5898.4 5881.5 6079.5 6113.7 6130.4 6161.3 5878.1 5894.9 5905.8 5908.9 5934.0 4997.1 5230.5 5251.1 5282.6 5317.2 6317.4 6423.7 6443.7 6447.3 6459.9 6170.1 6192.0 6239.8 6243.1 6245.4 6054.9 6143.4 6207.0 6256.6 6418.0 5977.2 5981.5 5985.4 5993.3 6003.5 6078.7 6086.9 6095.7 6103.7 6114.8 5692.8 5942.6 5944.2 5992.9 6065.1 5931.1 5964.4 5994.6 6023.5 6052.3 5654.5 5912.6 5938.2 5948.3 5979.1 5933.2 5947.3 5961.4 6030.9 6040.3 5921.7 5924.3 5938.9 5945.6 5948.2 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#1] ext.html Cycles:180 Average:63101.82 Median:42630.39 stddev:131106.68 (307.5%) stddev-sans-first:131390.60 23:55:40 INFO - PROCESS | 10921 | Values: 642.6 649.0 664.2 669.3 669.5 38085.0 39629.0 42690.0 42828.6 42961.6 44267.6 45112.3 45158.8 45176.0 45384.0 8686.4 8902.6 8912.9 8932.9 8938.0 8780.8 8837.7 8869.9 8881.8 8898.2 8902.6 8903.8 8919.9 8936.3 8959.0 8821.3 8890.7 8918.0 8931.6 8937.2 8754.9 8810.0 8811.7 8836.1 8853.6 38505.8 41275.0 41703.8 41738.4 41789.0 3465.9 3639.2 3657.9 3745.3 3748.5 31522.7 32449.7 32528.8 32639.2 33215.2 4448.7 4457.9 4469.6 4483.4 4499.9 330735.0 330819.0 333169.0 333773.3 334318.0 38189.7 38379.0 38503.2 38599.6 38731.2 39011.2 41717.2 41899.6 41986.8 42053.6 17660.5 17933.9 17959.1 17997.5 18003.4 14799.6 14939.0 14940.5 14944.1 14955.1 9257.7 9385.2 9404.7 9443.0 9450.7 3554.9 3568.3 3579.7 3587.4 3681.4 1136.1 1141.3 1200.9 1218.4 1233.2 34245.8 34252.5 34291.0 34523.3 34677.0 1198.4 1249.3 1285.2 1351.6 1363.7 17906.6 17964.5 17967.4 17983.7 18237.7 589194.0 590694.0 598374.0 658547.0 697944.0 60257.7 65874.7 66166.0 66359.3 66411.3 1500.4 1558.7 1575.0 1576.4 1598.1 32823.0 33665.7 33981.8 34051.5 34435.1 54851.2 54894.7 55036.0 55205.4 55394.0 463299.0 467503.0 467549.0 471998.0 473665.0 45358.0 45736.3 46638.5 46804.1 47343.3 50810.2 50990.7 51252.0 51347.0 52232.0 41889.0 42801.8 43198.0 43659.8 44039.1 41700.4 42132.0 42570.8 43452.8 43597.6 42929.4 43766.6 44140.3 44393.8 44474.6 37074.8 37273.8 37409.7 37886.6 38448.8 29270.0 29303.3 29350.7 29595.0 29716.4 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#2] jquery.html Cycles:180 Average:32446.99 Median:27071.23 stddev:87648.35 (323.8%) stddev-sans-first:87861.21 23:55:40 INFO - PROCESS | 10921 | Values: 405.8 408.3 408.5 420.2 435.7 19141.8 20237.4 20485.3 20686.0 21110.3 19113.9 19228.3 19381.1 19973.9 19975.1 5762.7 5965.4 5990.2 5995.5 6002.3 5867.9 5914.4 6022.6 7170.5 7190.4 7002.9 7166.9 7260.2 7317.9 7351.3 7121.7 7236.2 7241.2 7272.9 7377.1 7362.0 7380.2 7411.5 7425.7 7460.7 25895.9 26708.9 27433.6 27568.7 27708.4 2422.6 2601.8 2808.8 2970.3 2974.5 16497.0 16797.5 16863.7 17106.7 17145.6 2090.5 2201.5 2318.0 2335.7 2337.6 77979.0 88103.0 92966.0 101199.0 102905.5 18069.0 18679.3 18964.4 19052.8 19054.7 35485.5 35621.3 36258.5 36325.0 36376.0 10914.5 11498.1 11510.2 11552.3 11672.5 9747.5 9817.6 9826.1 9907.1 9975.9 9334.3 9375.0 9410.6 9548.0 9580.7 3470.8 3604.4 3755.3 3789.7 3927.0 1064.8 1089.5 1167.7 1174.2 1176.7 17828.9 19397.4 19430.5 19841.7 20150.7 1087.8 1089.9 1092.3 1095.6 1123.6 10966.8 11031.5 11070.8 11163.8 11209.3 518550.0 529580.0 530860.0 539185.0 543081.0 58679.7 99722.5 104582.5 105182.0 106847.5 1053.9 1070.9 1074.7 1100.5 1106.4 17577.3 17807.0 17847.8 18104.4 18151.5 41043.2 42950.4 43077.6 43787.0 45032.3 61783.7 62154.0 62689.7 62803.7 63407.0 8548.8 8759.3 8796.4 8881.2 8973.8 9920.5 13025.7 13299.9 13342.9 13854.4 11872.0 11914.8 12161.6 12204.8 12492.3 8977.3 11426.6 11533.7 11906.7 11948.8 12129.6 12258.4 12297.6 12590.6 12739.0 8651.7 9080.8 9091.4 9241.7 9497.7 9766.0 12246.4 12276.2 12393.3 12484.6 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#3] mootools.html Cycles:180 Average:6198.67 Median:4413.46 stddev:3503.96 (79.4%) stddev-sans-first:3496.56 23:55:40 INFO - PROCESS | 10921 | Values: 1574.8 1599.3 1820.6 1905.2 1915.0 8114.3 8389.6 9073.8 9696.2 9764.4 8879.9 9258.1 9437.8 9692.0 9695.6 2441.6 2482.1 2498.1 2502.0 2508.5 2490.5 2493.2 2518.4 2522.8 2528.3 2433.0 2457.7 2489.9 2492.5 2514.8 2347.2 2348.7 2354.9 2398.8 2399.3 2454.7 2466.5 2477.4 2489.0 2506.8 9520.2 9569.6 9606.8 9609.8 9615.3 5556.6 5632.6 5645.0 5649.0 5650.9 3498.5 3551.6 3575.3 3590.7 3665.5 1258.4 1268.2 1291.3 1297.6 1310.2 13408.8 13421.7 13481.9 13510.9 13583.0 4857.1 4871.0 4879.7 4945.1 4952.7 10509.6 10569.1 10639.5 10668.7 10672.0 5574.6 5578.4 5598.1 5622.4 5627.4 4509.3 5062.6 5151.9 5208.0 5218.3 4119.0 4129.4 4147.7 4161.4 4177.3 1189.4 1192.3 1193.8 1197.9 1203.4 1034.8 1091.2 1099.5 1135.5 1143.0 6223.2 6259.1 6317.7 6484.0 6537.2 1054.4 1076.5 1082.1 1108.2 1124.6 4410.7 4416.2 4439.9 4440.2 4503.4 11420.8 11655.2 11737.4 11766.8 11817.4 11558.6 11603.7 11658.2 11665.0 11709.3 7933.7 8161.4 8243.6 8336.7 8362.3 6296.2 6646.1 6659.3 6765.3 6776.8 10282.6 10569.0 10675.3 10701.5 10786.1 10837.4 10854.2 10854.3 10877.6 10960.7 7251.8 7788.1 8107.6 8189.5 8259.3 9086.5 9158.4 9172.4 9225.5 9232.4 8083.0 8315.0 8347.7 8410.4 8418.3 8040.1 8179.4 8180.6 8257.3 8313.0 7596.6 7695.6 7701.0 7734.6 7745.2 5655.9 5725.9 5735.7 5775.5 5864.4 5882.8 5938.4 5953.6 5962.2 5993.7 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#4] prototype.html Cycles:180 Average:5020.84 Median:5798.96 stddev:1487.65 (25.7%) stddev-sans-first:1449.87 23:55:40 INFO - PROCESS | 10921 | Values: 347.3 347.4 348.4 353.6 354.3 5762.4 5831.8 5838.9 5868.2 5872.0 5652.5 5690.9 5723.6 5732.6 5736.3 4044.7 4387.9 4396.1 4399.6 4436.4 4396.1 4430.5 4435.6 4453.7 4455.9 4311.7 4339.9 4341.0 4357.3 4362.7 4226.6 4351.1 4390.6 4391.4 4402.4 3819.5 3854.6 3869.7 3878.2 3883.7 5496.3 5498.3 5504.4 5554.0 5570.7 4699.7 4983.8 4989.4 5000.5 5014.9 5866.2 5909.6 5922.4 5967.9 5968.9 4230.0 4240.4 4251.4 4254.9 4259.0 6044.4 6110.4 6135.5 6194.9 6220.8 4271.1 4308.1 4312.8 4316.7 4319.6 4333.1 4351.1 4359.6 4360.4 4365.4 4909.7 4960.6 5000.5 5004.2 5036.8 5947.6 5978.0 6005.0 6028.8 6036.9 1458.5 1495.3 1509.7 1512.5 1516.1 950.5 957.9 1071.5 1089.4 1091.3 5781.9 5794.9 5835.5 5861.5 5881.7 6048.1 6154.3 6177.4 6178.3 6192.6 5705.4 5795.9 5802.0 5803.7 5813.5 3256.1 3259.1 3265.3 3279.4 3318.9 6098.4 6313.9 6314.1 6323.7 6353.3 6140.6 6194.9 6241.3 6294.6 6318.8 6166.5 6274.9 6364.3 6410.3 6412.0 6037.5 6047.9 6084.4 6090.8 6141.2 6033.6 6066.2 6074.9 6135.2 6145.5 6098.6 6331.5 6424.8 6444.7 6454.8 4457.5 4461.4 4470.8 4479.5 4496.6 5939.2 6014.6 6055.7 6116.4 6123.6 5858.4 5869.7 5891.9 5915.4 5948.8 5922.8 5931.6 5939.6 5951.6 5959.7 5601.8 5707.7 5931.9 5934.5 5939.6 6061.6 6064.6 6070.6 6089.6 6090.3 5746.5 5935.2 5936.7 5950.6 6021.7 23:55:40 INFO - PROCESS | 10921 | 23:55:40 INFO - PROCESS | 10921 | [#5] yui.html Cycles:180 Average:21310.27 Median:4540.13 stddev:29606.55 (652.1%) stddev-sans-first:29649.51 23:55:40 INFO - PROCESS | 10921 | Values: 790.3 813.0 840.5 852.9 879.2 16498.4 16975.7 17103.3 17112.0 17719.4 15688.5 15748.6 15792.8 15827.7 15841.0 866.3 875.2 895.0 898.2 902.7 883.4 887.5 888.3 892.2 893.0 918.0 922.5 923.4 927.1 931.7 913.9 915.0 916.4 916.5 916.7 675.7 677.2 679.7 682.0 682.8 16911.0 16943.4 16985.1 17011.1 17020.9 13610.5 13948.5 14297.6 14424.6 14804.4 14558.2 14659.6 14866.1 14870.6 15322.2 4773.5 4842.6 4844.1 4845.9 4856.5 80395.0 81046.6 81570.0 83547.6 84741.0 12457.0 12678.8 12724.5 12732.5 12887.0 23373.8 23796.0 23823.9 23832.4 23896.3 9371.9 9377.2 9383.3 9400.6 9407.3 4968.0 5122.3 5132.3 5135.9 5216.3 3709.4 3823.1 3828.0 3833.3 3837.4 645.5 645.8 654.2 655.7 667.4 725.7 728.0 729.3 775.0 775.4 10574.1 10992.8 11019.7 11235.9 11281.5 708.6 804.4 815.7 831.6 834.1 4425.6 4476.0 4604.3 5110.1 5134.8 73916.0 74382.3 118724.0 121792.0 122128.0 74062.0 81221.0 99736.5 105903.0 106804.5 69338.9 91058.1 92556.5 93375.5 94019.5 9672.7 10014.1 10241.7 10278.9 10307.4 54515.0 55533.2 55591.4 56036.0 57400.4 77175.0 80351.2 81393.2 81493.6 81841.6 12645.8 12648.9 12954.0 13020.2 13507.0 13976.4 14176.3 14289.9 14301.6 14340.7 12937.4 13033.1 13095.7 13324.2 13383.6 13580.8 13635.8 13847.9 14272.0 14277.2 12968.5 13477.9 13511.2 14326.6 14403.7 12518.9 12540.0 12818.7 13029.0 13082.4 11526.9 12054.2 12228.6 12768.2 13221.9 23:55:40 INFO - PROCESS | 10921 | -------- Summary: end -------- 23:55:40 INFO - PROCESS | 10921 | 23:55:41 INFO - TEST-INFO | 10921: exit 0 23:55:41 INFO - TEST-OK | dromaeo_css | took 1110497ms 23:55:41 INFO - TEST-START | kraken 23:55:41 INFO - operating with platform_type : linux_ 23:55:41 INFO - Initialising browser for kraken test... 23:55:41 INFO - TEST-INFO | started process 11162 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBkYW8e/profile http://localhost:47730/getInfo.html) 23:55:45 INFO - PROCESS | 11162 | __metrics Screen width/height:1600/1200 23:55:45 INFO - PROCESS | 11162 | colorDepth:24 23:55:45 INFO - PROCESS | 11162 | Browser inner width/height: 1024/697 23:55:45 INFO - PROCESS | 11162 | __metrics 23:55:46 ERROR - PROCESS | 11162 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 23:55:47 INFO - TEST-INFO | 11162: exit 0 23:55:47 INFO - Browser initialized. 23:55:47 INFO - Running cycle 1/1 for kraken test... 23:55:47 INFO - TEST-INFO | started process 11290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBkYW8e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:55:59 INFO - PROCESS | 11290 | RSS: Main: 156520448 23:55:59 INFO - PROCESS | 11290 | 23:56:01 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47730/tests/kraken/driver.html?testName=audio-beat-detection) 23:56:02 INFO - PROCESS | 11290 | RSS: Main: 161792000 23:56:02 INFO - PROCESS | 11290 | 23:56:05 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47730/tests/kraken/driver.html?testName=audio-dft) 23:56:05 INFO - PROCESS | 11290 | RSS: Main: 160960512 23:56:05 INFO - PROCESS | 11290 | 23:56:08 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47730/tests/kraken/driver.html?testName=audio-fft) 23:56:09 INFO - PROCESS | 11290 | RSS: Main: 161615872 23:56:09 INFO - PROCESS | 11290 | 23:56:11 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47730/tests/kraken/driver.html?testName=audio-oscillator) 23:56:12 INFO - PROCESS | 11290 | RSS: Main: 161181696 23:56:12 INFO - PROCESS | 11290 | 23:56:14 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47730/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:56:14 INFO - PROCESS | 11290 | RSS: Main: 161452032 23:56:14 INFO - PROCESS | 11290 | 23:56:17 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47730/tests/kraken/driver.html?testName=imaging-darkroom) 23:56:18 INFO - PROCESS | 11290 | RSS: Main: 161128448 23:56:18 INFO - PROCESS | 11290 | 23:56:22 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47730/tests/kraken/driver.html?testName=imaging-desaturate) 23:56:22 INFO - PROCESS | 11290 | RSS: Main: 158543872 23:56:22 INFO - PROCESS | 11290 | 23:56:25 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47730/tests/kraken/driver.html?testName=json-parse-financial) 23:56:26 INFO - PROCESS | 11290 | RSS: Main: 158908416 23:56:26 INFO - PROCESS | 11290 | 23:56:27 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47730/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:56:28 INFO - PROCESS | 11290 | RSS: Main: 159338496 23:56:28 INFO - PROCESS | 11290 | 23:56:30 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:56:30 INFO - PROCESS | 11290 | RSS: Main: 159604736 23:56:30 INFO - PROCESS | 11290 | 23:56:32 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:56:33 INFO - PROCESS | 11290 | RSS: Main: 160092160 23:56:33 INFO - PROCESS | 11290 | 23:56:35 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:56:36 INFO - PROCESS | 11290 | RSS: Main: 160206848 23:56:36 INFO - PROCESS | 11290 | 23:56:39 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:56:39 INFO - PROCESS | 11290 | RSS: Main: 159866880 23:56:39 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | Cycle 1(1): loaded http://localhost:47730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47730/tests/kraken/driver.html?testName=ai-astar) 23:56:41 INFO - PROCESS | 11290 | RSS: Main: 160849920 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | __start_tp_report 23:56:41 INFO - PROCESS | 11290 | _x_x_mozilla_page_load 23:56:41 INFO - PROCESS | 11290 | _x_x_mozilla_page_load_details 23:56:41 INFO - PROCESS | 11290 | |i|pagename|runs| 23:56:41 INFO - PROCESS | 11290 | |0;ai-astar;113;104;112;113;112;112;114;111;112;112 23:56:41 INFO - PROCESS | 11290 | |1;audio-beat-detection;155;135;157;178;128;165;157;126;185;124 23:56:41 INFO - PROCESS | 11290 | |2;audio-dft;162;161;163;164;161;161;163;161;165;164 23:56:41 INFO - PROCESS | 11290 | |3;audio-fft;75;77;75;187;75;75;75;75;160;76 23:56:41 INFO - PROCESS | 11290 | |4;audio-oscillator;84;84;150;83;84;84;138;85;83;149 23:56:41 INFO - PROCESS | 11290 | |5;imaging-gaussian-blur;117;122;117;120;118;117;117;117;117;118 23:56:41 INFO - PROCESS | 11290 | |6;imaging-darkroom;147;146;151;147;147;147;146;146;147;148 23:56:41 INFO - PROCESS | 11290 | |7;imaging-desaturate;96;97;96;95;95;96;96;96;96;95 23:56:41 INFO - PROCESS | 11290 | |8;json-parse-financial;52;54;53;52;61;54;52;52;53;56 23:56:41 INFO - PROCESS | 11290 | |9;json-stringify-tinderbox;60;60;60;59;61;60;61;61;60;60 23:56:41 INFO - PROCESS | 11290 | |10;stanford-crypto-aes;76;76;76;76;76;81;83;79;108;77 23:56:41 INFO - PROCESS | 11290 | |11;stanford-crypto-ccm;122;130;116;123;137;125;127;123;123;129 23:56:41 INFO - PROCESS | 11290 | |12;stanford-crypto-pbkdf2;156;151;156;155;156;152;158;154;158;153 23:56:41 INFO - PROCESS | 11290 | |13;stanford-crypto-sha256-iterative;64;62;64;62;62;63;66;62;61;61 23:56:41 INFO - PROCESS | 11290 | __end_tp_report 23:56:41 INFO - PROCESS | 11290 | __start_cc_report 23:56:41 INFO - PROCESS | 11290 | _x_x_mozilla_cycle_collect,885 23:56:41 INFO - PROCESS | 11290 | __end_cc_report 23:56:41 INFO - PROCESS | 11290 | __startTimestamp1468220201719__endTimestamp 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | ------- Summary: start ------- 23:56:41 INFO - PROCESS | 11290 | Number of tests: 14 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:2.76 (2.5%) stddev-sans-first:2.87 23:56:41 INFO - PROCESS | 11290 | Values: 113.0 104.0 112.0 113.0 112.0 112.0 114.0 111.0 112.0 112.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:157.00 stddev:21.88 (13.9%) stddev-sans-first:23.16 23:56:41 INFO - PROCESS | 11290 | Values: 155.0 135.0 157.0 178.0 128.0 165.0 157.0 126.0 185.0 124.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.51 (0.9%) stddev-sans-first:1.59 23:56:41 INFO - PROCESS | 11290 | Values: 162.0 161.0 163.0 164.0 161.0 161.0 163.0 161.0 165.0 164.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:41.86 (55.8%) stddev-sans-first:43.77 23:56:41 INFO - PROCESS | 11290 | Values: 75.0 77.0 75.0 187.0 75.0 75.0 75.0 75.0 160.0 76.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:84.00 stddev:30.03 (35.7%) stddev-sans-first:31.10 23:56:41 INFO - PROCESS | 11290 | Values: 84.0 84.0 150.0 83.0 84.0 84.0 138.0 85.0 83.0 149.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:117.50 stddev:1.70 (1.4%) stddev-sans-first:1.76 23:56:41 INFO - PROCESS | 11290 | Values: 117.0 122.0 117.0 120.0 118.0 117.0 117.0 117.0 117.0 118.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.48 (1.0%) stddev-sans-first:1.56 23:56:41 INFO - PROCESS | 11290 | Values: 147.0 146.0 151.0 147.0 147.0 147.0 146.0 146.0 147.0 148.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 23:56:41 INFO - PROCESS | 11290 | Values: 96.0 97.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:2.81 (5.2%) stddev-sans-first:2.89 23:56:41 INFO - PROCESS | 11290 | Values: 52.0 54.0 53.0 52.0 61.0 54.0 52.0 52.0 53.0 56.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 23:56:41 INFO - PROCESS | 11290 | Values: 60.0 60.0 60.0 59.0 61.0 60.0 61.0 61.0 60.0 60.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:76.50 stddev:9.87 (12.9%) stddev-sans-first:10.32 23:56:41 INFO - PROCESS | 11290 | Values: 76.0 76.0 76.0 76.0 76.0 81.0 83.0 79.0 108.0 77.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:126.00 stddev:5.66 (4.5%) stddev-sans-first:5.86 23:56:41 INFO - PROCESS | 11290 | Values: 122.0 130.0 116.0 123.0 137.0 125.0 127.0 123.0 123.0 129.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.90 Median:156.00 stddev:2.38 (1.5%) stddev-sans-first:2.49 23:56:41 INFO - PROCESS | 11290 | Values: 156.0 151.0 156.0 155.0 156.0 152.0 158.0 154.0 158.0 153.0 23:56:41 INFO - PROCESS | 11290 | 23:56:41 INFO - PROCESS | 11290 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:1.57 (2.5%) stddev-sans-first:1.59 23:56:41 INFO - PROCESS | 11290 | Values: 64.0 62.0 64.0 62.0 62.0 63.0 66.0 62.0 61.0 61.0 23:56:41 INFO - PROCESS | 11290 | -------- Summary: end -------- 23:56:41 INFO - PROCESS | 11290 | 23:56:42 INFO - TEST-INFO | 11290: exit 0 23:56:42 INFO - TEST-OK | kraken | took 61318ms 23:56:42 INFO - SUITE-END | took 1171s 23:56:42 INFO - Completed test suite (00:19:32) 23:56:42 INFO - javascript benchmark 23:56:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7803.849302256456, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.329734053189, 3381.391349557082, 3415.5730533683295, 3417.7607567470272, 3422.55506784118, 5535.833924982247, 5597.21638970544, 5620.522255608273, 5657.886842263152, 5662.065759149746, 5676.063449530833, 5718.685472299022, 5721.399253078429, 5721.513671337931, 5738.110592858103, 5127.077126117298, 5165.121929272014, 5208.322918020652, 5218.530332270114, 5228.320318358627, 4990.625703072266, 4990.65065445419, 5226.216067589873, 5234.528892399683, 5258.579313654917, 5121.923171152417, 5165.793368265265, 5191.896162076756, 5204.453532379102, 5205.557527610139, 5209.374875014984, 5271.051210782039, 5293.602979776532, 5299.417064122943, 5329.866753331159, 4944.961558072773, 5157.922631160535, 5210.687358758487, 5254.369475662945, 5262.815801446931, 5407.107827208506, 5501.174823776426, 5529.391766905677, 5549.861253468655, 5616.550675945955, 6098.298695650013, 6144.385561443864, 6150.50795936324, 6151.231096112986, 6189.721462534197, 6196.845078873019, 6204.906926396107, 6209.410106039919, 6210.596311239755, 6210.875782484325, 5510.090835012218, 5588.385277619459, 5637.971810140964, 5658.462446067617, 5699.316082070095, 6215.502759779207, 6220.595661282002, 6223.222097237845, 6228.159198508143, 6249.125122482856, 5729.054705973468, 5931.911021334683, 5984.072468767348, 5984.73068711909, 6008.128821320884, 6022.5483088768515, 6040.426159514839, 6092.573519853657, 6145.10895920098, 6147.293061297919, 6047.092936059538, 6113.480354169857, 6118.3269840317525, 6119.44924956756, 6135.723892424851, 5779.190913272145, 6002.4897883679505, 6121.081837724377, 6144.8771024579255, 6157.568970172135, 4638.6752927294765, 4700.106979673919, 4708.693934894221, 4710.905781884411, 4713.505081966344, 4209.915801683949, 4229.809658565372, 4230.7884605770205, 4236.851710190129, 4250.872473825791, 5660.490555849993, 5756.194132821408, 5794.333651630117, 5886.735096920649, 5898.380670029671, 5881.5000724938, 6079.513638908877, 6113.694315284218, 6130.398136742002, 6161.291451483136, 5878.11828225761, 5894.941050589525, 5905.793297234576, 5908.911366329508, 5933.970330148321, 4997.100521906019, 5230.476952304815, 5251.133562962144, 5282.550983166397, 5317.175837745156, 6317.399847014526, 6423.710933008026, 6443.677816109176, 6447.2907980122145, 6459.903101453482, 6170.10533472644, 6191.9690401547705, 6239.7504099837315, 6243.063540468966, 6245.437910588069, 6054.939450605614, 6143.38905964972, 6206.968965155326, 6256.562040657111, 6418.0, 5977.22296101504, 5981.491573216152, 5985.371535988833, 5993.340732519507, 6003.459688628044, 6078.7264573093435, 6086.878262434815, 6095.664738439428, 6103.664298463449, 6114.847128821637, 5692.800751973661, 5942.61373010753, 5944.167816505779, 5992.850178745566, 6065.120557519223, 5931.13998470228, 5964.373740757158, 5994.580379373577, 6023.5181185505935, 6052.273727152769, 5654.519365853784, 5912.586118971631, 5938.198343223609, 5948.315943666534, 5979.1031345296815, 5933.199018132669, 5947.345791962967, 5961.4038596140035, 6030.8793824122395, 6040.335563087969, 5921.6743079131065, 5924.289085309791, 5938.881222375442, 5945.613535120204, 5948.196993405835], "value": 5629.916643871239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.5951650460211, 648.951328650351, 664.2129077043702, 669.323982777395, 669.4577392312225, 38085.0, 39629.0, 42690.0, 42828.57171428285, 42961.570384296145, 44267.55732442674, 45112.3233151503, 45158.774206128925, 45176.0, 45384.0, 8686.391952563306, 8902.643894244222, 8912.930448346207, 8932.910670893272, 8937.95531022344, 8780.824383512358, 8837.690680826172, 8869.866951995726, 8881.777955551099, 8898.243649289818, 8902.554872256362, 8903.821923561525, 8919.910800891972, 8936.329775266848, 8958.955205223965, 8821.2942964563, 8890.73327800167, 8918.0, 8931.64273429062, 8937.195652391283, 8754.868676969787, 8809.955950220208, 8811.735647930509, 8836.072212417668, 8853.645854165825, 38505.80747096246, 41275.0, 41703.7914810424, 41738.373924391155, 41789.0, 3465.9480107798404, 3639.2357604903004, 3657.8902632921054, 3745.3445647011936, 3748.456473811282, 31522.684773152432, 32449.6755032449, 32528.837355813306, 32639.204843733583, 33215.169620759436, 4448.666350023761, 4457.910841783146, 4469.642428605704, 4483.39474170986, 4499.932501012488, 330735.0, 330819.0, 333169.0, 333773.3311333452, 334318.0, 38189.66336178276, 38379.0, 38503.229935401134, 38599.6140038596, 38731.2253754929, 39011.21977560433, 41717.165656686695, 41899.58100419018, 41986.79006604947, 42053.57946420558, 17660.470185894443, 17933.910330448525, 17959.102044897703, 17997.460076197734, 18003.36988205433, 14799.630009249855, 14939.0, 14940.477083302032, 14944.103353798806, 14955.102693838404, 9257.722268331961, 9385.15533601979, 9404.670836520689, 9443.0, 9450.669226577174, 3554.8578056877955, 3568.3220188164164, 3579.713622910161, 3587.443946188345, 3681.355762741563, 1136.1308598921833, 1141.2695874640237, 1200.9099317551218, 1218.4334284557724, 1233.1614502138632, 34245.82877085599, 34252.486212706826, 34291.0, 34523.30953380918, 34677.0, 1198.3648666206925, 1249.262934868432, 1285.1646429820692, 1351.6485713714308, 1363.7068030373516, 17906.55233619144, 17964.461066168034, 17967.371142009968, 17983.73024404635, 18237.7264341035, 589194.0, 590694.0, 598374.0, 658547.0, 697944.0, 60257.698711506164, 65874.67062664752, 66166.0, 66359.33640663531, 66411.33588664148, 1500.3923411018554, 1558.6648870492897, 1574.9842501574838, 1576.3852097681913, 1598.1130472587824, 32823.0, 33665.66334336625, 33981.83009084939, 34051.4892276621, 34435.13912152216, 54851.17723234075, 54894.72552637211, 55036.0, 55205.447945521635, 55394.0, 463299.0, 467503.0, 467549.0, 471998.0, 473665.0, 45358.0, 45736.31395529136, 46638.53361466342, 46804.06391872212, 47343.28985065158, 50810.237846433076, 50990.74504627453, 51252.0, 51347.0, 52232.0, 41889.0, 42801.785991069846, 43198.0, 43659.78170109129, 44039.119217614825, 41700.37449438321, 42132.0, 42570.787146064074, 43452.78273608612, 43597.56402436062, 42929.35605965975, 43766.56233437752, 44140.33789493224, 44393.778031109636, 44474.55525444704, 37074.814625926694, 37273.81363093276, 37409.69066082674, 37886.62113378831, 38448.80775596104, 29270.0, 29303.267418314714, 29350.706492934798, 29594.96417625459, 29716.40567188601], "value": 20074.813565302265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.8214385670305, 408.3344149037069, 408.4751094843127, 420.222588211808, 435.6645383055048, 19141.808581914178, 20237.39287821364, 20485.283015094476, 20686.0, 21110.26114086007, 19113.90443047786, 19228.32700855467, 19381.127849246783, 19973.90013049936, 19975.101120449577, 5762.683052432115, 5965.3736357682255, 5990.161377407167, 5995.52035837132, 6002.339742628307, 5867.88264234715, 5914.408559144094, 6022.638641681491, 7170.462215333845, 7190.388816950565, 7002.894956575656, 7166.928330716678, 7260.165081015698, 7317.926820731805, 7351.301626345516, 7121.715131394738, 7236.240194779525, 7241.203467618584, 7272.927270727304, 7377.077865266841, 7362.042934418545, 7380.225076367012, 7411.48119631626, 7425.74009909656, 7460.738874139433, 25895.870520647466, 26708.86645566779, 27433.588496172575, 27568.72431275702, 27708.445831083467, 2422.5881600127846, 2601.752833480816, 2808.7893407994484, 2970.287131088523, 2974.5092059810104, 16497.0, 16797.496075117644, 16863.747043794352, 17106.657866842717, 17145.571360716083, 2090.5296308330658, 2201.482651576878, 2318.038014224095, 2335.6963594732574, 2337.5909215887323, 77979.0, 88103.0, 92965.99999999933, 101199.0, 102905.48547257215, 18069.0, 18679.252829886926, 18964.43106706801, 19052.80947190538, 19054.714179287323, 35485.46771798425, 35621.28757424837, 36258.456123158176, 36325.0, 36376.0, 10914.454277286104, 11498.080153587693, 11510.19428640004, 11552.306861588331, 11672.474738636613, 9747.512624368896, 9817.558209880572, 9826.06652368024, 9907.108360247612, 9975.900240997642, 9334.299927505464, 9374.95312523433, 9410.576524056434, 9548.0, 9580.66467673628, 3470.774399664014, 3604.387254166798, 3755.282270552134, 3789.696824254053, 3926.960730392717, 1064.7657515346611, 1089.4661615808354, 1167.6613782003124, 1174.2015429508017, 1176.7411169542686, 17828.91085544564, 19397.418077457416, 19430.514237143903, 19841.702374464396, 20150.69773953392, 1087.7987572298991, 1089.9237053406343, 1092.2627226621998, 1095.6055819904827, 1123.5955056179769, 10966.835497467544, 11031.503582338813, 11070.833937490783, 11163.83254251187, 11209.327440353605, 518550.0, 529580.0, 530860.0, 539185.0, 543081.0, 58679.70660146672, 99722.5013874926, 104582.47708761407, 105182.0, 106847.46576267069, 1053.920955928293, 1070.9303895246785, 1074.6883403812963, 1100.5212732461405, 1106.4025426269884, 17577.29690812353, 17807.0, 17847.821521784615, 18104.366347177787, 18151.546211344823, 41043.179136416504, 42950.35574466448, 43077.56922430861, 43787.0, 45032.324515132954, 61783.691081544304, 62154.0, 62689.68655156695, 62803.6859815698, 63407.0, 8548.829023419621, 8759.343049271203, 8796.384253102222, 8881.156290152425, 8973.775655608662, 9920.454375009154, 13025.674358141123, 13299.867001329863, 13342.93256539455, 13854.376553054935, 11872.0, 11914.821277680669, 12161.574344897886, 12204.75590488203, 12492.25046497213, 8977.339192249454, 11426.600068997544, 11533.711657208636, 11906.702332441411, 11948.76102477963, 12129.575464858688, 12258.387080646111, 12297.569585064837, 12590.622281331574, 12739.012664047883, 8651.69719059855, 9080.818383632422, 9091.409058411182, 9241.676541321021, 9497.667581634609, 9766.0, 12246.387680615755, 12276.202046866925, 12393.318367489876, 12484.56304029391], "value": 10930.686766879655, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.7874037005004, 1599.2803238542656, 1820.563064864433, 1905.2379048380656, 1915.0329083812674, 8114.269715725583, 8389.580520973957, 9073.818523629532, 9696.224302055836, 9764.414135151896, 8879.91120088799, 9258.120478554527, 9437.81124377512, 9692.0, 9695.612175512988, 2441.59713647248, 2482.056818409002, 2498.113169824713, 2501.962470562943, 2508.548461276969, 2490.452100537888, 2493.2146373892256, 2518.44594189279, 2522.823402361835, 2528.292078218102, 2432.963505547418, 2457.7173625033083, 2489.9253022409357, 2492.53888030714, 2514.8365356251784, 2347.213683416058, 2348.6829278047503, 2354.8704821234824, 2398.760123987605, 2399.2802159352123, 2454.6563481112657, 2466.531359041794, 2477.355887469253, 2488.9751102488926, 2506.8495890246645, 9520.190783783386, 9569.569369378325, 9606.75983100421, 9609.807803843954, 9615.326927115104, 5556.611037227396, 5632.633878797865, 5644.971775141139, 5648.971755141239, 5650.943490565083, 3498.5451891253974, 3551.6448355164534, 3575.302815950891, 3590.7486475946694, 3665.505156803824, 1258.3519487463964, 1268.22448571999, 1291.3026965438653, 1297.5558711413466, 1310.2073245686413, 13408.798868016987, 13421.66445838852, 13481.865181348257, 13510.864891351157, 13583.0, 4857.125717370836, 4870.951290487121, 4879.707217566886, 4945.1346014448045, 4952.678075925125, 10509.579616815394, 10569.101626361846, 10639.468026598639, 10668.679939601669, 10672.0, 5574.58190635704, 5578.41426650204, 5598.1322894951345, 5622.437756224426, 5627.396192085318, 4509.2559727644575, 5062.594992400599, 5151.896962060738, 5208.036513244986, 5218.321618189611, 4118.979405102956, 4129.401236820644, 4147.730397524211, 4161.417401563784, 4177.268977928911, 1189.3636904256182, 1192.3442106841203, 1193.8089905615059, 1197.9101567382481, 1203.3862730007759, 1034.8344264917578, 1091.1598069486452, 1099.5052226498108, 1135.4549816088324, 1142.9998751092794, 6223.190985171989, 6259.123722678829, 6317.747290108436, 6483.967580162069, 6537.187113257973, 1054.435876805905, 1076.4832880217382, 1082.09644946469, 1108.174410064491, 1124.6344937895217, 4410.735355878657, 4416.183006143874, 4439.933400998988, 4440.156370289635, 4503.392042074343, 11420.771584568261, 11655.184137110491, 11737.354445505409, 11766.764664706658, 11817.409129543488, 11558.595449159238, 11603.709907252387, 11658.242214256046, 11665.0, 11709.297442153498, 7933.682652693828, 8161.428699990944, 8243.629036693363, 8336.708215212439, 8362.331013518904, 6296.244450665949, 6646.102776125162, 6659.267480577036, 6765.2896445872475, 6776.796696099125, 10282.640107596199, 10569.048785609331, 10675.252732308663, 10701.464926753475, 10786.083182929538, 10837.403942783223, 10854.18593605467, 10854.34873907568, 10877.564897404016, 10960.725981850517, 7251.8187045324285, 7788.143304236416, 8107.594620268845, 8189.549574773207, 8259.339252859876, 9086.45481271126, 9158.358914875895, 9172.35793494449, 9225.49259790718, 9232.399894006869, 8083.0, 8314.958425207835, 8347.749567512741, 8410.369222308229, 8418.284445822173, 8040.075391330072, 8179.386546008954, 8180.631871565674, 8257.298129659046, 8313.0, 7596.620168991418, 7695.615219239127, 7701.037370328709, 7734.574598397142, 7745.22547745221, 5655.915161272666, 5725.914111288251, 5735.71321433935, 5775.5090817281, 5864.354920958605, 5882.823515294548, 5938.376470470711, 5953.613015154001, 5962.19510366095, 5993.70031498432], "value": 4978.01617021879, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.2655333968655, 347.3851283228685, 348.37988380682384, 353.55628685999085, 354.3409258778673, 5762.366139724632, 5831.7958871439505, 5838.854028649281, 5868.207791948085, 5872.003518803606, 5652.463016013482, 5690.886182276352, 5723.627964182325, 5732.627379220347, 5736.311642602883, 4044.737092089012, 4387.890302742425, 4396.054848207635, 4399.560043995591, 4436.378906953029, 4396.1427521633295, 4430.468343798749, 4435.647127626073, 4453.710508816933, 4455.866324010285, 4311.655067594585, 4339.913201735963, 4340.978295108536, 4357.324614684729, 4362.650987920974, 4226.564560350693, 4351.129774045204, 4390.604845563915, 4391.429114215163, 4402.427684401039, 3819.4843696100947, 3854.5567259765207, 3869.7097717671286, 3878.2243551289853, 3883.650471457582, 5496.340439147288, 5498.257735205736, 5504.4495550445035, 5553.972230138864, 5570.721463926788, 4699.718016918996, 4983.7757300921185, 4989.376327959005, 5000.499950005007, 5014.874628134289, 5866.237389139427, 5909.6158749681135, 5922.437368449991, 5967.850803729898, 5968.940310596882, 4230.0, 4240.448741663564, 4251.404803327537, 4254.936175957363, 4259.041715613993, 6044.365341639153, 6110.411293063836, 6135.5398345124295, 6194.907076393858, 6220.813375598739, 4271.10306835568, 4308.095299986975, 4312.827486900552, 4316.69783115179, 4319.632831209319, 4333.133373325348, 4351.086271882877, 4359.607635312837, 4360.411344468456, 4365.406626581275, 4909.68087074339, 4960.603151747851, 5000.474950130184, 5004.224345226496, 5036.84889453317, 5947.613405128653, 5977.970110149421, 6004.969975150097, 6028.758849645965, 6036.939630603725, 1458.5405597236836, 1495.3046833222604, 1509.7357962356764, 1512.4630756081654, 1516.113073851787, 950.4629884115452, 957.8601464257512, 1071.49104175516, 1089.3899416326883, 1091.2579445976653, 5781.88436231273, 5794.870000349892, 5835.474807267281, 5861.501772349398, 5881.735321910524, 6048.12302216185, 6154.323024467305, 6177.382261773877, 6178.258608966863, 6192.59748116371, 5705.372409035002, 5795.913061304083, 5802.0, 5803.738831752581, 5813.500194916142, 3256.120847371244, 3259.1037464696924, 3265.330607225513, 3279.442494775894, 3318.883839065621, 6098.420650038239, 6313.936860631335, 6314.147590075281, 6323.747050118036, 6353.331600727705, 6140.5701600887505, 6194.907076393858, 6241.282252541016, 6294.559380843389, 6318.778842740574, 6166.475849552839, 6274.874502510015, 6364.268109167505, 6410.294867564564, 6411.96794016027, 6037.45662890342, 6047.879042419215, 6084.421979911901, 6090.756369745161, 6141.232345956755, 6033.637981721111, 6066.18106555627, 6074.90415806546, 6135.202423684892, 6145.525073982123, 6098.634081955097, 6331.51209465766, 6424.751337032001, 6444.677766111769, 6454.838629034124, 4457.487388950182, 4461.375407443025, 4470.7764611769935, 4479.46246450428, 4496.595306422438, 5939.227900372924, 6014.63912165254, 6055.666938318434, 6116.388361164026, 6123.601965872204, 5858.414158584278, 5869.73586188614, 5891.9116213255975, 5915.40845915405, 5948.762049517971, 5922.838164069997, 5931.5847890648965, 5939.584229103922, 5951.583389162717, 5959.692370734284, 5601.80393686219, 5707.68607726579, 5931.881362372815, 5934.525237981037, 5939.554533410098, 6061.605995610271, 6064.575479716377, 6070.605410648293, 6089.634621922699, 6090.269167699905, 5746.5402767779315, 5935.228420305332, 5936.732847021981, 5950.613210141327, 6021.675231449074], "value": 4545.5666262445475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.2650535002449, 813.032491335311, 840.5041025794782, 852.8635418333068, 879.1735768377724, 16498.42255521057, 16975.74536381955, 17103.315867365305, 17112.0, 17719.40525352718, 15688.450904218354, 15748.606284842885, 15792.763108553352, 15827.683446331066, 15841.049537027757, 866.3199388480044, 875.1904488348268, 894.9841929410102, 898.24996128233, 902.6705252582825, 883.4390162246988, 887.5384799904047, 888.3159966825541, 892.2193081054078, 893.0176805513915, 918.0314767919875, 922.4695799915042, 923.353652443289, 927.0868194828089, 931.7204838548442, 913.9314551408638, 915.0254978447931, 916.4409710076848, 916.5234078279324, 916.6745805239144, 675.6655455549535, 677.2110491277656, 679.741698154698, 682.0349205873683, 682.757621044532, 16911.0, 16943.40698075574, 16985.06582137982, 17011.149442527825, 17020.91489542557, 13610.455581776718, 13948.511802086878, 14297.642558936006, 14424.639384015378, 14804.40782368704, 14558.199299038548, 14659.63350916225, 14866.108033518023, 14870.553883383407, 15322.15728134952, 4773.474917759044, 4842.564169224752, 4844.10384078943, 4845.927311090301, 4856.465788763233, 80395.0, 81046.59476702579, 81570.0, 83547.5822620883, 84740.99999999939, 12457.0, 12678.80981785274, 12724.491020359266, 12732.490700371882, 12887.0, 23373.766262337158, 23796.0, 23823.880880595487, 23832.40418989539, 23896.283111506684, 9371.906280937239, 9377.202937750231, 9383.296252781069, 9400.623975040922, 9407.29445291606, 4968.0, 5122.282880396748, 5132.255822905732, 5135.948640513547, 5216.347956505437, 3709.387950988111, 3823.0633494793396, 3828.0, 3833.2525157594, 3837.4435706822355, 645.4674893213117, 645.7804346522205, 654.201873714068, 655.6951666184259, 667.4160109903834, 725.7060890339421, 728.0244472407, 729.274371999853, 774.9651265693058, 775.4261846233859, 10574.101201397967, 10992.835107473395, 11019.72450688723, 11235.943820280847, 11281.548738050386, 708.5890183693446, 804.4489524675616, 815.7104227999093, 831.6382373667474, 834.0741776627938, 4425.579569940851, 4476.0, 4604.263317869192, 5110.105731497048, 5134.845954621367, 73916.0, 74382.25617743754, 118724.0, 121792.0, 122128.0, 74062.0, 81221.0, 99736.50131749295, 105903.0, 106804.46597766962, 69338.87975041135, 91058.08941910496, 92556.53721731348, 93375.53312233531, 94019.52990235006, 9672.70981870545, 10014.148797352307, 10241.743956400851, 10278.948605256926, 10307.433091180057, 54515.0, 55533.1670024958, 55591.44408555863, 56036.0, 57400.42599574118, 77175.0, 80351.19648803437, 81393.18606814093, 81493.59253203696, 81841.59079204565, 12645.810312845131, 12648.873511265137, 12954.0, 13020.21878687244, 13507.0, 13976.437618321428, 14176.291185440481, 14289.857101429268, 14301.64245893861, 14340.713185736435, 12937.417816198482, 13033.087683862417, 13095.738085238432, 13324.200547967153, 13383.598492045254, 13580.79628805549, 13635.795463067863, 13847.86152138506, 14272.0, 14277.214753188675, 12968.546100886424, 13477.932610336886, 13511.189328640314, 14326.57020289393, 14403.711925761636, 12518.874811251771, 12540.0, 12818.74362512726, 13029.0, 13082.411291491715, 11526.94236528812, 12054.156209065632, 12228.633141005785, 12768.170068945488, 13221.93389033087], "value": 8079.171977157609, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 112.0, 113.0, 112.0, 112.0, 114.0, 111.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 135.0, 157.0, 178.0, 128.0, 165.0, 157.0, 126.0, 185.0, 124.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 164.0, 161.0, 161.0, 163.0, 161.0, 165.0, 164.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 187.0, 75.0, 75.0, 75.0, 75.0, 160.0, 76.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 150.0, 83.0, 84.0, 84.0, 138.0, 85.0, 83.0, 149.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 117.0, 120.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 151.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 52.0, 61.0, 54.0, 52.0, 52.0, 53.0, 56.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 76.0, 76.0, 81.0, 83.0, 79.0, 108.0, 77.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 130.0, 116.0, 123.0, 137.0, 125.0, 127.0, 123.0, 123.0, 129.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 156.0, 155.0, 156.0, 152.0, 158.0, 154.0, 158.0, 153.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 62.0, 62.0, 63.0, 66.0, 62.0, 61.0, 61.0], "value": 62.7, "unit": "score"}]}]} 23:56:42 INFO - Return code: 0 23:56:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:56:43 ERROR - # TBPL SUCCESS # 23:56:43 INFO - Running post-action listener: _resource_record_post_action 23:56:43 INFO - ##### 23:56:43 INFO - ##### Finished run-tests step (success) 23:56:43 INFO - ##### 23:56:43 INFO - Running post-run listener: _resource_record_post_run 23:56:43 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 290816; Write bytes: 258363392; Read time: 48; Write time: 223156 23:56:43 INFO - TinderboxPrint: CPU usage
13.3% 23:56:43 INFO - TinderboxPrint: I/O read bytes / time
290,816 / 48 23:56:43 INFO - TinderboxPrint: I/O write bytes / time
258,363,392 / 223,156 23:56:43 INFO - TinderboxPrint: CPU idle
8,219.6 (86.6%) 23:56:43 INFO - TinderboxPrint: CPU user
1,211.0 (12.8%) 23:56:43 INFO - TinderboxPrint: Swap in / out
0 / 0 23:56:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 8523776; Read time: 0; Write time: 1760 23:56:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249171968; Read time: 36; Write time: 221396 23:56:43 INFO - Running post-run listener: _upload_blobber_files 23:56:43 INFO - Blob upload gear active. 23:56:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:56:43 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 23:56:43 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'] 23:56:43 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 23:56:44 INFO - (blobuploader) - INFO - Open directory for files ... 23:56:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:56:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:44 INFO - (blobuploader) - INFO - Done attempting. 23:56:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:56:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:45 INFO - (blobuploader) - INFO - Done attempting. 23:56:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:56:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:49 INFO - (blobuploader) - INFO - Done attempting. 23:56:49 INFO - (blobuploader) - INFO - Iteration through files over. 23:56:49 INFO - Return code: 0 23:56:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:56:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:56:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18439b22f2daa3ee2ed3e330b0e2375696a257508b312310db9784b55a042a1f4a12e1526e0f497ce34beac106ec1e2d2c0a27f6485cedc3e39a3dd160b2560e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acfd65f1d51730ec0a5a6ca589246032f0b4cb706586d08830c93c8458cfff1ff00a3408d3a77997ec61186f9d2756564ed23cfe5a8b39f246a436d4b1bd26ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f57c04cd31a3ad5900af38edb399a6e33199e4cf6e0459567f1fe676187664c4c1061bbc38cad4bfae9009df8725d20290d54c276277f8677b27ad62aa07eeb"} 23:56:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:56:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:56:49 INFO - Contents: 23:56:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18439b22f2daa3ee2ed3e330b0e2375696a257508b312310db9784b55a042a1f4a12e1526e0f497ce34beac106ec1e2d2c0a27f6485cedc3e39a3dd160b2560e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acfd65f1d51730ec0a5a6ca589246032f0b4cb706586d08830c93c8458cfff1ff00a3408d3a77997ec61186f9d2756564ed23cfe5a8b39f246a436d4b1bd26ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f57c04cd31a3ad5900af38edb399a6e33199e4cf6e0459567f1fe676187664c4c1061bbc38cad4bfae9009df8725d20290d54c276277f8677b27ad62aa07eeb"} 23:56:49 INFO - Running post-run listener: copy_logs_to_upload_dir 23:56:49 INFO - Copying logs to upload dir... 23:56:49 INFO - mkdir: /builds/slave/test/build/upload/logs 23:56:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.261724 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-10 23:56:49.614590) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 23:56:49.617910) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18439b22f2daa3ee2ed3e330b0e2375696a257508b312310db9784b55a042a1f4a12e1526e0f497ce34beac106ec1e2d2c0a27f6485cedc3e39a3dd160b2560e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acfd65f1d51730ec0a5a6ca589246032f0b4cb706586d08830c93c8458cfff1ff00a3408d3a77997ec61186f9d2756564ed23cfe5a8b39f246a436d4b1bd26ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f57c04cd31a3ad5900af38edb399a6e33199e4cf6e0459567f1fe676187664c4c1061bbc38cad4bfae9009df8725d20290d54c276277f8677b27ad62aa07eeb"} build_url:https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/LmnGhLIySxaDUsqZTg7oXg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18439b22f2daa3ee2ed3e330b0e2375696a257508b312310db9784b55a042a1f4a12e1526e0f497ce34beac106ec1e2d2c0a27f6485cedc3e39a3dd160b2560e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acfd65f1d51730ec0a5a6ca589246032f0b4cb706586d08830c93c8458cfff1ff00a3408d3a77997ec61186f9d2756564ed23cfe5a8b39f246a436d4b1bd26ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f57c04cd31a3ad5900af38edb399a6e33199e4cf6e0459567f1fe676187664c4c1061bbc38cad4bfae9009df8725d20290d54c276277f8677b27ad62aa07eeb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 23:56:49.642935) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:56:49.643284) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1468216072.161470-1487825471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 23:56:49.729928) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 23:56:49.730233) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 23:56:49.730728) ========= ========= Total master_lag: 0.18 =========