builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1470004436.92 results: success (0) buildid: 20160731124125 builduid: 5c8365d92e7c4b409d9e20a793f2e5f3 revision: 1a408f5eb42e99435820445547affab4e21df92e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:56.918212) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:56.918684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:56.919068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:56.975704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:56.976003) ========= 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=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-31 15:33:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-07-31 15:33:57 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.100271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.100610) ========= 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=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018320 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.154226) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.154615) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1a408f5eb42e99435820445547affab4e21df92e --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 1a408f5eb42e99435820445547affab4e21df92e --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=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-31 15:33:57,187 truncating revision to first 12 chars 2016-07-31 15:33:57,187 Setting DEBUG logging. 2016-07-31 15:33:57,187 attempt 1/10 2016-07-31 15:33:57,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1a408f5eb42e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-31 15:33:57,523 unpacking tar archive at: mozilla-aurora-1a408f5eb42e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.863329) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.863671) ========= script_repo_revision: 1a408f5eb42e99435820445547affab4e21df92e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.864110) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.864442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 15:33:57.886934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-31 15:33:57.887298) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:33:58 INFO - MultiFileLogger online at 20160731 15:33:58 in /builds/slave/test 15:33:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:33:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:58 INFO - {'append_to_log': False, 15:33:58 INFO - 'base_work_dir': '/builds/slave/test', 15:33:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:33:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:58 INFO - 'branch': 'Mozilla-Aurora', 15:33:58 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:58 INFO - 'config_files': ('talos/linux_config.py',), 15:33:58 INFO - 'default_actions': ('clobber', 15:33:58 INFO - 'read-buildbot-config', 15:33:58 INFO - 'download-and-extract', 15:33:58 INFO - 'populate-webroot', 15:33:58 INFO - 'create-virtualenv', 15:33:58 INFO - 'install', 15:33:58 INFO - 'run-tests'), 15:33:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:58 INFO - 'download_minidump_stackwalk': True, 15:33:58 INFO - 'download_symbols': 'ondemand', 15:33:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:58 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:58 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:58 INFO - 'installer_path': 'installer.exe', 15:33:58 INFO - 'log_level': 'info', 15:33:58 INFO - 'log_name': 'talos', 15:33:58 INFO - 'log_to_console': True, 15:33:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:33:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:33:58 INFO - 'opt_config_files': (), 15:33:58 INFO - 'pip_index': False, 15:33:58 INFO - 'sps_profile': False, 15:33:58 INFO - 'sps_profile_interval': 0, 15:33:58 INFO - 'suite': 'dromaeojs', 15:33:58 INFO - 'system_bits': '32', 15:33:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:58 INFO - 'title': 'talos-linux64-ix-058', 15:33:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:58 INFO - 'use_talos_json': True, 15:33:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:58 INFO - 'work_dir': 'build'} 15:33:58 INFO - ##### 15:33:58 INFO - ##### Running clobber step. 15:33:58 INFO - ##### 15:33:58 INFO - Running pre-action listener: _resource_record_pre_action 15:33:58 INFO - Running main action method: clobber 15:33:58 INFO - rmtree: /builds/slave/test/build 15:33:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:58 INFO - Running post-action listener: _resource_record_post_action 15:33:58 INFO - ##### 15:33:58 INFO - ##### Finished clobber step (success) 15:33:58 INFO - ##### 15:33:58 INFO - ##### 15:33:58 INFO - ##### Running read-buildbot-config step. 15:33:58 INFO - ##### 15:33:58 INFO - Running pre-action listener: _resource_record_pre_action 15:33:58 INFO - Running main action method: read_buildbot_config 15:33:58 INFO - Using buildbot properties: 15:33:58 INFO - { 15:33:58 INFO - "project": "", 15:33:58 INFO - "product": "firefox", 15:33:58 INFO - "script_repo_revision": "production", 15:33:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:33:58 INFO - "repository": "", 15:33:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:33:58 INFO - "buildid": "20160731124125", 15:33:58 INFO - "pgo_build": "True", 15:33:58 INFO - "basedir": "/builds/slave/test", 15:33:58 INFO - "buildnumber": 645, 15:33:58 INFO - "slavename": "talos-linux64-ix-058", 15:33:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:33:58 INFO - "platform": "ubuntu64_hw", 15:33:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:33:58 INFO - "branch": "mozilla-aurora", 15:33:58 INFO - "repo_path": "releases/mozilla-aurora", 15:33:58 INFO - "revision": "1a408f5eb42e99435820445547affab4e21df92e", 15:33:58 INFO - "stage_platform": "linux64-pgo", 15:33:58 INFO - "builduid": "5c8365d92e7c4b409d9e20a793f2e5f3", 15:33:58 INFO - "slavebuilddir": "test" 15:33:58 INFO - } 15:33:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 15:33:58 INFO - Running post-action listener: _resource_record_post_action 15:33:58 INFO - ##### 15:33:58 INFO - ##### Finished read-buildbot-config step (success) 15:33:58 INFO - ##### 15:33:58 INFO - ##### 15:33:58 INFO - ##### Running download-and-extract step. 15:33:58 INFO - ##### 15:33:58 INFO - Running pre-action listener: _resource_record_pre_action 15:33:58 INFO - Running main action method: download_and_extract 15:33:58 INFO - mkdir: /builds/slave/test/build/tests 15:33:58 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')]} 15:33:58 INFO - https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:33:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:33:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:33:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:08 INFO - Downloaded 1361 bytes. 15:34:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:34:08 INFO - Using the following test package requirements: 15:34:08 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 15:34:08 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:34:08 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:34:08 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'jsshell-linux-x86_64.zip'], 15:34:08 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:34:08 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 15:34:08 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:34:08 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:34:08 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:34:08 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:34:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:34:08 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:08 INFO - https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:34:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:34:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:34:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:16 INFO - Downloaded 21971074 bytes. 15:34:16 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'] 15:34:16 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 15:34:17 INFO - Return code: 0 15:34:17 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 15:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:17 INFO - https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:34:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:34:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:34:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:18 INFO - Downloaded 21971074 bytes. 15:34:18 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'] 15:34:18 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 15:34:20 INFO - Return code: 0 15:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:20 INFO - https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:34:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 15:34:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 15:34:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:20 INFO - Downloaded 10833369 bytes. 15:34:20 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'] 15:34:20 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 15:34:21 INFO - Return code: 0 15:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:21 INFO - https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:34:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:34:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:34:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/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 15:34:22 INFO - Downloaded 61975356 bytes. 15:34:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:34:22 INFO - mkdir: /builds/slave/test/properties 15:34:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:34:22 INFO - Writing to file /builds/slave/test/properties/build_url 15:34:22 INFO - Contents: 15:34:22 INFO - build_url:https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:34:23 INFO - Running post-action listener: _resource_record_post_action 15:34:23 INFO - Running post-action listener: set_extra_try_arguments 15:34:23 INFO - ##### 15:34:23 INFO - ##### Finished download-and-extract step (success) 15:34:23 INFO - ##### 15:34:23 INFO - ##### 15:34:23 INFO - ##### Running populate-webroot step. 15:34:23 INFO - ##### 15:34:23 INFO - Running pre-action listener: _resource_record_pre_action 15:34:23 INFO - Running main action method: populate_webroot 15:34:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:34:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:34:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:34:23 INFO - '--tppagecycles', 15:34:23 INFO - '7'], 15:34:23 INFO - 'tests': ['tsvgm']}}, 15:34:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:34:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:34:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:34:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:34:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['damp', 'tps']}, 15:34:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'tests': ['damp', 'tps']}, 15:34:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['dromaeo_dom']}, 15:34:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:34:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'other-e10s': {'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['a11yr', 15:34:23 INFO - 'ts_paint', 15:34:23 INFO - 'tpaint', 15:34:23 INFO - 'sessionrestore', 15:34:23 INFO - 'sessionrestore_no_auto_restore', 15:34:23 INFO - 'tabpaint']}, 15:34:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['tsvgx', 15:34:23 INFO - 'tsvgr_opacity', 15:34:23 INFO - 'tart', 15:34:23 INFO - 'tscrollx', 15:34:23 INFO - 'cart']}, 15:34:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:34:23 INFO - 'tsvgr_opacity', 15:34:23 INFO - 'tart', 15:34:23 INFO - 'tscrollx', 15:34:23 INFO - 'cart']}, 15:34:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'talos_options': ['--disable-e10s'], 15:34:23 INFO - 'tests': ['tp5o']}, 15:34:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'tests': ['tp5o']}, 15:34:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'talos_options': ['--disable-e10s', 15:34:23 INFO - '--xperf_path', 15:34:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:23 INFO - 'tests': ['tp5n']}, 15:34:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:23 INFO - 'talos_options': ['--xperf_path', 15:34:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:23 INFO - 'tests': ['tp5n']}}, 15:34:23 INFO - 'talos.zip': {'path': '', 15:34:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:34:23 INFO - Running post-action listener: _resource_record_post_action 15:34:23 INFO - ##### 15:34:23 INFO - ##### Finished populate-webroot step (success) 15:34:23 INFO - ##### 15:34:23 INFO - ##### 15:34:23 INFO - ##### Running create-virtualenv step. 15:34:23 INFO - ##### 15:34:23 INFO - Running pre-action listener: _resource_record_pre_action 15:34:23 INFO - Running main action method: create_virtualenv 15:34:23 INFO - Creating virtualenv /builds/slave/test/build/venv 15:34:23 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 15:34:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:34:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:34:23 INFO - Using real prefix '/usr' 15:34:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:34:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:34:25 INFO - Installing pip.................done. 15:34:25 INFO - Return code: 0 15:34:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:25 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 15:34:25 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 15:34:25 INFO - Using env: {'DISPLAY': ':0', 15:34:25 INFO - 'HOME': '/home/cltbld', 15:34:25 INFO - 'LANG': 'en_US.UTF-8', 15:34:25 INFO - 'LANGUAGE': 'en_US:en', 15:34:25 INFO - 'LOGNAME': 'cltbld', 15:34:25 INFO - 'MAIL': '/var/mail/cltbld', 15:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:25 INFO - 'MOZ_NO_REMOTE': '1', 15:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:25 INFO - 'NO_EM_RESTART': '1', 15:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:25 INFO - 'PWD': '/builds/slave/test', 15:34:25 INFO - 'SHELL': '/bin/bash', 15:34:25 INFO - 'SHLVL': '1', 15:34:25 INFO - 'TERM': 'linux', 15:34:25 INFO - 'TMOUT': '86400', 15:34:25 INFO - 'USER': 'cltbld', 15:34:25 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:25 INFO - '_': '/tools/buildbot/bin/python'} 15:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:26 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 15:34:26 INFO - Cleaning up... 15:34:26 INFO - Return code: 0 15:34:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:34:26 INFO - Using env: {'DISPLAY': ':0', 15:34:26 INFO - 'HOME': '/home/cltbld', 15:34:26 INFO - 'LANG': 'en_US.UTF-8', 15:34:26 INFO - 'LANGUAGE': 'en_US:en', 15:34:26 INFO - 'LOGNAME': 'cltbld', 15:34:26 INFO - 'MAIL': '/var/mail/cltbld', 15:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:26 INFO - 'MOZ_NO_REMOTE': '1', 15:34:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:26 INFO - 'NO_EM_RESTART': '1', 15:34:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:26 INFO - 'PWD': '/builds/slave/test', 15:34:26 INFO - 'SHELL': '/bin/bash', 15:34:26 INFO - 'SHLVL': '1', 15:34:26 INFO - 'TERM': 'linux', 15:34:26 INFO - 'TMOUT': '86400', 15:34:26 INFO - 'USER': 'cltbld', 15:34:26 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:26 INFO - '_': '/tools/buildbot/bin/python'} 15:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:26 INFO - Downloading/unpacking psutil>=3.1.1 15:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:28 INFO - Installing collected packages: psutil 15:34:28 INFO - Running setup.py install for psutil 15:34:28 INFO - building 'psutil._psutil_linux' extension 15:34:28 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 15:34:28 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 15:34:28 INFO - building 'psutil._psutil_posix' extension 15:34:28 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 15:34:28 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 15:34:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:28 INFO - Successfully installed psutil 15:34:28 INFO - Cleaning up... 15:34:28 INFO - Return code: 0 15:34:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:28 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:28 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 15:34:28 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 15:34:28 INFO - Using env: {'DISPLAY': ':0', 15:34:28 INFO - 'HOME': '/home/cltbld', 15:34:28 INFO - 'LANG': 'en_US.UTF-8', 15:34:28 INFO - 'LANGUAGE': 'en_US:en', 15:34:28 INFO - 'LOGNAME': 'cltbld', 15:34:28 INFO - 'MAIL': '/var/mail/cltbld', 15:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:28 INFO - 'MOZ_NO_REMOTE': '1', 15:34:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:28 INFO - 'NO_EM_RESTART': '1', 15:34:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:28 INFO - 'PWD': '/builds/slave/test', 15:34:28 INFO - 'SHELL': '/bin/bash', 15:34:28 INFO - 'SHLVL': '1', 15:34:28 INFO - 'TERM': 'linux', 15:34:28 INFO - 'TMOUT': '86400', 15:34:28 INFO - 'USER': 'cltbld', 15:34:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:28 INFO - '_': '/tools/buildbot/bin/python'} 15:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:34:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:34:31 INFO - Installing collected packages: mozsystemmonitor 15:34:31 INFO - Running setup.py install for mozsystemmonitor 15:34:31 INFO - Successfully installed mozsystemmonitor 15:34:31 INFO - Cleaning up... 15:34:31 INFO - Return code: 0 15:34:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:34:31 INFO - Using env: {'DISPLAY': ':0', 15:34:31 INFO - 'HOME': '/home/cltbld', 15:34:31 INFO - 'LANG': 'en_US.UTF-8', 15:34:31 INFO - 'LANGUAGE': 'en_US:en', 15:34:31 INFO - 'LOGNAME': 'cltbld', 15:34:31 INFO - 'MAIL': '/var/mail/cltbld', 15:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:31 INFO - 'MOZ_NO_REMOTE': '1', 15:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:31 INFO - 'NO_EM_RESTART': '1', 15:34:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:31 INFO - 'PWD': '/builds/slave/test', 15:34:31 INFO - 'SHELL': '/bin/bash', 15:34:31 INFO - 'SHLVL': '1', 15:34:31 INFO - 'TERM': 'linux', 15:34:31 INFO - 'TMOUT': '86400', 15:34:31 INFO - 'USER': 'cltbld', 15:34:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:31 INFO - '_': '/tools/buildbot/bin/python'} 15:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:31 INFO - Downloading/unpacking jsonschema==2.5.1 15:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:34:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:33 INFO - Downloading functools32-3.2.3-2.tar.gz 15:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:34:33 INFO - warning: no files found matching '*.txt' 15:34:33 INFO - no previously-included directories found matching 'build' 15:34:33 INFO - no previously-included directories found matching 'dist' 15:34:33 INFO - no previously-included directories found matching '.git*' 15:34:33 INFO - Installing collected packages: jsonschema, functools32 15:34:33 INFO - Running setup.py install for functools32 15:34:33 INFO - warning: no files found matching '*.txt' 15:34:33 INFO - no previously-included directories found matching 'build' 15:34:33 INFO - no previously-included directories found matching 'dist' 15:34:33 INFO - no previously-included directories found matching '.git*' 15:34:33 INFO - Successfully installed jsonschema functools32 15:34:33 INFO - Cleaning up... 15:34:33 INFO - Return code: 0 15:34:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:34:33 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')]} 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:33 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')]} 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:33 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:33 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 15:34:33 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 15:34:33 INFO - Using env: {'DISPLAY': ':0', 15:34:33 INFO - 'HOME': '/home/cltbld', 15:34:33 INFO - 'LANG': 'en_US.UTF-8', 15:34:33 INFO - 'LANGUAGE': 'en_US:en', 15:34:33 INFO - 'LOGNAME': 'cltbld', 15:34:33 INFO - 'MAIL': '/var/mail/cltbld', 15:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:33 INFO - 'MOZ_NO_REMOTE': '1', 15:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:33 INFO - 'NO_EM_RESTART': '1', 15:34:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:33 INFO - 'PWD': '/builds/slave/test', 15:34:33 INFO - 'SHELL': '/bin/bash', 15:34:33 INFO - 'SHLVL': '1', 15:34:33 INFO - 'TERM': 'linux', 15:34:33 INFO - 'TMOUT': '86400', 15:34:33 INFO - 'USER': 'cltbld', 15:34:33 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:33 INFO - '_': '/tools/buildbot/bin/python'} 15:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:34 INFO - Downloading/unpacking blobuploader==1.2.4 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:34:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:34:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:34:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:36 INFO - Downloading docopt-0.6.1.tar.gz 15:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:34:36 INFO - Installing collected packages: blobuploader, requests, docopt 15:34:36 INFO - Running setup.py install for blobuploader 15:34:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:34:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:34:36 INFO - Running setup.py install for requests 15:34:37 INFO - Running setup.py install for docopt 15:34:37 INFO - Successfully installed blobuploader requests docopt 15:34:37 INFO - Cleaning up... 15:34:37 INFO - Return code: 0 15:34:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:37 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:37 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 15:34:37 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 15:34:37 INFO - Using env: {'DISPLAY': ':0', 15:34:37 INFO - 'HOME': '/home/cltbld', 15:34:37 INFO - 'LANG': 'en_US.UTF-8', 15:34:37 INFO - 'LANGUAGE': 'en_US:en', 15:34:37 INFO - 'LOGNAME': 'cltbld', 15:34:37 INFO - 'MAIL': '/var/mail/cltbld', 15:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:37 INFO - 'MOZ_NO_REMOTE': '1', 15:34:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:37 INFO - 'NO_EM_RESTART': '1', 15:34:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:37 INFO - 'PWD': '/builds/slave/test', 15:34:37 INFO - 'SHELL': '/bin/bash', 15:34:37 INFO - 'SHLVL': '1', 15:34:37 INFO - 'TERM': 'linux', 15:34:37 INFO - 'TMOUT': '86400', 15:34:37 INFO - 'USER': 'cltbld', 15:34:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:37 INFO - '_': '/tools/buildbot/bin/python'} 15:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:37 INFO - Running setup.py (path:/tmp/pip-nK1gnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:37 INFO - Running setup.py (path:/tmp/pip-9kO2ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:37 INFO - Running setup.py (path:/tmp/pip-no5E6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:37 INFO - Running setup.py (path:/tmp/pip-9CUvSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:37 INFO - Running setup.py (path:/tmp/pip-0gajwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:37 INFO - Running setup.py (path:/tmp/pip-fbPCPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:38 INFO - Running setup.py (path:/tmp/pip-uFwudo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:38 INFO - Running setup.py (path:/tmp/pip-xXoKDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:38 INFO - Running setup.py (path:/tmp/pip-TA3bC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:38 INFO - Running setup.py (path:/tmp/pip-UZrjTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:38 INFO - Running setup.py (path:/tmp/pip-1pQKSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:38 INFO - Running setup.py (path:/tmp/pip-C5Lo39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:38 INFO - Running setup.py (path:/tmp/pip-NhtwkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:38 INFO - Running setup.py (path:/tmp/pip-OzU3x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:38 INFO - Running setup.py (path:/tmp/pip-yWaY8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:38 INFO - Running setup.py (path:/tmp/pip-eEoeET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:38 INFO - Running setup.py (path:/tmp/pip-DpzYLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:34:38 INFO - Running setup.py install for manifestparser 15:34:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:34:38 INFO - Running setup.py install for mozcrash 15:34:39 INFO - Running setup.py install for mozdebug 15:34:39 INFO - Running setup.py install for mozdevice 15:34:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for mozfile 15:34:39 INFO - Running setup.py install for mozhttpd 15:34:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for mozinfo 15:34:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for mozInstall 15:34:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for mozleak 15:34:39 INFO - Running setup.py install for mozlog 15:34:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for moznetwork 15:34:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:34:39 INFO - Running setup.py install for mozprocess 15:34:40 INFO - Running setup.py install for mozprofile 15:34:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:34:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:34:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:34:40 INFO - Running setup.py install for mozrunner 15:34:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:34:40 INFO - Running setup.py install for mozscreenshot 15:34:40 INFO - Running setup.py install for moztest 15:34:40 INFO - Running setup.py install for mozversion 15:34:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:34:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:34:40 INFO - Cleaning up... 15:34:40 INFO - Return code: 0 15:34:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:40 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:40 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 15:34:40 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 15:34:40 INFO - Using env: {'DISPLAY': ':0', 15:34:40 INFO - 'HOME': '/home/cltbld', 15:34:40 INFO - 'LANG': 'en_US.UTF-8', 15:34:40 INFO - 'LANGUAGE': 'en_US:en', 15:34:40 INFO - 'LOGNAME': 'cltbld', 15:34:40 INFO - 'MAIL': '/var/mail/cltbld', 15:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:40 INFO - 'MOZ_NO_REMOTE': '1', 15:34:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:40 INFO - 'NO_EM_RESTART': '1', 15:34:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:40 INFO - 'PWD': '/builds/slave/test', 15:34:40 INFO - 'SHELL': '/bin/bash', 15:34:40 INFO - 'SHLVL': '1', 15:34:40 INFO - 'TERM': 'linux', 15:34:40 INFO - 'TMOUT': '86400', 15:34:40 INFO - 'USER': 'cltbld', 15:34:40 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:40 INFO - '_': '/tools/buildbot/bin/python'} 15:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:40 INFO - Running setup.py (path:/tmp/pip-PUQgGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:40 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)) 15:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:40 INFO - Running setup.py (path:/tmp/pip-SaHPPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:41 INFO - Running setup.py (path:/tmp/pip-Whv9ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:41 INFO - Running setup.py (path:/tmp/pip-wAQqOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:41 INFO - Running setup.py (path:/tmp/pip-SiD9u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:41 INFO - Running setup.py (path:/tmp/pip-F281Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:41 INFO - Running setup.py (path:/tmp/pip-VtcICC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:41 INFO - Running setup.py (path:/tmp/pip-L7EEzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:41 INFO - Running setup.py (path:/tmp/pip-rEQVsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:41 INFO - Running setup.py (path:/tmp/pip-cQ2vFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:41 INFO - Running setup.py (path:/tmp/pip-QFWm49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:41 INFO - Running setup.py (path:/tmp/pip-XC1tRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:41 INFO - Running setup.py (path:/tmp/pip-8j0Dns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:41 INFO - Running setup.py (path:/tmp/pip-zSmSL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:41 INFO - Running setup.py (path:/tmp/pip-LwdTse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:41 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)) 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:41 INFO - Running setup.py (path:/tmp/pip-7XSdmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:42 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)) 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:42 INFO - Running setup.py (path:/tmp/pip-LyJ_Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:42 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)) 15:34:42 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)) 15:34:42 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)) 15:34:42 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)) 15:34:42 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)) 15:34:42 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)) 15:34:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:43 INFO - Downloading blessings-1.6.tar.gz 15:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:44 INFO - Installing collected packages: blessings 15:34:44 INFO - Running setup.py install for blessings 15:34:44 INFO - Successfully installed blessings 15:34:44 INFO - Cleaning up... 15:34:44 INFO - Return code: 0 15:34:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:44 INFO - Reading from file tmpfile_stdout 15:34:44 INFO - Current package versions: 15:34:44 INFO - argparse == 1.2.1 15:34:44 INFO - blessings == 1.6 15:34:44 INFO - blobuploader == 1.2.4 15:34:44 INFO - docopt == 0.6.1 15:34:44 INFO - functools32 == 3.2.3-2 15:34:44 INFO - jsonschema == 2.5.1 15:34:44 INFO - manifestparser == 1.1 15:34:44 INFO - mozInstall == 1.12 15:34:44 INFO - mozcrash == 0.17 15:34:44 INFO - mozdebug == 0.1 15:34:44 INFO - mozdevice == 0.48 15:34:44 INFO - mozfile == 1.2 15:34:44 INFO - mozhttpd == 0.7 15:34:44 INFO - mozinfo == 0.9 15:34:44 INFO - mozleak == 0.1 15:34:44 INFO - mozlog == 3.2 15:34:44 INFO - moznetwork == 0.27 15:34:44 INFO - mozprocess == 0.23 15:34:44 INFO - mozprofile == 0.28 15:34:44 INFO - mozrunner == 6.12 15:34:44 INFO - mozscreenshot == 0.1 15:34:44 INFO - mozsystemmonitor == 0.3 15:34:44 INFO - moztest == 0.7 15:34:44 INFO - mozversion == 1.4 15:34:44 INFO - psutil == 3.1.1 15:34:44 INFO - requests == 1.2.3 15:34:44 INFO - wsgiref == 0.1.2 15:34:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:44 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 15:34:44 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 15:34:44 INFO - Using env: {'DISPLAY': ':0', 15:34:44 INFO - 'HOME': '/home/cltbld', 15:34:44 INFO - 'LANG': 'en_US.UTF-8', 15:34:44 INFO - 'LANGUAGE': 'en_US:en', 15:34:44 INFO - 'LOGNAME': 'cltbld', 15:34:44 INFO - 'MAIL': '/var/mail/cltbld', 15:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:44 INFO - 'MOZ_NO_REMOTE': '1', 15:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:44 INFO - 'NO_EM_RESTART': '1', 15:34:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:44 INFO - 'PWD': '/builds/slave/test', 15:34:44 INFO - 'SHELL': '/bin/bash', 15:34:44 INFO - 'SHLVL': '1', 15:34:44 INFO - 'TERM': 'linux', 15:34:44 INFO - 'TMOUT': '86400', 15:34:44 INFO - 'USER': 'cltbld', 15:34:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:44 INFO - '_': '/tools/buildbot/bin/python'} 15:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 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)) 15:34:44 INFO - Cleaning up... 15:34:44 INFO - Return code: 0 15:34:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:44 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 15:34:44 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 15:34:44 INFO - Using env: {'DISPLAY': ':0', 15:34:44 INFO - 'HOME': '/home/cltbld', 15:34:44 INFO - 'LANG': 'en_US.UTF-8', 15:34:44 INFO - 'LANGUAGE': 'en_US:en', 15:34:44 INFO - 'LOGNAME': 'cltbld', 15:34:44 INFO - 'MAIL': '/var/mail/cltbld', 15:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:44 INFO - 'MOZ_NO_REMOTE': '1', 15:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:44 INFO - 'NO_EM_RESTART': '1', 15:34:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:44 INFO - 'PWD': '/builds/slave/test', 15:34:44 INFO - 'SHELL': '/bin/bash', 15:34:44 INFO - 'SHLVL': '1', 15:34:44 INFO - 'TERM': 'linux', 15:34:44 INFO - 'TMOUT': '86400', 15:34:44 INFO - 'USER': 'cltbld', 15:34:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:44 INFO - '_': '/tools/buildbot/bin/python'} 15:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:34:44 INFO - Cleaning up... 15:34:44 INFO - Return code: 0 15:34:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:44 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 0x7fb53e47ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x226d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, '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': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', '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 15:34:44 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 15:34:44 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 15:34:44 INFO - Using env: {'DISPLAY': ':0', 15:34:44 INFO - 'HOME': '/home/cltbld', 15:34:44 INFO - 'LANG': 'en_US.UTF-8', 15:34:44 INFO - 'LANGUAGE': 'en_US:en', 15:34:44 INFO - 'LOGNAME': 'cltbld', 15:34:44 INFO - 'MAIL': '/var/mail/cltbld', 15:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:44 INFO - 'MOZ_NO_REMOTE': '1', 15:34:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:34:44 INFO - 'NO_EM_RESTART': '1', 15:34:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:44 INFO - 'PWD': '/builds/slave/test', 15:34:44 INFO - 'SHELL': '/bin/bash', 15:34:44 INFO - 'SHLVL': '1', 15:34:44 INFO - 'TERM': 'linux', 15:34:44 INFO - 'TMOUT': '86400', 15:34:44 INFO - 'USER': 'cltbld', 15:34:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:44 INFO - '_': '/tools/buildbot/bin/python'} 15:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:45 INFO - Downloading/unpacking flake8 15:34:45 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 15:34:45 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 15:34:45 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 15:34:45 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 15:34:45 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 15:34:45 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 15:34:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:34:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:34:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:34:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:34:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:34:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:34:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:34:47 INFO - no previously-included directories found matching 'docs/_build' 15:34:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:34:47 INFO - Running setup.py install for pep8 15:34:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:34:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:34:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:34:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:34:47 INFO - no previously-included directories found matching 'docs/_build' 15:34:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:34:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:34:47 INFO - Cleaning up... 15:34:47 INFO - Return code: 0 15:34:47 INFO - Running post-action listener: _resource_record_post_action 15:34:47 INFO - Running post-action listener: _start_resource_monitoring 15:34:48 INFO - Starting resource monitoring. 15:34:48 INFO - ##### 15:34:48 INFO - ##### Finished create-virtualenv step (success) 15:34:48 INFO - ##### 15:34:48 INFO - ##### 15:34:48 INFO - ##### Running install step. 15:34:48 INFO - ##### 15:34:48 INFO - Running pre-action listener: _resource_record_pre_action 15:34:48 INFO - Running main action method: install 15:34:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:48 INFO - Reading from file tmpfile_stdout 15:34:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:48 INFO - Reading from file tmpfile_stdout 15:34:48 INFO - Output received: 15:34:48 INFO - Usage: mozinstall [options] installer 15:34:48 INFO - Options: 15:34:48 INFO - -h, --help show this help message and exit 15:34:48 INFO - -d DEST, --destination=DEST 15:34:48 INFO - Directory to install application into. [default: 15:34:48 INFO - "/builds/slave/test"] 15:34:48 INFO - --app=APP Application being installed. [default: firefox] 15:34:48 INFO - mkdir: /builds/slave/test/build/application 15:34:48 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'] 15:34:48 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 15:35:03 INFO - Reading from file tmpfile_stdout 15:35:03 INFO - Output received: 15:35:03 INFO - /builds/slave/test/build/application/firefox/firefox 15:35:03 INFO - Running post-action listener: _resource_record_post_action 15:35:03 INFO - ##### 15:35:03 INFO - ##### Finished install step (success) 15:35:03 INFO - ##### 15:35:03 INFO - ##### 15:35:03 INFO - ##### Running run-tests step. 15:35:03 INFO - ##### 15:35:03 INFO - Running pre-action listener: _resource_record_pre_action 15:35:03 INFO - Running main action method: run_tests 15:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:35:03 INFO - Python 2.7.3 15:35:03 INFO - Return code: 0 15:35:03 INFO - grabbing minidump binary from tooltool 15:35:03 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')]} 15:35:03 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 0x22fc1c0>, '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 0x22fd0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22fd550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:35:03 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 15:35:03 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 15:35:03 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 15:35:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:35:03 INFO - Return code: 0 15:35:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:35:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:35:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:35:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:35:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:35:04 INFO - Return code: 0 15:35:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:35:04 INFO - Using env: {'DISPLAY': ':0', 15:35:04 INFO - 'HOME': '/home/cltbld', 15:35:04 INFO - 'LANG': 'en_US.UTF-8', 15:35:04 INFO - 'LANGUAGE': 'en_US:en', 15:35:04 INFO - 'LOGNAME': 'cltbld', 15:35:04 INFO - 'MAIL': '/var/mail/cltbld', 15:35:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:35:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:35:04 INFO - 'MOZ_NO_REMOTE': '1', 15:35:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:35:04 INFO - 'NO_EM_RESTART': '1', 15:35:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:35:04 INFO - 'PWD': '/builds/slave/test', 15:35:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:35:04 INFO - 'SHELL': '/bin/bash', 15:35:04 INFO - 'SHLVL': '1', 15:35:04 INFO - 'TERM': 'linux', 15:35:04 INFO - 'TMOUT': '86400', 15:35:04 INFO - 'USER': 'cltbld', 15:35:04 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609', 15:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:35:04 INFO - '_': '/tools/buildbot/bin/python'} 15:35:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:35:05 INFO - mozversion application_buildid: 20160731124125 15:35:05 INFO - mozversion application_changeset: 1a408f5eb42e99435820445547affab4e21df92e 15:35:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:35:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:35:05 INFO - mozversion application_name: Firefox 15:35:05 INFO - mozversion application_remotingname: firefox-dev 15:35:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:35:05 INFO - mozversion application_vendor: Mozilla 15:35:05 INFO - mozversion application_version: 49.0a2 15:35:05 INFO - mozversion platform_buildid: 20160731124125 15:35:05 INFO - mozversion platform_changeset: 1a408f5eb42e99435820445547affab4e21df92e 15:35:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:35:05 INFO - mozversion platform_version: 49.0a2 15:35:05 INFO - using testdate: 1470004505 15:35:05 INFO - actual date: 1470004505 15:35:05 INFO - starting webserver on 'localhost:53371' 15:35:05 INFO - SUITE-START | Running 2 tests 15:35:05 INFO - TEST-START | dromaeo_css 15:35:05 INFO - operating with platform_type : linux_ 15:35:05 INFO - Initialising browser for dromaeo_css test... 15:35:05 INFO - TEST-INFO | started process 23530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5oz6Vb/profile http://localhost:53371/getInfo.html) 15:35:09 INFO - PROCESS | 23530 | __metrics Screen width/height:1600/1200 15:35:09 INFO - PROCESS | 23530 | colorDepth:24 15:35:09 INFO - PROCESS | 23530 | Browser inner width/height: 1024/697 15:35:09 INFO - PROCESS | 23530 | __metrics 15:35:10 INFO - PROCESS | 23530 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:35:10 ERROR - PROCESS | 23530 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:35:11 INFO - TEST-INFO | 23530: exit 0 15:35:11 INFO - Browser initialized. 15:35:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:35:11 INFO - TEST-INFO | started process 23625 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5oz6Vb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:22 INFO - PROCESS | 23625 | RSS: Main: 142753792 15:35:22 INFO - PROCESS | 23625 | 15:36:14 INFO - PROCESS | 23625 | 1470004574031 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] 15:38:21 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53371/tests/dromaeo/cssquery-ext.html) 15:38:22 INFO - PROCESS | 23625 | RSS: Main: 255418368 15:38:22 INFO - PROCESS | 23625 | 15:41:25 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-ext.html (next: http://localhost:53371/tests/dromaeo/cssquery-jquery.html) 15:41:25 INFO - PROCESS | 23625 | RSS: Main: 223248384 15:41:25 INFO - PROCESS | 23625 | 15:44:27 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53371/tests/dromaeo/cssquery-mootools.html) 15:44:28 INFO - PROCESS | 23625 | RSS: Main: 223797248 15:44:28 INFO - PROCESS | 23625 | 15:47:30 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53371/tests/dromaeo/cssquery-prototype.html) 15:47:31 INFO - PROCESS | 23625 | RSS: Main: 223490048 15:47:31 INFO - PROCESS | 23625 | 15:50:33 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53371/tests/dromaeo/cssquery-yui.html) 15:50:34 INFO - PROCESS | 23625 | RSS: Main: 216297472 15:50:34 INFO - PROCESS | 23625 | 15:53:36 INFO - PROCESS | 23625 | Cycle 1(1): loaded http://localhost:53371/tests/dromaeo/cssquery-yui.html (next: http://localhost:53371/tests/dromaeo/cssquery-dojo.html) 15:53:37 INFO - PROCESS | 23625 | RSS: Main: 262090752 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | __start_tp_report 15:53:37 INFO - PROCESS | 23625 | _x_x_mozilla_page_load 15:53:37 INFO - PROCESS | 23625 | _x_x_mozilla_page_load_details 15:53:37 INFO - PROCESS | 23625 | |i|pagename|runs| 15:53:37 INFO - PROCESS | 23625 | |0;dojo.html;3151.4642510773165;3296.884609038684;3301.3067255876263;3350.514175444561;3356.5972083350002;5375.704336261509;5503.367112782032;5514.834554963348;5516.420775818537;5523.812380338227;5374.7043912584795;5439.755211015504;5459.535939445152;5461.262729531512;5475.808346707866;4896.436909755379;4911.115999120157;4919.926201106986;4972.428170760355;5023.0707319145895;4900.485449027854;5040.042391945519;5083.313752643401;5088.643794934356;5096.108181068298;4896.951030489703;4909.484504127069;4934.5312195341385;5018.623603229754;5065.214891691794;5023.2214006829;5025.221090730943;5041.521055499722;5043.798248070109;5069.543741063304;4786.234202527614;4830.275458681191;4893;4964.230544265609;4997.100521906055;5259.053370393328;5285.180796976437;5294.54996325309;5321.042212401766;5339.359276886798;5743.540516758691;5838.883222335529;5840;5840.532757379399;5846.123081537761;5770.567207459415;5811.215485909433;5831.679257640828;5875.559333050039;5886.470217680429;5473.0969390050595;5496.835094947158;5511.255980442629;5514.28314319144;5515.806946756844;5707.4577915098;5991.670458124758;5993.670348130808;5995.850103747442;6013.819585412444;5678.4605462480995;5709.200711900337;5728;5781.335146458127;5788.0449725795615;5831.650100993954;5840.941590584125;5874.324452687995;5878.382769809108;5880.794172203952;5614.522765564891;5651.858703532363;5815;5821.417858214229;5826.126081087786;5657.632253903483;5700.486956173964;5704.629199102045;5705.2012718219485;5735.598508104393;4455.287154055336;4461.910761784811;4467.687261891637;4468.419105516262;4497.055618320124;3868.168343806039;3906.8437262509183;3972.9205415892097;3997.540282867449;4037.818298176589;5388.89886833125;5393.946060539422;5434.456554344534;5450;5461.9453805461435;5692.174634677948;5696.316442026983;5704.686242256633;5716.942830571641;5723.54211663066;5377.973110134424;5456.890862182813;5458.536024437888;5498.917516237179;5556.166575013699;5019.397672279277;5054;5059.468755780664;5066.417362003343;5074.720890351066;5872.148538461899;6038.547108966845;6052.455279024909;6082.665453400105;6083;5750.25246717924;5793.594448388572;5829.94170058294;5834.066549352084;5862.736176872137;6068.150458935665;6071.7571297147615;6072.089186622042;6078.179445774764;6082.482988945989;5646.689432081275;5673.602847800779;5771.538276937751;5771.884562308646;5775;5751.769929202785;5863.882722345615;5866.7066646668345;5867.295924488918;5892.793752218652;5649.378568357395;5657.179708942263;5660.349059858169;5665.036943719495;5666.744996475251;5537.418571049981;5550.69471179089;5562.694051827189;5695.601307908407;5713.742881570256;5567.693776842312;5583.497485226349;5593.468620481048;5605.80379686709;5611.57913156523;5686.687232202268;5708.400617935057;5717.028105222039;5745.511631511201;5756.65460072397;5500.752466138953;5575.107982722907;5606.327240731139;5631.746571404378;5640.689762062962 15:53:37 INFO - PROCESS | 23625 | |1;ext.html;590.669225233869;641.7914177892184;642.2838535033437;646.1858058845855;649.9122618446509;31975.360492790158;34502.82748586257;47942.76028619861;49468.75265623667;49605.25592116121;47561;47657;47675;47916;47925.52074479254;8311.709090181845;9100.681476148337;9249.445033298023;9249.537523123818;9253.30600204984;8314.750557483256;8609.354298427612;10769.299995500307;11078.335299881992;11087.113030957544;10815.242932994694;11320.773584528304;11330.490127944262;11348.09215262777;11350.09199264057;8415.621297041647;11071.335719856794;11168.218224724313;11174;11174.776504469946;10146.086852183265;10433.895661043369;10455.477226138662;10464.947675261652;10467.424291664032;44688.32967505458;44869.551304486864;44936.3259551107;45227.773861130816;45370.77314613406;3840.8655697050467;3848.903777405559;3858.614138586147;3944.960550394488;4144.544100148981;35016.82491587526;35719.4642080369;35781.381738828495;35825;36092.09769755751;4833.492483289239;4898.076999641676;4909;4920.975395123037;5008.173651347523;393842;395846;403881;551297;553647.9999999959;38824.41763373495;39846.800765995984;70443;71459;71858.64070679613;40438.393424098664;40473.59526404757;40639;40672.79663601722;40679.38980915289;17455;17572.12139393025;17595.824041759675;17603.207855646528;17607.647847042987;13718.657033574042;14592.781108283385;14749.483768068067;14900.1059936402;14902.180380078982;9058.547072646474;9194;9208.217301529312;9250;9264.629414823332;3515.244222492125;3620.2759448110482;3626.3110009098186;3633.8364773585254;3675.650813172744;1077.7251800790748;1089.9182561307935;1104.86741591008;1147.0500149745467;1183.526589364244;32000;32475.837620811744;33384;33625;34125.65874341274;1155.774623948322;1204.3857632607433;1205.516635730398;1298.5000774701807;1303.1985329022582;15573.221338933008;17610.82389176092;17761.911190444225;17804.91097544504;18443.538911527052;619561.6087678222;622794;623966;624029;624088;64997.67501162464;65527.99999999905;65543.67228163924;65612.34387656063;65799.67100164469;1244.004796163084;1417.7731562949878;1482.140358592014;1488.1220080152546;1489.292586021631;34204.94770313789;35015.64984350124;35664.465033024004;35823.82088089543;35857;54562.45437545574;55114.448855512535;55364.44635553754;55375;55407.16889246584;468680;469406;469698;470623;474338;26589.33526661849;31013.8449307752;31231;31424.214394640316;31683.20791980219;51238;51604.483955159965;52306.47693523016;52633.7368313156;53438;42671.93320167021;43383.566164339216;43504.564954350055;44137;44996.32505512486;41632.58367416408;43545;43705.344419834364;44328.556714432445;44848.32727509155;43422.78288608663;43918.56081439145;44679.77660111809;45346.77326613346;45400;36657.45013824848;38578;38744.2251154981;38769.418458722575;39600.207995839344;29021.85489072541;29105.12684619465;29393.412131757672;29404.26489337698;29573.112806615834 15:53:37 INFO - PROCESS | 23625 | |2;jquery.html;402.9435878976943;420.9095044565418;422.44659496060183;427.0945595337884;427.9293916503777;18670.53323666909;19925;20152.89923550382;20370.89814550925;20962.371128866158;19433.31983380582;19695.704564431544;19745.407637770855;19996.800031999675;20078.598428031466;5716.742746576414;5913.024350982083;5956.55325850563;5959.0763431668165;5961.135635332896;4383.934240986372;5077.974610126944;5843.152742852284;5868.501177399926;6739.764108256188;6490.058941453486;6618.040384144296;6654.3012983636745;6690.899636505432;6707.564008339442;6499.545031847772;6500.4474619657385;6540.247871494767;6570.1458810354825;6615.272320044791;3610.2598967211657;3777.1690228149764;3782.167923056923;5894.233749612523;5901.055831067051;21433.356999290045;22429.887850560644;22539;24311.149109781072;24420.023199072017;2564.7691707746394;2571.0744132112422;2603.3621762668076;2676.116881429143;3104.2394613319657;13815;14917.477888273967;15014;15443.382264709398;15526;2090.028136916326;2107.473131717071;2152.6770984352315;2191.244020812817;2231.6094683430333;57891.42108578902;63522;74556.62721686356;76945;85665.57167214125;11468.369239691898;11532.942335288437;11739.530418783324;11839.467223974942;11912.642620721392;16402.425915092914;16423;16512.66974660524;16517.504474865775;16542.255598497857;8700;9018.458892466473;9063.59213835379;9085;9199.402038867585;8842.115788421235;8854.29165666745;8885.244754195837;8890.911090889138;9000.324975626854;4642.9767851161205;5008.749562521859;5065.468125846806;5069.8732531687;5130.204818253177;1988.6221617892554;2039.9082041308177;2041.275347251734;2083.874967501955;2091.1948899673735;979.3634137810337;1008.3798463944621;1068.615298492542;1098.7912296663608;1131.660501849442;19173.520661983282;19379.4186174415;19549.315773947845;19717.507062323555;20322.593548128956;1089.2701889733896;1097.2209731091016;1099.4118146791427;1101.0255923507752;1102.2725001498973;10577.365358078387;10846.132309415229;10888.61889833852;10909.290896091728;10961.232713709964;491841;510902;527059;529256;536024;92551.13205959143;94628;94740;96705;98118;1058.708855064848;1059.2638116509106;1075.5966512557948;1079.4440862955585;1085.9412073228698;17106.315747369965;17235.827641723423;17352;17483.475495735147;17518.649627007642;37798.24403511969;38173;38338.42492362561;38609.42085868771;39384;48422.75788621034;57093.42906570881;57485.56286092881;57492.71253643705;57952.4204757947;8840.557972101498;8852.617954112755;8952.641894324153;8954.238889694188;9090.636374544943;12026.879731202926;13221.933890330487;13260.867391326348;13559.254241016839;13702;10517.631882884063;10795.946020269848;10949.671509854717;10956.835647465454;11093.223474356717;11357.204995650225;11440.828387574027;11706;11715.882841171479;11821.881781182077;10868.347899126078;10964.780704386027;10966.232363734462;11230.270032447865;11458.713532161762;8656.394052416523;8688.739337819876;8794.868076978979;8811.295096392401;8944.776380590538;9885.85171222418;10728.839067413839;10845.620403285848;11213.607523736957;11311.095112391153 15:53:37 INFO - PROCESS | 23625 | |3;mootools.html;1272.2112290379966;1507.9849201507982;1539.7921280627115;1660.6097567071736;1832.1663643042416;8385.87421188682;8777.253933415659;8791.999999999993;9015.549222538879;9018.14327638875;8755.036945935957;8939.597718102685;8942.508162051085;8942.86585701215;8943.50810705411;2080.147139672727;2326.639370897505;2389.8446600970924;2392.306231192952;2422.4307287787356;2364.420716924356;2372.395039264985;2374.620060790274;2376.097083108425;2377.583922813509;2360.386299562109;2365.82256330775;2369.1352656280505;2384.713834339873;2399.1123284384716;2041.1529215375601;2263.5359751250953;2321.791038806499;2325.93022209334;2333.2766842278947;2364.728056273533;2367.857928524294;2392.928212153638;2399.1602938971396;2400.675908752331;9009.549522523914;9089.454632722058;9103;9106;9114.635414583408;5287.206918962186;5452.781888724447;5458.754356053947;5459.372172200168;5463.262459567947;3348.380549598308;3494.860205591773;3548.4854696068924;3577.964220357789;3594.7663401879054;1240.8883200511907;1264.8229247905208;1278.4119305119564;1281.9102662813607;1297.1762930539082;12502.687432814162;12514.624561263177;12527.686807829787;12588.748225035539;12637.93681031589;4666.300054991779;4722.456917454537;4723.716577005391;4748.738819364969;4858.684185527929;9960.501974901372;9963.153131983718;10027.197824174049;10030.348027378197;10048.69853904384;5431.83704488866;5431.891362172734;5436;5436.3476382834315;5437.918431223535;4492.348609451611;4926.926096108561;5018.04657115147;5027.0197311524635;5049.798008079709;4073.716779214538;4077.4903137107863;4168.416421700964;4177.540470548237;4207.548395803443;1270.091884302728;1272.6436597752645;1272.720001599665;1275.2093701904878;1296.125115547002;889.6263569300909;962.225408546113;1025.5133789670192;1026.7741096958657;1042.1818872185297;6009.609375390585;6013.278406591237;6095.390460953958;6226.501879849601;6319.96840015797;935.3312381647155;955.4171955107379;1022.1720406470782;1069.9037086662238;1162.2271189658782;4285.164392943346;4320.027993701392;4334.306510958231;4339;4394.450693663292;10729.463526823627;10772.569097236179;10991.230613857113;11014.06380457654;11039.172062095375;10758.946205268923;10920.453977301104;11011.83482247767;11077.279976801483;11107.72230694239;8259;8308.501489910625;8345.582720863933;8392.118827523143;8407.663693452316;5869.325027621708;6074.969625151846;6096.177016102769;6201.255849298113;6220.7511699531515;10000.549975250986;10089.142422894136;10100.242481813746;10107.191424686154;10194.388336699822;10350.171986240932;10354.430506322225;10460.424676642553;10463.848976612415;10476.895231047592;6451.41937225652;6546.18172728409;7404.444666650131;7579.772606821804;7703.037120359955;8436.367272454714;8446.831063378575;8512.361572882182;8515.233628973423;8542.103079176846;7711.575863327571;7731;7774.144844067035;7933.127355990951;8037.638306276349;7744.806379840324;7844.4901081429525;7845.803854903673;7917.485363451588;7925.564093974886;7247.2390399007345;7295.890561641473;7322.340989310987;7332.523385979895;7333.74331898381;5116.590672746246;5204.375474943031;5243.842684719464;5264.815731449381;5445.019896418683;5202.427732949297;5216.295800066942;5335.708740569595;5424.349078110652;5564.33228012641 15:53:37 INFO - PROCESS | 23625 | |4;prototype.html;333.9933201335973;339.45347989736536;340.5334691472683;343.730171815125;344.42652982783665;5207.583393328534;5259.13224317988;5372.9462705372935;5388.703621300827;5395.541378982786;5250.238715386266;5260.237265596486;5284.550813180875;5295.258663787064;5316.3354580677415;4214.883055990157;4222.59885310895;4255.787210639455;4269.573042695721;4281.72168809028;4021.6782657387485;4070.837166513336;4243.448351714273;4259.8083086261195;4280.464941882265;4188.350805625133;4200.970762163274;4220.7256528325715;4221.944513871532;4227.8308867645255;4185.183889141618;4193.8741837744765;4205.600467955539;4209.5790420957965;4211.726237794564;3646.2342907989355;3712.4988126602834;3743.1577894973684;3760.4394176416754;3767.1523907120677;5104.719240441774;5123.257127716498;5133.22903598258;5158.148905430599;5185.377754669464;4738.455077666078;4826.903461930741;4838.830640927586;4867.269909513601;4868.196747536652;5400.91898621521;5512.125877201731;5535.944640553583;5549.250851135085;5570.526505247059;4132.090939993197;4142.71001029928;4145.046639272984;4149.585041495857;4151.875443736692;5728;5730.025895597759;5771.307443106812;5781.971090144522;5812.680302583399;4012.498437695288;4116.9794151029055;4198.328267477189;4206.116715489781;4216.114615930689;4195.706300558932;4216.599423054805;4240.342746874239;4245.745255284693;4253.447051883297;4785.521447855187;4790.233562629963;4791.425028996473;4804.639652026112;4810.518948105231;5494.780208791603;5552.7223638817895;5606.299212598426;5612.242347283146;5620.859478512988;1405.6485878530368;1423.5942756314607;1456.8126976514106;1464.2239613005124;1465.2527211122192;953.5422996961497;969.4038166527534;971.3977334052933;981.8625392445064;983.8243299257462;4831.115864812726;4871.318015477836;4887.731174785421;4895.657303988758;4913.778879950411;5130.820421285237;5167.560757335594;5169.405518365361;5179.7928082877015;5190.480951904779;4631.560001799824;4957.686213153517;4959.776810043557;4964;4965.32968049316;2717.388587567821;2721.9591706124424;2774.875130619127;2776.7500924916853;2780.179846945156;5324.361076670747;5331;5333.37332113709;5338.786448542015;5350.304460420199;5222.955408918231;5253.3958594761325;5443.510084092451;5923.4076592340425;5940.316863560746;5980.970095149496;5985.401459854067;5988.161657367885;6023.879522409527;6095.512359011268;5580.218769372373;5815.883682326415;5828.417158284137;5829.038208695518;5841.561882858888;5654;5678.432156784289;5717.8570535736935;5773.134029895549;5786.479216870502;5968.522518198447;5983.341832398346;5997.620547274064;6044.607100538451;6049.6067755597505;4049.838006479708;4185.727927684691;4198.748075115503;4202.453681021448;4202.978985105055;5557.944420555743;5652.745626446737;5763.827085187451;5807.186993820953;5828.242328497268;5586.4916472552995;5590.580706446952;5629.746661400165;5644.209810626598;5646.322441307069;5558.888822223614;5606.6636001840025;5639.210510528611;5699.406769919594;5705.34388545306;5321.5476684482255;5552.666839989613;5563.749631266522;5564.91652625219;5567.3040869891265;5631.577631677558;5647.576431767716;5648.096304591246;5652.208690783212;5658.349289831557;5495.587830912617;5531.598513011152;5562.9721851392105;5577.024020796425;5608.261438953938 15:53:37 INFO - PROCESS | 23625 | |5;yui.html;863.4819108534883;882.8587426011838;894.253298495756;902.3593248793356;925.7731855695356;16524.42164524242;16771.245293961754;16820.495385138434;17728;18369.632607347845;17132.657346853088;17695.55761105973;17810.732839007393;17816.287348506077;17847.91076044621;700.1038670501766;838.0613712641849;862.6506264962669;867.453504292295;868.8740132680757;859.4370687199894;861.7888617288742;868.8740132680757;871.856143736286;872.3021582733819;882.1355072029382;895.2524641924001;897.5512243878061;898.0884402331639;899.5277479323343;738.5162718419443;886.7118186589385;898.6091050393068;901.1889299630319;915.0163574157807;667.4927055437902;669.0532895952222;669.0566301514889;669.5848573884174;669.8760729265101;16301.673966520602;16433.342666293334;16453.91773041139;16454.095024773633;16458.917705411517;15308.234588270654;15562.476641335492;15730.921345393313;15757.763633545392;15774.76337854933;14292.285385730673;14331.42674293027;14528.70942581153;14601.707965840624;14684.632884177874;4672.929906051412;4716.752003727392;4762.880927976794;4768.976155119202;4840.152973229706;81646.9999999994;82130.58934705348;82495.58752206201;82575.58712206401;82853.58573207156;12068.698282543006;12150.270983741004;12407.945324647506;12550.671490162897;12562.246265224116;22933.426664333525;22941.42646433819;22952.19667311636;22996;23000.769992300196;8447;8715.172058654418;8731.51976641278;8855.690050848189;9147;4971.403431588233;5069.949300507022;5094.821681241138;5117.411497677815;5183.870403239949;3475.982620086934;3540.6990405815277;3543.255916257563;3560.9821950890077;3562.127278816655;599.4335353091374;617.8331850400457;658.6969993802809;662.4303099334569;701.1200942816732;623.9199005689584;641.6919878458366;745.1356426545182;783.1620166421874;799.0850476204713;10543.444185132308;10595.94702026485;10736.30214036085;10848.45757712127;11052.005319521282;659.5020759326677;666.9032990216391;758.3705524414722;780.2938340801584;804.9195080492021;5096.388433388017;5175.4824517548695;5259.474052594786;5288.36539615241;5293.444188360167;80101;112110;127140;131322.34338828435;132862;88826;116300.41849790866;116309;118004;119898;97323;102270.97729022763;102845.48577257065;102893.48553257185;104110;10168;10384.273100882865;10513.106385956973;10524.789504210026;10555.577776888838;49911;57912.42087579239;59004.40995589989;59972;60043.39956600378;68020;68510;68554.65722671355;74835;78165.60917195378;13606.115602485806;14045;14430.350634221695;14657.633559161106;14683.119012859263;15283.923580382472;15603.765943510629;15795.605109872346;16104.436344727877;16572.419965301156;14287.785683214968;14510.564683059525;14601.561953141423;15350.923245383701;15701.607459813597;14530.927345363205;14741.852581474048;14848.331825067682;14985.925070374578;15160.241987900341;14992.925035374754;15305;15645.452409165624;15671.451499197474;15786.289616967493;11621.360825154698;12084.335361555199;12110.273383597012;12171.573994910135;12260.14179007461;12421.93789031049;12646.557370491988;12759.234445933274;12838.935805320914;12946.093773435769 15:53:37 INFO - PROCESS | 23625 | __end_tp_report 15:53:37 INFO - PROCESS | 23625 | __start_cc_report 15:53:37 INFO - PROCESS | 23625 | _x_x_mozilla_cycle_collect,892 15:53:37 INFO - PROCESS | 23625 | __end_cc_report 15:53:37 INFO - PROCESS | 23625 | __startTimestamp1470005617162__endTimestamp 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | ------- Summary: start ------- 15:53:37 INFO - PROCESS | 23625 | Number of tests: 6 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#0] dojo.html Cycles:165 Average:5403.13 Median:5593.47 stddev:590.94 (10.6%) stddev-sans-first:565.74 15:53:37 INFO - PROCESS | 23625 | Values: 3151.5 3296.9 3301.3 3350.5 3356.6 5375.7 5503.4 5514.8 5516.4 5523.8 5374.7 5439.8 5459.5 5461.3 5475.8 4896.4 4911.1 4919.9 4972.4 5023.1 4900.5 5040.0 5083.3 5088.6 5096.1 4897.0 4909.5 4934.5 5018.6 5065.2 5023.2 5025.2 5041.5 5043.8 5069.5 4786.2 4830.3 4893.0 4964.2 4997.1 5259.1 5285.2 5294.5 5321.0 5339.4 5743.5 5838.9 5840.0 5840.5 5846.1 5770.6 5811.2 5831.7 5875.6 5886.5 5473.1 5496.8 5511.3 5514.3 5515.8 5707.5 5991.7 5993.7 5995.9 6013.8 5678.5 5709.2 5728.0 5781.3 5788.0 5831.7 5840.9 5874.3 5878.4 5880.8 5614.5 5651.9 5815.0 5821.4 5826.1 5657.6 5700.5 5704.6 5705.2 5735.6 4455.3 4461.9 4467.7 4468.4 4497.1 3868.2 3906.8 3972.9 3997.5 4037.8 5388.9 5393.9 5434.5 5450.0 5461.9 5692.2 5696.3 5704.7 5716.9 5723.5 5378.0 5456.9 5458.5 5498.9 5556.2 5019.4 5054.0 5059.5 5066.4 5074.7 5872.1 6038.5 6052.5 6082.7 6083.0 5750.3 5793.6 5829.9 5834.1 5862.7 6068.2 6071.8 6072.1 6078.2 6082.5 5646.7 5673.6 5771.5 5771.9 5775.0 5751.8 5863.9 5866.7 5867.3 5892.8 5649.4 5657.2 5660.3 5665.0 5666.7 5537.4 5550.7 5562.7 5695.6 5713.7 5567.7 5583.5 5593.5 5605.8 5611.6 5686.7 5708.4 5717.0 5745.5 5756.7 5500.8 5575.1 5606.3 5631.7 5640.7 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#1] ext.html Cycles:180 Average:67267.61 Median:39723.50 stddev:139766.73 (351.8%) stddev-sans-first:140069.16 15:53:37 INFO - PROCESS | 23625 | Values: 590.7 641.8 642.3 646.2 649.9 31975.4 34502.8 47942.8 49468.8 49605.3 47561.0 47657.0 47675.0 47916.0 47925.5 8311.7 9100.7 9249.4 9249.5 9253.3 8314.8 8609.4 10769.3 11078.3 11087.1 10815.2 11320.8 11330.5 11348.1 11350.1 8415.6 11071.3 11168.2 11174.0 11174.8 10146.1 10433.9 10455.5 10464.9 10467.4 44688.3 44869.6 44936.3 45227.8 45370.8 3840.9 3848.9 3858.6 3945.0 4144.5 35016.8 35719.5 35781.4 35825.0 36092.1 4833.5 4898.1 4909.0 4921.0 5008.2 393842.0 395846.0 403881.0 551297.0 553648.0 38824.4 39846.8 70443.0 71459.0 71858.6 40438.4 40473.6 40639.0 40672.8 40679.4 17455.0 17572.1 17595.8 17603.2 17607.6 13718.7 14592.8 14749.5 14900.1 14902.2 9058.5 9194.0 9208.2 9250.0 9264.6 3515.2 3620.3 3626.3 3633.8 3675.7 1077.7 1089.9 1104.9 1147.1 1183.5 32000.0 32475.8 33384.0 33625.0 34125.7 1155.8 1204.4 1205.5 1298.5 1303.2 15573.2 17610.8 17761.9 17804.9 18443.5 619561.6 622794.0 623966.0 624029.0 624088.0 64997.7 65528.0 65543.7 65612.3 65799.7 1244.0 1417.8 1482.1 1488.1 1489.3 34204.9 35015.6 35664.5 35823.8 35857.0 54562.5 55114.4 55364.4 55375.0 55407.2 468680.0 469406.0 469698.0 470623.0 474338.0 26589.3 31013.8 31231.0 31424.2 31683.2 51238.0 51604.5 52306.5 52633.7 53438.0 42671.9 43383.6 43504.6 44137.0 44996.3 41632.6 43545.0 43705.3 44328.6 44848.3 43422.8 43918.6 44679.8 45346.8 45400.0 36657.5 38578.0 38744.2 38769.4 39600.2 29021.9 29105.1 29393.4 29404.3 29573.1 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#2] jquery.html Cycles:180 Average:29775.75 Median:23425.07 stddev:85320.19 (364.2%) stddev-sans-first:85531.03 15:53:37 INFO - PROCESS | 23625 | Values: 402.9 420.9 422.4 427.1 427.9 18670.5 19925.0 20152.9 20370.9 20962.4 19433.3 19695.7 19745.4 19996.8 20078.6 5716.7 5913.0 5956.6 5959.1 5961.1 4383.9 5078.0 5843.2 5868.5 6739.8 6490.1 6618.0 6654.3 6690.9 6707.6 6499.5 6500.4 6540.2 6570.1 6615.3 3610.3 3777.2 3782.2 5894.2 5901.1 21433.4 22429.9 22539.0 24311.1 24420.0 2564.8 2571.1 2603.4 2676.1 3104.2 13815.0 14917.5 15014.0 15443.4 15526.0 2090.0 2107.5 2152.7 2191.2 2231.6 57891.4 63522.0 74556.6 76945.0 85665.6 11468.4 11532.9 11739.5 11839.5 11912.6 16402.4 16423.0 16512.7 16517.5 16542.3 8700.0 9018.5 9063.6 9085.0 9199.4 8842.1 8854.3 8885.2 8890.9 9000.3 4643.0 5008.7 5065.5 5069.9 5130.2 1988.6 2039.9 2041.3 2083.9 2091.2 979.4 1008.4 1068.6 1098.8 1131.7 19173.5 19379.4 19549.3 19717.5 20322.6 1089.3 1097.2 1099.4 1101.0 1102.3 10577.4 10846.1 10888.6 10909.3 10961.2 491841.0 510902.0 527059.0 529256.0 536024.0 92551.1 94628.0 94740.0 96705.0 98118.0 1058.7 1059.3 1075.6 1079.4 1085.9 17106.3 17235.8 17352.0 17483.5 17518.6 37798.2 38173.0 38338.4 38609.4 39384.0 48422.8 57093.4 57485.6 57492.7 57952.4 8840.6 8852.6 8952.6 8954.2 9090.6 12026.9 13221.9 13260.9 13559.3 13702.0 10517.6 10795.9 10949.7 10956.8 11093.2 11357.2 11440.8 11706.0 11715.9 11821.9 10868.3 10964.8 10966.2 11230.3 11458.7 8656.4 8688.7 8794.9 8811.3 8944.8 9885.9 10728.8 10845.6 11213.6 11311.1 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#3] mootools.html Cycles:180 Average:5885.79 Median:4694.38 stddev:3298.63 (70.3%) stddev-sans-first:3289.66 15:53:37 INFO - PROCESS | 23625 | Values: 1272.2 1508.0 1539.8 1660.6 1832.2 8385.9 8777.3 8792.0 9015.5 9018.1 8755.0 8939.6 8942.5 8942.9 8943.5 2080.1 2326.6 2389.8 2392.3 2422.4 2364.4 2372.4 2374.6 2376.1 2377.6 2360.4 2365.8 2369.1 2384.7 2399.1 2041.2 2263.5 2321.8 2325.9 2333.3 2364.7 2367.9 2392.9 2399.2 2400.7 9009.5 9089.5 9103.0 9106.0 9114.6 5287.2 5452.8 5458.8 5459.4 5463.3 3348.4 3494.9 3548.5 3578.0 3594.8 1240.9 1264.8 1278.4 1281.9 1297.2 12502.7 12514.6 12527.7 12588.7 12637.9 4666.3 4722.5 4723.7 4748.7 4858.7 9960.5 9963.2 10027.2 10030.3 10048.7 5431.8 5431.9 5436.0 5436.3 5437.9 4492.3 4926.9 5018.0 5027.0 5049.8 4073.7 4077.5 4168.4 4177.5 4207.5 1270.1 1272.6 1272.7 1275.2 1296.1 889.6 962.2 1025.5 1026.8 1042.2 6009.6 6013.3 6095.4 6226.5 6320.0 935.3 955.4 1022.2 1069.9 1162.2 4285.2 4320.0 4334.3 4339.0 4394.5 10729.5 10772.6 10991.2 11014.1 11039.2 10758.9 10920.5 11011.8 11077.3 11107.7 8259.0 8308.5 8345.6 8392.1 8407.7 5869.3 6075.0 6096.2 6201.3 6220.8 10000.5 10089.1 10100.2 10107.2 10194.4 10350.2 10354.4 10460.4 10463.8 10476.9 6451.4 6546.2 7404.4 7579.8 7703.0 8436.4 8446.8 8512.4 8515.2 8542.1 7711.6 7731.0 7774.1 7933.1 8037.6 7744.8 7844.5 7845.8 7917.5 7925.6 7247.2 7295.9 7322.3 7332.5 7333.7 5116.6 5204.4 5243.8 5264.8 5445.0 5202.4 5216.3 5335.7 5424.3 5564.3 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#4] prototype.html Cycles:180 Average:4668.14 Median:5215.27 stddev:1359.35 (26.1%) stddev-sans-first:1323.66 15:53:37 INFO - PROCESS | 23625 | Values: 334.0 339.5 340.5 343.7 344.4 5207.6 5259.1 5372.9 5388.7 5395.5 5250.2 5260.2 5284.6 5295.3 5316.3 4214.9 4222.6 4255.8 4269.6 4281.7 4021.7 4070.8 4243.4 4259.8 4280.5 4188.4 4201.0 4220.7 4221.9 4227.8 4185.2 4193.9 4205.6 4209.6 4211.7 3646.2 3712.5 3743.2 3760.4 3767.2 5104.7 5123.3 5133.2 5158.1 5185.4 4738.5 4826.9 4838.8 4867.3 4868.2 5400.9 5512.1 5535.9 5549.3 5570.5 4132.1 4142.7 4145.0 4149.6 4151.9 5728.0 5730.0 5771.3 5782.0 5812.7 4012.5 4117.0 4198.3 4206.1 4216.1 4195.7 4216.6 4240.3 4245.7 4253.4 4785.5 4790.2 4791.4 4804.6 4810.5 5494.8 5552.7 5606.3 5612.2 5620.9 1405.6 1423.6 1456.8 1464.2 1465.3 953.5 969.4 971.4 981.9 983.8 4831.1 4871.3 4887.7 4895.7 4913.8 5130.8 5167.6 5169.4 5179.8 5190.5 4631.6 4957.7 4959.8 4964.0 4965.3 2717.4 2722.0 2774.9 2776.8 2780.2 5324.4 5331.0 5333.4 5338.8 5350.3 5223.0 5253.4 5443.5 5923.4 5940.3 5981.0 5985.4 5988.2 6023.9 6095.5 5580.2 5815.9 5828.4 5829.0 5841.6 5654.0 5678.4 5717.9 5773.1 5786.5 5968.5 5983.3 5997.6 6044.6 6049.6 4049.8 4185.7 4198.7 4202.5 4203.0 5557.9 5652.7 5763.8 5807.2 5828.2 5586.5 5590.6 5629.7 5644.2 5646.3 5558.9 5606.7 5639.2 5699.4 5705.3 5321.5 5552.7 5563.7 5564.9 5567.3 5631.6 5647.6 5648.1 5652.2 5658.3 5495.6 5531.6 5563.0 5577.0 5608.3 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - PROCESS | 23625 | [#5] yui.html Cycles:180 Average:22621.55 Median:22998.38 stddev:32455.15 (141.1%) stddev-sans-first:32505.07 15:53:37 INFO - PROCESS | 23625 | Values: 863.5 882.9 894.3 902.4 925.8 16524.4 16771.2 16820.5 17728.0 18369.6 17132.7 17695.6 17810.7 17816.3 17847.9 700.1 838.1 862.7 867.5 868.9 859.4 861.8 868.9 871.9 872.3 882.1 895.3 897.6 898.1 899.5 738.5 886.7 898.6 901.2 915.0 667.5 669.1 669.1 669.6 669.9 16301.7 16433.3 16453.9 16454.1 16458.9 15308.2 15562.5 15730.9 15757.8 15774.8 14292.3 14331.4 14528.7 14601.7 14684.6 4672.9 4716.8 4762.9 4769.0 4840.2 81647.0 82130.6 82495.6 82575.6 82853.6 12068.7 12150.3 12407.9 12550.7 12562.2 22933.4 22941.4 22952.2 22996.0 23000.8 8447.0 8715.2 8731.5 8855.7 9147.0 4971.4 5069.9 5094.8 5117.4 5183.9 3476.0 3540.7 3543.3 3561.0 3562.1 599.4 617.8 658.7 662.4 701.1 623.9 641.7 745.1 783.2 799.1 10543.4 10595.9 10736.3 10848.5 11052.0 659.5 666.9 758.4 780.3 804.9 5096.4 5175.5 5259.5 5288.4 5293.4 80101.0 112110.0 127140.0 131322.3 132862.0 88826.0 116300.4 116309.0 118004.0 119898.0 97323.0 102271.0 102845.5 102893.5 104110.0 10168.0 10384.3 10513.1 10524.8 10555.6 49911.0 57912.4 59004.4 59972.0 60043.4 68020.0 68510.0 68554.7 74835.0 78165.6 13606.1 14045.0 14430.4 14657.6 14683.1 15283.9 15603.8 15795.6 16104.4 16572.4 14287.8 14510.6 14601.6 15350.9 15701.6 14530.9 14741.9 14848.3 14985.9 15160.2 14992.9 15305.0 15645.5 15671.5 15786.3 11621.4 12084.3 12110.3 12171.6 12260.1 12421.9 12646.6 12759.2 12838.9 12946.1 15:53:37 INFO - PROCESS | 23625 | -------- Summary: end -------- 15:53:37 INFO - PROCESS | 23625 | 15:53:37 INFO - TEST-INFO | 23625: exit 0 15:53:37 INFO - TEST-OK | dromaeo_css | took 1112849ms 15:53:37 INFO - TEST-START | kraken 15:53:37 INFO - operating with platform_type : linux_ 15:53:37 INFO - Initialising browser for kraken test... 15:53:37 INFO - TEST-INFO | started process 23815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESTrS3/profile http://localhost:53371/getInfo.html) 15:53:41 INFO - PROCESS | 23815 | __metrics Screen width/height:1600/1200 15:53:41 INFO - PROCESS | 23815 | colorDepth:24 15:53:41 INFO - PROCESS | 23815 | Browser inner width/height: 1024/697 15:53:41 INFO - PROCESS | 23815 | __metrics 15:53:42 INFO - PROCESS | 23815 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:53:42 ERROR - PROCESS | 23815 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:53:43 INFO - TEST-INFO | 23815: exit 0 15:53:43 INFO - Browser initialized. 15:53:43 INFO - Running cycle 1/1 for kraken test... 15:53:43 INFO - TEST-INFO | started process 23906 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESTrS3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:55 INFO - PROCESS | 23906 | RSS: Main: 146059264 15:53:55 INFO - PROCESS | 23906 | 15:53:57 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53371/tests/kraken/driver.html?testName=audio-beat-detection) 15:53:58 INFO - PROCESS | 23906 | RSS: Main: 196222976 15:53:58 INFO - PROCESS | 23906 | 15:54:01 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53371/tests/kraken/driver.html?testName=audio-dft) 15:54:01 INFO - PROCESS | 23906 | RSS: Main: 196452352 15:54:01 INFO - PROCESS | 23906 | 15:54:04 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53371/tests/kraken/driver.html?testName=audio-fft) 15:54:05 INFO - PROCESS | 23906 | RSS: Main: 283639808 15:54:05 INFO - PROCESS | 23906 | 15:54:07 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53371/tests/kraken/driver.html?testName=audio-oscillator) 15:54:07 INFO - PROCESS | 23906 | RSS: Main: 191442944 15:54:07 INFO - PROCESS | 23906 | 15:54:10 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53371/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:54:10 INFO - PROCESS | 23906 | RSS: Main: 192532480 15:54:10 INFO - PROCESS | 23906 | 15:54:13 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53371/tests/kraken/driver.html?testName=imaging-darkroom) 15:54:14 INFO - PROCESS | 23906 | RSS: Main: 227639296 15:54:14 INFO - PROCESS | 23906 | 15:54:17 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53371/tests/kraken/driver.html?testName=imaging-desaturate) 15:54:18 INFO - PROCESS | 23906 | RSS: Main: 227647488 15:54:18 INFO - PROCESS | 23906 | 15:54:21 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53371/tests/kraken/driver.html?testName=json-parse-financial) 15:54:21 INFO - PROCESS | 23906 | RSS: Main: 226791424 15:54:21 INFO - PROCESS | 23906 | 15:54:23 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53371/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:54:23 INFO - PROCESS | 23906 | RSS: Main: 204406784 15:54:23 INFO - PROCESS | 23906 | 15:54:25 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:54:26 INFO - PROCESS | 23906 | RSS: Main: 208515072 15:54:26 INFO - PROCESS | 23906 | 15:54:28 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:54:28 INFO - PROCESS | 23906 | RSS: Main: 211677184 15:54:28 INFO - PROCESS | 23906 | 15:54:31 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:54:31 INFO - PROCESS | 23906 | RSS: Main: 213381120 15:54:31 INFO - PROCESS | 23906 | 15:54:34 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:54:35 INFO - PROCESS | 23906 | RSS: Main: 212860928 15:54:35 INFO - PROCESS | 23906 | 15:54:36 INFO - PROCESS | 23906 | Cycle 1(1): loaded http://localhost:53371/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53371/tests/kraken/driver.html?testName=ai-astar) 15:54:37 INFO - PROCESS | 23906 | RSS: Main: 254963712 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | __start_tp_report 15:54:37 INFO - PROCESS | 23906 | _x_x_mozilla_page_load 15:54:37 INFO - PROCESS | 23906 | _x_x_mozilla_page_load_details 15:54:37 INFO - PROCESS | 23906 | |i|pagename|runs| 15:54:37 INFO - PROCESS | 23906 | |0;ai-astar;112;106;112;111;111;110;112;111;111;109 15:54:37 INFO - PROCESS | 23906 | |1;audio-beat-detection;159;127;130;172;126;128;125;151;173;126 15:54:37 INFO - PROCESS | 23906 | |2;audio-dft;161;162;160;160;160;162;161;162;160;162 15:54:37 INFO - PROCESS | 23906 | |3;audio-fft;74;76;75;106;75;74;75;76;103;74 15:54:37 INFO - PROCESS | 23906 | |4;audio-oscillator;84;84;122;83;84;84;116;84;84;121 15:54:37 INFO - PROCESS | 23906 | |5;imaging-gaussian-blur;116;118;117;117;117;117;117;117;117;116 15:54:37 INFO - PROCESS | 23906 | |6;imaging-darkroom;147;148;148;147;145;146;147;146;146;145 15:54:37 INFO - PROCESS | 23906 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;94 15:54:37 INFO - PROCESS | 23906 | |8;json-parse-financial;52;52;52;53;54;61;53;54;52;53 15:54:37 INFO - PROCESS | 23906 | |9;json-stringify-tinderbox;59;61;59;59;60;59;60;59;60;60 15:54:37 INFO - PROCESS | 23906 | |10;stanford-crypto-aes;77;77;75;83;81;76;76;76;106;82 15:54:37 INFO - PROCESS | 23906 | |11;stanford-crypto-ccm;127;118;118;120;123;118;125;126;131;123 15:54:37 INFO - PROCESS | 23906 | |12;stanford-crypto-pbkdf2;159;154;152;152;155;153;152;157;157;153 15:54:37 INFO - PROCESS | 23906 | |13;stanford-crypto-sha256-iterative;63;62;63;60;61;64;61;61;61;63 15:54:37 INFO - PROCESS | 23906 | __end_tp_report 15:54:37 INFO - PROCESS | 23906 | __start_cc_report 15:54:37 INFO - PROCESS | 23906 | _x_x_mozilla_cycle_collect,1573 15:54:37 INFO - PROCESS | 23906 | __end_cc_report 15:54:37 INFO - PROCESS | 23906 | __startTimestamp1470005677011__endTimestamp 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | ------- Summary: start ------- 15:54:37 INFO - PROCESS | 23906 | Number of tests: 14 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.84 (1.7%) stddev-sans-first:1.87 15:54:37 INFO - PROCESS | 23906 | Values: 112.0 106.0 112.0 111.0 111.0 110.0 112.0 111.0 111.0 109.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:140.50 stddev:19.99 (14.2%) stddev-sans-first:20.20 15:54:37 INFO - PROCESS | 23906 | Values: 159.0 127.0 130.0 172.0 126.0 128.0 125.0 151.0 173.0 126.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:0.94 (0.6%) stddev-sans-first:1.00 15:54:37 INFO - PROCESS | 23906 | Values: 161.0 162.0 160.0 160.0 160.0 162.0 161.0 162.0 160.0 162.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.53 (16.7%) stddev-sans-first:13.05 15:54:37 INFO - PROCESS | 23906 | Values: 74.0 76.0 75.0 106.0 75.0 74.0 75.0 76.0 103.0 74.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.00 stddev:17.37 (20.7%) stddev-sans-first:17.99 15:54:37 INFO - PROCESS | 23906 | Values: 84.0 84.0 122.0 83.0 84.0 84.0 116.0 84.0 84.0 121.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 15:54:37 INFO - PROCESS | 23906 | Values: 116.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 15:54:37 INFO - PROCESS | 23906 | Values: 147.0 148.0 148.0 147.0 145.0 146.0 147.0 146.0 146.0 145.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 15:54:37 INFO - PROCESS | 23906 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.00 stddev:2.72 (5.1%) stddev-sans-first:2.82 15:54:37 INFO - PROCESS | 23906 | Values: 52.0 52.0 52.0 53.0 54.0 61.0 53.0 54.0 52.0 53.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 15:54:37 INFO - PROCESS | 23906 | Values: 59.0 61.0 59.0 59.0 60.0 59.0 60.0 59.0 60.0 60.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:77.00 stddev:9.27 (12.0%) stddev-sans-first:9.72 15:54:37 INFO - PROCESS | 23906 | Values: 77.0 77.0 75.0 83.0 81.0 76.0 76.0 76.0 106.0 82.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:124.00 stddev:4.43 (3.6%) stddev-sans-first:4.45 15:54:37 INFO - PROCESS | 23906 | Values: 127.0 118.0 118.0 120.0 123.0 118.0 125.0 126.0 131.0 123.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:154.50 stddev:2.50 (1.6%) stddev-sans-first:2.03 15:54:37 INFO - PROCESS | 23906 | Values: 159.0 154.0 152.0 152.0 155.0 153.0 152.0 157.0 157.0 153.0 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - PROCESS | 23906 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.50 stddev:1.29 (2.1%) stddev-sans-first:1.30 15:54:37 INFO - PROCESS | 23906 | Values: 63.0 62.0 63.0 60.0 61.0 64.0 61.0 61.0 61.0 63.0 15:54:37 INFO - PROCESS | 23906 | -------- Summary: end -------- 15:54:37 INFO - PROCESS | 23906 | 15:54:37 INFO - TEST-INFO | 23906: exit 0 15:54:38 INFO - TEST-OK | kraken | took 60076ms 15:54:38 INFO - SUITE-END | took 1172s 15:54:38 INFO - Completed test suite (00:19:33) 15:54:38 INFO - javascript benchmark 15:54:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.4642510773165, 3296.884609038684, 3301.3067255876263, 3350.514175444561, 3356.5972083350002, 5375.704336261509, 5503.367112782032, 5514.834554963348, 5516.420775818537, 5523.812380338227, 5374.7043912584795, 5439.755211015504, 5459.535939445152, 5461.262729531512, 5475.808346707866, 4896.436909755379, 4911.115999120157, 4919.926201106986, 4972.428170760355, 5023.0707319145895, 4900.485449027854, 5040.042391945519, 5083.313752643401, 5088.643794934356, 5096.108181068298, 4896.951030489703, 4909.484504127069, 4934.5312195341385, 5018.623603229754, 5065.214891691794, 5023.2214006829, 5025.221090730943, 5041.521055499722, 5043.798248070109, 5069.543741063304, 4786.234202527614, 4830.275458681191, 4893.0, 4964.230544265609, 4997.100521906055, 5259.053370393328, 5285.180796976437, 5294.54996325309, 5321.042212401766, 5339.359276886798, 5743.540516758691, 5838.883222335529, 5840.0, 5840.532757379399, 5846.123081537761, 5770.567207459415, 5811.215485909433, 5831.679257640828, 5875.559333050039, 5886.470217680429, 5473.0969390050595, 5496.835094947158, 5511.255980442629, 5514.28314319144, 5515.806946756844, 5707.4577915098, 5991.670458124758, 5993.670348130808, 5995.850103747442, 6013.819585412444, 5678.4605462480995, 5709.200711900337, 5728.0, 5781.335146458127, 5788.0449725795615, 5831.650100993954, 5840.941590584125, 5874.324452687995, 5878.382769809108, 5880.794172203952, 5614.522765564891, 5651.858703532363, 5815.0, 5821.417858214229, 5826.126081087786, 5657.632253903483, 5700.486956173964, 5704.629199102045, 5705.2012718219485, 5735.598508104393, 4455.287154055336, 4461.910761784811, 4467.687261891637, 4468.419105516262, 4497.055618320124, 3868.168343806039, 3906.8437262509183, 3972.9205415892097, 3997.540282867449, 4037.818298176589, 5388.89886833125, 5393.946060539422, 5434.456554344534, 5450.0, 5461.9453805461435, 5692.174634677948, 5696.316442026983, 5704.686242256633, 5716.942830571641, 5723.54211663066, 5377.973110134424, 5456.890862182813, 5458.536024437888, 5498.917516237179, 5556.166575013699, 5019.397672279277, 5054.0, 5059.468755780664, 5066.417362003343, 5074.720890351066, 5872.148538461899, 6038.547108966845, 6052.455279024909, 6082.665453400105, 6083.0, 5750.25246717924, 5793.594448388572, 5829.94170058294, 5834.066549352084, 5862.736176872137, 6068.150458935665, 6071.7571297147615, 6072.089186622042, 6078.179445774764, 6082.482988945989, 5646.689432081275, 5673.602847800779, 5771.538276937751, 5771.884562308646, 5775.0, 5751.769929202785, 5863.882722345615, 5866.7066646668345, 5867.295924488918, 5892.793752218652, 5649.378568357395, 5657.179708942263, 5660.349059858169, 5665.036943719495, 5666.744996475251, 5537.418571049981, 5550.69471179089, 5562.694051827189, 5695.601307908407, 5713.742881570256, 5567.693776842312, 5583.497485226349, 5593.468620481048, 5605.80379686709, 5611.57913156523, 5686.687232202268, 5708.400617935057, 5717.028105222039, 5745.511631511201, 5756.65460072397, 5500.752466138953, 5575.107982722907, 5606.327240731139, 5631.746571404378, 5640.689762062962], "value": 5365.367724543618, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.669225233869, 641.7914177892184, 642.2838535033437, 646.1858058845855, 649.9122618446509, 31975.360492790158, 34502.82748586257, 47942.76028619861, 49468.75265623667, 49605.25592116121, 47561.0, 47657.0, 47675.0, 47916.0, 47925.52074479254, 8311.709090181845, 9100.681476148337, 9249.445033298023, 9249.537523123818, 9253.30600204984, 8314.750557483256, 8609.354298427612, 10769.299995500307, 11078.335299881992, 11087.113030957544, 10815.242932994694, 11320.773584528304, 11330.490127944262, 11348.09215262777, 11350.09199264057, 8415.621297041647, 11071.335719856794, 11168.218224724313, 11174.0, 11174.776504469946, 10146.086852183265, 10433.895661043369, 10455.477226138662, 10464.947675261652, 10467.424291664032, 44688.32967505458, 44869.551304486864, 44936.3259551107, 45227.773861130816, 45370.77314613406, 3840.8655697050467, 3848.903777405559, 3858.614138586147, 3944.960550394488, 4144.544100148981, 35016.82491587526, 35719.4642080369, 35781.381738828495, 35825.0, 36092.09769755751, 4833.492483289239, 4898.076999641676, 4909.0, 4920.975395123037, 5008.173651347523, 393842.0, 395846.0, 403881.0, 551297.0, 553647.9999999959, 38824.41763373495, 39846.800765995984, 70443.0, 71459.0, 71858.64070679613, 40438.393424098664, 40473.59526404757, 40639.0, 40672.79663601722, 40679.38980915289, 17455.0, 17572.12139393025, 17595.824041759675, 17603.207855646528, 17607.647847042987, 13718.657033574042, 14592.781108283385, 14749.483768068067, 14900.1059936402, 14902.180380078982, 9058.547072646474, 9194.0, 9208.217301529312, 9250.0, 9264.629414823332, 3515.244222492125, 3620.2759448110482, 3626.3110009098186, 3633.8364773585254, 3675.650813172744, 1077.7251800790748, 1089.9182561307935, 1104.86741591008, 1147.0500149745467, 1183.526589364244, 32000.0, 32475.837620811744, 33384.0, 33625.0, 34125.65874341274, 1155.774623948322, 1204.3857632607433, 1205.516635730398, 1298.5000774701807, 1303.1985329022582, 15573.221338933008, 17610.82389176092, 17761.911190444225, 17804.91097544504, 18443.538911527052, 619561.6087678222, 622794.0, 623966.0, 624029.0, 624088.0, 64997.67501162464, 65527.99999999905, 65543.67228163924, 65612.34387656063, 65799.67100164469, 1244.004796163084, 1417.7731562949878, 1482.140358592014, 1488.1220080152546, 1489.292586021631, 34204.94770313789, 35015.64984350124, 35664.465033024004, 35823.82088089543, 35857.0, 54562.45437545574, 55114.448855512535, 55364.44635553754, 55375.0, 55407.16889246584, 468680.0, 469406.0, 469698.0, 470623.0, 474338.0, 26589.33526661849, 31013.8449307752, 31231.0, 31424.214394640316, 31683.20791980219, 51238.0, 51604.483955159965, 52306.47693523016, 52633.7368313156, 53438.0, 42671.93320167021, 43383.566164339216, 43504.564954350055, 44137.0, 44996.32505512486, 41632.58367416408, 43545.0, 43705.344419834364, 44328.556714432445, 44848.32727509155, 43422.78288608663, 43918.56081439145, 44679.77660111809, 45346.77326613346, 45400.0, 36657.45013824848, 38578.0, 38744.2251154981, 38769.418458722575, 39600.207995839344, 29021.85489072541, 29105.12684619465, 29393.412131757672, 29404.26489337698, 29573.112806615834], "value": 20773.590022363915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.9435878976943, 420.9095044565418, 422.44659496060183, 427.0945595337884, 427.9293916503777, 18670.53323666909, 19925.0, 20152.89923550382, 20370.89814550925, 20962.371128866158, 19433.31983380582, 19695.704564431544, 19745.407637770855, 19996.800031999675, 20078.598428031466, 5716.742746576414, 5913.024350982083, 5956.55325850563, 5959.0763431668165, 5961.135635332896, 4383.934240986372, 5077.974610126944, 5843.152742852284, 5868.501177399926, 6739.764108256188, 6490.058941453486, 6618.040384144296, 6654.3012983636745, 6690.899636505432, 6707.564008339442, 6499.545031847772, 6500.4474619657385, 6540.247871494767, 6570.1458810354825, 6615.272320044791, 3610.2598967211657, 3777.1690228149764, 3782.167923056923, 5894.233749612523, 5901.055831067051, 21433.356999290045, 22429.887850560644, 22539.0, 24311.149109781072, 24420.023199072017, 2564.7691707746394, 2571.0744132112422, 2603.3621762668076, 2676.116881429143, 3104.2394613319657, 13815.0, 14917.477888273967, 15014.0, 15443.382264709398, 15526.0, 2090.028136916326, 2107.473131717071, 2152.6770984352315, 2191.244020812817, 2231.6094683430333, 57891.42108578902, 63522.0, 74556.62721686356, 76945.0, 85665.57167214125, 11468.369239691898, 11532.942335288437, 11739.530418783324, 11839.467223974942, 11912.642620721392, 16402.425915092914, 16423.0, 16512.66974660524, 16517.504474865775, 16542.255598497857, 8700.0, 9018.458892466473, 9063.59213835379, 9085.0, 9199.402038867585, 8842.115788421235, 8854.29165666745, 8885.244754195837, 8890.911090889138, 9000.324975626854, 4642.9767851161205, 5008.749562521859, 5065.468125846806, 5069.8732531687, 5130.204818253177, 1988.6221617892554, 2039.9082041308177, 2041.275347251734, 2083.874967501955, 2091.1948899673735, 979.3634137810337, 1008.3798463944621, 1068.615298492542, 1098.7912296663608, 1131.660501849442, 19173.520661983282, 19379.4186174415, 19549.315773947845, 19717.507062323555, 20322.593548128956, 1089.2701889733896, 1097.2209731091016, 1099.4118146791427, 1101.0255923507752, 1102.2725001498973, 10577.365358078387, 10846.132309415229, 10888.61889833852, 10909.290896091728, 10961.232713709964, 491841.0, 510902.0, 527059.0, 529256.0, 536024.0, 92551.13205959143, 94628.0, 94740.0, 96705.0, 98118.0, 1058.708855064848, 1059.2638116509106, 1075.5966512557948, 1079.4440862955585, 1085.9412073228698, 17106.315747369965, 17235.827641723423, 17352.0, 17483.475495735147, 17518.649627007642, 37798.24403511969, 38173.0, 38338.42492362561, 38609.42085868771, 39384.0, 48422.75788621034, 57093.42906570881, 57485.56286092881, 57492.71253643705, 57952.4204757947, 8840.557972101498, 8852.617954112755, 8952.641894324153, 8954.238889694188, 9090.636374544943, 12026.879731202926, 13221.933890330487, 13260.867391326348, 13559.254241016839, 13702.0, 10517.631882884063, 10795.946020269848, 10949.671509854717, 10956.835647465454, 11093.223474356717, 11357.204995650225, 11440.828387574027, 11706.0, 11715.882841171479, 11821.881781182077, 10868.347899126078, 10964.780704386027, 10966.232363734462, 11230.270032447865, 11458.713532161762, 8656.394052416523, 8688.739337819876, 8794.868076978979, 8811.295096392401, 8944.776380590538, 9885.85171222418, 10728.839067413839, 10845.620403285848, 11213.607523736957, 11311.095112391153], "value": 9548.027712709914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.2112290379966, 1507.9849201507982, 1539.7921280627115, 1660.6097567071736, 1832.1663643042416, 8385.87421188682, 8777.253933415659, 8791.999999999993, 9015.549222538879, 9018.14327638875, 8755.036945935957, 8939.597718102685, 8942.508162051085, 8942.86585701215, 8943.50810705411, 2080.147139672727, 2326.639370897505, 2389.8446600970924, 2392.306231192952, 2422.4307287787356, 2364.420716924356, 2372.395039264985, 2374.620060790274, 2376.097083108425, 2377.583922813509, 2360.386299562109, 2365.82256330775, 2369.1352656280505, 2384.713834339873, 2399.1123284384716, 2041.1529215375601, 2263.5359751250953, 2321.791038806499, 2325.93022209334, 2333.2766842278947, 2364.728056273533, 2367.857928524294, 2392.928212153638, 2399.1602938971396, 2400.675908752331, 9009.549522523914, 9089.454632722058, 9103.0, 9106.0, 9114.635414583408, 5287.206918962186, 5452.781888724447, 5458.754356053947, 5459.372172200168, 5463.262459567947, 3348.380549598308, 3494.860205591773, 3548.4854696068924, 3577.964220357789, 3594.7663401879054, 1240.8883200511907, 1264.8229247905208, 1278.4119305119564, 1281.9102662813607, 1297.1762930539082, 12502.687432814162, 12514.624561263177, 12527.686807829787, 12588.748225035539, 12637.93681031589, 4666.300054991779, 4722.456917454537, 4723.716577005391, 4748.738819364969, 4858.684185527929, 9960.501974901372, 9963.153131983718, 10027.197824174049, 10030.348027378197, 10048.69853904384, 5431.83704488866, 5431.891362172734, 5436.0, 5436.3476382834315, 5437.918431223535, 4492.348609451611, 4926.926096108561, 5018.04657115147, 5027.0197311524635, 5049.798008079709, 4073.716779214538, 4077.4903137107863, 4168.416421700964, 4177.540470548237, 4207.548395803443, 1270.091884302728, 1272.6436597752645, 1272.720001599665, 1275.2093701904878, 1296.125115547002, 889.6263569300909, 962.225408546113, 1025.5133789670192, 1026.7741096958657, 1042.1818872185297, 6009.609375390585, 6013.278406591237, 6095.390460953958, 6226.501879849601, 6319.96840015797, 935.3312381647155, 955.4171955107379, 1022.1720406470782, 1069.9037086662238, 1162.2271189658782, 4285.164392943346, 4320.027993701392, 4334.306510958231, 4339.0, 4394.450693663292, 10729.463526823627, 10772.569097236179, 10991.230613857113, 11014.06380457654, 11039.172062095375, 10758.946205268923, 10920.453977301104, 11011.83482247767, 11077.279976801483, 11107.72230694239, 8259.0, 8308.501489910625, 8345.582720863933, 8392.118827523143, 8407.663693452316, 5869.325027621708, 6074.969625151846, 6096.177016102769, 6201.255849298113, 6220.7511699531515, 10000.549975250986, 10089.142422894136, 10100.242481813746, 10107.191424686154, 10194.388336699822, 10350.171986240932, 10354.430506322225, 10460.424676642553, 10463.848976612415, 10476.895231047592, 6451.41937225652, 6546.18172728409, 7404.444666650131, 7579.772606821804, 7703.037120359955, 8436.367272454714, 8446.831063378575, 8512.361572882182, 8515.233628973423, 8542.103079176846, 7711.575863327571, 7731.0, 7774.144844067035, 7933.127355990951, 8037.638306276349, 7744.806379840324, 7844.4901081429525, 7845.803854903673, 7917.485363451588, 7925.564093974886, 7247.2390399007345, 7295.890561641473, 7322.340989310987, 7332.523385979895, 7333.74331898381, 5116.590672746246, 5204.375474943031, 5243.842684719464, 5264.815731449381, 5445.019896418683, 5202.427732949297, 5216.295800066942, 5335.708740569595, 5424.349078110652, 5564.33228012641], "value": 4739.828446980006, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.9933201335973, 339.45347989736536, 340.5334691472683, 343.730171815125, 344.42652982783665, 5207.583393328534, 5259.13224317988, 5372.9462705372935, 5388.703621300827, 5395.541378982786, 5250.238715386266, 5260.237265596486, 5284.550813180875, 5295.258663787064, 5316.3354580677415, 4214.883055990157, 4222.59885310895, 4255.787210639455, 4269.573042695721, 4281.72168809028, 4021.6782657387485, 4070.837166513336, 4243.448351714273, 4259.8083086261195, 4280.464941882265, 4188.350805625133, 4200.970762163274, 4220.7256528325715, 4221.944513871532, 4227.8308867645255, 4185.183889141618, 4193.8741837744765, 4205.600467955539, 4209.5790420957965, 4211.726237794564, 3646.2342907989355, 3712.4988126602834, 3743.1577894973684, 3760.4394176416754, 3767.1523907120677, 5104.719240441774, 5123.257127716498, 5133.22903598258, 5158.148905430599, 5185.377754669464, 4738.455077666078, 4826.903461930741, 4838.830640927586, 4867.269909513601, 4868.196747536652, 5400.91898621521, 5512.125877201731, 5535.944640553583, 5549.250851135085, 5570.526505247059, 4132.090939993197, 4142.71001029928, 4145.046639272984, 4149.585041495857, 4151.875443736692, 5728.0, 5730.025895597759, 5771.307443106812, 5781.971090144522, 5812.680302583399, 4012.498437695288, 4116.9794151029055, 4198.328267477189, 4206.116715489781, 4216.114615930689, 4195.706300558932, 4216.599423054805, 4240.342746874239, 4245.745255284693, 4253.447051883297, 4785.521447855187, 4790.233562629963, 4791.425028996473, 4804.639652026112, 4810.518948105231, 5494.780208791603, 5552.7223638817895, 5606.299212598426, 5612.242347283146, 5620.859478512988, 1405.6485878530368, 1423.5942756314607, 1456.8126976514106, 1464.2239613005124, 1465.2527211122192, 953.5422996961497, 969.4038166527534, 971.3977334052933, 981.8625392445064, 983.8243299257462, 4831.115864812726, 4871.318015477836, 4887.731174785421, 4895.657303988758, 4913.778879950411, 5130.820421285237, 5167.560757335594, 5169.405518365361, 5179.7928082877015, 5190.480951904779, 4631.560001799824, 4957.686213153517, 4959.776810043557, 4964.0, 4965.32968049316, 2717.388587567821, 2721.9591706124424, 2774.875130619127, 2776.7500924916853, 2780.179846945156, 5324.361076670747, 5331.0, 5333.37332113709, 5338.786448542015, 5350.304460420199, 5222.955408918231, 5253.3958594761325, 5443.510084092451, 5923.4076592340425, 5940.316863560746, 5980.970095149496, 5985.401459854067, 5988.161657367885, 6023.879522409527, 6095.512359011268, 5580.218769372373, 5815.883682326415, 5828.417158284137, 5829.038208695518, 5841.561882858888, 5654.0, 5678.432156784289, 5717.8570535736935, 5773.134029895549, 5786.479216870502, 5968.522518198447, 5983.341832398346, 5997.620547274064, 6044.607100538451, 6049.6067755597505, 4049.838006479708, 4185.727927684691, 4198.748075115503, 4202.453681021448, 4202.978985105055, 5557.944420555743, 5652.745626446737, 5763.827085187451, 5807.186993820953, 5828.242328497268, 5586.4916472552995, 5590.580706446952, 5629.746661400165, 5644.209810626598, 5646.322441307069, 5558.888822223614, 5606.6636001840025, 5639.210510528611, 5699.406769919594, 5705.34388545306, 5321.5476684482255, 5552.666839989613, 5563.749631266522, 5564.91652625219, 5567.3040869891265, 5631.577631677558, 5647.576431767716, 5648.096304591246, 5652.208690783212, 5658.349289831557, 5495.587830912617, 5531.598513011152, 5562.9721851392105, 5577.024020796425, 5608.261438953938], "value": 4238.910884639858, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.4819108534883, 882.8587426011838, 894.253298495756, 902.3593248793356, 925.7731855695356, 16524.42164524242, 16771.245293961754, 16820.495385138434, 17728.0, 18369.632607347845, 17132.657346853088, 17695.55761105973, 17810.732839007393, 17816.287348506077, 17847.91076044621, 700.1038670501766, 838.0613712641849, 862.6506264962669, 867.453504292295, 868.8740132680757, 859.4370687199894, 861.7888617288742, 868.8740132680757, 871.856143736286, 872.3021582733819, 882.1355072029382, 895.2524641924001, 897.5512243878061, 898.0884402331639, 899.5277479323343, 738.5162718419443, 886.7118186589385, 898.6091050393068, 901.1889299630319, 915.0163574157807, 667.4927055437902, 669.0532895952222, 669.0566301514889, 669.5848573884174, 669.8760729265101, 16301.673966520602, 16433.342666293334, 16453.91773041139, 16454.095024773633, 16458.917705411517, 15308.234588270654, 15562.476641335492, 15730.921345393313, 15757.763633545392, 15774.76337854933, 14292.285385730673, 14331.42674293027, 14528.70942581153, 14601.707965840624, 14684.632884177874, 4672.929906051412, 4716.752003727392, 4762.880927976794, 4768.976155119202, 4840.152973229706, 81646.9999999994, 82130.58934705348, 82495.58752206201, 82575.58712206401, 82853.58573207156, 12068.698282543006, 12150.270983741004, 12407.945324647506, 12550.671490162897, 12562.246265224116, 22933.426664333525, 22941.42646433819, 22952.19667311636, 22996.0, 23000.769992300196, 8447.0, 8715.172058654418, 8731.51976641278, 8855.690050848189, 9147.0, 4971.403431588233, 5069.949300507022, 5094.821681241138, 5117.411497677815, 5183.870403239949, 3475.982620086934, 3540.6990405815277, 3543.255916257563, 3560.9821950890077, 3562.127278816655, 599.4335353091374, 617.8331850400457, 658.6969993802809, 662.4303099334569, 701.1200942816732, 623.9199005689584, 641.6919878458366, 745.1356426545182, 783.1620166421874, 799.0850476204713, 10543.444185132308, 10595.94702026485, 10736.30214036085, 10848.45757712127, 11052.005319521282, 659.5020759326677, 666.9032990216391, 758.3705524414722, 780.2938340801584, 804.9195080492021, 5096.388433388017, 5175.4824517548695, 5259.474052594786, 5288.36539615241, 5293.444188360167, 80101.0, 112110.0, 127140.0, 131322.34338828435, 132862.0, 88826.0, 116300.41849790866, 116309.0, 118004.0, 119898.0, 97323.0, 102270.97729022763, 102845.48577257065, 102893.48553257185, 104110.0, 10168.0, 10384.273100882865, 10513.106385956973, 10524.789504210026, 10555.577776888838, 49911.0, 57912.42087579239, 59004.40995589989, 59972.0, 60043.39956600378, 68020.0, 68510.0, 68554.65722671355, 74835.0, 78165.60917195378, 13606.115602485806, 14045.0, 14430.350634221695, 14657.633559161106, 14683.119012859263, 15283.923580382472, 15603.765943510629, 15795.605109872346, 16104.436344727877, 16572.419965301156, 14287.785683214968, 14510.564683059525, 14601.561953141423, 15350.923245383701, 15701.607459813597, 14530.927345363205, 14741.852581474048, 14848.331825067682, 14985.925070374578, 15160.241987900341, 14992.925035374754, 15305.0, 15645.452409165624, 15671.451499197474, 15786.289616967493, 11621.360825154698, 12084.335361555199, 12110.273383597012, 12171.573994910135, 12260.14179007461, 12421.93789031049, 12646.557370491988, 12759.234445933274, 12838.935805320914, 12946.093773435769], "value": 8221.74591968534, "unit": "score"}], "name": "dromaeo_css", "value": 7484.715441544819, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0, 109.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 127.0, 130.0, 172.0, 126.0, 128.0, 125.0, 151.0, 173.0, 126.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 160.0, 160.0, 162.0, 161.0, 162.0, 160.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 106.0, 75.0, 74.0, 75.0, 76.0, 103.0, 74.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 122.0, 83.0, 84.0, 84.0, 116.0, 84.0, 84.0, 121.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 54.0, 61.0, 53.0, 54.0, 52.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 83.0, 81.0, 76.0, 76.0, 76.0, 106.0, 82.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 118.0, 118.0, 120.0, 123.0, 118.0, 125.0, 126.0, 131.0, 123.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 152.0, 152.0, 155.0, 153.0, 152.0, 157.0, 157.0, 153.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 60.0, 61.0, 64.0, 61.0, 61.0, 61.0, 63.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1480.1000000000004, "alertThreshold": 2.0}]} 15:54:38 INFO - Return code: 0 15:54:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:54:38 ERROR - # TBPL SUCCESS # 15:54:38 INFO - Running post-action listener: _resource_record_post_action 15:54:38 INFO - ##### 15:54:38 INFO - ##### Finished run-tests step (success) 15:54:38 INFO - ##### 15:54:38 INFO - Running post-run listener: _resource_record_post_run 15:54:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 352256; Write bytes: 251023360; Read time: 132; Write time: 137612 15:54:38 INFO - TinderboxPrint: CPU usage
13.1% 15:54:38 INFO - TinderboxPrint: I/O read bytes / time
352,256 / 132 15:54:38 INFO - TinderboxPrint: I/O write bytes / time
251,023,360 / 137,612 15:54:38 INFO - TinderboxPrint: CPU idle
8,254.5 (86.9%) 15:54:38 INFO - TinderboxPrint: CPU user
1,197.0 (12.6%) 15:54:38 INFO - TinderboxPrint: Swap in / out
0 / 0 15:54:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 593920; Read time: 0; Write time: 48 15:54:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 352256; Write bytes: 249815040; Read time: 132; Write time: 137548 15:54:38 INFO - Running post-run listener: _upload_blobber_files 15:54:38 INFO - Blob upload gear active. 15:54:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:54:38 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 15:54:38 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'] 15:54:38 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 15:54:39 INFO - (blobuploader) - INFO - Open directory for files ... 15:54:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:54:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:54:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:40 INFO - (blobuploader) - INFO - Done attempting. 15:54:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:54:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:54:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:40 INFO - (blobuploader) - INFO - Done attempting. 15:54:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:54:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:54:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:44 INFO - (blobuploader) - INFO - Done attempting. 15:54:44 INFO - (blobuploader) - INFO - Iteration through files over. 15:54:44 INFO - Return code: 0 15:54:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:54:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:54:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a1de96a31a8e214f89d45ce5825f511e38efa094a69ad35d2a4cfd0613dc862c317e3d90024b2c097dbabf84f99b20b75d48555940741cd626bbcac69da6243", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74dc8dfd621e38ff8b173bb318776353700bdf0a2b7667a2ef7b747374f2bcb433e1348ac5cb8eb0a77b48b45116c38df5e255ea9cbbf548e059f6024b01bfb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b65771dc38045d6335fa78c7020894a9e427e7f294efdbf78e88292b9cfc5456877edf7d51b14f382ee5c004246c146ab667b2691c07faf1fe298c1b6d2bc98b"} 15:54:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:54:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:54:44 INFO - Contents: 15:54:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a1de96a31a8e214f89d45ce5825f511e38efa094a69ad35d2a4cfd0613dc862c317e3d90024b2c097dbabf84f99b20b75d48555940741cd626bbcac69da6243", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74dc8dfd621e38ff8b173bb318776353700bdf0a2b7667a2ef7b747374f2bcb433e1348ac5cb8eb0a77b48b45116c38df5e255ea9cbbf548e059f6024b01bfb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b65771dc38045d6335fa78c7020894a9e427e7f294efdbf78e88292b9cfc5456877edf7d51b14f382ee5c004246c146ab667b2691c07faf1fe298c1b6d2bc98b"} 15:54:44 INFO - Running post-run listener: copy_logs_to_upload_dir 15:54:44 INFO - Copying logs to upload dir... 15:54:44 INFO - mkdir: /builds/slave/test/build/upload/logs 15:54:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.749595 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-31 15:54:44.648352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 15:54:44.651703) ========= 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=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a1de96a31a8e214f89d45ce5825f511e38efa094a69ad35d2a4cfd0613dc862c317e3d90024b2c097dbabf84f99b20b75d48555940741cd626bbcac69da6243", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74dc8dfd621e38ff8b173bb318776353700bdf0a2b7667a2ef7b747374f2bcb433e1348ac5cb8eb0a77b48b45116c38df5e255ea9cbbf548e059f6024b01bfb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b65771dc38045d6335fa78c7020894a9e427e7f294efdbf78e88292b9cfc5456877edf7d51b14f382ee5c004246c146ab667b2691c07faf1fe298c1b6d2bc98b"} build_url:https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/QLyEpgBmQ7a4hIGyAf1oaQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a1de96a31a8e214f89d45ce5825f511e38efa094a69ad35d2a4cfd0613dc862c317e3d90024b2c097dbabf84f99b20b75d48555940741cd626bbcac69da6243", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74dc8dfd621e38ff8b173bb318776353700bdf0a2b7667a2ef7b747374f2bcb433e1348ac5cb8eb0a77b48b45116c38df5e255ea9cbbf548e059f6024b01bfb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b65771dc38045d6335fa78c7020894a9e427e7f294efdbf78e88292b9cfc5456877edf7d51b14f382ee5c004246c146ab667b2691c07faf1fe298c1b6d2bc98b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 15:54:44.693003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:54:44.693288) ========= 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=0e2a256762fed810e2f0f810000001b3-1470003583.193999-1259428609 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:54:44.744974) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 15:54:44.745244) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 15:54:44.745512) ========= ========= Total master_lag: 0.16 =========