builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1465512498.17 results: success (0) buildid: 20160609130607 builduid: 6a4fdc5838bc41dfaee730cf1bbdf452 revision: 3ccccf8e5036179a3178437cabc154b5e04b333d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.172045) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.172584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.172910) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.252489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.253136) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-09 15:48:18-- 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% 43.6M=0s 2016-06-09 15:48:18 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.341360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.342172) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016259 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.394224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:18.395021) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3ccccf8e5036179a3178437cabc154b5e04b333d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3ccccf8e5036179a3178437cabc154b5e04b333d --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=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-09 15:48:18,689 truncating revision to first 12 chars 2016-06-09 15:48:18,689 Setting DEBUG logging. 2016-06-09 15:48:18,689 attempt 1/10 2016-06-09 15:48:18,689 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3ccccf8e5036?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 15:48:19,175 unpacking tar archive at: mozilla-central-3ccccf8e5036/testing/mozharness/ program finished with exit code 0 elapsedTime=0.841126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:19.254178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:19.254857) ========= script_repo_revision: 3ccccf8e5036179a3178437cabc154b5e04b333d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:19.255609) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:19.256154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 15:48:19.267616) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-09 15:48:19.267931) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:48:19 INFO - MultiFileLogger online at 20160609 15:48:19 in /builds/slave/test 15:48:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:48:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:19 INFO - {'append_to_log': False, 15:48:19 INFO - 'base_work_dir': '/builds/slave/test', 15:48:19 INFO - 'blob_upload_branch': 'Firefox', 15:48:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:19 INFO - 'branch': 'Firefox', 15:48:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:19 INFO - 'config_files': ('talos/linux_config.py',), 15:48:19 INFO - 'default_actions': ('clobber', 15:48:19 INFO - 'read-buildbot-config', 15:48:19 INFO - 'download-and-extract', 15:48:19 INFO - 'populate-webroot', 15:48:19 INFO - 'create-virtualenv', 15:48:19 INFO - 'install', 15:48:19 INFO - 'run-tests'), 15:48:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:19 INFO - 'download_minidump_stackwalk': True, 15:48:19 INFO - 'download_symbols': 'ondemand', 15:48:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:48:19 INFO - 'tooltool.py': '/tools/tooltool.py', 15:48:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:48:19 INFO - '/tools/misc-python/virtualenv.py')}, 15:48:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:19 INFO - 'installer_path': 'installer.exe', 15:48:19 INFO - 'log_level': 'info', 15:48:19 INFO - 'log_name': 'talos', 15:48:19 INFO - 'log_to_console': True, 15:48:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:48:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:48:19 INFO - 'opt_config_files': (), 15:48:19 INFO - 'pip_index': False, 15:48:19 INFO - 'sps_profile': False, 15:48:19 INFO - 'sps_profile_interval': 0, 15:48:19 INFO - 'suite': 'dromaeojs', 15:48:19 INFO - 'system_bits': '32', 15:48:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:19 INFO - 'title': 'talos-linux64-ix-040', 15:48:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:19 INFO - 'use_talos_json': True, 15:48:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:19 INFO - 'work_dir': 'build'} 15:48:19 INFO - ##### 15:48:19 INFO - ##### Running clobber step. 15:48:19 INFO - ##### 15:48:19 INFO - Running pre-action listener: _resource_record_pre_action 15:48:19 INFO - Running main action method: clobber 15:48:19 INFO - rmtree: /builds/slave/test/build 15:48:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:20 INFO - Running post-action listener: _resource_record_post_action 15:48:20 INFO - ##### 15:48:20 INFO - ##### Finished clobber step (success) 15:48:20 INFO - ##### 15:48:20 INFO - ##### 15:48:20 INFO - ##### Running read-buildbot-config step. 15:48:20 INFO - ##### 15:48:20 INFO - Running pre-action listener: _resource_record_pre_action 15:48:20 INFO - Running main action method: read_buildbot_config 15:48:20 INFO - Using buildbot properties: 15:48:20 INFO - { 15:48:20 INFO - "project": "", 15:48:20 INFO - "product": "firefox", 15:48:20 INFO - "script_repo_revision": "production", 15:48:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:48:20 INFO - "repository": "", 15:48:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 15:48:20 INFO - "buildid": "20160609130607", 15:48:20 INFO - "stage_platform": "linux64-pgo", 15:48:20 INFO - "basedir": "/builds/slave/test", 15:48:20 INFO - "nightly_build": "True", 15:48:20 INFO - "buildnumber": 333, 15:48:20 INFO - "slavename": "talos-linux64-ix-040", 15:48:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:48:20 INFO - "platform": "ubuntu64_hw", 15:48:20 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 15:48:20 INFO - "branch": "mozilla-central", 15:48:20 INFO - "repo_path": "mozilla-central", 15:48:20 INFO - "revision": "3ccccf8e5036179a3178437cabc154b5e04b333d", 15:48:20 INFO - "pgo_build": "True", 15:48:20 INFO - "builduid": "6a4fdc5838bc41dfaee730cf1bbdf452", 15:48:20 INFO - "slavebuilddir": "test" 15:48:20 INFO - } 15:48:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:48:20 INFO - Running post-action listener: _resource_record_post_action 15:48:20 INFO - ##### 15:48:20 INFO - ##### Finished read-buildbot-config step (success) 15:48:20 INFO - ##### 15:48:20 INFO - ##### 15:48:20 INFO - ##### Running download-and-extract step. 15:48:20 INFO - ##### 15:48:20 INFO - Running pre-action listener: _resource_record_pre_action 15:48:20 INFO - Running main action method: download_and_extract 15:48:20 INFO - mkdir: /builds/slave/test/build/tests 15:48: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:48:20 INFO - https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:48:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:48:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:48:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:48:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:48:21 INFO - Downloaded 1361 bytes. 15:48:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:48:21 INFO - Using the following test package requirements: 15:48:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:48:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:48:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:48:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'jsshell-linux-x86_64.zip'], 15:48:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:48:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:48:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:48:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:48:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:48:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:48:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:48:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:48: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:48:21 INFO - https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:48:24 INFO - Downloaded 24452140 bytes. 15:48:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:48:25 INFO - Return code: 0 15:48:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:48: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:48:25 INFO - https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:48:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:48:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:48:28 INFO - Downloaded 24452140 bytes. 15:48:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:48:29 INFO - Return code: 0 15:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:29 INFO - https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:48:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:48:31 INFO - Downloaded 10833377 bytes. 15:48:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:48:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:48:31 INFO - Return code: 0 15:48: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:48:31 INFO - https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:48:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:48:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:48:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:48:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:48:33 INFO - Downloaded 59355004 bytes. 15:48:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:48:33 INFO - mkdir: /builds/slave/test/properties 15:48:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:33 INFO - Contents: 15:48:33 INFO - build_url:https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:48:34 INFO - Running post-action listener: _resource_record_post_action 15:48:34 INFO - Running post-action listener: set_extra_try_arguments 15:48:34 INFO - ##### 15:48:34 INFO - ##### Finished download-and-extract step (success) 15:48:34 INFO - ##### 15:48:34 INFO - ##### 15:48:34 INFO - ##### Running populate-webroot step. 15:48:34 INFO - ##### 15:48:34 INFO - Running pre-action listener: _resource_record_pre_action 15:48:34 INFO - Running main action method: populate_webroot 15:48:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:34 INFO - '--tppagecycles', 15:48:34 INFO - '7'], 15:48:34 INFO - 'tests': ['tsvgm']}}, 15:48:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['damp', 'tps']}, 15:48:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'tests': ['damp', 'tps']}, 15:48:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['dromaeo_dom']}, 15:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['a11yr', 15:48:34 INFO - 'ts_paint', 15:48:34 INFO - 'tpaint', 15:48:34 INFO - 'sessionrestore', 15:48:34 INFO - 'sessionrestore_no_auto_restore', 15:48:34 INFO - 'tabpaint']}, 15:48:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['tsvgx', 15:48:34 INFO - 'tsvgr_opacity', 15:48:34 INFO - 'tart', 15:48:34 INFO - 'tscrollx', 15:48:34 INFO - 'cart']}, 15:48:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:34 INFO - 'tsvgr_opacity', 15:48:34 INFO - 'tart', 15:48:34 INFO - 'tscrollx', 15:48:34 INFO - 'cart']}, 15:48:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'talos_options': ['--disable-e10s'], 15:48:34 INFO - 'tests': ['tp5o']}, 15:48:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'tests': ['tp5o']}, 15:48:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'talos_options': ['--disable-e10s', 15:48:34 INFO - '--xperf_path', 15:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:34 INFO - 'tests': ['tp5n']}, 15:48:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:34 INFO - 'talos_options': ['--xperf_path', 15:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:34 INFO - 'tests': ['tp5n']}}, 15:48:34 INFO - 'talos.zip': {'path': '', 15:48:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:34 INFO - Running post-action listener: _resource_record_post_action 15:48:34 INFO - ##### 15:48:34 INFO - ##### Finished populate-webroot step (success) 15:48:34 INFO - ##### 15:48:34 INFO - ##### 15:48:34 INFO - ##### Running create-virtualenv step. 15:48:34 INFO - ##### 15:48:34 INFO - Running pre-action listener: _resource_record_pre_action 15:48:34 INFO - Running main action method: create_virtualenv 15:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:34 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:48:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:34 INFO - Using real prefix '/usr' 15:48:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:36 INFO - Installing pip.................done. 15:48:36 INFO - Return code: 0 15:48:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:48:36 INFO - Using env: {'DISPLAY': ':0', 15:48:36 INFO - 'HOME': '/home/cltbld', 15:48:36 INFO - 'LANG': 'en_US.UTF-8', 15:48:36 INFO - 'LANGUAGE': 'en_US:en', 15:48:36 INFO - 'LOGNAME': 'cltbld', 15:48:36 INFO - 'MAIL': '/var/mail/cltbld', 15:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:36 INFO - 'MOZ_NO_REMOTE': '1', 15:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:36 INFO - 'NO_EM_RESTART': '1', 15:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:36 INFO - 'PWD': '/builds/slave/test', 15:48:36 INFO - 'SHELL': '/bin/bash', 15:48:36 INFO - 'SHLVL': '1', 15:48:36 INFO - 'TERM': 'linux', 15:48:36 INFO - 'TMOUT': '86400', 15:48:36 INFO - 'USER': 'cltbld', 15:48:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:36 INFO - '_': '/tools/buildbot/bin/python'} 15:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:36 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:48:36 INFO - Cleaning up... 15:48:36 INFO - Return code: 0 15:48:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:48:36 INFO - Using env: {'DISPLAY': ':0', 15:48:36 INFO - 'HOME': '/home/cltbld', 15:48:36 INFO - 'LANG': 'en_US.UTF-8', 15:48:36 INFO - 'LANGUAGE': 'en_US:en', 15:48:36 INFO - 'LOGNAME': 'cltbld', 15:48:36 INFO - 'MAIL': '/var/mail/cltbld', 15:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:36 INFO - 'MOZ_NO_REMOTE': '1', 15:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:36 INFO - 'NO_EM_RESTART': '1', 15:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:36 INFO - 'PWD': '/builds/slave/test', 15:48:36 INFO - 'SHELL': '/bin/bash', 15:48:36 INFO - 'SHLVL': '1', 15:48:36 INFO - 'TERM': 'linux', 15:48:36 INFO - 'TMOUT': '86400', 15:48:36 INFO - 'USER': 'cltbld', 15:48:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:36 INFO - '_': '/tools/buildbot/bin/python'} 15:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:37 INFO - Downloading/unpacking psutil>=3.1.1 15:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:39 INFO - Installing collected packages: psutil 15:48:39 INFO - Running setup.py install for psutil 15:48:39 INFO - building 'psutil._psutil_linux' extension 15:48:39 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:48:39 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:48:39 INFO - building 'psutil._psutil_posix' extension 15:48:39 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:48:39 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:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:39 INFO - Successfully installed psutil 15:48:39 INFO - Cleaning up... 15:48:39 INFO - Return code: 0 15:48:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:48:39 INFO - Using env: {'DISPLAY': ':0', 15:48:39 INFO - 'HOME': '/home/cltbld', 15:48:39 INFO - 'LANG': 'en_US.UTF-8', 15:48:39 INFO - 'LANGUAGE': 'en_US:en', 15:48:39 INFO - 'LOGNAME': 'cltbld', 15:48:39 INFO - 'MAIL': '/var/mail/cltbld', 15:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:39 INFO - 'MOZ_NO_REMOTE': '1', 15:48:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:39 INFO - 'NO_EM_RESTART': '1', 15:48:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:39 INFO - 'PWD': '/builds/slave/test', 15:48:39 INFO - 'SHELL': '/bin/bash', 15:48:39 INFO - 'SHLVL': '1', 15:48:39 INFO - 'TERM': 'linux', 15:48:39 INFO - 'TMOUT': '86400', 15:48:39 INFO - 'USER': 'cltbld', 15:48:39 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:39 INFO - '_': '/tools/buildbot/bin/python'} 15:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:41 INFO - Installing collected packages: mozsystemmonitor 15:48:41 INFO - Running setup.py install for mozsystemmonitor 15:48:41 INFO - Successfully installed mozsystemmonitor 15:48:41 INFO - Cleaning up... 15:48:41 INFO - Return code: 0 15:48:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:41 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:41 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:48:41 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:48:41 INFO - Using env: {'DISPLAY': ':0', 15:48:41 INFO - 'HOME': '/home/cltbld', 15:48:41 INFO - 'LANG': 'en_US.UTF-8', 15:48:41 INFO - 'LANGUAGE': 'en_US:en', 15:48:41 INFO - 'LOGNAME': 'cltbld', 15:48:41 INFO - 'MAIL': '/var/mail/cltbld', 15:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:41 INFO - 'MOZ_NO_REMOTE': '1', 15:48:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:41 INFO - 'NO_EM_RESTART': '1', 15:48:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:41 INFO - 'PWD': '/builds/slave/test', 15:48:41 INFO - 'SHELL': '/bin/bash', 15:48:41 INFO - 'SHLVL': '1', 15:48:41 INFO - 'TERM': 'linux', 15:48:41 INFO - 'TMOUT': '86400', 15:48:41 INFO - 'USER': 'cltbld', 15:48:41 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:41 INFO - '_': '/tools/buildbot/bin/python'} 15:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:42 INFO - Downloading/unpacking jsonschema==2.5.1 15:48: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:48: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:48: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:48: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:48: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:48: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:48:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:48:44 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:48:44 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:48:44 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:48:44 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:48:44 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:48:44 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:48:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:44 INFO - warning: no files found matching '*.txt' 15:48:44 INFO - no previously-included directories found matching 'build' 15:48:44 INFO - no previously-included directories found matching 'dist' 15:48:44 INFO - no previously-included directories found matching '.git*' 15:48:44 INFO - Installing collected packages: jsonschema, functools32 15:48:44 INFO - Running setup.py install for functools32 15:48:44 INFO - warning: no files found matching '*.txt' 15:48:44 INFO - no previously-included directories found matching 'build' 15:48:44 INFO - no previously-included directories found matching 'dist' 15:48:44 INFO - no previously-included directories found matching '.git*' 15:48:44 INFO - Successfully installed jsonschema functools32 15:48:44 INFO - Cleaning up... 15:48:44 INFO - Return code: 0 15:48:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:48: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:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48: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:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48: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', '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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:48: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 blobuploader==1.2.4 15:48:44 INFO - Using env: {'DISPLAY': ':0', 15:48:44 INFO - 'HOME': '/home/cltbld', 15:48:44 INFO - 'LANG': 'en_US.UTF-8', 15:48:44 INFO - 'LANGUAGE': 'en_US:en', 15:48:44 INFO - 'LOGNAME': 'cltbld', 15:48:44 INFO - 'MAIL': '/var/mail/cltbld', 15:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:44 INFO - 'MOZ_NO_REMOTE': '1', 15:48:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:44 INFO - 'NO_EM_RESTART': '1', 15:48:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:44 INFO - 'PWD': '/builds/slave/test', 15:48:44 INFO - 'SHELL': '/bin/bash', 15:48:44 INFO - 'SHLVL': '1', 15:48:44 INFO - 'TERM': 'linux', 15:48:44 INFO - 'TMOUT': '86400', 15:48:44 INFO - 'USER': 'cltbld', 15:48:44 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:44 INFO - '_': '/tools/buildbot/bin/python'} 15:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:44 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:48:44 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:48:44 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:48:44 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:48:44 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:48:44 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:48:46 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48: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:48: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:48: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:48: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:48: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:48: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:48:47 INFO - Downloading docopt-0.6.1.tar.gz 15:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:47 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:47 INFO - Running setup.py install for blobuploader 15:48:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:47 INFO - Running setup.py install for requests 15:48:47 INFO - Running setup.py install for docopt 15:48:47 INFO - Successfully installed blobuploader requests docopt 15:48:47 INFO - Cleaning up... 15:48:48 INFO - Return code: 0 15:48:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:48 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:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:48 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:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:48 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:48 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:48:48 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:48:48 INFO - Using env: {'DISPLAY': ':0', 15:48:48 INFO - 'HOME': '/home/cltbld', 15:48:48 INFO - 'LANG': 'en_US.UTF-8', 15:48:48 INFO - 'LANGUAGE': 'en_US:en', 15:48:48 INFO - 'LOGNAME': 'cltbld', 15:48:48 INFO - 'MAIL': '/var/mail/cltbld', 15:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:48 INFO - 'MOZ_NO_REMOTE': '1', 15:48:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:48 INFO - 'NO_EM_RESTART': '1', 15:48:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:48 INFO - 'PWD': '/builds/slave/test', 15:48:48 INFO - 'SHELL': '/bin/bash', 15:48:48 INFO - 'SHLVL': '1', 15:48:48 INFO - 'TERM': 'linux', 15:48:48 INFO - 'TMOUT': '86400', 15:48:48 INFO - 'USER': 'cltbld', 15:48:48 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:48 INFO - '_': '/tools/buildbot/bin/python'} 15:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:48 INFO - Running setup.py (path:/tmp/pip-Pjv34v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:48 INFO - Running setup.py (path:/tmp/pip-gdHSIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:48 INFO - Running setup.py (path:/tmp/pip-e2rYJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:48 INFO - Running setup.py (path:/tmp/pip-KIcWO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:48 INFO - Running setup.py (path:/tmp/pip-dF8x2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:48 INFO - Running setup.py (path:/tmp/pip-OrUa5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:48 INFO - Running setup.py (path:/tmp/pip-pHDXtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:48 INFO - Running setup.py (path:/tmp/pip-L_CG2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:48 INFO - Running setup.py (path:/tmp/pip-EhWw1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:48 INFO - Running setup.py (path:/tmp/pip-bnmBLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:48 INFO - Running setup.py (path:/tmp/pip-E9gBgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:49 INFO - Running setup.py (path:/tmp/pip-CTu9A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:49 INFO - Running setup.py (path:/tmp/pip-ME1lOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:49 INFO - Running setup.py (path:/tmp/pip-poJjgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:49 INFO - Running setup.py (path:/tmp/pip-Ae39lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:49 INFO - Running setup.py (path:/tmp/pip-Yr1bpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:49 INFO - Running setup.py (path:/tmp/pip-d3UagN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:49 INFO - Running setup.py install for manifestparser 15:48:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:49 INFO - Running setup.py install for mozcrash 15:48:49 INFO - Running setup.py install for mozdebug 15:48:49 INFO - Running setup.py install for mozdevice 15:48:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:48:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:48:49 INFO - Running setup.py install for mozfile 15:48:50 INFO - Running setup.py install for mozhttpd 15:48:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for mozinfo 15:48:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for mozInstall 15:48:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for mozleak 15:48:50 INFO - Running setup.py install for mozlog 15:48:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for moznetwork 15:48:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for mozprocess 15:48:50 INFO - Running setup.py install for mozprofile 15:48:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:48:50 INFO - Running setup.py install for mozrunner 15:48:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:48:51 INFO - Running setup.py install for mozscreenshot 15:48:51 INFO - Running setup.py install for moztest 15:48:51 INFO - Running setup.py install for mozversion 15:48:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:48:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:48:51 INFO - Cleaning up... 15:48:51 INFO - Return code: 0 15:48:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:51 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:51 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:48:51 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:48:51 INFO - Using env: {'DISPLAY': ':0', 15:48:51 INFO - 'HOME': '/home/cltbld', 15:48:51 INFO - 'LANG': 'en_US.UTF-8', 15:48:51 INFO - 'LANGUAGE': 'en_US:en', 15:48:51 INFO - 'LOGNAME': 'cltbld', 15:48:51 INFO - 'MAIL': '/var/mail/cltbld', 15:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:51 INFO - 'MOZ_NO_REMOTE': '1', 15:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:51 INFO - 'NO_EM_RESTART': '1', 15:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:51 INFO - 'PWD': '/builds/slave/test', 15:48:51 INFO - 'SHELL': '/bin/bash', 15:48:51 INFO - 'SHLVL': '1', 15:48:51 INFO - 'TERM': 'linux', 15:48:51 INFO - 'TMOUT': '86400', 15:48:51 INFO - 'USER': 'cltbld', 15:48:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:51 INFO - '_': '/tools/buildbot/bin/python'} 15:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:51 INFO - Running setup.py (path:/tmp/pip-CIpDUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:51 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:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:51 INFO - Running setup.py (path:/tmp/pip-2U4qm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:51 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:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:51 INFO - Running setup.py (path:/tmp/pip-fEtrku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:51 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:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:51 INFO - Running setup.py (path:/tmp/pip-GqkTiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:51 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:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:51 INFO - Running setup.py (path:/tmp/pip-ZfBwaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:51 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:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:51 INFO - Running setup.py (path:/tmp/pip-BFhSil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:52 INFO - Running setup.py (path:/tmp/pip-n2inSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:52 INFO - Running setup.py (path:/tmp/pip-aMd11p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:52 INFO - Running setup.py (path:/tmp/pip-9nGgZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:52 INFO - Running setup.py (path:/tmp/pip-YzmZeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:52 INFO - Running setup.py (path:/tmp/pip-zCglTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:52 INFO - Running setup.py (path:/tmp/pip-yjWP42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:52 INFO - Running setup.py (path:/tmp/pip-mRwSQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:52 INFO - Running setup.py (path:/tmp/pip-sOIxJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:52 INFO - Running setup.py (path:/tmp/pip-w5LDMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:52 INFO - Running setup.py (path:/tmp/pip-5jHJIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:52 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:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:52 INFO - Running setup.py (path:/tmp/pip-uE_3LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:52 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:48:52 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:48:52 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:48:52 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:48:52 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:48:52 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:48:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:54 INFO - Downloading blessings-1.6.tar.gz 15:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:54 INFO - Installing collected packages: blessings 15:48:54 INFO - Running setup.py install for blessings 15:48:54 INFO - Successfully installed blessings 15:48:54 INFO - Cleaning up... 15:48:54 INFO - Return code: 0 15:48:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:55 INFO - Reading from file tmpfile_stdout 15:48:55 INFO - Current package versions: 15:48:55 INFO - argparse == 1.2.1 15:48:55 INFO - blessings == 1.6 15:48:55 INFO - blobuploader == 1.2.4 15:48:55 INFO - docopt == 0.6.1 15:48:55 INFO - functools32 == 3.2.3-2 15:48:55 INFO - jsonschema == 2.5.1 15:48:55 INFO - manifestparser == 1.1 15:48:55 INFO - mozInstall == 1.12 15:48:55 INFO - mozcrash == 0.17 15:48:55 INFO - mozdebug == 0.1 15:48:55 INFO - mozdevice == 0.48 15:48:55 INFO - mozfile == 1.2 15:48:55 INFO - mozhttpd == 0.7 15:48:55 INFO - mozinfo == 0.9 15:48:55 INFO - mozleak == 0.1 15:48:55 INFO - mozlog == 3.2 15:48:55 INFO - moznetwork == 0.27 15:48:55 INFO - mozprocess == 0.22 15:48:55 INFO - mozprofile == 0.28 15:48:55 INFO - mozrunner == 6.11 15:48:55 INFO - mozscreenshot == 0.1 15:48:55 INFO - mozsystemmonitor == 0.3 15:48:55 INFO - moztest == 0.7 15:48:55 INFO - mozversion == 1.4 15:48:55 INFO - psutil == 3.1.1 15:48:55 INFO - requests == 1.2.3 15:48:55 INFO - wsgiref == 0.1.2 15:48:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:55 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:48:55 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:48:55 INFO - Using env: {'DISPLAY': ':0', 15:48:55 INFO - 'HOME': '/home/cltbld', 15:48:55 INFO - 'LANG': 'en_US.UTF-8', 15:48:55 INFO - 'LANGUAGE': 'en_US:en', 15:48:55 INFO - 'LOGNAME': 'cltbld', 15:48:55 INFO - 'MAIL': '/var/mail/cltbld', 15:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:55 INFO - 'MOZ_NO_REMOTE': '1', 15:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:55 INFO - 'NO_EM_RESTART': '1', 15:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:55 INFO - 'PWD': '/builds/slave/test', 15:48:55 INFO - 'SHELL': '/bin/bash', 15:48:55 INFO - 'SHLVL': '1', 15:48:55 INFO - 'TERM': 'linux', 15:48:55 INFO - 'TMOUT': '86400', 15:48:55 INFO - 'USER': 'cltbld', 15:48:55 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:55 INFO - '_': '/tools/buildbot/bin/python'} 15:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 INFO - Cleaning up... 15:48:55 INFO - Return code: 0 15:48:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:55 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:48:55 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:48:55 INFO - Using env: {'DISPLAY': ':0', 15:48:55 INFO - 'HOME': '/home/cltbld', 15:48:55 INFO - 'LANG': 'en_US.UTF-8', 15:48:55 INFO - 'LANGUAGE': 'en_US:en', 15:48:55 INFO - 'LOGNAME': 'cltbld', 15:48:55 INFO - 'MAIL': '/var/mail/cltbld', 15:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:55 INFO - 'MOZ_NO_REMOTE': '1', 15:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:55 INFO - 'NO_EM_RESTART': '1', 15:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:55 INFO - 'PWD': '/builds/slave/test', 15:48:55 INFO - 'SHELL': '/bin/bash', 15:48:55 INFO - 'SHLVL': '1', 15:48:55 INFO - 'TERM': 'linux', 15:48:55 INFO - 'TMOUT': '86400', 15:48:55 INFO - 'USER': 'cltbld', 15:48:55 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:55 INFO - '_': '/tools/buildbot/bin/python'} 15:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:48:55 INFO - Cleaning up... 15:48:55 INFO - Return code: 0 15:48:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:55 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 0x7fc62cbb1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c76a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, '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': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', '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:48:55 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:48:55 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:48:55 INFO - Using env: {'DISPLAY': ':0', 15:48:55 INFO - 'HOME': '/home/cltbld', 15:48:55 INFO - 'LANG': 'en_US.UTF-8', 15:48:55 INFO - 'LANGUAGE': 'en_US:en', 15:48:55 INFO - 'LOGNAME': 'cltbld', 15:48:55 INFO - 'MAIL': '/var/mail/cltbld', 15:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:55 INFO - 'MOZ_NO_REMOTE': '1', 15:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:55 INFO - 'NO_EM_RESTART': '1', 15:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:55 INFO - 'PWD': '/builds/slave/test', 15:48:55 INFO - 'SHELL': '/bin/bash', 15:48:55 INFO - 'SHLVL': '1', 15:48:55 INFO - 'TERM': 'linux', 15:48:55 INFO - 'TMOUT': '86400', 15:48:55 INFO - 'USER': 'cltbld', 15:48:55 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:55 INFO - '_': '/tools/buildbot/bin/python'} 15:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:55 INFO - Downloading/unpacking flake8 15:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:48:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:48:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:48:58 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:48:58 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:48:58 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:48:58 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:48:58 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:48:58 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:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:58 INFO - no previously-included directories found matching 'docs/_build' 15:48:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:48:58 INFO - Running setup.py install for pep8 15:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:58 INFO - no previously-included directories found matching 'docs/_build' 15:48:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:48:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:48:58 INFO - Cleaning up... 15:48:58 INFO - Return code: 0 15:48:58 INFO - Running post-action listener: _resource_record_post_action 15:48:58 INFO - Running post-action listener: _start_resource_monitoring 15:48:58 INFO - Starting resource monitoring. 15:48:58 INFO - ##### 15:48:58 INFO - ##### Finished create-virtualenv step (success) 15:48:58 INFO - ##### 15:48:58 INFO - ##### 15:48:58 INFO - ##### Running install step. 15:48:58 INFO - ##### 15:48:58 INFO - Running pre-action listener: _resource_record_pre_action 15:48:58 INFO - Running main action method: install 15:48:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:59 INFO - Reading from file tmpfile_stdout 15:48:59 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:59 INFO - Reading from file tmpfile_stdout 15:48:59 INFO - Output received: 15:48:59 INFO - Usage: mozinstall [options] installer 15:48:59 INFO - Options: 15:48:59 INFO - -h, --help show this help message and exit 15:48:59 INFO - -d DEST, --destination=DEST 15:48:59 INFO - Directory to install application into. [default: 15:48:59 INFO - "/builds/slave/test"] 15:48:59 INFO - --app=APP Application being installed. [default: firefox] 15:48:59 INFO - mkdir: /builds/slave/test/build/application 15:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:49:15 INFO - Reading from file tmpfile_stdout 15:49:15 INFO - Output received: 15:49:15 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:15 INFO - Running post-action listener: _resource_record_post_action 15:49:15 INFO - ##### 15:49:15 INFO - ##### Finished install step (success) 15:49:15 INFO - ##### 15:49:15 INFO - ##### 15:49:15 INFO - ##### Running run-tests step. 15:49:15 INFO - ##### 15:49:15 INFO - Running pre-action listener: _resource_record_pre_action 15:49:15 INFO - Running main action method: run_tests 15:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:15 INFO - Python 2.7.3 15:49:15 INFO - Return code: 0 15:49:15 INFO - grabbing minidump binary from tooltool 15:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:15 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 0x2c73d00>, '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 0x2c74ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c74f60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:15 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:49:15 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:49:15 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:49:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:16 INFO - Return code: 0 15:49:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:49:16 INFO - Return code: 0 15:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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:49:16 INFO - Using env: {'DISPLAY': ':0', 15:49:16 INFO - 'HOME': '/home/cltbld', 15:49:16 INFO - 'LANG': 'en_US.UTF-8', 15:49:16 INFO - 'LANGUAGE': 'en_US:en', 15:49:16 INFO - 'LOGNAME': 'cltbld', 15:49:16 INFO - 'MAIL': '/var/mail/cltbld', 15:49:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:16 INFO - 'MOZ_NO_REMOTE': '1', 15:49:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:16 INFO - 'NO_EM_RESTART': '1', 15:49:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:16 INFO - 'PWD': '/builds/slave/test', 15:49:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:16 INFO - 'SHELL': '/bin/bash', 15:49:16 INFO - 'SHLVL': '1', 15:49:16 INFO - 'TERM': 'linux', 15:49:16 INFO - 'TMOUT': '86400', 15:49:16 INFO - 'USER': 'cltbld', 15:49:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984', 15:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:16 INFO - '_': '/tools/buildbot/bin/python'} 15:49:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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:49:17 INFO - mozversion application_buildid: 20160609130607 15:49:17 INFO - mozversion application_changeset: 3ccccf8e5036179a3178437cabc154b5e04b333d 15:49:17 INFO - mozversion application_display_name: Nightly 15:49:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:17 INFO - mozversion application_name: Firefox 15:49:17 INFO - mozversion application_remotingname: firefox 15:49:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:49:17 INFO - mozversion application_vendor: Mozilla 15:49:17 INFO - mozversion application_version: 50.0a1 15:49:17 INFO - mozversion platform_buildid: 20160609130607 15:49:17 INFO - mozversion platform_changeset: 3ccccf8e5036179a3178437cabc154b5e04b333d 15:49:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:49:17 INFO - mozversion platform_version: 50.0a1 15:49:17 INFO - using testdate: 1465512557 15:49:17 INFO - actual date: 1465512557 15:49:17 INFO - starting webserver on 'localhost:51751' 15:49:17 INFO - SUITE-START | Running 2 tests 15:49:17 INFO - TEST-START | dromaeo_css 15:49:17 INFO - operating with platform_type : linux_ 15:49:17 INFO - Initialising browser for dromaeo_css test... 15:49:17 INFO - TEST-INFO | started process 3640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1YjQOu/profile http://localhost:51751/getInfo.html) 15:49:21 INFO - PROCESS | 3640 | __metrics Screen width/height:1600/1200 15:49:21 INFO - PROCESS | 3640 | colorDepth:24 15:49:21 INFO - PROCESS | 3640 | Browser inner width/height: 1024/697 15:49:21 INFO - PROCESS | 3640 | __metrics 15:49:22 ERROR - PROCESS | 3640 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:49:22 INFO - PROCESS | 3640 | console.error: PushService: 15:49:22 INFO - PROCESS | 3640 | stateChangeProcessEnqueue: Error transitioning state 15:49:22 INFO - PROCESS | 3640 | UnknownError 15:49:23 INFO - TEST-INFO | 3640: exit 0 15:49:23 INFO - Browser initialized. 15:49:23 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:23 INFO - TEST-INFO | started process 3721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1YjQOu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:35 INFO - PROCESS | 3721 | RSS: Main: 173129728 15:49:35 INFO - PROCESS | 3721 | 15:50:26 INFO - PROCESS | 3721 | 1465512626397 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:52:35 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51751/tests/dromaeo/cssquery-ext.html) 15:52:36 INFO - PROCESS | 3721 | RSS: Main: 265445376 15:52:36 INFO - PROCESS | 3721 | 15:55:38 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-ext.html (next: http://localhost:51751/tests/dromaeo/cssquery-jquery.html) 15:55:39 INFO - PROCESS | 3721 | RSS: Main: 272453632 15:55:39 INFO - PROCESS | 3721 | 15:58:41 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51751/tests/dromaeo/cssquery-mootools.html) 15:58:41 INFO - PROCESS | 3721 | RSS: Main: 272113664 15:58:41 INFO - PROCESS | 3721 | 16:01:43 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51751/tests/dromaeo/cssquery-prototype.html) 16:01:44 INFO - PROCESS | 3721 | RSS: Main: 269754368 16:01:44 INFO - PROCESS | 3721 | 16:04:47 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51751/tests/dromaeo/cssquery-yui.html) 16:04:47 INFO - PROCESS | 3721 | RSS: Main: 246042624 16:04:47 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:51751/tests/dromaeo/cssquery-yui.html (next: http://localhost:51751/tests/dromaeo/cssquery-dojo.html) 16:07:51 INFO - PROCESS | 3721 | RSS: Main: 295469056 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | __start_tp_report 16:07:51 INFO - PROCESS | 3721 | _x_x_mozilla_page_load 16:07:51 INFO - PROCESS | 3721 | _x_x_mozilla_page_load_details 16:07:51 INFO - PROCESS | 3721 | |i|pagename|runs| 16:07:51 INFO - PROCESS | 3721 | |0;dojo.html;3106.3010822564925;3120.0795765249236;3126.1090589182086;3155.7475401967845;3180.0618817448863;5284.841454756353;5360.169173778066;5383.81156659517;5444.10172321567;5469.097598896177;5383.353997520293;5397.055515284829;5412.052890744124;5429.864253393667;5462.508374246327;4857.222844344906;4903.730294833783;4922.975385123069;4923.950760492403;5107.872303192431;4999.350084489011;5014.974925125388;5042.747862606873;5071.644984851062;5076.517730815585;4796;4821.083994041121;4839.588634966032;4849.3453383793085;4901.901961960776;4803.687760295605;4827.348307978448;4830.372051633265;4884.072026315024;4938.234573641092;4847.951520484785;4871.171900776876;4881.292212629184;4884.120858245515;4899.975500122477;5205.27126202332;5368.141097424394;5414.458554144593;5432.674039557639;5475.397706252309;5834.6790926499025;5855.151003104568;5856.443637854397;5875.500582450497;5878.206442130343;5661.2357331760095;5890.558208134365;5931.970340148272;5932.021216499273;5954.225950626391;5372.731363431852;5433.8369848904595;5447.972760136214;5514.476124768141;5524.226608274845;5984.431479009487;5990.820275391745;5991.940080599225;5994.07091900756;5994.700264986734;5581.441855814467;5629.887402252014;5725.51333136688;5741.110127930177;5741.311042674905;5747.08046712524;5791.420857914175;5797.043487824461;5799.536037117021;5802.0426629605645;5717.142428635738;5762.88474230513;5774.971125144431;5778.8266352009505;5787.623804452697;5638.915416268759;5648.604597678123;5687.60186786921;5761.596688231867;5769.826905192851;4367.519572846984;4368.322909948963;4400.965773043332;4408.669349798778;4410.470743510799;3873.8063096845044;3888.5333759948985;3888.766673999569;3895.104126051024;3927.66614837742;5268.578513718893;5310.7610157543;5352.411234764173;5405.00007498614;5428.2671839301975;5793.623414478046;5843.444872737168;5850.4149585042005;5853.7365818538265;5905.793297234576;5446.564274858002;5447.918281225785;5449.427810079964;5486.3690675572025;5510.173473978935;4787.736674482867;4830.782614782343;4882.829100981448;4911.140550403665;4970.15507363749;6028;6030.005049166926;6034.064719968412;6038.969805151034;6067.605605635619;5852.912206316993;5867.530597552271;5867.589268751147;5868.413158684098;5873.882522349443;5748;5809.244798176379;5825.79609713658;5889.5582831286965;5997.280326360865;5682.914756278742;5689.203511508309;5698.287714035746;5702.40124786908;5707.68607726579;5746.2529871116485;5788.334341550775;5798.82603521895;5799.652020878761;5802.361740208658;5528.44715528444;5591.27313449249;5593.4126916674795;5655.688937108499;5683.261176047087;5666.03677374839;5677.375488696158;5677.432256774289;5678.886422271614;5685.232493613309;5320.28176196224;5563.054280772198;5573.693446860461;5573.77704891816;5574.581906356958;5630.8029218977135;5665.575081868794;5701.857453563694;5785.82642520725;5788.9131663024245;5591.692456914909;5596.468335508121;5599.244102046172;5609.1305847593685;5612.803551875665 16:07:51 INFO - PROCESS | 3721 | |1;ext.html;617.9011358182692;634.1059106659608;635.2599221906479;636.5067073018413;646.6411141816291;40714.79642601786;42517.99999999996;42618.360724589154;45949.31076033862;46577.53422465774;43516.564834351644;44066.559334406644;44079;44088.55911440885;44091;8734.606942687578;9000.549972501381;9021.548922553879;9022.774430639221;9028.638854445815;8829.161229683203;8925.59834807432;8927.41971771836;8928.15182557656;8930.598123084477;8941.195292423681;8988.595513201888;8991.550422478882;8997.05530919254;8999.460032398045;8965.417247878899;8972.685955991574;8992.550372481415;9005.864912026293;9006.59470323837;8798;8868.37921345506;8869.911300886974;8871.423357481743;8873.157050080232;42300.788496057634;42758.358624620654;42788.35817462741;42795.35806962898;42800.143997119885;3458.602260739997;3532.1699400640828;3619.402798538238;3888.9027774305587;4144.3990621360035;31943.84028079868;32332.515012274835;32607.836960815283;32636.510452343235;33416;4185.183889141618;4305.289627211507;4374.365716971021;4384.583464570893;4433.290673492258;348701;350334;350995;351187.2440637806;352187;57395.713021434625;57397;57584.424155758745;57876.13185802216;58023.709881450326;60050.09924851131;60671.089933651034;60734;60746.69626651838;60906;17122.82877171237;17364.826351736574;17372.3051077958;17379.826201738073;17389.565260868327;13531.120477168952;13633.727325453436;13635.38640761168;13640.113392629448;13644.658883527793;15255.542333730005;16919.661606767797;16936.052353979205;16948.57628559296;16957.321707131578;5071.695698258116;5609.971950140223;5709.8287051388525;5753.712314384264;5922.940770592325;940.7459985803795;972.7762614598682;1007.9244056695777;1011.9494025298706;1171.1086595148774;29127.27181820429;31713.682863171074;31826.363472730878;31870;32042.359152816814;1066.3921564708196;1142.8628564572305;1148.8276758486295;1151.2746969409218;1228.1280290993316;16082;16790.916045419694;16796.916015419843;16907.830921690627;16936.745948810778;493633;497008;503406;503730;504245;59237;59248.70375648094;59307;59349.40650593525;59407;1249.237964841446;1338.598420473854;1372.7597670407838;1379.8827099696437;1408.4084684432562;32269.51595726019;33566.66433335733;33859.492107617916;33933;34344.65655343414;50780;51185.48814511807;51327;51333;51347.74326128345;408654;413221;414855;415341;415431;22978.655320169877;25997.480050399263;26111;27716.58425123665;27774.861125694242;48016;48857.5114248853;49221.261681074095;49309.753451232515;50271;40151.79924100361;41250.21687941504;41770;42209.788951056275;42438;40514.79742601386;41549.58450415457;41635.375469367376;42261;42385.78807105945;40984.79507602443;41367.07012833776;41852.58147418609;41855.58144418517;41967.16065678608;33585.83207083949;36355.818220908724;37204;37495.81252093722;37511;28210.576841346985;28379.57430638501;28428.43143137167;28474.295356290426;30385.392292154476 16:07:51 INFO - PROCESS | 3721 | |2;jquery.html;383.41912003314985;386.79693161090427;390.5411141908258;395.52065604208576;408.06756561257515;15130.470433534801;17216.39742609009;18326.45020649381;19779.901100494495;19817.306394276202;15321.157336346496;15814.762778558303;18970.905145474288;19215.903920480414;19249.90375048123;5452.91820622692;5685.445775943497;5704.429557044283;5712.200291959129;5718.542516598662;5544.972275138639;5685.431456854323;5699.401562835905;5718.256626638552;5732.799352022659;4017.919641607166;6022.759089636409;6299.6850157491945;6304.716287767039;6410.358964103576;6330.525210609223;6357.904631430509;6360.968195158995;6390.201224846895;6431.421172094534;5507.7246137692955;5624.971875140639;5649.067903795869;5657.6322539035245;5667.716614169275;20200.898995505075;21808.454788630253;22210.555788884132;22541.887290563605;22551.548969020692;2265.920692775745;2792.720727927191;2862.541993281065;2880.927976800576;2939.2504911247497;9681.467519286436;10081.89918100817;10113.045074181206;10319.007487828521;10323.741906452398;1850.4541160357726;1873.325602782997;1931.5267759399037;1940.9126589303514;2085.18677715691;84255;86163;86286;86962.99999999937;87324;9813.460259685786;9987.151092157103;10020.448875311928;10262;10424;13789.724205515833;13802.65493362674;14355.497557585435;14482.275886205647;14860.554183374516;7253.419726421874;7294.489385742947;7608.277213664693;8119.634616442274;8283.8343233135;8059.073206581201;8161.632726527202;8259.545724985184;8301.086880443147;8371.539565323965;4460.8661740147845;4578.862634120982;4582.381378513858;4597.310403439511;4730.668853180245;1771.663383957044;1784.5360206346184;1791.2029147029448;1852.6109517001312;1864.3567969050655;448.7307615430781;924.3021518753433;974.3617930255693;1023.6928921323573;1072.4155335342257;11129.777404451866;11789.941050294865;11838.348890810916;11877.76244475123;12051.939740301243;451.9231730605739;461.893764434182;462.1819379697983;472.0959362820161;495.8066354121895;6205.410486003822;6298.653574053471;6355.3009168991375;6364.968175159094;6411.551191416649;490324;507352;508115;510157;529115;96893;97142;99512.50243748735;99840;100067.99932000732;464.6027646362379;465.7042777836098;470.9481956984729;472.4826315184867;472.65968502678015;10765;10905.34567925927;11101.444927753742;11244.55021799119;11274.943625281821;36867.26265474729;37923.43114853224;38880;40707.59292407156;42632;50679.74660126676;51424.74287628538;57592.424075760384;58878.705606471696;59976.70011649914;7335.816604584928;7690.00029996097;7716.961415192888;8169.468984515987;8685.478871267744;8126.593670316579;9482.336236463658;10982.341059536453;12096;12569.857535616096;11787.233829801036;11813.940930295637;11973.940130299292;12031.939840301093;12207.63377098653;7482.551046936984;8659.701044843325;11767.176297659424;11931.284122952651;12046.457909394274;11880.881191188324;12004.339761313211;12103.273803571461;12139.453724582238;12330.445129969352;8357.164283571594;8457.281131103678;8593.226609605166;8699.43453675509;8795.384323097322;11663.066954643438;11783.587574434854;11930.880691193324;11931.880681193425;12021.098417618889 16:07:51 INFO - PROCESS | 3721 | |3;mootools.html;1328.6944002879343;1460.7662773956165;1497.3785878860272;1616.2080580515544;1732.8440440360364;8580.39937204396;8994.730158095259;9046.59290331935;9268.934072581638;9327.76680582984;8844.823103537925;8986.370954033204;9079.455232686043;9095.363324567266;9127.771805704844;2411.819113566485;2444.963325550118;2446.0460420436043;2457.1891275879007;2462.064415522099;2415.3818537208012;2482.6027835546315;2488.2908371114236;2488.9004439822384;2490.5018996200743;2351.27110595715;2356.231808141058;2400.3797436730224;2403.2910291463972;2405.867677277749;2290.3129061281547;2298.069281940817;2304.5966955782756;2310.1336998625598;2337.801286890616;2294.1855641247284;2353.7646235376324;2360.44529535559;2363.905443782264;2377.9286621401384;8033.678652853879;8254.133316001853;9253.259739220914;9320.114589114024;9331.16019558243;5309.150535914274;5486.1222204447095;5486.561075114021;5576.302962129734;5578.693171875545;3334.299797042624;3385.5260263563123;3450.292689998544;3452.085197422685;3475.391806433864;1010.1413798271484;1034.8344264917653;1165.6153469355093;1191.74377508836;1205.6624145239348;12922.870771292355;12949.223046617233;12950.935245323808;12953.54662586805;12963.287019214034;4893.266010098514;4935.358403407534;5038.974805125951;5039.294498770175;5055.570276526537;10741.83887241692;10780.407077610807;10781.407022613676;10807.189460790314;10809.67570972872;5553;5563.582731295169;5571.860703482445;5573.498385145356;5580.637258578179;4890.339804126468;5092.083424983539;5160.819371321985;5231.424543300234;5244.475552444725;4124.793760312033;4127.153933443637;4176.624103830609;4182.895427614273;4193.308104162779;1308.2150709574369;1327.8529884174568;1339.7923321885123;1342.02703040295;1343.2746316988716;800.8718605023168;932.6083045121065;936.2603543200795;947.393668052447;960.865478832964;6190.783322583688;6351.428371446592;6369.904451433232;6458.354164583504;6496.707648155844;446.2191165460444;456.9520200378979;458.2278860120707;466.65001249063204;467.3387157172631;4103.466549348565;4179.770112643773;4197.244495990751;4308.4399028126145;4362.498312694017;10101.69694909154;10883.619073332395;11102.333859968267;11453.59912403062;11473.885261147281;11359.204855660024;11641.185117041725;11697.532098716127;11764.647060588197;11765.647030589096;8527.360447966428;8650.351223658377;8685.435446695945;8698;8699.565021748887;6334.461570766352;6404.967975160094;6442.420182183626;6462.483001359786;6482.384173503509;10505.579776808841;10599.682009539727;10739.731506712082;10768.407737574194;10806.973337532922;10788.190885683449;10790.352578845295;10855.294405863593;11030.06244469229;11145.49845256949;7708.768736937901;7790.4936179150445;7791.0650721913735;7851.411144164096;7870.763877083697;8760.080191579793;8942.195202431552;8942.195202431813;8950.776230594287;8952.28381729447;7760.68957241704;7767.300942915164;7907.960460197893;8080.515169089874;8222.547759873265;7776.88334674969;7823.765287041398;7858.842823143391;7861.764147075597;7898.763037108896;7442.702291908479;7466.328030477283;7485.1017877855;7493.100827900686;7533.510321829063;5359.571234301168;5368.275282836923;5501.724913754216;5565.387807341108;5585.190147428519;5353.4646535346155;5481.205225242238;5558.555315574825;5643.492085712305;5733.0253856845275 16:07:51 INFO - PROCESS | 3721 | |4;prototype.html;329.0474077545506;329.7790480378146;332.7504371721209;343.69411224010616;346.66027293252614;5279.973600131999;5337.14605663094;5385.326834145727;5393.649412788166;5416.402161362398;5226.040234911908;5266.736663166846;5289.841304760854;5307.363116426025;5313.495217954288;3943.5662077171487;4007.378856277267;4203.117345357478;4211.284081706102;4214.325707886739;4062.6546743526837;4105.9178816423655;4150.754773567933;4171.0823618803815;4188.120494696117;3968.630822366279;4156.459660244164;4159.2721273777115;4161.2093702196635;4184.539700632928;4004.8398064077546;4098.979505102485;4126.360414135784;4126.587341265879;4174.958250417502;3632.7820330780237;3648.21563363878;3674.356987527172;3676.8713095041544;3682.576503702082;5119.411267704225;5173.58611311098;5186.766595503212;5194.558462530689;5223.608229382773;4438.1567502174485;4683.929741053887;4689.648276379251;4762.952370476286;4763.928541071887;5429.972850135763;5454.781808727646;5469.699166545838;5470.7264636768405;5525.198846167324;3947.842086316544;3959.5050618672667;3988.3618621020496;3993.8602148924647;4016.6385025347568;5493.423190565013;5690.5447564194765;5708.743106560215;5741.425857414267;5756.597038207327;4024.879253622396;4046.5346485153996;4048.129652124748;4051.845224111114;4052.7163098583405;4078.084169818594;4082;4088.836446542105;4095.4471146394853;4120.526139493936;4557.908841823145;4642;4691.882702932454;4692.671512994058;4698.788554515055;5461.924468701868;5502.312210973629;5524.613277070566;5557.249771280825;5561.58288128392;1397.1407584335561;1401.9859801401856;1414.2575148047151;1415.7310111079069;1417.1780367386891;897.264243320484;911.6399022386121;939.1641439119189;964.7298756348234;968.2641192693603;5267.631265811357;5300.628955973045;5369.382521010056;5409.918851217235;5414.864628384322;5570.192322113271;5584.329880414376;5593;5628.690422026828;5638.210650508932;5077.695338279715;5252.185911183773;5288.338957630296;5304.708241046702;5311.495407936317;2970.50986587215;2975.613170287849;3009.0822299198517;3045.710657487535;3053.6793636668276;5565.888682226333;5811.360750317462;5822.184894114827;5845.7369418376275;5850.765969361263;5793.304803423532;5829.417058294137;5834.416558344216;5836.212111364996;5856.677882716406;5853.619514731614;5871.4715675589405;5872.236609240771;5887.646741195542;5907.822765317047;5547.317837607381;5669.433056694297;5672.404397538199;5677.858053548694;5752.577216556142;5523.475269849358;5599.552035837205;5599.664020158803;5623.409541998031;5635.8591035224445;5668.234788303526;5776.42235776419;5858.740370820294;5892.616979896293;5935.732892019783;4070.6973768393827;4077.653399461079;4104.302268614283;4104.445899803488;4104.91790164201;5706.942930570641;5749.8562535936935;5754.108113242454;5808.448197421239;5822;5439.945600543944;5634.295713035871;5651.124075768263;5667.263255776723;5669.801556945487;5520.751566179451;5526.392096869422;5546.334439867242;5548.916766248591;5554.833354999357;5286.603504737237;5320.57435405159;5481.260029895913;5483.396826349178;5518.392976772632;5584.581156413204;5601.551875850004;5629.521490673174;5643.1535269709875;5672.31932168126;5392.5416339611575;5408.621396502364;5449.945500544944;5466.508014278734;5526.50261476469 16:07:51 INFO - PROCESS | 3721 | |5;yui.html;774.1716363491063;785.3821128475339;815.6900377856416;822.2312138150826;853.7268074216254;13224.7355052899;14196.36116374763;14203.928980355096;14220.146791192536;14222.857771422296;15007.324670389831;15436.151011694352;15935.681286374265;15977.84022159778;16680.74978875318;682.9726810927582;800.0599295827379;809.8623234050226;815.8409110223508;821.8643923752574;810.7608255564593;818.304441224957;818.9262966333058;819.3199644295221;819.418213068719;845.2181731898;847.1401527449643;848.321342925657;848.6350869126273;849.1254008371387;711.6939715922151;832.0597724571256;840.6679361652144;843.5655637346771;856.9442986205939;621.9471344935686;625.5558553427072;625.871696302257;626.067159931703;626.5457543281085;14648.633784155374;14672.119672819665;14700.55898323052;14708.779368309484;14715.63210919725;13106;13277;13346.599602011955;13666.18002919828;13784.517541886085;12869.549565765154;12969.286689232089;13049.478020879153;13268;13295.73408531824;4259.063006138645;4306.224879521685;4362.663899255595;4394.8242070317465;4482.394876691637;73498;74250.25749742487;74452.39416620447;74948.25051749522;75012.62493687551;11144.721381965515;11219.495122719498;11317.886821131684;11321.660350189508;11462.885371146182;20569.280075197297;20620.27829025977;20636.58726825485;20646.17415303401;20651.17395304171;8435.704750333709;8478.406511544255;8699.217070463688;8735.868961965576;8974.37179397449;4671.836485723052;4790.688605240648;4850.248211527219;4928.211486162197;5001.599872010231;3268.2156282492033;3340.8329583520726;3341.114604629775;3341.8329083545727;3362.8486718097743;593.0155941137691;600.2137200267663;604.7611193578512;614.8339948213957;619.5817822969477;544.5262621519304;569.5785119011902;578.8842231553706;592.2774215457135;703.6341102626708;8748.212660860552;9219.769505762275;9295.535223238947;9413.105754953269;9535.52322383878;643.6685107169811;683.4703105093593;685.020162294017;685.8662560800695;686.5455994818544;4112.198121366365;4167.541570427251;4256.59562341577;4281.154593394346;4362.978185109054;69071.65464172648;95414.13757587284;105583.47208264063;107267.46366268118;107430.46284768527;90934.54532727294;91347;91727;92484.53757731302;92911.49578693614;81138;81419.18580814234;82090;82154;83166.58416707878;8604.870926935975;8709.303255739653;8809.515476648847;8823.955880220557;9000.50497222659;42441.575584244994;45511.77244113758;46372.53627463682;52012;52371;64244.67877660582;70198.64900675464;70470.29529704842;71944.28055719585;74152;13218.801717974045;13235.40440680191;13475.326233688473;13778.931105344409;13907.443702252202;14642.780358294422;14847;14872.25092177211;14899.64534088864;15059.623509412353;13710.794338084737;14120.858791412365;14315.785263221269;14400.567982960529;14716.043457175248;14220.431182752574;14363.640908977359;14416.495422660157;14616.780748288571;15175.772363414779;13595.388207530883;13684.452621895012;13899.096558723651;14132.78800817968;14243.430262789372;11445.656630301104;11484.88515114838;11704.94147529257;11792.23350482216;11866.762664746484;11743.177977541833;11852.525898963944;11862.881371186522;11982.880171198176;12010.63968080959 16:07:51 INFO - PROCESS | 3721 | __end_tp_report 16:07:51 INFO - PROCESS | 3721 | __start_cc_report 16:07:51 INFO - PROCESS | 3721 | _x_x_mozilla_cycle_collect,811 16:07:51 INFO - PROCESS | 3721 | __end_cc_report 16:07:51 INFO - PROCESS | 3721 | __startTimestamp1465513671253__endTimestamp 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | ------- Summary: start ------- 16:07:51 INFO - PROCESS | 3721 | Number of tests: 6 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#0] dojo.html Cycles:165 Average:5369.80 Median:5591.27 stddev:623.41 (11.1%) stddev-sans-first:599.50 16:07:51 INFO - PROCESS | 3721 | Values: 3106.3 3120.1 3126.1 3155.7 3180.1 5284.8 5360.2 5383.8 5444.1 5469.1 5383.4 5397.1 5412.1 5429.9 5462.5 4857.2 4903.7 4923.0 4924.0 5107.9 4999.4 5015.0 5042.7 5071.6 5076.5 4796.0 4821.1 4839.6 4849.3 4901.9 4803.7 4827.3 4830.4 4884.1 4938.2 4848.0 4871.2 4881.3 4884.1 4900.0 5205.3 5368.1 5414.5 5432.7 5475.4 5834.7 5855.2 5856.4 5875.5 5878.2 5661.2 5890.6 5932.0 5932.0 5954.2 5372.7 5433.8 5448.0 5514.5 5524.2 5984.4 5990.8 5991.9 5994.1 5994.7 5581.4 5629.9 5725.5 5741.1 5741.3 5747.1 5791.4 5797.0 5799.5 5802.0 5717.1 5762.9 5775.0 5778.8 5787.6 5638.9 5648.6 5687.6 5761.6 5769.8 4367.5 4368.3 4401.0 4408.7 4410.5 3873.8 3888.5 3888.8 3895.1 3927.7 5268.6 5310.8 5352.4 5405.0 5428.3 5793.6 5843.4 5850.4 5853.7 5905.8 5446.6 5447.9 5449.4 5486.4 5510.2 4787.7 4830.8 4882.8 4911.1 4970.2 6028.0 6030.0 6034.1 6039.0 6067.6 5852.9 5867.5 5867.6 5868.4 5873.9 5748.0 5809.2 5825.8 5889.6 5997.3 5682.9 5689.2 5698.3 5702.4 5707.7 5746.3 5788.3 5798.8 5799.7 5802.4 5528.4 5591.3 5593.4 5655.7 5683.3 5666.0 5677.4 5677.4 5678.9 5685.2 5320.3 5563.1 5573.7 5573.8 5574.6 5630.8 5665.6 5701.9 5785.8 5788.9 5591.7 5596.5 5599.2 5609.1 5612.8 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#1] ext.html Cycles:180 Average:58701.99 Median:42088.47 stddev:112647.16 (267.6%) stddev-sans-first:112878.75 16:07:51 INFO - PROCESS | 3721 | Values: 617.9 634.1 635.3 636.5 646.6 40714.8 42518.0 42618.4 45949.3 46577.5 43516.6 44066.6 44079.0 44088.6 44091.0 8734.6 9000.5 9021.5 9022.8 9028.6 8829.2 8925.6 8927.4 8928.2 8930.6 8941.2 8988.6 8991.6 8997.1 8999.5 8965.4 8972.7 8992.6 9005.9 9006.6 8798.0 8868.4 8869.9 8871.4 8873.2 42300.8 42758.4 42788.4 42795.4 42800.1 3458.6 3532.2 3619.4 3888.9 4144.4 31943.8 32332.5 32607.8 32636.5 33416.0 4185.2 4305.3 4374.4 4384.6 4433.3 348701.0 350334.0 350995.0 351187.2 352187.0 57395.7 57397.0 57584.4 57876.1 58023.7 60050.1 60671.1 60734.0 60746.7 60906.0 17122.8 17364.8 17372.3 17379.8 17389.6 13531.1 13633.7 13635.4 13640.1 13644.7 15255.5 16919.7 16936.1 16948.6 16957.3 5071.7 5610.0 5709.8 5753.7 5922.9 940.7 972.8 1007.9 1011.9 1171.1 29127.3 31713.7 31826.4 31870.0 32042.4 1066.4 1142.9 1148.8 1151.3 1228.1 16082.0 16790.9 16796.9 16907.8 16936.7 493633.0 497008.0 503406.0 503730.0 504245.0 59237.0 59248.7 59307.0 59349.4 59407.0 1249.2 1338.6 1372.8 1379.9 1408.4 32269.5 33566.7 33859.5 33933.0 34344.7 50780.0 51185.5 51327.0 51333.0 51347.7 408654.0 413221.0 414855.0 415341.0 415431.0 22978.7 25997.5 26111.0 27716.6 27774.9 48016.0 48857.5 49221.3 49309.8 50271.0 40151.8 41250.2 41770.0 42209.8 42438.0 40514.8 41549.6 41635.4 42261.0 42385.8 40984.8 41367.1 41852.6 41855.6 41967.2 33585.8 36355.8 37204.0 37495.8 37511.0 28210.6 28379.6 28428.4 28474.3 30385.4 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#2] jquery.html Cycles:180 Average:28946.36 Median:4580.62 stddev:84213.83 (1838.5%) stddev-sans-first:84422.76 16:07:51 INFO - PROCESS | 3721 | Values: 383.4 386.8 390.5 395.5 408.1 15130.5 17216.4 18326.5 19779.9 19817.3 15321.2 15814.8 18970.9 19215.9 19249.9 5452.9 5685.4 5704.4 5712.2 5718.5 5545.0 5685.4 5699.4 5718.3 5732.8 4017.9 6022.8 6299.7 6304.7 6410.4 6330.5 6357.9 6361.0 6390.2 6431.4 5507.7 5625.0 5649.1 5657.6 5667.7 20200.9 21808.5 22210.6 22541.9 22551.5 2265.9 2792.7 2862.5 2880.9 2939.3 9681.5 10081.9 10113.0 10319.0 10323.7 1850.5 1873.3 1931.5 1940.9 2085.2 84255.0 86163.0 86286.0 86963.0 87324.0 9813.5 9987.2 10020.4 10262.0 10424.0 13789.7 13802.7 14355.5 14482.3 14860.6 7253.4 7294.5 7608.3 8119.6 8283.8 8059.1 8161.6 8259.5 8301.1 8371.5 4460.9 4578.9 4582.4 4597.3 4730.7 1771.7 1784.5 1791.2 1852.6 1864.4 448.7 924.3 974.4 1023.7 1072.4 11129.8 11789.9 11838.3 11877.8 12051.9 451.9 461.9 462.2 472.1 495.8 6205.4 6298.7 6355.3 6365.0 6411.6 490324.0 507352.0 508115.0 510157.0 529115.0 96893.0 97142.0 99512.5 99840.0 100068.0 464.6 465.7 470.9 472.5 472.7 10765.0 10905.3 11101.4 11244.6 11274.9 36867.3 37923.4 38880.0 40707.6 42632.0 50679.7 51424.7 57592.4 58878.7 59976.7 7335.8 7690.0 7717.0 8169.5 8685.5 8126.6 9482.3 10982.3 12096.0 12569.9 11787.2 11813.9 11973.9 12031.9 12207.6 7482.6 8659.7 11767.2 11931.3 12046.5 11880.9 12004.3 12103.3 12139.5 12330.4 8357.2 8457.3 8593.2 8699.4 8795.4 11663.1 11783.6 11930.9 11931.9 12021.1 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#3] mootools.html Cycles:180 Average:6046.18 Median:4891.80 stddev:3478.26 (71.1%) stddev-sans-first:3469.94 16:07:51 INFO - PROCESS | 3721 | Values: 1328.7 1460.8 1497.4 1616.2 1732.8 8580.4 8994.7 9046.6 9268.9 9327.8 8844.8 8986.4 9079.5 9095.4 9127.8 2411.8 2445.0 2446.0 2457.2 2462.1 2415.4 2482.6 2488.3 2488.9 2490.5 2351.3 2356.2 2400.4 2403.3 2405.9 2290.3 2298.1 2304.6 2310.1 2337.8 2294.2 2353.8 2360.4 2363.9 2377.9 8033.7 8254.1 9253.3 9320.1 9331.2 5309.2 5486.1 5486.6 5576.3 5578.7 3334.3 3385.5 3450.3 3452.1 3475.4 1010.1 1034.8 1165.6 1191.7 1205.7 12922.9 12949.2 12950.9 12953.5 12963.3 4893.3 4935.4 5039.0 5039.3 5055.6 10741.8 10780.4 10781.4 10807.2 10809.7 5553.0 5563.6 5571.9 5573.5 5580.6 4890.3 5092.1 5160.8 5231.4 5244.5 4124.8 4127.2 4176.6 4182.9 4193.3 1308.2 1327.9 1339.8 1342.0 1343.3 800.9 932.6 936.3 947.4 960.9 6190.8 6351.4 6369.9 6458.4 6496.7 446.2 457.0 458.2 466.7 467.3 4103.5 4179.8 4197.2 4308.4 4362.5 10101.7 10883.6 11102.3 11453.6 11473.9 11359.2 11641.2 11697.5 11764.6 11765.6 8527.4 8650.4 8685.4 8698.0 8699.6 6334.5 6405.0 6442.4 6462.5 6482.4 10505.6 10599.7 10739.7 10768.4 10807.0 10788.2 10790.4 10855.3 11030.1 11145.5 7708.8 7790.5 7791.1 7851.4 7870.8 8760.1 8942.2 8942.2 8950.8 8952.3 7760.7 7767.3 7908.0 8080.5 8222.5 7776.9 7823.8 7858.8 7861.8 7898.8 7442.7 7466.3 7485.1 7493.1 7533.5 5359.6 5368.3 5501.7 5565.4 5585.2 5353.5 5481.2 5558.6 5643.5 5733.0 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#4] prototype.html Cycles:180 Average:4668.28 Median:5353.26 stddev:1371.03 (25.6%) stddev-sans-first:1335.63 16:07:51 INFO - PROCESS | 3721 | Values: 329.0 329.8 332.8 343.7 346.7 5280.0 5337.1 5385.3 5393.6 5416.4 5226.0 5266.7 5289.8 5307.4 5313.5 3943.6 4007.4 4203.1 4211.3 4214.3 4062.7 4105.9 4150.8 4171.1 4188.1 3968.6 4156.5 4159.3 4161.2 4184.5 4004.8 4099.0 4126.4 4126.6 4175.0 3632.8 3648.2 3674.4 3676.9 3682.6 5119.4 5173.6 5186.8 5194.6 5223.6 4438.2 4683.9 4689.6 4763.0 4763.9 5430.0 5454.8 5469.7 5470.7 5525.2 3947.8 3959.5 3988.4 3993.9 4016.6 5493.4 5690.5 5708.7 5741.4 5756.6 4024.9 4046.5 4048.1 4051.8 4052.7 4078.1 4082.0 4088.8 4095.4 4120.5 4557.9 4642.0 4691.9 4692.7 4698.8 5461.9 5502.3 5524.6 5557.2 5561.6 1397.1 1402.0 1414.3 1415.7 1417.2 897.3 911.6 939.2 964.7 968.3 5267.6 5300.6 5369.4 5409.9 5414.9 5570.2 5584.3 5593.0 5628.7 5638.2 5077.7 5252.2 5288.3 5304.7 5311.5 2970.5 2975.6 3009.1 3045.7 3053.7 5565.9 5811.4 5822.2 5845.7 5850.8 5793.3 5829.4 5834.4 5836.2 5856.7 5853.6 5871.5 5872.2 5887.6 5907.8 5547.3 5669.4 5672.4 5677.9 5752.6 5523.5 5599.6 5599.7 5623.4 5635.9 5668.2 5776.4 5858.7 5892.6 5935.7 4070.7 4077.7 4104.3 4104.4 4104.9 5706.9 5749.9 5754.1 5808.4 5822.0 5439.9 5634.3 5651.1 5667.3 5669.8 5520.8 5526.4 5546.3 5548.9 5554.8 5286.6 5320.6 5481.3 5483.4 5518.4 5584.6 5601.6 5629.5 5643.2 5672.3 5392.5 5408.6 5449.9 5466.5 5526.5 16:07:51 INFO - PROCESS | 3721 | 16:07:51 INFO - PROCESS | 3721 | [#5] yui.html Cycles:180 Average:19746.56 Median:25522.19 stddev:27303.67 (107.0%) stddev-sans-first:27343.10 16:07:51 INFO - PROCESS | 3721 | Values: 774.2 785.4 815.7 822.2 853.7 13224.7 14196.4 14203.9 14220.1 14222.9 15007.3 15436.2 15935.7 15977.8 16680.7 683.0 800.1 809.9 815.8 821.9 810.8 818.3 818.9 819.3 819.4 845.2 847.1 848.3 848.6 849.1 711.7 832.1 840.7 843.6 856.9 621.9 625.6 625.9 626.1 626.5 14648.6 14672.1 14700.6 14708.8 14715.6 13106.0 13277.0 13346.6 13666.2 13784.5 12869.5 12969.3 13049.5 13268.0 13295.7 4259.1 4306.2 4362.7 4394.8 4482.4 73498.0 74250.3 74452.4 74948.3 75012.6 11144.7 11219.5 11317.9 11321.7 11462.9 20569.3 20620.3 20636.6 20646.2 20651.2 8435.7 8478.4 8699.2 8735.9 8974.4 4671.8 4790.7 4850.2 4928.2 5001.6 3268.2 3340.8 3341.1 3341.8 3362.8 593.0 600.2 604.8 614.8 619.6 544.5 569.6 578.9 592.3 703.6 8748.2 9219.8 9295.5 9413.1 9535.5 643.7 683.5 685.0 685.9 686.5 4112.2 4167.5 4256.6 4281.2 4363.0 69071.7 95414.1 105583.5 107267.5 107430.5 90934.5 91347.0 91727.0 92484.5 92911.5 81138.0 81419.2 82090.0 82154.0 83166.6 8604.9 8709.3 8809.5 8824.0 9000.5 42441.6 45511.8 46372.5 52012.0 52371.0 64244.7 70198.6 70470.3 71944.3 74152.0 13218.8 13235.4 13475.3 13778.9 13907.4 14642.8 14847.0 14872.3 14899.6 15059.6 13710.8 14120.9 14315.8 14400.6 14716.0 14220.4 14363.6 14416.5 14616.8 15175.8 13595.4 13684.5 13899.1 14132.8 14243.4 11445.7 11484.9 11704.9 11792.2 11866.8 11743.2 11852.5 11862.9 11982.9 12010.6 16:07:51 INFO - PROCESS | 3721 | -------- Summary: end -------- 16:07:51 INFO - PROCESS | 3721 | 16:07:52 INFO - TEST-INFO | 3721: exit 0 16:07:52 INFO - TEST-OK | dromaeo_css | took 1114899ms 16:07:52 INFO - TEST-START | kraken 16:07:52 INFO - operating with platform_type : linux_ 16:07:52 INFO - Initialising browser for kraken test... 16:07:52 INFO - TEST-INFO | started process 3898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6yLPbZ/profile http://localhost:51751/getInfo.html) 16:07:56 INFO - PROCESS | 3898 | __metrics Screen width/height:1600/1200 16:07:56 INFO - PROCESS | 3898 | colorDepth:24 16:07:56 INFO - PROCESS | 3898 | Browser inner width/height: 1024/697 16:07:56 INFO - PROCESS | 3898 | __metrics 16:07:57 ERROR - PROCESS | 3898 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:07:57 INFO - PROCESS | 3898 | console.error: PushService: 16:07:57 INFO - PROCESS | 3898 | stateChangeProcessEnqueue: Error transitioning state 16:07:57 INFO - PROCESS | 3898 | UnknownError 16:07:58 INFO - TEST-INFO | 3898: exit 0 16:07:58 INFO - Browser initialized. 16:07:58 INFO - Running cycle 1/1 for kraken test... 16:07:58 INFO - TEST-INFO | started process 3982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6yLPbZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:10 INFO - PROCESS | 3982 | RSS: Main: 172924928 16:08:10 INFO - PROCESS | 3982 | 16:08:12 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51751/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:13 INFO - PROCESS | 3982 | RSS: Main: 231419904 16:08:13 INFO - PROCESS | 3982 | 16:08:16 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51751/tests/kraken/driver.html?testName=audio-dft) 16:08:16 INFO - PROCESS | 3982 | RSS: Main: 215830528 16:08:16 INFO - PROCESS | 3982 | 16:08:19 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51751/tests/kraken/driver.html?testName=audio-fft) 16:08:20 INFO - PROCESS | 3982 | RSS: Main: 285323264 16:08:20 INFO - PROCESS | 3982 | 16:08:22 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51751/tests/kraken/driver.html?testName=audio-oscillator) 16:08:23 INFO - PROCESS | 3982 | RSS: Main: 196292608 16:08:23 INFO - PROCESS | 3982 | 16:08:25 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:25 INFO - PROCESS | 3982 | RSS: Main: 200654848 16:08:25 INFO - PROCESS | 3982 | 16:08:28 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51751/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:29 INFO - PROCESS | 3982 | RSS: Main: 234971136 16:08:29 INFO - PROCESS | 3982 | 16:08:32 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51751/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:33 INFO - PROCESS | 3982 | RSS: Main: 236945408 16:08:33 INFO - PROCESS | 3982 | 16:08:36 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51751/tests/kraken/driver.html?testName=json-parse-financial) 16:08:36 INFO - PROCESS | 3982 | RSS: Main: 237432832 16:08:36 INFO - PROCESS | 3982 | 16:08:38 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:38 INFO - PROCESS | 3982 | RSS: Main: 220823552 16:08:38 INFO - PROCESS | 3982 | 16:08:40 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:41 INFO - PROCESS | 3982 | RSS: Main: 226471936 16:08:41 INFO - PROCESS | 3982 | 16:08:43 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:44 INFO - PROCESS | 3982 | RSS: Main: 235040768 16:08:44 INFO - PROCESS | 3982 | 16:08:46 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:47 INFO - PROCESS | 3982 | RSS: Main: 239894528 16:08:47 INFO - PROCESS | 3982 | 16:08:49 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:50 INFO - PROCESS | 3982 | RSS: Main: 239075328 16:08:50 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:51751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51751/tests/kraken/driver.html?testName=ai-astar) 16:08:52 INFO - PROCESS | 3982 | RSS: Main: 282169344 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | __start_tp_report 16:08:52 INFO - PROCESS | 3982 | _x_x_mozilla_page_load 16:08:52 INFO - PROCESS | 3982 | _x_x_mozilla_page_load_details 16:08:52 INFO - PROCESS | 3982 | |i|pagename|runs| 16:08:52 INFO - PROCESS | 3982 | |0;ai-astar;112;105;109;108;109;109;109;110;110;110 16:08:52 INFO - PROCESS | 3982 | |1;audio-beat-detection;152;124;128;197;127;128;158;129;163;129 16:08:52 INFO - PROCESS | 3982 | |2;audio-dft;159;159;160;161;160;162;161;169;161;160 16:08:52 INFO - PROCESS | 3982 | |3;audio-fft;76;75;75;109;75;75;76;76;122;75 16:08:52 INFO - PROCESS | 3982 | |4;audio-oscillator;84;85;107;85;85;85;120;85;85;122 16:08:52 INFO - PROCESS | 3982 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;117 16:08:52 INFO - PROCESS | 3982 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;146;145 16:08:52 INFO - PROCESS | 3982 | |7;imaging-desaturate;94;95;95;95;95;94;94;94;95;94 16:08:52 INFO - PROCESS | 3982 | |8;json-parse-financial;57;55;53;53;54;53;55;53;55;54 16:08:52 INFO - PROCESS | 3982 | |9;json-stringify-tinderbox;61;61;62;62;62;61;60;62;62;62 16:08:52 INFO - PROCESS | 3982 | |10;stanford-crypto-aes;79;80;85;79;80;85;82;79;116;85 16:08:52 INFO - PROCESS | 3982 | |11;stanford-crypto-ccm;122;122;123;138;124;127;123;125;138;121 16:08:52 INFO - PROCESS | 3982 | |12;stanford-crypto-pbkdf2;165;160;162;161;157;164;159;167;161;156 16:08:52 INFO - PROCESS | 3982 | |13;stanford-crypto-sha256-iterative;63;74;66;62;64;65;63;63;62;62 16:08:52 INFO - PROCESS | 3982 | __end_tp_report 16:08:52 INFO - PROCESS | 3982 | __start_cc_report 16:08:52 INFO - PROCESS | 3982 | _x_x_mozilla_cycle_collect,1618 16:08:52 INFO - PROCESS | 3982 | __end_cc_report 16:08:52 INFO - PROCESS | 3982 | __startTimestamp1465513732470__endTimestamp 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | ------- Summary: start ------- 16:08:52 INFO - PROCESS | 3982 | Number of tests: 14 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:1.79 (1.6%) stddev-sans-first:1.56 16:08:52 INFO - PROCESS | 3982 | Values: 112.0 105.0 109.0 108.0 109.0 109.0 109.0 110.0 110.0 110.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:140.50 stddev:23.75 (16.9%) stddev-sans-first:25.00 16:08:52 INFO - PROCESS | 3982 | Values: 152.0 124.0 128.0 197.0 127.0 128.0 158.0 129.0 163.0 129.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:2.90 (1.8%) stddev-sans-first:2.96 16:08:52 INFO - PROCESS | 3982 | Values: 159.0 159.0 160.0 161.0 160.0 162.0 161.0 169.0 161.0 160.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#3] audio-fft Cycles:10 Average:83.40 Median:75.00 stddev:17.20 (22.9%) stddev-sans-first:18.03 16:08:52 INFO - PROCESS | 3982 | Values: 76.0 75.0 75.0 109.0 75.0 75.0 76.0 76.0 122.0 75.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:85.00 stddev:15.68 (18.5%) stddev-sans-first:16.19 16:08:52 INFO - PROCESS | 3982 | Values: 84.0 85.0 107.0 85.0 85.0 85.0 120.0 85.0 85.0 122.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:08:52 INFO - PROCESS | 3982 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:08:52 INFO - PROCESS | 3982 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:08:52 INFO - PROCESS | 3982 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.32 (2.4%) stddev-sans-first:0.93 16:08:52 INFO - PROCESS | 3982 | Values: 57.0 55.0 53.0 53.0 54.0 53.0 55.0 53.0 55.0 54.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 16:08:52 INFO - PROCESS | 3982 | Values: 61.0 61.0 62.0 62.0 62.0 61.0 60.0 62.0 62.0 62.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:81.00 stddev:11.20 (13.8%) stddev-sans-first:11.66 16:08:52 INFO - PROCESS | 3982 | Values: 79.0 80.0 85.0 79.0 80.0 85.0 82.0 79.0 116.0 85.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:124.50 stddev:6.40 (5.1%) stddev-sans-first:6.59 16:08:52 INFO - PROCESS | 3982 | Values: 122.0 122.0 123.0 138.0 124.0 127.0 123.0 125.0 138.0 121.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:161.50 stddev:3.46 (2.1%) stddev-sans-first:3.38 16:08:52 INFO - PROCESS | 3982 | Values: 165.0 160.0 162.0 161.0 157.0 164.0 159.0 167.0 161.0 156.0 16:08:52 INFO - PROCESS | 3982 | 16:08:52 INFO - PROCESS | 3982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:63.50 stddev:3.63 (5.7%) stddev-sans-first:3.81 16:08:52 INFO - PROCESS | 3982 | Values: 63.0 74.0 66.0 62.0 64.0 65.0 63.0 63.0 62.0 62.0 16:08:52 INFO - PROCESS | 3982 | -------- Summary: end -------- 16:08:52 INFO - PROCESS | 3982 | 16:08:53 INFO - TEST-INFO | 3982: exit 0 16:08:53 INFO - TEST-OK | kraken | took 61487ms 16:08:53 INFO - SUITE-END | took 1176s 16:08:53 INFO - Completed test suite (00:19:36) 16:08:53 INFO - javascript benchmark 16:08:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.3010822564925, 3120.0795765249236, 3126.1090589182086, 3155.7475401967845, 3180.0618817448863, 5284.841454756353, 5360.169173778066, 5383.81156659517, 5444.10172321567, 5469.097598896177, 5383.353997520293, 5397.055515284829, 5412.052890744124, 5429.864253393667, 5462.508374246327, 4857.222844344906, 4903.730294833783, 4922.975385123069, 4923.950760492403, 5107.872303192431, 4999.350084489011, 5014.974925125388, 5042.747862606873, 5071.644984851062, 5076.517730815585, 4796.0, 4821.083994041121, 4839.588634966032, 4849.3453383793085, 4901.901961960776, 4803.687760295605, 4827.348307978448, 4830.372051633265, 4884.072026315024, 4938.234573641092, 4847.951520484785, 4871.171900776876, 4881.292212629184, 4884.120858245515, 4899.975500122477, 5205.27126202332, 5368.141097424394, 5414.458554144593, 5432.674039557639, 5475.397706252309, 5834.6790926499025, 5855.151003104568, 5856.443637854397, 5875.500582450497, 5878.206442130343, 5661.2357331760095, 5890.558208134365, 5931.970340148272, 5932.021216499273, 5954.225950626391, 5372.731363431852, 5433.8369848904595, 5447.972760136214, 5514.476124768141, 5524.226608274845, 5984.431479009487, 5990.820275391745, 5991.940080599225, 5994.07091900756, 5994.700264986734, 5581.441855814467, 5629.887402252014, 5725.51333136688, 5741.110127930177, 5741.311042674905, 5747.08046712524, 5791.420857914175, 5797.043487824461, 5799.536037117021, 5802.0426629605645, 5717.142428635738, 5762.88474230513, 5774.971125144431, 5778.8266352009505, 5787.623804452697, 5638.915416268759, 5648.604597678123, 5687.60186786921, 5761.596688231867, 5769.826905192851, 4367.519572846984, 4368.322909948963, 4400.965773043332, 4408.669349798778, 4410.470743510799, 3873.8063096845044, 3888.5333759948985, 3888.766673999569, 3895.104126051024, 3927.66614837742, 5268.578513718893, 5310.7610157543, 5352.411234764173, 5405.00007498614, 5428.2671839301975, 5793.623414478046, 5843.444872737168, 5850.4149585042005, 5853.7365818538265, 5905.793297234576, 5446.564274858002, 5447.918281225785, 5449.427810079964, 5486.3690675572025, 5510.173473978935, 4787.736674482867, 4830.782614782343, 4882.829100981448, 4911.140550403665, 4970.15507363749, 6028.0, 6030.005049166926, 6034.064719968412, 6038.969805151034, 6067.605605635619, 5852.912206316993, 5867.530597552271, 5867.589268751147, 5868.413158684098, 5873.882522349443, 5748.0, 5809.244798176379, 5825.79609713658, 5889.5582831286965, 5997.280326360865, 5682.914756278742, 5689.203511508309, 5698.287714035746, 5702.40124786908, 5707.68607726579, 5746.2529871116485, 5788.334341550775, 5798.82603521895, 5799.652020878761, 5802.361740208658, 5528.44715528444, 5591.27313449249, 5593.4126916674795, 5655.688937108499, 5683.261176047087, 5666.03677374839, 5677.375488696158, 5677.432256774289, 5678.886422271614, 5685.232493613309, 5320.28176196224, 5563.054280772198, 5573.693446860461, 5573.77704891816, 5574.581906356958, 5630.8029218977135, 5665.575081868794, 5701.857453563694, 5785.82642520725, 5788.9131663024245, 5591.692456914909, 5596.468335508121, 5599.244102046172, 5609.1305847593685, 5612.803551875665], "value": 5326.979427898974, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.9011358182692, 634.1059106659608, 635.2599221906479, 636.5067073018413, 646.6411141816291, 40714.79642601786, 42517.99999999996, 42618.360724589154, 45949.31076033862, 46577.53422465774, 43516.564834351644, 44066.559334406644, 44079.0, 44088.55911440885, 44091.0, 8734.606942687578, 9000.549972501381, 9021.548922553879, 9022.774430639221, 9028.638854445815, 8829.161229683203, 8925.59834807432, 8927.41971771836, 8928.15182557656, 8930.598123084477, 8941.195292423681, 8988.595513201888, 8991.550422478882, 8997.05530919254, 8999.460032398045, 8965.417247878899, 8972.685955991574, 8992.550372481415, 9005.864912026293, 9006.59470323837, 8798.0, 8868.37921345506, 8869.911300886974, 8871.423357481743, 8873.157050080232, 42300.788496057634, 42758.358624620654, 42788.35817462741, 42795.35806962898, 42800.143997119885, 3458.602260739997, 3532.1699400640828, 3619.402798538238, 3888.9027774305587, 4144.3990621360035, 31943.84028079868, 32332.515012274835, 32607.836960815283, 32636.510452343235, 33416.0, 4185.183889141618, 4305.289627211507, 4374.365716971021, 4384.583464570893, 4433.290673492258, 348701.0, 350334.0, 350995.0, 351187.2440637806, 352187.0, 57395.713021434625, 57397.0, 57584.424155758745, 57876.13185802216, 58023.709881450326, 60050.09924851131, 60671.089933651034, 60734.0, 60746.69626651838, 60906.0, 17122.82877171237, 17364.826351736574, 17372.3051077958, 17379.826201738073, 17389.565260868327, 13531.120477168952, 13633.727325453436, 13635.38640761168, 13640.113392629448, 13644.658883527793, 15255.542333730005, 16919.661606767797, 16936.052353979205, 16948.57628559296, 16957.321707131578, 5071.695698258116, 5609.971950140223, 5709.8287051388525, 5753.712314384264, 5922.940770592325, 940.7459985803795, 972.7762614598682, 1007.9244056695777, 1011.9494025298706, 1171.1086595148774, 29127.27181820429, 31713.682863171074, 31826.363472730878, 31870.0, 32042.359152816814, 1066.3921564708196, 1142.8628564572305, 1148.8276758486295, 1151.2746969409218, 1228.1280290993316, 16082.0, 16790.916045419694, 16796.916015419843, 16907.830921690627, 16936.745948810778, 493633.0, 497008.0, 503406.0, 503730.0, 504245.0, 59237.0, 59248.70375648094, 59307.0, 59349.40650593525, 59407.0, 1249.237964841446, 1338.598420473854, 1372.7597670407838, 1379.8827099696437, 1408.4084684432562, 32269.51595726019, 33566.66433335733, 33859.492107617916, 33933.0, 34344.65655343414, 50780.0, 51185.48814511807, 51327.0, 51333.0, 51347.74326128345, 408654.0, 413221.0, 414855.0, 415341.0, 415431.0, 22978.655320169877, 25997.480050399263, 26111.0, 27716.58425123665, 27774.861125694242, 48016.0, 48857.5114248853, 49221.261681074095, 49309.753451232515, 50271.0, 40151.79924100361, 41250.21687941504, 41770.0, 42209.788951056275, 42438.0, 40514.79742601386, 41549.58450415457, 41635.375469367376, 42261.0, 42385.78807105945, 40984.79507602443, 41367.07012833776, 41852.58147418609, 41855.58144418517, 41967.16065678608, 33585.83207083949, 36355.818220908724, 37204.0, 37495.81252093722, 37511.0, 28210.576841346985, 28379.57430638501, 28428.43143137167, 28474.295356290426, 30385.392292154476], "value": 20023.001410094294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.41912003314985, 386.79693161090427, 390.5411141908258, 395.52065604208576, 408.06756561257515, 15130.470433534801, 17216.39742609009, 18326.45020649381, 19779.901100494495, 19817.306394276202, 15321.157336346496, 15814.762778558303, 18970.905145474288, 19215.903920480414, 19249.90375048123, 5452.91820622692, 5685.445775943497, 5704.429557044283, 5712.200291959129, 5718.542516598662, 5544.972275138639, 5685.431456854323, 5699.401562835905, 5718.256626638552, 5732.799352022659, 4017.919641607166, 6022.759089636409, 6299.6850157491945, 6304.716287767039, 6410.358964103576, 6330.525210609223, 6357.904631430509, 6360.968195158995, 6390.201224846895, 6431.421172094534, 5507.7246137692955, 5624.971875140639, 5649.067903795869, 5657.6322539035245, 5667.716614169275, 20200.898995505075, 21808.454788630253, 22210.555788884132, 22541.887290563605, 22551.548969020692, 2265.920692775745, 2792.720727927191, 2862.541993281065, 2880.927976800576, 2939.2504911247497, 9681.467519286436, 10081.89918100817, 10113.045074181206, 10319.007487828521, 10323.741906452398, 1850.4541160357726, 1873.325602782997, 1931.5267759399037, 1940.9126589303514, 2085.18677715691, 84255.0, 86163.0, 86286.0, 86962.99999999937, 87324.0, 9813.460259685786, 9987.151092157103, 10020.448875311928, 10262.0, 10424.0, 13789.724205515833, 13802.65493362674, 14355.497557585435, 14482.275886205647, 14860.554183374516, 7253.419726421874, 7294.489385742947, 7608.277213664693, 8119.634616442274, 8283.8343233135, 8059.073206581201, 8161.632726527202, 8259.545724985184, 8301.086880443147, 8371.539565323965, 4460.8661740147845, 4578.862634120982, 4582.381378513858, 4597.310403439511, 4730.668853180245, 1771.663383957044, 1784.5360206346184, 1791.2029147029448, 1852.6109517001312, 1864.3567969050655, 448.7307615430781, 924.3021518753433, 974.3617930255693, 1023.6928921323573, 1072.4155335342257, 11129.777404451866, 11789.941050294865, 11838.348890810916, 11877.76244475123, 12051.939740301243, 451.9231730605739, 461.893764434182, 462.1819379697983, 472.0959362820161, 495.8066354121895, 6205.410486003822, 6298.653574053471, 6355.3009168991375, 6364.968175159094, 6411.551191416649, 490324.0, 507352.0, 508115.0, 510157.0, 529115.0, 96893.0, 97142.0, 99512.50243748735, 99840.0, 100067.99932000732, 464.6027646362379, 465.7042777836098, 470.9481956984729, 472.4826315184867, 472.65968502678015, 10765.0, 10905.34567925927, 11101.444927753742, 11244.55021799119, 11274.943625281821, 36867.26265474729, 37923.43114853224, 38880.0, 40707.59292407156, 42632.0, 50679.74660126676, 51424.74287628538, 57592.424075760384, 58878.705606471696, 59976.70011649914, 7335.816604584928, 7690.00029996097, 7716.961415192888, 8169.468984515987, 8685.478871267744, 8126.593670316579, 9482.336236463658, 10982.341059536453, 12096.0, 12569.857535616096, 11787.233829801036, 11813.940930295637, 11973.940130299292, 12031.939840301093, 12207.63377098653, 7482.551046936984, 8659.701044843325, 11767.176297659424, 11931.284122952651, 12046.457909394274, 11880.881191188324, 12004.339761313211, 12103.273803571461, 12139.453724582238, 12330.445129969352, 8357.164283571594, 8457.281131103678, 8593.226609605166, 8699.43453675509, 8795.384323097322, 11663.066954643438, 11783.587574434854, 11930.880691193324, 11931.880681193425, 12021.098417618889], "value": 8345.851954733836, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.6944002879343, 1460.7662773956165, 1497.3785878860272, 1616.2080580515544, 1732.8440440360364, 8580.39937204396, 8994.730158095259, 9046.59290331935, 9268.934072581638, 9327.76680582984, 8844.823103537925, 8986.370954033204, 9079.455232686043, 9095.363324567266, 9127.771805704844, 2411.819113566485, 2444.963325550118, 2446.0460420436043, 2457.1891275879007, 2462.064415522099, 2415.3818537208012, 2482.6027835546315, 2488.2908371114236, 2488.9004439822384, 2490.5018996200743, 2351.27110595715, 2356.231808141058, 2400.3797436730224, 2403.2910291463972, 2405.867677277749, 2290.3129061281547, 2298.069281940817, 2304.5966955782756, 2310.1336998625598, 2337.801286890616, 2294.1855641247284, 2353.7646235376324, 2360.44529535559, 2363.905443782264, 2377.9286621401384, 8033.678652853879, 8254.133316001853, 9253.259739220914, 9320.114589114024, 9331.16019558243, 5309.150535914274, 5486.1222204447095, 5486.561075114021, 5576.302962129734, 5578.693171875545, 3334.299797042624, 3385.5260263563123, 3450.292689998544, 3452.085197422685, 3475.391806433864, 1010.1413798271484, 1034.8344264917653, 1165.6153469355093, 1191.74377508836, 1205.6624145239348, 12922.870771292355, 12949.223046617233, 12950.935245323808, 12953.54662586805, 12963.287019214034, 4893.266010098514, 4935.358403407534, 5038.974805125951, 5039.294498770175, 5055.570276526537, 10741.83887241692, 10780.407077610807, 10781.407022613676, 10807.189460790314, 10809.67570972872, 5553.0, 5563.582731295169, 5571.860703482445, 5573.498385145356, 5580.637258578179, 4890.339804126468, 5092.083424983539, 5160.819371321985, 5231.424543300234, 5244.475552444725, 4124.793760312033, 4127.153933443637, 4176.624103830609, 4182.895427614273, 4193.308104162779, 1308.2150709574369, 1327.8529884174568, 1339.7923321885123, 1342.02703040295, 1343.2746316988716, 800.8718605023168, 932.6083045121065, 936.2603543200795, 947.393668052447, 960.865478832964, 6190.783322583688, 6351.428371446592, 6369.904451433232, 6458.354164583504, 6496.707648155844, 446.2191165460444, 456.9520200378979, 458.2278860120707, 466.65001249063204, 467.3387157172631, 4103.466549348565, 4179.770112643773, 4197.244495990751, 4308.4399028126145, 4362.498312694017, 10101.69694909154, 10883.619073332395, 11102.333859968267, 11453.59912403062, 11473.885261147281, 11359.204855660024, 11641.185117041725, 11697.532098716127, 11764.647060588197, 11765.647030589096, 8527.360447966428, 8650.351223658377, 8685.435446695945, 8698.0, 8699.565021748887, 6334.461570766352, 6404.967975160094, 6442.420182183626, 6462.483001359786, 6482.384173503509, 10505.579776808841, 10599.682009539727, 10739.731506712082, 10768.407737574194, 10806.973337532922, 10788.190885683449, 10790.352578845295, 10855.294405863593, 11030.06244469229, 11145.49845256949, 7708.768736937901, 7790.4936179150445, 7791.0650721913735, 7851.411144164096, 7870.763877083697, 8760.080191579793, 8942.195202431552, 8942.195202431813, 8950.776230594287, 8952.28381729447, 7760.68957241704, 7767.300942915164, 7907.960460197893, 8080.515169089874, 8222.547759873265, 7776.88334674969, 7823.765287041398, 7858.842823143391, 7861.764147075597, 7898.763037108896, 7442.702291908479, 7466.328030477283, 7485.1017877855, 7493.100827900686, 7533.510321829063, 5359.571234301168, 5368.275282836923, 5501.724913754216, 5565.387807341108, 5585.190147428519, 5353.4646535346155, 5481.205225242238, 5558.555315574825, 5643.492085712305, 5733.0253856845275], "value": 4722.5950839788275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.0474077545506, 329.7790480378146, 332.7504371721209, 343.69411224010616, 346.66027293252614, 5279.973600131999, 5337.14605663094, 5385.326834145727, 5393.649412788166, 5416.402161362398, 5226.040234911908, 5266.736663166846, 5289.841304760854, 5307.363116426025, 5313.495217954288, 3943.5662077171487, 4007.378856277267, 4203.117345357478, 4211.284081706102, 4214.325707886739, 4062.6546743526837, 4105.9178816423655, 4150.754773567933, 4171.0823618803815, 4188.120494696117, 3968.630822366279, 4156.459660244164, 4159.2721273777115, 4161.2093702196635, 4184.539700632928, 4004.8398064077546, 4098.979505102485, 4126.360414135784, 4126.587341265879, 4174.958250417502, 3632.7820330780237, 3648.21563363878, 3674.356987527172, 3676.8713095041544, 3682.576503702082, 5119.411267704225, 5173.58611311098, 5186.766595503212, 5194.558462530689, 5223.608229382773, 4438.1567502174485, 4683.929741053887, 4689.648276379251, 4762.952370476286, 4763.928541071887, 5429.972850135763, 5454.781808727646, 5469.699166545838, 5470.7264636768405, 5525.198846167324, 3947.842086316544, 3959.5050618672667, 3988.3618621020496, 3993.8602148924647, 4016.6385025347568, 5493.423190565013, 5690.5447564194765, 5708.743106560215, 5741.425857414267, 5756.597038207327, 4024.879253622396, 4046.5346485153996, 4048.129652124748, 4051.845224111114, 4052.7163098583405, 4078.084169818594, 4082.0, 4088.836446542105, 4095.4471146394853, 4120.526139493936, 4557.908841823145, 4642.0, 4691.882702932454, 4692.671512994058, 4698.788554515055, 5461.924468701868, 5502.312210973629, 5524.613277070566, 5557.249771280825, 5561.58288128392, 1397.1407584335561, 1401.9859801401856, 1414.2575148047151, 1415.7310111079069, 1417.1780367386891, 897.264243320484, 911.6399022386121, 939.1641439119189, 964.7298756348234, 968.2641192693603, 5267.631265811357, 5300.628955973045, 5369.382521010056, 5409.918851217235, 5414.864628384322, 5570.192322113271, 5584.329880414376, 5593.0, 5628.690422026828, 5638.210650508932, 5077.695338279715, 5252.185911183773, 5288.338957630296, 5304.708241046702, 5311.495407936317, 2970.50986587215, 2975.613170287849, 3009.0822299198517, 3045.710657487535, 3053.6793636668276, 5565.888682226333, 5811.360750317462, 5822.184894114827, 5845.7369418376275, 5850.765969361263, 5793.304803423532, 5829.417058294137, 5834.416558344216, 5836.212111364996, 5856.677882716406, 5853.619514731614, 5871.4715675589405, 5872.236609240771, 5887.646741195542, 5907.822765317047, 5547.317837607381, 5669.433056694297, 5672.404397538199, 5677.858053548694, 5752.577216556142, 5523.475269849358, 5599.552035837205, 5599.664020158803, 5623.409541998031, 5635.8591035224445, 5668.234788303526, 5776.42235776419, 5858.740370820294, 5892.616979896293, 5935.732892019783, 4070.6973768393827, 4077.653399461079, 4104.302268614283, 4104.445899803488, 4104.91790164201, 5706.942930570641, 5749.8562535936935, 5754.108113242454, 5808.448197421239, 5822.0, 5439.945600543944, 5634.295713035871, 5651.124075768263, 5667.263255776723, 5669.801556945487, 5520.751566179451, 5526.392096869422, 5546.334439867242, 5548.916766248591, 5554.833354999357, 5286.603504737237, 5320.57435405159, 5481.260029895913, 5483.396826349178, 5518.392976772632, 5584.581156413204, 5601.551875850004, 5629.521490673174, 5643.1535269709875, 5672.31932168126, 5392.5416339611575, 5408.621396502364, 5449.945500544944, 5466.508014278734, 5526.50261476469], "value": 4231.528961837656, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.1716363491063, 785.3821128475339, 815.6900377856416, 822.2312138150826, 853.7268074216254, 13224.7355052899, 14196.36116374763, 14203.928980355096, 14220.146791192536, 14222.857771422296, 15007.324670389831, 15436.151011694352, 15935.681286374265, 15977.84022159778, 16680.74978875318, 682.9726810927582, 800.0599295827379, 809.8623234050226, 815.8409110223508, 821.8643923752574, 810.7608255564593, 818.304441224957, 818.9262966333058, 819.3199644295221, 819.418213068719, 845.2181731898, 847.1401527449643, 848.321342925657, 848.6350869126273, 849.1254008371387, 711.6939715922151, 832.0597724571256, 840.6679361652144, 843.5655637346771, 856.9442986205939, 621.9471344935686, 625.5558553427072, 625.871696302257, 626.067159931703, 626.5457543281085, 14648.633784155374, 14672.119672819665, 14700.55898323052, 14708.779368309484, 14715.63210919725, 13106.0, 13277.0, 13346.599602011955, 13666.18002919828, 13784.517541886085, 12869.549565765154, 12969.286689232089, 13049.478020879153, 13268.0, 13295.73408531824, 4259.063006138645, 4306.224879521685, 4362.663899255595, 4394.8242070317465, 4482.394876691637, 73498.0, 74250.25749742487, 74452.39416620447, 74948.25051749522, 75012.62493687551, 11144.721381965515, 11219.495122719498, 11317.886821131684, 11321.660350189508, 11462.885371146182, 20569.280075197297, 20620.27829025977, 20636.58726825485, 20646.17415303401, 20651.17395304171, 8435.704750333709, 8478.406511544255, 8699.217070463688, 8735.868961965576, 8974.37179397449, 4671.836485723052, 4790.688605240648, 4850.248211527219, 4928.211486162197, 5001.599872010231, 3268.2156282492033, 3340.8329583520726, 3341.114604629775, 3341.8329083545727, 3362.8486718097743, 593.0155941137691, 600.2137200267663, 604.7611193578512, 614.8339948213957, 619.5817822969477, 544.5262621519304, 569.5785119011902, 578.8842231553706, 592.2774215457135, 703.6341102626708, 8748.212660860552, 9219.769505762275, 9295.535223238947, 9413.105754953269, 9535.52322383878, 643.6685107169811, 683.4703105093593, 685.020162294017, 685.8662560800695, 686.5455994818544, 4112.198121366365, 4167.541570427251, 4256.59562341577, 4281.154593394346, 4362.978185109054, 69071.65464172648, 95414.13757587284, 105583.47208264063, 107267.46366268118, 107430.46284768527, 90934.54532727294, 91347.0, 91727.0, 92484.53757731302, 92911.49578693614, 81138.0, 81419.18580814234, 82090.0, 82154.0, 83166.58416707878, 8604.870926935975, 8709.303255739653, 8809.515476648847, 8823.955880220557, 9000.50497222659, 42441.575584244994, 45511.77244113758, 46372.53627463682, 52012.0, 52371.0, 64244.67877660582, 70198.64900675464, 70470.29529704842, 71944.28055719585, 74152.0, 13218.801717974045, 13235.40440680191, 13475.326233688473, 13778.931105344409, 13907.443702252202, 14642.780358294422, 14847.0, 14872.25092177211, 14899.64534088864, 15059.623509412353, 13710.794338084737, 14120.858791412365, 14315.785263221269, 14400.567982960529, 14716.043457175248, 14220.431182752574, 14363.640908977359, 14416.495422660157, 14616.780748288571, 15175.772363414779, 13595.388207530883, 13684.452621895012, 13899.096558723651, 14132.78800817968, 14243.430262789372, 11445.656630301104, 11484.88515114838, 11704.94147529257, 11792.23350482216, 11866.762664746484, 11743.177977541833, 11852.525898963944, 11862.881371186522, 11982.880171198176, 12010.63968080959], "value": 7432.270400461146, "unit": "score"}], "name": "dromaeo_css", "value": 7137.6283903213625, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 109.0, 108.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 128.0, 197.0, 127.0, 128.0, 158.0, 129.0, 163.0, 129.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 160.0, 161.0, 160.0, 162.0, 161.0, 169.0, 161.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 109.0, 75.0, 75.0, 76.0, 76.0, 122.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 107.0, 85.0, 85.0, 85.0, 120.0, 85.0, 85.0, 122.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 53.0, 53.0, 54.0, 53.0, 55.0, 53.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 62.0, 62.0, 61.0, 60.0, 62.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 85.0, 79.0, 80.0, 85.0, 82.0, 79.0, 116.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 123.0, 138.0, 124.0, 127.0, 123.0, 125.0, 138.0, 121.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 160.0, 162.0, 161.0, 157.0, 164.0, 159.0, 167.0, 161.0, 156.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 66.0, 62.0, 64.0, 65.0, 63.0, 63.0, 62.0, 62.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1501.2, "alertThreshold": 2.0}]} 16:08:53 INFO - Return code: 0 16:08:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:08:53 ERROR - # TBPL SUCCESS # 16:08:53 INFO - Running post-action listener: _resource_record_post_action 16:08:53 INFO - ##### 16:08:53 INFO - ##### Finished run-tests step (success) 16:08:53 INFO - ##### 16:08:53 INFO - Running post-run listener: _resource_record_post_run 16:08:53 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 541544448; Read time: 180; Write time: 486444 16:08:53 INFO - TinderboxPrint: CPU usage
13.1% 16:08:53 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 180 16:08:53 INFO - TinderboxPrint: I/O write bytes / time
541,544,448 / 486,444 16:08:53 INFO - TinderboxPrint: CPU idle
8,273.6 (86.8%) 16:08:53 INFO - TinderboxPrint: CPU user
1,207.1 (12.7%) 16:08:53 INFO - TinderboxPrint: Swap in / out
0 / 0 16:08:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 248320000; Read time: 0; Write time: 242416 16:08:53 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292638720; Read time: 180; Write time: 244008 16:08:54 INFO - Running post-run listener: _upload_blobber_files 16:08:54 INFO - Blob upload gear active. 16:08:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:54 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 16:08:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:08:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:08:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:58 INFO - (blobuploader) - INFO - Done attempting. 16:08:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:08:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:08:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:59 INFO - (blobuploader) - INFO - Done attempting. 16:08:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:08:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:09:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:00 INFO - (blobuploader) - INFO - Done attempting. 16:09:00 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:00 INFO - Return code: 0 16:09:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0ccbeac0859ca94763c573eb81c5c0f428cc62393abd5391b625458fb9a88f8cd98b5a2eeaf3aa6d227e8578e6ca0735a909cb583a1564ed9e1f236970da260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2726e6672ac19379b3ede253ba28a9e94a9dec075a5a370ef99d9c4af958ba3a0dea7cc2aaa5b359a73dda1ba1273b4c49c4db036e17c4a03c28d0a01d01709", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05917e0399ee65c6cf300b20c92ae32ceef2c51c167030cf5411b08aea7332f61ab6af1780bf024b162a63628c1b1006707f55af964ac093e9f9c218f0eef5f"} 16:09:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:00 INFO - Contents: 16:09:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0ccbeac0859ca94763c573eb81c5c0f428cc62393abd5391b625458fb9a88f8cd98b5a2eeaf3aa6d227e8578e6ca0735a909cb583a1564ed9e1f236970da260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2726e6672ac19379b3ede253ba28a9e94a9dec075a5a370ef99d9c4af958ba3a0dea7cc2aaa5b359a73dda1ba1273b4c49c4db036e17c4a03c28d0a01d01709", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05917e0399ee65c6cf300b20c92ae32ceef2c51c167030cf5411b08aea7332f61ab6af1780bf024b162a63628c1b1006707f55af964ac093e9f9c218f0eef5f"} 16:09:00 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:00 INFO - Copying logs to upload dir... 16:09:00 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.086957 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-09 16:09:00.377045) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 16:09:00.381075) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0ccbeac0859ca94763c573eb81c5c0f428cc62393abd5391b625458fb9a88f8cd98b5a2eeaf3aa6d227e8578e6ca0735a909cb583a1564ed9e1f236970da260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2726e6672ac19379b3ede253ba28a9e94a9dec075a5a370ef99d9c4af958ba3a0dea7cc2aaa5b359a73dda1ba1273b4c49c4db036e17c4a03c28d0a01d01709", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05917e0399ee65c6cf300b20c92ae32ceef2c51c167030cf5411b08aea7332f61ab6af1780bf024b162a63628c1b1006707f55af964ac093e9f9c218f0eef5f"} build_url:https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/NDz9q9AKTmi_YQLuWMv6Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0ccbeac0859ca94763c573eb81c5c0f428cc62393abd5391b625458fb9a88f8cd98b5a2eeaf3aa6d227e8578e6ca0735a909cb583a1564ed9e1f236970da260", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2726e6672ac19379b3ede253ba28a9e94a9dec075a5a370ef99d9c4af958ba3a0dea7cc2aaa5b359a73dda1ba1273b4c49c4db036e17c4a03c28d0a01d01709", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e05917e0399ee65c6cf300b20c92ae32ceef2c51c167030cf5411b08aea7332f61ab6af1780bf024b162a63628c1b1006707f55af964ac093e9f9c218f0eef5f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 16:09:00.406833) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 16:09:00.407194) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1465512497.517007-1235883984 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 16:09:00.493527) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 16:09:00.493893) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 16:09:00.494172) ========= ========= Total master_lag: 0.19 =========