builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1470075854.54 results: success (0) buildid: 20160801070053 builduid: eaf10adbc6074edb9e3b1e3d32dab73b revision: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.541255) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.541657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.541945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.763078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.763403) ========= 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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 11:24:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.6M=0s 2016-08-01 11:24:15 (45.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084940 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.876247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.876523) ========= 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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017791 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.928289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.928616) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 --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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 11:24:15,648 truncating revision to first 12 chars 2016-08-01 11:24:15,649 Setting DEBUG logging. 2016-08-01 11:24:15,649 attempt 1/10 2016-08-01 11:24:15,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bad9d4f5bf7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 11:24:15,955 unpacking tar archive at: mozilla-beta-bad9d4f5bf7e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.668832 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.614648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.614957) ========= script_repo_revision: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.615331) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.615594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.626373) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-01 11:24:15.626658) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:24:16 INFO - MultiFileLogger online at 20160801 11:24:16 in /builds/slave/test 11:24:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:24:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:24:16 INFO - {'append_to_log': False, 11:24:16 INFO - 'base_work_dir': '/builds/slave/test', 11:24:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:24:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:24:16 INFO - 'branch': 'Mozilla-Beta', 11:24:16 INFO - 'buildbot_json_path': 'buildprops.json', 11:24:16 INFO - 'config_files': ('talos/linux_config.py',), 11:24:16 INFO - 'default_actions': ('clobber', 11:24:16 INFO - 'read-buildbot-config', 11:24:16 INFO - 'download-and-extract', 11:24:16 INFO - 'populate-webroot', 11:24:16 INFO - 'create-virtualenv', 11:24:16 INFO - 'install', 11:24:16 INFO - 'run-tests'), 11:24:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:24:16 INFO - 'download_minidump_stackwalk': True, 11:24:16 INFO - 'download_symbols': 'ondemand', 11:24:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:24:16 INFO - 'tooltool.py': '/tools/tooltool.py', 11:24:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:24:16 INFO - '/tools/misc-python/virtualenv.py')}, 11:24:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:24:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:24:16 INFO - 'installer_path': 'installer.exe', 11:24:16 INFO - 'log_level': 'info', 11:24:16 INFO - 'log_name': 'talos', 11:24:16 INFO - 'log_to_console': True, 11:24:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:24:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:24:16 INFO - 'opt_config_files': (), 11:24:16 INFO - 'pip_index': False, 11:24:16 INFO - 'sps_profile': False, 11:24:16 INFO - 'sps_profile_interval': 0, 11:24:16 INFO - 'suite': 'dromaeojs', 11:24:16 INFO - 'system_bits': '32', 11:24:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:24:16 INFO - 'title': 'talos-linux64-ix-024', 11:24:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:24:16 INFO - 'use_talos_json': True, 11:24:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:24:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:24:16 INFO - 'work_dir': 'build'} 11:24:16 INFO - ##### 11:24:16 INFO - ##### Running clobber step. 11:24:16 INFO - ##### 11:24:16 INFO - Running pre-action listener: _resource_record_pre_action 11:24:16 INFO - Running main action method: clobber 11:24:16 INFO - rmtree: /builds/slave/test/build 11:24:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:24:16 INFO - Running post-action listener: _resource_record_post_action 11:24:16 INFO - ##### 11:24:16 INFO - ##### Finished clobber step (success) 11:24:16 INFO - ##### 11:24:16 INFO - ##### 11:24:16 INFO - ##### Running read-buildbot-config step. 11:24:16 INFO - ##### 11:24:16 INFO - Running pre-action listener: _resource_record_pre_action 11:24:16 INFO - Running main action method: read_buildbot_config 11:24:16 INFO - Using buildbot properties: 11:24:16 INFO - { 11:24:16 INFO - "project": "", 11:24:16 INFO - "product": "firefox", 11:24:16 INFO - "script_repo_revision": "production", 11:24:16 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:24:16 INFO - "repository": "", 11:24:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 11:24:16 INFO - "buildid": "20160801070053", 11:24:16 INFO - "pgo_build": "True", 11:24:16 INFO - "basedir": "/builds/slave/test", 11:24:16 INFO - "buildnumber": 181, 11:24:16 INFO - "slavename": "talos-linux64-ix-024", 11:24:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:24:16 INFO - "platform": "ubuntu64_hw", 11:24:16 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:24:16 INFO - "branch": "mozilla-beta", 11:24:16 INFO - "repo_path": "releases/mozilla-beta", 11:24:16 INFO - "revision": "bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2", 11:24:16 INFO - "stage_platform": "linux64-pgo", 11:24:16 INFO - "builduid": "eaf10adbc6074edb9e3b1e3d32dab73b", 11:24:16 INFO - "slavebuilddir": "test" 11:24:16 INFO - } 11:24:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 11:24:16 INFO - Running post-action listener: _resource_record_post_action 11:24:16 INFO - ##### 11:24:16 INFO - ##### Finished read-buildbot-config step (success) 11:24:16 INFO - ##### 11:24:16 INFO - ##### 11:24:16 INFO - ##### Running download-and-extract step. 11:24:16 INFO - ##### 11:24:16 INFO - Running pre-action listener: _resource_record_pre_action 11:24:16 INFO - Running main action method: download_and_extract 11:24:16 INFO - mkdir: /builds/slave/test/build/tests 11:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:16 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:24:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:24:21 INFO - Downloaded 1327 bytes. 11:24:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:21 INFO - Using the following test package requirements: 11:24:21 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 11:24:21 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:24:21 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 11:24:21 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'jsshell-linux-x86_64.zip'], 11:24:21 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:24:21 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 11:24:21 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 11:24:21 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 11:24:21 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:24:21 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:21 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:24:21 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:24: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')]} 11:24:21 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:24:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:30 INFO - Downloaded 21968684 bytes. 11:24:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:32 INFO - Return code: 0 11:24:32 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:32 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:24:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:33 INFO - Downloaded 21968684 bytes. 11:24:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:35 INFO - Return code: 0 11:24:35 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')]} 11:24:35 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:24:36 INFO - Downloaded 10833369 bytes. 11:24:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:24:36 INFO - Return code: 0 11:24: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')]} 11:24:36 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:24:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:24:39 INFO - Downloaded 55264299 bytes. 11:24:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:39 INFO - mkdir: /builds/slave/test/properties 11:24:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:24:39 INFO - Writing to file /builds/slave/test/properties/build_url 11:24:39 INFO - Contents: 11:24:39 INFO - build_url:https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:40 INFO - Running post-action listener: _resource_record_post_action 11:24:40 INFO - Running post-action listener: set_extra_try_arguments 11:24:40 INFO - ##### 11:24:40 INFO - ##### Finished download-and-extract step (success) 11:24:40 INFO - ##### 11:24:40 INFO - ##### 11:24:40 INFO - ##### Running populate-webroot step. 11:24:40 INFO - ##### 11:24:40 INFO - Running pre-action listener: _resource_record_pre_action 11:24:40 INFO - Running main action method: populate_webroot 11:24:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:24:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:24:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:24:40 INFO - '--tppagecycles', 11:24:40 INFO - '7'], 11:24:40 INFO - 'tests': ['tsvgm']}}, 11:24:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:24:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:24:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:24:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:24:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['damp', 'tps']}, 11:24:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'tests': ['damp', 'tps']}, 11:24:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['dromaeo_dom']}, 11:24:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:24:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'other-e10s': {'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['a11yr', 11:24:40 INFO - 'ts_paint', 11:24:40 INFO - 'tpaint', 11:24:40 INFO - 'sessionrestore', 11:24:40 INFO - 'sessionrestore_no_auto_restore', 11:24:40 INFO - 'tabpaint']}, 11:24:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['tsvgx', 11:24:40 INFO - 'tsvgr_opacity', 11:24:40 INFO - 'tart', 11:24:40 INFO - 'tscrollx', 11:24:40 INFO - 'cart']}, 11:24:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:24:40 INFO - 'tsvgr_opacity', 11:24:40 INFO - 'tart', 11:24:40 INFO - 'tscrollx', 11:24:40 INFO - 'cart']}, 11:24:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'talos_options': ['--disable-e10s'], 11:24:40 INFO - 'tests': ['tp5o']}, 11:24:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'tests': ['tp5o']}, 11:24:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'talos_options': ['--disable-e10s', 11:24:40 INFO - '--xperf_path', 11:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:40 INFO - 'tests': ['tp5n']}, 11:24:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:40 INFO - 'talos_options': ['--xperf_path', 11:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:40 INFO - 'tests': ['tp5n']}}, 11:24:40 INFO - 'talos.zip': {'path': '', 11:24:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:24:40 INFO - Running post-action listener: _resource_record_post_action 11:24:40 INFO - ##### 11:24:40 INFO - ##### Finished populate-webroot step (success) 11:24:40 INFO - ##### 11:24:40 INFO - ##### 11:24:40 INFO - ##### Running create-virtualenv step. 11:24:40 INFO - ##### 11:24:40 INFO - Running pre-action listener: _resource_record_pre_action 11:24:40 INFO - Running main action method: create_virtualenv 11:24:40 INFO - Creating virtualenv /builds/slave/test/build/venv 11:24:40 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 11:24:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:24:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:24:40 INFO - Using real prefix '/usr' 11:24:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:24:40 INFO - Installing distribute.............................................................................................................................................................................................done. 11:24:42 INFO - Installing pip.................done. 11:24:42 INFO - Return code: 0 11:24:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:42 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:42 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 11:24:42 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 11:24:42 INFO - Using env: {'DISPLAY': ':0', 11:24:42 INFO - 'HOME': '/home/cltbld', 11:24:42 INFO - 'LANG': 'en_US.UTF-8', 11:24:42 INFO - 'LANGUAGE': 'en_US:en', 11:24:42 INFO - 'LOGNAME': 'cltbld', 11:24:42 INFO - 'MAIL': '/var/mail/cltbld', 11:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:42 INFO - 'MOZ_NO_REMOTE': '1', 11:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:42 INFO - 'NO_EM_RESTART': '1', 11:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:42 INFO - 'PWD': '/builds/slave/test', 11:24:42 INFO - 'SHELL': '/bin/bash', 11:24:42 INFO - 'SHLVL': '1', 11:24:42 INFO - 'TERM': 'linux', 11:24:42 INFO - 'TMOUT': '86400', 11:24:42 INFO - 'USER': 'cltbld', 11:24:42 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:42 INFO - '_': '/tools/buildbot/bin/python'} 11:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:42 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 11:24:42 INFO - Cleaning up... 11:24:42 INFO - Return code: 0 11:24:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:42 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:42 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 11:24:42 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 11:24:42 INFO - Using env: {'DISPLAY': ':0', 11:24:42 INFO - 'HOME': '/home/cltbld', 11:24:42 INFO - 'LANG': 'en_US.UTF-8', 11:24:42 INFO - 'LANGUAGE': 'en_US:en', 11:24:42 INFO - 'LOGNAME': 'cltbld', 11:24:42 INFO - 'MAIL': '/var/mail/cltbld', 11:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:42 INFO - 'MOZ_NO_REMOTE': '1', 11:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:42 INFO - 'NO_EM_RESTART': '1', 11:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:42 INFO - 'PWD': '/builds/slave/test', 11:24:42 INFO - 'SHELL': '/bin/bash', 11:24:42 INFO - 'SHLVL': '1', 11:24:42 INFO - 'TERM': 'linux', 11:24:42 INFO - 'TMOUT': '86400', 11:24:42 INFO - 'USER': 'cltbld', 11:24:42 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:42 INFO - '_': '/tools/buildbot/bin/python'} 11:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:43 INFO - Downloading/unpacking psutil>=3.1.1 11:24:43 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 11:24:43 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 11:24:43 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 11:24:43 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 11:24:43 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 11:24:43 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 11:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:24:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:24:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:24:45 INFO - Installing collected packages: psutil 11:24:45 INFO - Running setup.py install for psutil 11:24:45 INFO - building 'psutil._psutil_linux' extension 11:24:45 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 11:24:45 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 11:24:45 INFO - building 'psutil._psutil_posix' extension 11:24:45 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 11:24:45 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 11:24:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:24:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:24:45 INFO - Successfully installed psutil 11:24:45 INFO - Cleaning up... 11:24:45 INFO - Return code: 0 11:24:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:45 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:45 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 11:24:45 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 11:24:45 INFO - Using env: {'DISPLAY': ':0', 11:24:45 INFO - 'HOME': '/home/cltbld', 11:24:45 INFO - 'LANG': 'en_US.UTF-8', 11:24:45 INFO - 'LANGUAGE': 'en_US:en', 11:24:45 INFO - 'LOGNAME': 'cltbld', 11:24:45 INFO - 'MAIL': '/var/mail/cltbld', 11:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:45 INFO - 'MOZ_NO_REMOTE': '1', 11:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:45 INFO - 'NO_EM_RESTART': '1', 11:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:45 INFO - 'PWD': '/builds/slave/test', 11:24:45 INFO - 'SHELL': '/bin/bash', 11:24:45 INFO - 'SHLVL': '1', 11:24:45 INFO - 'TERM': 'linux', 11:24:45 INFO - 'TMOUT': '86400', 11:24:45 INFO - 'USER': 'cltbld', 11:24:45 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:45 INFO - '_': '/tools/buildbot/bin/python'} 11:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:24:47 INFO - Installing collected packages: mozsystemmonitor 11:24:47 INFO - Running setup.py install for mozsystemmonitor 11:24:47 INFO - Successfully installed mozsystemmonitor 11:24:47 INFO - Cleaning up... 11:24:48 INFO - Return code: 0 11:24:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:24: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')]} 11:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24: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')]} 11:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:48 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:48 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 11:24:48 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 11:24:48 INFO - Using env: {'DISPLAY': ':0', 11:24:48 INFO - 'HOME': '/home/cltbld', 11:24:48 INFO - 'LANG': 'en_US.UTF-8', 11:24:48 INFO - 'LANGUAGE': 'en_US:en', 11:24:48 INFO - 'LOGNAME': 'cltbld', 11:24:48 INFO - 'MAIL': '/var/mail/cltbld', 11:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:48 INFO - 'MOZ_NO_REMOTE': '1', 11:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:48 INFO - 'NO_EM_RESTART': '1', 11:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:48 INFO - 'PWD': '/builds/slave/test', 11:24:48 INFO - 'SHELL': '/bin/bash', 11:24:48 INFO - 'SHLVL': '1', 11:24:48 INFO - 'TERM': 'linux', 11:24:48 INFO - 'TMOUT': '86400', 11:24:48 INFO - 'USER': 'cltbld', 11:24:48 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:48 INFO - '_': '/tools/buildbot/bin/python'} 11:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:48 INFO - Downloading/unpacking jsonschema==2.5.1 11:24:48 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 11:24:48 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 11:24:48 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 11:24:48 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 11:24:48 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 11:24:48 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 11:24:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:24:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:50 INFO - Downloading functools32-3.2.3-2.tar.gz 11:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:24:50 INFO - warning: no files found matching '*.txt' 11:24:50 INFO - no previously-included directories found matching 'build' 11:24:50 INFO - no previously-included directories found matching 'dist' 11:24:50 INFO - no previously-included directories found matching '.git*' 11:24:50 INFO - Installing collected packages: jsonschema, functools32 11:24:50 INFO - Running setup.py install for functools32 11:24:50 INFO - warning: no files found matching '*.txt' 11:24:50 INFO - no previously-included directories found matching 'build' 11:24:50 INFO - no previously-included directories found matching 'dist' 11:24:50 INFO - no previously-included directories found matching '.git*' 11:24:50 INFO - Successfully installed jsonschema functools32 11:24:50 INFO - Cleaning up... 11:24:50 INFO - Return code: 0 11:24:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:24:50 INFO - Using env: {'DISPLAY': ':0', 11:24:50 INFO - 'HOME': '/home/cltbld', 11:24:50 INFO - 'LANG': 'en_US.UTF-8', 11:24:50 INFO - 'LANGUAGE': 'en_US:en', 11:24:50 INFO - 'LOGNAME': 'cltbld', 11:24:50 INFO - 'MAIL': '/var/mail/cltbld', 11:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:50 INFO - 'MOZ_NO_REMOTE': '1', 11:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:50 INFO - 'NO_EM_RESTART': '1', 11:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:50 INFO - 'PWD': '/builds/slave/test', 11:24:50 INFO - 'SHELL': '/bin/bash', 11:24:50 INFO - 'SHLVL': '1', 11:24:50 INFO - 'TERM': 'linux', 11:24:50 INFO - 'TMOUT': '86400', 11:24:50 INFO - 'USER': 'cltbld', 11:24:50 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:50 INFO - '_': '/tools/buildbot/bin/python'} 11:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:50 INFO - Downloading/unpacking blobuploader==1.2.4 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:52 INFO - Downloading blobuploader-1.2.4.tar.gz 11:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:24:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:24:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:24:53 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 11:24:53 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 11:24:53 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 11:24:53 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 11:24:53 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 11:24:53 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 11:24:53 INFO - Downloading docopt-0.6.1.tar.gz 11:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:24:53 INFO - Installing collected packages: blobuploader, requests, docopt 11:24:53 INFO - Running setup.py install for blobuploader 11:24:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:24:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:24:53 INFO - Running setup.py install for requests 11:24:53 INFO - Running setup.py install for docopt 11:24:54 INFO - Successfully installed blobuploader requests docopt 11:24:54 INFO - Cleaning up... 11:24:54 INFO - Return code: 0 11:24:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:24:54 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')]} 11:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 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')]} 11:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:54 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 11:24:54 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 11:24:54 INFO - Using env: {'DISPLAY': ':0', 11:24:54 INFO - 'HOME': '/home/cltbld', 11:24:54 INFO - 'LANG': 'en_US.UTF-8', 11:24:54 INFO - 'LANGUAGE': 'en_US:en', 11:24:54 INFO - 'LOGNAME': 'cltbld', 11:24:54 INFO - 'MAIL': '/var/mail/cltbld', 11:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:54 INFO - 'MOZ_NO_REMOTE': '1', 11:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:54 INFO - 'NO_EM_RESTART': '1', 11:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:54 INFO - 'PWD': '/builds/slave/test', 11:24:54 INFO - 'SHELL': '/bin/bash', 11:24:54 INFO - 'SHLVL': '1', 11:24:54 INFO - 'TERM': 'linux', 11:24:54 INFO - 'TMOUT': '86400', 11:24:54 INFO - 'USER': 'cltbld', 11:24:54 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:54 INFO - '_': '/tools/buildbot/bin/python'} 11:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:24:54 INFO - Running setup.py (path:/tmp/pip-rryCic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:24:54 INFO - Running setup.py (path:/tmp/pip-1NNNsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:24:54 INFO - Running setup.py (path:/tmp/pip-I8aLco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:24:54 INFO - Running setup.py (path:/tmp/pip-Kg_tvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:24:54 INFO - Running setup.py (path:/tmp/pip-EHhev3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:24:54 INFO - Running setup.py (path:/tmp/pip-1FQWAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:24:54 INFO - Running setup.py (path:/tmp/pip-RyLr_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:24:54 INFO - Running setup.py (path:/tmp/pip-je2yeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:24:54 INFO - Running setup.py (path:/tmp/pip-LUvtMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:24:54 INFO - Running setup.py (path:/tmp/pip-OKBBax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:24:55 INFO - Running setup.py (path:/tmp/pip-L7i1iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:24:55 INFO - Running setup.py (path:/tmp/pip-BV9dzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:24:55 INFO - Running setup.py (path:/tmp/pip-Mnrkhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:24:55 INFO - Running setup.py (path:/tmp/pip-YPO362-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:55 INFO - Running setup.py (path:/tmp/pip-2hCHjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:24:55 INFO - Running setup.py (path:/tmp/pip-kLUWyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:24:55 INFO - Running setup.py (path:/tmp/pip-mM_AaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:24:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:24:55 INFO - Running setup.py install for manifestparser 11:24:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:24:55 INFO - Running setup.py install for mozcrash 11:24:55 INFO - Running setup.py install for mozdebug 11:24:55 INFO - Running setup.py install for mozdevice 11:24:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:24:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:24:55 INFO - Running setup.py install for mozfile 11:24:56 INFO - Running setup.py install for mozhttpd 11:24:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozinfo 11:24:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozInstall 11:24:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozleak 11:24:56 INFO - Running setup.py install for mozlog 11:24:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for moznetwork 11:24:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozprocess 11:24:56 INFO - Running setup.py install for mozprofile 11:24:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozrunner 11:24:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:24:56 INFO - Running setup.py install for mozscreenshot 11:24:57 INFO - Running setup.py install for moztest 11:24:57 INFO - Running setup.py install for mozversion 11:24:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:24:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:24:57 INFO - Cleaning up... 11:24:57 INFO - Return code: 0 11:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:57 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:24:57 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 11:24:57 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 11:24:57 INFO - Using env: {'DISPLAY': ':0', 11:24:57 INFO - 'HOME': '/home/cltbld', 11:24:57 INFO - 'LANG': 'en_US.UTF-8', 11:24:57 INFO - 'LANGUAGE': 'en_US:en', 11:24:57 INFO - 'LOGNAME': 'cltbld', 11:24:57 INFO - 'MAIL': '/var/mail/cltbld', 11:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:57 INFO - 'MOZ_NO_REMOTE': '1', 11:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:57 INFO - 'NO_EM_RESTART': '1', 11:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:57 INFO - 'PWD': '/builds/slave/test', 11:24:57 INFO - 'SHELL': '/bin/bash', 11:24:57 INFO - 'SHLVL': '1', 11:24:57 INFO - 'TERM': 'linux', 11:24:57 INFO - 'TMOUT': '86400', 11:24:57 INFO - 'USER': 'cltbld', 11:24:57 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:57 INFO - '_': '/tools/buildbot/bin/python'} 11:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:24:57 INFO - Running setup.py (path:/tmp/pip-WGvNTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:24:57 INFO - Running setup.py (path:/tmp/pip-wrGfNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:24:57 INFO - Running setup.py (path:/tmp/pip-wk6hRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:24:57 INFO - Running setup.py (path:/tmp/pip-Qpx_7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:24:57 INFO - Running setup.py (path:/tmp/pip-i_MgGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:24:57 INFO - Running setup.py (path:/tmp/pip-MmX8Wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:24:57 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)) 11:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:24:57 INFO - Running setup.py (path:/tmp/pip-f1mMfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:24:58 INFO - Running setup.py (path:/tmp/pip-6rRdwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:24:58 INFO - Running setup.py (path:/tmp/pip-5waktq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:24:58 INFO - Running setup.py (path:/tmp/pip-9R6CJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:24:58 INFO - Running setup.py (path:/tmp/pip-wfM1po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:24:58 INFO - Running setup.py (path:/tmp/pip-YPnCzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:24:58 INFO - Running setup.py (path:/tmp/pip-zU4JZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:24:58 INFO - Running setup.py (path:/tmp/pip-jAkRWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:58 INFO - Running setup.py (path:/tmp/pip-YDL7F5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:24:58 INFO - Running setup.py (path:/tmp/pip-zcCuvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:24:58 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)) 11:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:24:58 INFO - Running setup.py (path:/tmp/pip-GRtKDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:24:58 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)) 11:24:58 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)) 11:24:58 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)) 11:24:58 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)) 11:24:58 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)) 11:24:58 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)) 11:24:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:25:00 INFO - Downloading blessings-1.6.tar.gz 11:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:25:00 INFO - Installing collected packages: blessings 11:25:00 INFO - Running setup.py install for blessings 11:25:00 INFO - Successfully installed blessings 11:25:00 INFO - Cleaning up... 11:25:00 INFO - Return code: 0 11:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:01 INFO - Reading from file tmpfile_stdout 11:25:01 INFO - Current package versions: 11:25:01 INFO - argparse == 1.2.1 11:25:01 INFO - blessings == 1.6 11:25:01 INFO - blobuploader == 1.2.4 11:25:01 INFO - docopt == 0.6.1 11:25:01 INFO - functools32 == 3.2.3-2 11:25:01 INFO - jsonschema == 2.5.1 11:25:01 INFO - manifestparser == 1.1 11:25:01 INFO - mozInstall == 1.12 11:25:01 INFO - mozcrash == 0.17 11:25:01 INFO - mozdebug == 0.1 11:25:01 INFO - mozdevice == 0.48 11:25:01 INFO - mozfile == 1.2 11:25:01 INFO - mozhttpd == 0.7 11:25:01 INFO - mozinfo == 0.9 11:25:01 INFO - mozleak == 0.1 11:25:01 INFO - mozlog == 3.2 11:25:01 INFO - moznetwork == 0.27 11:25:01 INFO - mozprocess == 0.23 11:25:01 INFO - mozprofile == 0.28 11:25:01 INFO - mozrunner == 6.12 11:25:01 INFO - mozscreenshot == 0.1 11:25:01 INFO - mozsystemmonitor == 0.3 11:25:01 INFO - moztest == 0.7 11:25:01 INFO - mozversion == 1.4 11:25:01 INFO - psutil == 3.1.1 11:25:01 INFO - requests == 1.2.3 11:25:01 INFO - wsgiref == 0.1.2 11:25:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:01 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')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:25:01 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 11:25:01 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 11:25:01 INFO - Using env: {'DISPLAY': ':0', 11:25:01 INFO - 'HOME': '/home/cltbld', 11:25:01 INFO - 'LANG': 'en_US.UTF-8', 11:25:01 INFO - 'LANGUAGE': 'en_US:en', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MAIL': '/var/mail/cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SHLVL': '1', 11:25:01 INFO - 'TERM': 'linux', 11:25:01 INFO - 'TMOUT': '86400', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - '_': '/tools/buildbot/bin/python'} 11:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 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)) 11:25:01 INFO - Cleaning up... 11:25:01 INFO - Return code: 0 11:25:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:25:01 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')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:25:01 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 11:25:01 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 11:25:01 INFO - Using env: {'DISPLAY': ':0', 11:25:01 INFO - 'HOME': '/home/cltbld', 11:25:01 INFO - 'LANG': 'en_US.UTF-8', 11:25:01 INFO - 'LANGUAGE': 'en_US:en', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MAIL': '/var/mail/cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SHLVL': '1', 11:25:01 INFO - 'TERM': 'linux', 11:25:01 INFO - 'TMOUT': '86400', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - '_': '/tools/buildbot/bin/python'} 11:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:25:01 INFO - Cleaning up... 11:25:01 INFO - Return code: 0 11:25:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:25:01 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')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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 0x7f5b5873af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22c8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, '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': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', '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 11:25:01 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 11:25:01 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 11:25:01 INFO - Using env: {'DISPLAY': ':0', 11:25:01 INFO - 'HOME': '/home/cltbld', 11:25:01 INFO - 'LANG': 'en_US.UTF-8', 11:25:01 INFO - 'LANGUAGE': 'en_US:en', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MAIL': '/var/mail/cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SHLVL': '1', 11:25:01 INFO - 'TERM': 'linux', 11:25:01 INFO - 'TMOUT': '86400', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - '_': '/tools/buildbot/bin/python'} 11:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:01 INFO - Downloading/unpacking flake8 11:25:01 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 11:25:01 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 11:25:01 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 11:25:01 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 11:25:01 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 11:25:01 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 11:25:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:25:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:25:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:25:04 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 11:25:04 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 11:25:04 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 11:25:04 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 11:25:04 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 11:25:04 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 11:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:25:04 INFO - no previously-included directories found matching 'docs/_build' 11:25:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:25:04 INFO - Running setup.py install for pep8 11:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:25:04 INFO - no previously-included directories found matching 'docs/_build' 11:25:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:25:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:25:04 INFO - Cleaning up... 11:25:04 INFO - Return code: 0 11:25:04 INFO - Running post-action listener: _resource_record_post_action 11:25:04 INFO - Running post-action listener: _start_resource_monitoring 11:25:04 INFO - Starting resource monitoring. 11:25:04 INFO - ##### 11:25:04 INFO - ##### Finished create-virtualenv step (success) 11:25:04 INFO - ##### 11:25:04 INFO - ##### 11:25:04 INFO - ##### Running install step. 11:25:04 INFO - ##### 11:25:04 INFO - Running pre-action listener: _resource_record_pre_action 11:25:04 INFO - Running main action method: install 11:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:04 INFO - Reading from file tmpfile_stdout 11:25:04 INFO - Detecting whether we're running mozinstall >=1.0... 11:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:25:04 INFO - Reading from file tmpfile_stdout 11:25:04 INFO - Output received: 11:25:04 INFO - Usage: mozinstall [options] installer 11:25:04 INFO - Options: 11:25:04 INFO - -h, --help show this help message and exit 11:25:04 INFO - -d DEST, --destination=DEST 11:25:04 INFO - Directory to install application into. [default: 11:25:04 INFO - "/builds/slave/test"] 11:25:04 INFO - --app=APP Application being installed. [default: firefox] 11:25:04 INFO - mkdir: /builds/slave/test/build/application 11:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:25:18 INFO - Reading from file tmpfile_stdout 11:25:18 INFO - Output received: 11:25:18 INFO - /builds/slave/test/build/application/firefox/firefox 11:25:18 INFO - Running post-action listener: _resource_record_post_action 11:25:18 INFO - ##### 11:25:18 INFO - ##### Finished install step (success) 11:25:18 INFO - ##### 11:25:18 INFO - ##### 11:25:18 INFO - ##### Running run-tests step. 11:25:18 INFO - ##### 11:25:18 INFO - Running pre-action listener: _resource_record_pre_action 11:25:18 INFO - Running main action method: run_tests 11:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:25:18 INFO - Python 2.7.3 11:25:18 INFO - Return code: 0 11:25:18 INFO - grabbing minidump binary from tooltool 11:25:18 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')]} 11:25:18 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 0x23571c0>, '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 0x23580c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2358550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:25:18 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 11:25:18 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 11:25:18 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 11:25:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:25:18 INFO - Return code: 0 11:25:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:25:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:25:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:25:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:25:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:25:19 INFO - Return code: 0 11:25:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 11:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 11:25:19 INFO - Using env: {'DISPLAY': ':0', 11:25:19 INFO - 'HOME': '/home/cltbld', 11:25:19 INFO - 'LANG': 'en_US.UTF-8', 11:25:19 INFO - 'LANGUAGE': 'en_US:en', 11:25:19 INFO - 'LOGNAME': 'cltbld', 11:25:19 INFO - 'MAIL': '/var/mail/cltbld', 11:25:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:25:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:19 INFO - 'MOZ_NO_REMOTE': '1', 11:25:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:19 INFO - 'NO_EM_RESTART': '1', 11:25:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:19 INFO - 'PWD': '/builds/slave/test', 11:25:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:25:19 INFO - 'SHELL': '/bin/bash', 11:25:19 INFO - 'SHLVL': '1', 11:25:19 INFO - 'TERM': 'linux', 11:25:19 INFO - 'TMOUT': '86400', 11:25:19 INFO - 'USER': 'cltbld', 11:25:19 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500', 11:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:19 INFO - '_': '/tools/buildbot/bin/python'} 11:25:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 11:25:19 INFO - mozversion application_buildid: 20160801070053 11:25:19 INFO - mozversion application_changeset: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 11:25:19 INFO - mozversion application_display_name: Firefox 11:25:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:25:19 INFO - mozversion application_name: Firefox 11:25:19 INFO - mozversion application_remotingname: firefox 11:25:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:25:19 INFO - mozversion application_vendor: Mozilla 11:25:19 INFO - mozversion application_version: 49.0 11:25:19 INFO - mozversion platform_buildid: 20160801070053 11:25:19 INFO - mozversion platform_changeset: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 11:25:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:25:19 INFO - mozversion platform_version: 49.0 11:25:19 INFO - using testdate: 1470075919 11:25:19 INFO - actual date: 1470075919 11:25:19 INFO - starting webserver on 'localhost:36656' 11:25:19 INFO - SUITE-START | Running 2 tests 11:25:19 INFO - TEST-START | dromaeo_css 11:25:19 INFO - operating with platform_type : linux_ 11:25:19 INFO - Initialising browser for dromaeo_css test... 11:25:19 INFO - TEST-INFO | started process 21373 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjiHo6q/profile http://localhost:36656/getInfo.html) 11:25:23 INFO - PROCESS | 21373 | __metrics Screen width/height:1600/1200 11:25:23 INFO - PROCESS | 21373 | colorDepth:24 11:25:23 INFO - PROCESS | 21373 | Browser inner width/height: 1024/697 11:25:23 INFO - PROCESS | 21373 | __metrics 11:25:24 INFO - PROCESS | 21373 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:25:24 ERROR - PROCESS | 21373 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:25:25 INFO - TEST-INFO | 21373: exit 0 11:25:25 INFO - Browser initialized. 11:25:25 INFO - Running cycle 1/1 for dromaeo_css test... 11:25:25 INFO - TEST-INFO | started process 21464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjiHo6q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:25:36 INFO - PROCESS | 21464 | RSS: Main: 140505088 11:25:36 INFO - PROCESS | 21464 | 11:26:28 INFO - PROCESS | 21464 | 1470075988262 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] 11:28:36 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36656/tests/dromaeo/cssquery-ext.html) 11:28:37 INFO - PROCESS | 21464 | RSS: Main: 256851968 11:28:37 INFO - PROCESS | 21464 | 11:31:39 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-ext.html (next: http://localhost:36656/tests/dromaeo/cssquery-jquery.html) 11:31:40 INFO - PROCESS | 21464 | RSS: Main: 232824832 11:31:40 INFO - PROCESS | 21464 | 11:34:42 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36656/tests/dromaeo/cssquery-mootools.html) 11:34:42 INFO - PROCESS | 21464 | RSS: Main: 219348992 11:34:42 INFO - PROCESS | 21464 | 11:37:44 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36656/tests/dromaeo/cssquery-prototype.html) 11:37:45 INFO - PROCESS | 21464 | RSS: Main: 220643328 11:37:45 INFO - PROCESS | 21464 | 11:40:47 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36656/tests/dromaeo/cssquery-yui.html) 11:40:48 INFO - PROCESS | 21464 | RSS: Main: 213942272 11:40:48 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:36656/tests/dromaeo/cssquery-yui.html (next: http://localhost:36656/tests/dromaeo/cssquery-dojo.html) 11:43:51 INFO - PROCESS | 21464 | RSS: Main: 258101248 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | __start_tp_report 11:43:51 INFO - PROCESS | 21464 | _x_x_mozilla_page_load 11:43:51 INFO - PROCESS | 21464 | _x_x_mozilla_page_load_details 11:43:51 INFO - PROCESS | 21464 | |i|pagename|runs| 11:43:51 INFO - PROCESS | 21464 | |0;dojo.html;3420;3449.8620055197775;3457.4813777933305;3478.5825700915893;3492.5634295713035;5763.827085187446;5859.150423188635;5882;5892.587518873675;5900.793472228467;5797.507211886995;5887.970560147204;5889.175515427833;5890.057590785475;5892.764289428418;5114.462981386957;5159.561437277817;5166.0701073806795;5191.117510023286;5238.161894096946;5258.237555554442;5292.5765938725;5297.496737809921;5301.204819277101;5303.522682958533;5206.531412172903;5227.08526007949;5254.343207099113;5258.737063146847;5289;5344.946550534483;5351.892962140774;5360;5361.115415956401;5362.168863826113;5024.497550244982;5133.871653208662;5171.586273098181;5259.86850328741;5268.946210757826;5565.415631358689;5609.747561359749;5623.156526521014;5632.662040277596;5669.007923613392;6222.0355844844125;6225.252969643671;6228.314885362606;6230.221222347207;6232.9376706232815;6022.09668549721;6066.827797573043;6091.329953705089;6142.907856382158;6170.84572885677;5767.452092051249;5807.2160258365;5808.215890854723;5814.622049566765;5823.475887170175;6164.568480206342;6286.937130628727;6287.811365659038;6289.213848268875;6294.307626161119;6040.09398590215;6043.576949613484;6097.207363042776;6153.7230824613;6165.938340616626;6228.626282422979;6235.220597425322;6241.7191226394925;6246.094316324107;6254;6036.094585812163;6091.390860913873;6180.7527698891545;6185.84535386619;6203.534734894901;6116.2871805091745;6215.254169499689;6232.626042437378;6232.750689972441;6233.812985610439;4652.232381657056;4689.906201875943;4691.601213896857;4710.9764451177525;4723.197056500403;4117.094239267416;4132.33882578786;4160.4591403118;4208.642265407413;4228.492580890313;5919.585629005928;5925.377835327315;5934.080217566284;5936.554758393051;5950.137230101611;6201.224846894138;6238.688065596702;6247.3440288769025;6252.937470625327;6259.624422534572;5782.479576838021;5802.65184088956;5809.070548712186;5825.825225243249;5912.881742365129;5295.364556253275;5298.470152984748;5301.575873930076;5302.416734159239;5306.124489459196;6425.871482570323;6425.903611445832;6429.196350456193;6430.485561155097;6433.678316084177;6067.089936509559;6105.236845394325;6120.721420533169;6141.048137538689;6241.843953901189;6467.676616169078;6497.285298617054;6509.414152726088;6511.38141876521;6514.0880276760345;6021.397860213943;6176.042713379434;6177.1969643945995;6185.350538193425;6187.566870319034;6112.837854638479;6144.446999770043;6248.281447633581;6262.185915830913;6274.435300822948;6092.9086063709965;6095.695215239309;6096.177016102769;6099.725512351866;6102.755889764359;5922.2597175353085;6014.849628759316;6024.865792572235;6094.421030002142;6130.63216207029;5963.701814909324;6030.638161710312;6037.78867739627;6044.33512313654;6050.152978582913;6164.969175154096;6170.290416602148;6176.474999624902;6184.041473571604;6188.226471691039;5768.365479797303;5883.058710606282;5900.468957793819;6031.4270144336215;6059.242594675666 11:43:51 INFO - PROCESS | 21464 | |1;ext.html;632.0866348126958;654.110409842614;665.0987911380076;669.7555392281816;680.87063457943;33137.83431082844;45409.77295113524;47169.52830471694;47189.29216061762;47203.999999999956;48611.756941215244;48963;48989.26516102252;49064.26403603949;49109.75445122778;8190.590470476483;9164.541772911345;9304.395214311082;9315.72052838416;10859.83710244347;10722.946385268102;11053.336799791998;11079.279846809932;11112;11205.103591712685;11152.609658661948;11210.27133236341;11221.60724374647;11269.830952535718;11274.210805243636;11104;11144.777104457946;11179.552817887356;11188.104951603853;11211.103111751121;11085.168612354026;11168.218224724273;11169.385683787388;11176.888231117666;11187.608433704863;45423.545764542265;45811.77094114541;45903.77048114738;45935;46028.3095753564;3810.7713537187856;3827.617238276178;3944.7633142011573;4141.295979683431;4406.806631592533;35560;35674.28651426957;35743.64256357429;36235.09412264688;36625.63374366249;4759.690620109682;4836.758162091881;4853.369062021915;4872.340107665553;4961.751912404401;362232;364211;364255;364286;366049;65974.67012665002;66244.66877665678;66493.33506664968;66821;66832.66583667148;40862.59137408647;41990.37014444786;42047.57952420437;43855.12289754187;43867.341989870176;19109.80890191108;19356;19392.515187120152;19405;19599.804001960085;12879.42042608085;13815.930920345334;14205.36075876588;14207.218602976729;14398.856011439751;21572.137114515244;21618.13527458884;21759.129634814748;21895.452613684465;21916;7610.467267291237;7631.427642926803;7711;7721.343685786771;7810.570418626917;1029.4183786160795;1139.4787958075897;1213.3993673131679;1236.6351926181799;1256.296473974577;33457;34782.91211803988;34992.475112873326;35229.47155792665;35538.82230588831;1129.565117429779;1176.9117316201318;1181.8699943006263;1185.2651356159236;1273.0134146036896;18137.36519221821;18380.816191838177;18492.81507184911;18638.16128274231;18724.344647937258;563254;567344;568110;569538;570380;67556.32443675598;67593;68040.3195968034;68175.65912170506;68269.3173068263;1378.2281922123643;1453.4331610672057;1474.5576327102042;1488.3823213366547;1506.4501456968128;34076.65923340834;35493.1126721834;35666.64333356737;36171.45742813807;36229.09427264339;56417.435825641216;56947.43052569421;56951.14573281321;56973.430265696814;57041;436336;438126;438197;439668;439771;27751;31182.844085779427;31703.365932681678;31738.682613173572;33678.663213368534;54313;54484.45515544953;54906.72546637241;54907.725461372436;55840.16239756488;45054.77472612747;45285.320720189884;45332.54667453283;46109;47313.763431182626;44051.11897762091;44558.77720611376;44719;44911.32633010573;45438.54561454343;44460.77769611131;44918.550814491435;45600.7719961398;45970.54029459663;46264.53735462602;38042.23915521729;39900;40190.59809401986;40329.193416130925;40469.392959106226;29553.408931821672;30155.849220753757;30323.848380757954;30441.695583043886;30584.541231881984 11:43:51 INFO - PROCESS | 21464 | |2;jquery.html;411.2043196414937;413.2891426745996;423.5002696817755;424.28296179456714;436.1016306408798;19282.710759338603;20112.396628101145;20910.89544552277;21224;21287.574248515022;19846.40460786175;20447.795522044777;20524.486887827774;20661.79338206621;20779.480512987182;5688.772449102031;5895.498882594985;5903.675297858616;5937.495312898409;5938.732757025944;4219.451471308726;5803.854903627422;5811;6337.651429171417;6628.270890202074;6629.403353698166;6645.33546645334;6657.700403481855;6659.267480577133;6681.064650948871;6520.967395163041;6616.470682345401;6678.465722742169;6700.329967003309;6721.6975236114495;3956.248312820555;5469.644473109275;5837.474627283565;5837.824865254049;5885.087811389241;23342.066317347286;23914.64128038081;24148.758512414828;24385.756142438702;24405.633915491282;2465.815063870199;2687.704352521221;2692.717264687199;3111.299957509565;3165.5568220449154;10911.618093366773;11015.614453494089;14366.497172598909;14512.274386280644;15146.31841567121;1959.8530110241788;2110.883901385423;2142.924997625076;2263.2418139923147;2301.528186721718;75930;76403.23596764017;76988;87490.56254718814;89087.55456222677;11047.944760276308;11255.380954047607;11450.54197832094;11506.76986460266;11567.595134170264;15880.761788572949;15886;15963.600909977344;15965.121918294606;15976.600584985468;8637.352198585131;8771.38600297973;8818.86771698425;8929.866052009225;8930.01769805334;8541.743747687578;8634.050254472004;8663.003754568179;8797.736067917973;8868.467891926504;4815.085133824562;4867;4920.114379411669;4997.425296090996;5060.089183946851;1831.9633607328046;1838.7299309355035;1980.5642758593087;2041.9693704594147;2138;951.2485136742034;996.2279233593878;1014.9644762433279;1016.9847452288221;1033.3851358441716;19715.40853774389;19801.10895009728;19814.20743170286;19853;20088.799112008986;1025.369397820335;1039.750459889621;1047.8323468245044;1049.4070849969896;1062.808694435009;10849.712971875739;10894.891051089546;10951.123910087173;11526.827097593376;11707.88292117068;508572;532499;546473;551098;552597;98774.5061274689;100738.496307518;102671;104102.47948760208;104173.47913260537;1091.345192884279;1110.3559935237543;1124.1905827803969;1141.1270378160714;1156.2195518025298;18388.908055459637;18522.907385463524;18625.348112815987;18685.252589896252;18788.812111878706;38749;41876.371854421595;42264.78867605642;42921.785391072845;43450.130997379245;50356;58555.121673174086;59115.70442147762;59376.70311648414;60110;8502.91497085046;8985.640574376952;8988.820223595621;9001.729948101567;9158.542072896194;12602.11785175029;13384.598462046155;13410.12834165776;13418.93290533541;13427.798583021067;11328.660140195807;11334.94332528332;11632.592859249886;11720.70698232551;11856.11079169083;11512.36681982499;11693;11722.296662199955;11739.23694959825;11843.407829608313;11933.940330298294;12036.458359373673;12325.815112773136;12361.320127393079;12652.620421387373;7983.121856595886;8285;8795.648174073222;8966.327525435749;9045.185933265775;10739.802255676392;11112;11374.488148033524;11388.37363944991;11443.313401195956 11:43:51 INFO - PROCESS | 21464 | |3;mootools.html;1459.649684075822;1615.5153453963808;1663.2432243329285;1837.3661086925015;1954.1792447172195;8548.059713431525;9285.117913798194;9549.045095490465;9582.904170958296;9583.424994500318;9187.908120918788;9427.010163932791;9428.905710942889;9727.902720972788;9747.463889486075;2309.930702078936;2342.367560758594;2342.672025916373;2349.3539276698875;2364.9645255321184;2430.513897220554;2436.6954130733657;2510.3849556858595;2511.5604769165416;2513.258588716324;2464.568700477418;2481;2483.7640424159677;2527.166035208377;2532.328932832801;2374.4301367671806;2397.5804234258935;2409.6867407237037;2411.5297516984197;2449.7672721091467;2316.027268547214;2339.0760649543504;2341.391238278043;2344.4138965258685;2388.235764555343;8327.66689332426;9131.543422828898;9634.614615415376;9730.562124704406;9740.172085372751;5587.8603034924045;5835.212246346774;5851.736671849777;5851.76592936282;5859.589828711992;3529.1177205698577;3623.6376362363817;3678.9632103678887;3715.8699445519546;3716.3867961786273;1126.1835169502128;1130.5082289204222;1142.9257098288585;1153.2158132469917;2380.857148571074;13497.325133743372;14096.08375455592;14136.081154725012;14157.362918668685;14159.292035398188;5151.459096794858;5152.407473140562;5357.4106848246665;5385.946140538545;5458.5087342139395;11097;11196.94401527987;11197.16021298406;11221.382823944761;11228.214025018171;5936.406359364029;5980.461758441761;5983.760649573968;5989.2812862456785;5993.580449368589;5164.767585458663;5245.318108645852;5254.159334506537;5254.211868219798;5582.637128586629;3858.266929283427;3863.9227215456094;3932.6853851691794;3975.5229372475487;4009;1073.3559864081644;1129.2772625519674;1146.8553804385685;1155.1105648650491;1158.9826152607718;1061.9415932123652;1085.1915323084352;1086.6794295682791;1100.4827730966433;1112.4660163121746;6242.843928901813;6388.968055159695;6477.773277935344;6582.835429114215;6604.1084453599115;486.1977736734346;492.33534728116757;500.5520059546685;501.79426435161406;505.59552358113365;4432.357308190294;4436.889077773017;4446.088551846929;4450.087732014929;4582.908341833145;12442.253464792142;12780.360981950866;12810;12820.551280705131;12840.357982100857;11668.824967625493;12504.062195335386;12560.560380386643;12630.179038362478;12681.619551413472;9058.048904864894;9192.126747958799;9265.397749146285;9279.76800579991;9280.350375473652;6550.279469258473;6711.429528490133;6878.55289406187;6920.446364290945;6928.653567321715;10927.726806829893;11345.886541134483;11421.885781142082;11588.536458541901;11606.593769218036;11641;11669.649910502698;11678.416079196177;11742.941285293518;11757.294562326288;8781.868271976053;8893.911060889308;8963.462192268486;8966.955165224394;8976.192142707188;9409.776729025183;9418.152366287068;9516;9549.761255968657;9552.044795520393;8302.086770455366;8354.039285482004;8402.24379805796;8421.70524031656;8439.029511606244;8226.794330141794;8441.113683063435;8470.703525376583;8585.699500517452;8632.223099921037;7884.960575197317;7909.762707118795;8026.43814932949;8079.313258373104;8084.39367047462;4733.479317275028;5245.947540524546;5298.549623282064;5330.866728331823;5351.705656188947;5537.5569954404355;5600.159976003632;5757.0788673811985;5777.8555536111935;5874.03078492044 11:43:51 INFO - PROCESS | 21464 | |4;prototype.html;347.44582391086215;355.79008385052816;358.2816453011711;358.4676755018795;360.4395165517621;5550.500454959053;5624.128260119683;5645.689487078209;5654.321481422225;5691;5567.387587365387;5585.832425027245;5589.7205139743055;5597.63615365002;5603.635763675358;3915.531675621642;4115.382692596112;4332.1335732853395;4337.371081193241;4346.695731298811;4296.8281268749215;4308.80610372534;4321.719088259269;4326.178026175033;4340.891477713051;4119.505659320886;4278.871633850989;4318.309070548713;4327.632151267146;4336.588024137702;4185.686073544481;4298.312270036779;4300.290452075404;4324.200022995756;4331.566843315659;3827.9808600957094;3828.5214348206473;3849.345611246095;3858.9421158682644;3880.20455806559;5363.570914326844;5363.892722145574;5366.517013468807;5388.703621300827;5401.703591138138;5014.724190169539;5027.899442011176;5031.773570189313;5039.3196918415915;5042.697438153723;5531.917021244684;5730.255066841326;5738.368779434259;5785.537157027427;5848.210491583667;4133.834646614132;4142.979285103585;4166.291730405838;4260.2970509866145;4279.54495471538;5918.585699001072;6045.274567051938;6046.27444706638;6078.483328917047;6080.145555605539;4143.316352801814;4328.61042506176;4342.392065110887;4352.564743525622;4357.237483440385;4335.59374547123;4379.562043795659;4390.714603550759;4391.494978077562;4404.097160082176;4970.577500912391;5012.749362531932;5032.220005899091;5033.370828646419;5035.874103147451;5744.597878148573;5779.768809247667;5853.180554722342;5866.0724991007755;5883.823485295448;1459.9489017884323;1480.370842391979;1493.2608358862215;1499.7600383938518;1501.527018989015;929.9581518831669;952.8665986761858;955.8566215067796;957.4919501784717;961.2358175250693;4949.876253093701;5087.949120508822;5098.796048158032;5104.897902041938;5111.897762044739;5347.598930080259;5351.973240133774;5355;5426.8371948841595;5447.373552041566;4842.4020073375705;5064.151584671642;5091.287219789232;5108.514691104339;5189.481051894856;2841.602175695404;2859.8999035033676;2864.770818334528;2866.441043996401;2900.8984685536325;5506.256655351476;5519.172124181405;5532.585056120807;5552.833904880021;5564.944350556442;5422.349318081855;5707.800226992035;6204.448887778056;6211.9689401552705;6215.906761398583;5988.940110598838;6146.969265153735;6279.340669229757;6295.874082518416;6337.778177763848;6083.117947897618;6083.878322433438;6102.877942441038;6109.816705498843;6121.602095863755;5728.427157284238;5983.311919129182;5984.102384642164;5994.550408719277;5994.610350327215;6255.46828519581;6257.217847769029;6257.874842503215;6301.243850737941;6313.2424109107205;4255.765827909871;4259.61663450291;4289.485261768607;4301.161273551627;4317.913641727211;5870.765169393177;6079.483243924316;6086.360932102242;6088.969555152196;6096.506355942823;5893.703385255237;5908.2231351266555;5909.6158749681135;5966.343702192842;5968.641881487125;5821.970890145522;5947.899638566879;5965.582409231312;5978.163057172086;5981.996031329553;5772.509336706259;5825.155352473952;5837.824865254049;5848.970755146197;5882.82327543139;5850.391142435864;5920.046384693127;5947.286325640951;5967.671778052249;6010.4891084258325;5666.320041595035;5752.008955343282;5828.76684932598;5842;5851.707414629166 11:43:51 INFO - PROCESS | 21464 | |5;yui.html;792.3186059988409;801.535109636411;816.9591520423985;853.5604163855616;867.8784970104184;15125.546233612995;16291.103989280586;16346;16626.418075367365;17194.39819606314;15929.522114336589;15955.680886382266;16076.27656755446;16078.59803504913;16093.517194484155;731.381982225019;878.3587980774039;881.5548148185158;901.9909800902005;904.2720609909021;897.0894542039835;897.5422534507383;900.0279697926256;901.134910485931;901.197933838884;947.6209516193543;948.4404201521102;949.7815502434444;949.8670186173906;949.8860136783561;768.7078910014187;909.9135582119721;920.6179435534245;931.1619542411815;932.1098351074742;683.9042534045237;683.9794806155824;684.2575805251308;684.5892464521247;684.852756657321;15114.773278400833;15175.696486070217;15182.696346073128;15205.467808626645;15210.315535800915;13951.372188251553;14212.431502739879;14351.569452916428;14403.063800852911;14755.55733328002;13115.147515411563;13169.617190194987;13527.864721352758;13533.255670938095;13663.316834158253;4655.301704744282;4700.623950083985;4759.238521836524;4761.666683332168;4801.7118972861745;80262.19737802664;80871;82079.76880346739;82255.58872205661;82499;12282.32447215394;12402.503899843905;12411.255324680367;12559.497620095277;12641.051921105955;22214.888925555268;22620.660690089662;22655.773442265698;22670.206542771255;22977.88511057434;8316.584170791437;8335.416520843604;8636.352273579507;8733.257673097858;8771.649134034567;4724.811007559728;4806.8077276909235;4834.395700537433;4887.120318342661;4898.730569818694;3728;3837.4052021936086;3844.231153769257;4131.876043718693;4313.762743049162;662.0631805994464;708.9929100708927;748.046241042675;766.3256334425781;783.5572901310741;653.7875190563086;708.7271400510738;709.1950636028042;725.5102805606194;730.9013283206806;9596.66411675588;9987.850182247272;10007.09936105754;10082.243831712503;10116.140128089195;669.7086767256259;682.3585829320423;719.70851805018;725.74598890389;730.8867125595541;4448.221561226773;4628.492134149686;4721.7403042832975;4737.360456338419;4778.737169455713;121646;127211.36394317969;129731;133334;134119;108622.45688771506;109848.4507577457;111599;112068;113420.43289783498;95962.52018739862;97619.51190244146;98176.01823981812;98270.50864745774;100758;8817.735467935972;8855.822883542422;9178.908210917805;9185.494797786187;9204.217641500547;45716.77141614271;45802.77098614619;48282.5171748278;50515.2422713667;57942.4205757937;52255.73872130615;52265;58191.418085820296;72569;74228.25771742214;12283.938580307042;12367.62897113088;12573.497060117494;12641.304728240035;12791.232526048467;13703.246321452416;13763.793543097061;13986.300684965507;14056.508022219174;14281.14313141215;12603.684907877376;12776.872231277568;12815.935920320711;13231.206127632373;13668;12752.4261408235;12909;13201.60395188146;13498.055136140376;13518.121322114031;12668.303243321705;12784.808227876403;12858.0999330046;13073.607791766262;13377.598672039854;10460.63387781424;10573;10605.840912386475;10655.627053053417;10958.452077396258;11291.322520648788;11471.381480371858;11623.12826537996;11695;11740 11:43:51 INFO - PROCESS | 21464 | __end_tp_report 11:43:51 INFO - PROCESS | 21464 | __start_cc_report 11:43:51 INFO - PROCESS | 21464 | _x_x_mozilla_cycle_collect,916 11:43:51 INFO - PROCESS | 21464 | __end_cc_report 11:43:51 INFO - PROCESS | 21464 | __startTimestamp1470077031358__endTimestamp 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | ------- Summary: start ------- 11:43:51 INFO - PROCESS | 21464 | Number of tests: 6 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#0] dojo.html Cycles:165 Average:5757.08 Median:6022.10 stddev:655.90 (10.9%) stddev-sans-first:631.77 11:43:51 INFO - PROCESS | 21464 | Values: 3420.0 3449.9 3457.5 3478.6 3492.6 5763.8 5859.2 5882.0 5892.6 5900.8 5797.5 5888.0 5889.2 5890.1 5892.8 5114.5 5159.6 5166.1 5191.1 5238.2 5258.2 5292.6 5297.5 5301.2 5303.5 5206.5 5227.1 5254.3 5258.7 5289.0 5344.9 5351.9 5360.0 5361.1 5362.2 5024.5 5133.9 5171.6 5259.9 5268.9 5565.4 5609.7 5623.2 5632.7 5669.0 6222.0 6225.3 6228.3 6230.2 6232.9 6022.1 6066.8 6091.3 6142.9 6170.8 5767.5 5807.2 5808.2 5814.6 5823.5 6164.6 6286.9 6287.8 6289.2 6294.3 6040.1 6043.6 6097.2 6153.7 6165.9 6228.6 6235.2 6241.7 6246.1 6254.0 6036.1 6091.4 6180.8 6185.8 6203.5 6116.3 6215.3 6232.6 6232.8 6233.8 4652.2 4689.9 4691.6 4711.0 4723.2 4117.1 4132.3 4160.5 4208.6 4228.5 5919.6 5925.4 5934.1 5936.6 5950.1 6201.2 6238.7 6247.3 6252.9 6259.6 5782.5 5802.7 5809.1 5825.8 5912.9 5295.4 5298.5 5301.6 5302.4 5306.1 6425.9 6425.9 6429.2 6430.5 6433.7 6067.1 6105.2 6120.7 6141.0 6241.8 6467.7 6497.3 6509.4 6511.4 6514.1 6021.4 6176.0 6177.2 6185.4 6187.6 6112.8 6144.4 6248.3 6262.2 6274.4 6092.9 6095.7 6096.2 6099.7 6102.8 5922.3 6014.8 6024.9 6094.4 6130.6 5963.7 6030.6 6037.8 6044.3 6050.2 6165.0 6170.3 6176.5 6184.0 6188.2 5768.4 5883.1 5900.5 6031.4 6059.2 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#1] ext.html Cycles:180 Average:63606.70 Median:21922.38 stddev:122880.50 (560.5%) stddev-sans-first:123134.25 11:43:51 INFO - PROCESS | 21464 | Values: 632.1 654.1 665.1 669.8 680.9 33137.8 45409.8 47169.5 47189.3 47204.0 48611.8 48963.0 48989.3 49064.3 49109.8 8190.6 9164.5 9304.4 9315.7 10859.8 10722.9 11053.3 11079.3 11112.0 11205.1 11152.6 11210.3 11221.6 11269.8 11274.2 11104.0 11144.8 11179.6 11188.1 11211.1 11085.2 11168.2 11169.4 11176.9 11187.6 45423.5 45811.8 45903.8 45935.0 46028.3 3810.8 3827.6 3944.8 4141.3 4406.8 35560.0 35674.3 35743.6 36235.1 36625.6 4759.7 4836.8 4853.4 4872.3 4961.8 362232.0 364211.0 364255.0 364286.0 366049.0 65974.7 66244.7 66493.3 66821.0 66832.7 40862.6 41990.4 42047.6 43855.1 43867.3 19109.8 19356.0 19392.5 19405.0 19599.8 12879.4 13815.9 14205.4 14207.2 14398.9 21572.1 21618.1 21759.1 21895.5 21916.0 7610.5 7631.4 7711.0 7721.3 7810.6 1029.4 1139.5 1213.4 1236.6 1256.3 33457.0 34782.9 34992.5 35229.5 35538.8 1129.6 1176.9 1181.9 1185.3 1273.0 18137.4 18380.8 18492.8 18638.2 18724.3 563254.0 567344.0 568110.0 569538.0 570380.0 67556.3 67593.0 68040.3 68175.7 68269.3 1378.2 1453.4 1474.6 1488.4 1506.5 34076.7 35493.1 35666.6 36171.5 36229.1 56417.4 56947.4 56951.1 56973.4 57041.0 436336.0 438126.0 438197.0 439668.0 439771.0 27751.0 31182.8 31703.4 31738.7 33678.7 54313.0 54484.5 54906.7 54907.7 55840.2 45054.8 45285.3 45332.5 46109.0 47313.8 44051.1 44558.8 44719.0 44911.3 45438.5 44460.8 44918.6 45600.8 45970.5 46264.5 38042.2 39900.0 40190.6 40329.2 40469.4 29553.4 30155.8 30323.8 30441.7 30584.5 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#2] jquery.html Cycles:180 Average:31025.77 Median:2282.39 stddev:88683.75 (3885.6%) stddev-sans-first:88902.74 11:43:51 INFO - PROCESS | 21464 | Values: 411.2 413.3 423.5 424.3 436.1 19282.7 20112.4 20910.9 21224.0 21287.6 19846.4 20447.8 20524.5 20661.8 20779.5 5688.8 5895.5 5903.7 5937.5 5938.7 4219.5 5803.9 5811.0 6337.7 6628.3 6629.4 6645.3 6657.7 6659.3 6681.1 6521.0 6616.5 6678.5 6700.3 6721.7 3956.2 5469.6 5837.5 5837.8 5885.1 23342.1 23914.6 24148.8 24385.8 24405.6 2465.8 2687.7 2692.7 3111.3 3165.6 10911.6 11015.6 14366.5 14512.3 15146.3 1959.9 2110.9 2142.9 2263.2 2301.5 75930.0 76403.2 76988.0 87490.6 89087.6 11047.9 11255.4 11450.5 11506.8 11567.6 15880.8 15886.0 15963.6 15965.1 15976.6 8637.4 8771.4 8818.9 8929.9 8930.0 8541.7 8634.1 8663.0 8797.7 8868.5 4815.1 4867.0 4920.1 4997.4 5060.1 1832.0 1838.7 1980.6 2042.0 2138.0 951.2 996.2 1015.0 1017.0 1033.4 19715.4 19801.1 19814.2 19853.0 20088.8 1025.4 1039.8 1047.8 1049.4 1062.8 10849.7 10894.9 10951.1 11526.8 11707.9 508572.0 532499.0 546473.0 551098.0 552597.0 98774.5 100738.5 102671.0 104102.5 104173.5 1091.3 1110.4 1124.2 1141.1 1156.2 18388.9 18522.9 18625.3 18685.3 18788.8 38749.0 41876.4 42264.8 42921.8 43450.1 50356.0 58555.1 59115.7 59376.7 60110.0 8502.9 8985.6 8988.8 9001.7 9158.5 12602.1 13384.6 13410.1 13418.9 13427.8 11328.7 11334.9 11632.6 11720.7 11856.1 11512.4 11693.0 11722.3 11739.2 11843.4 11933.9 12036.5 12325.8 12361.3 12652.6 7983.1 8285.0 8795.6 8966.3 9045.2 10739.8 11112.0 11374.5 11388.4 11443.3 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#3] mootools.html Cycles:180 Average:6402.54 Median:2609.84 stddev:3782.62 (144.9%) stddev-sans-first:3774.99 11:43:51 INFO - PROCESS | 21464 | Values: 1459.6 1615.5 1663.2 1837.4 1954.2 8548.1 9285.1 9549.0 9582.9 9583.4 9187.9 9427.0 9428.9 9727.9 9747.5 2309.9 2342.4 2342.7 2349.4 2365.0 2430.5 2436.7 2510.4 2511.6 2513.3 2464.6 2481.0 2483.8 2527.2 2532.3 2374.4 2397.6 2409.7 2411.5 2449.8 2316.0 2339.1 2341.4 2344.4 2388.2 8327.7 9131.5 9634.6 9730.6 9740.2 5587.9 5835.2 5851.7 5851.8 5859.6 3529.1 3623.6 3679.0 3715.9 3716.4 1126.2 1130.5 1142.9 1153.2 2380.9 13497.3 14096.1 14136.1 14157.4 14159.3 5151.5 5152.4 5357.4 5385.9 5458.5 11097.0 11196.9 11197.2 11221.4 11228.2 5936.4 5980.5 5983.8 5989.3 5993.6 5164.8 5245.3 5254.2 5254.2 5582.6 3858.3 3863.9 3932.7 3975.5 4009.0 1073.4 1129.3 1146.9 1155.1 1159.0 1061.9 1085.2 1086.7 1100.5 1112.5 6242.8 6389.0 6477.8 6582.8 6604.1 486.2 492.3 500.6 501.8 505.6 4432.4 4436.9 4446.1 4450.1 4582.9 12442.3 12780.4 12810.0 12820.6 12840.4 11668.8 12504.1 12560.6 12630.2 12681.6 9058.0 9192.1 9265.4 9279.8 9280.4 6550.3 6711.4 6878.6 6920.4 6928.7 10927.7 11345.9 11421.9 11588.5 11606.6 11641.0 11669.6 11678.4 11742.9 11757.3 8781.9 8893.9 8963.5 8967.0 8976.2 9409.8 9418.2 9516.0 9549.8 9552.0 8302.1 8354.0 8402.2 8421.7 8439.0 8226.8 8441.1 8470.7 8585.7 8632.2 7885.0 7909.8 8026.4 8079.3 8084.4 4733.5 5245.9 5298.5 5330.9 5351.7 5537.6 5600.2 5757.1 5777.9 5874.0 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#4] prototype.html Cycles:180 Average:4861.21 Median:5476.82 stddev:1443.64 (26.4%) stddev-sans-first:1407.37 11:43:51 INFO - PROCESS | 21464 | Values: 347.4 355.8 358.3 358.5 360.4 5550.5 5624.1 5645.7 5654.3 5691.0 5567.4 5585.8 5589.7 5597.6 5603.6 3915.5 4115.4 4332.1 4337.4 4346.7 4296.8 4308.8 4321.7 4326.2 4340.9 4119.5 4278.9 4318.3 4327.6 4336.6 4185.7 4298.3 4300.3 4324.2 4331.6 3828.0 3828.5 3849.3 3858.9 3880.2 5363.6 5363.9 5366.5 5388.7 5401.7 5014.7 5027.9 5031.8 5039.3 5042.7 5531.9 5730.3 5738.4 5785.5 5848.2 4133.8 4143.0 4166.3 4260.3 4279.5 5918.6 6045.3 6046.3 6078.5 6080.1 4143.3 4328.6 4342.4 4352.6 4357.2 4335.6 4379.6 4390.7 4391.5 4404.1 4970.6 5012.7 5032.2 5033.4 5035.9 5744.6 5779.8 5853.2 5866.1 5883.8 1459.9 1480.4 1493.3 1499.8 1501.5 930.0 952.9 955.9 957.5 961.2 4949.9 5087.9 5098.8 5104.9 5111.9 5347.6 5352.0 5355.0 5426.8 5447.4 4842.4 5064.2 5091.3 5108.5 5189.5 2841.6 2859.9 2864.8 2866.4 2900.9 5506.3 5519.2 5532.6 5552.8 5564.9 5422.3 5707.8 6204.4 6212.0 6215.9 5988.9 6147.0 6279.3 6295.9 6337.8 6083.1 6083.9 6102.9 6109.8 6121.6 5728.4 5983.3 5984.1 5994.6 5994.6 6255.5 6257.2 6257.9 6301.2 6313.2 4255.8 4259.6 4289.5 4301.2 4317.9 5870.8 6079.5 6086.4 6089.0 6096.5 5893.7 5908.2 5909.6 5966.3 5968.6 5822.0 5947.9 5965.6 5978.2 5982.0 5772.5 5825.2 5837.8 5849.0 5882.8 5850.4 5920.0 5947.3 5967.7 6010.5 5666.3 5752.0 5828.8 5842.0 5851.7 11:43:51 INFO - PROCESS | 21464 | 11:43:51 INFO - PROCESS | 21464 | [#5] yui.html Cycles:180 Average:21745.65 Median:45759.77 stddev:32582.97 (71.2%) stddev-sans-first:32636.39 11:43:51 INFO - PROCESS | 21464 | Values: 792.3 801.5 817.0 853.6 867.9 15125.5 16291.1 16346.0 16626.4 17194.4 15929.5 15955.7 16076.3 16078.6 16093.5 731.4 878.4 881.6 902.0 904.3 897.1 897.5 900.0 901.1 901.2 947.6 948.4 949.8 949.9 949.9 768.7 909.9 920.6 931.2 932.1 683.9 684.0 684.3 684.6 684.9 15114.8 15175.7 15182.7 15205.5 15210.3 13951.4 14212.4 14351.6 14403.1 14755.6 13115.1 13169.6 13527.9 13533.3 13663.3 4655.3 4700.6 4759.2 4761.7 4801.7 80262.2 80871.0 82079.8 82255.6 82499.0 12282.3 12402.5 12411.3 12559.5 12641.1 22214.9 22620.7 22655.8 22670.2 22977.9 8316.6 8335.4 8636.4 8733.3 8771.6 4724.8 4806.8 4834.4 4887.1 4898.7 3728.0 3837.4 3844.2 4131.9 4313.8 662.1 709.0 748.0 766.3 783.6 653.8 708.7 709.2 725.5 730.9 9596.7 9987.9 10007.1 10082.2 10116.1 669.7 682.4 719.7 725.7 730.9 4448.2 4628.5 4721.7 4737.4 4778.7 121646.0 127211.4 129731.0 133334.0 134119.0 108622.5 109848.5 111599.0 112068.0 113420.4 95962.5 97619.5 98176.0 98270.5 100758.0 8817.7 8855.8 9178.9 9185.5 9204.2 45716.8 45802.8 48282.5 50515.2 57942.4 52255.7 52265.0 58191.4 72569.0 74228.3 12283.9 12367.6 12573.5 12641.3 12791.2 13703.2 13763.8 13986.3 14056.5 14281.1 12603.7 12776.9 12815.9 13231.2 13668.0 12752.4 12909.0 13201.6 13498.1 13518.1 12668.3 12784.8 12858.1 13073.6 13377.6 10460.6 10573.0 10605.8 10655.6 10958.5 11291.3 11471.4 11623.1 11695.0 11740.0 11:43:51 INFO - PROCESS | 21464 | -------- Summary: end -------- 11:43:51 INFO - PROCESS | 21464 | 11:43:52 INFO - TEST-INFO | 21464: exit 0 11:43:52 INFO - TEST-OK | dromaeo_css | took 1112778ms 11:43:52 INFO - TEST-START | kraken 11:43:52 INFO - operating with platform_type : linux_ 11:43:52 INFO - Initialising browser for kraken test... 11:43:52 INFO - TEST-INFO | started process 21661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphLRWuO/profile http://localhost:36656/getInfo.html) 11:43:55 INFO - PROCESS | 21661 | __metrics Screen width/height:1600/1200 11:43:55 INFO - PROCESS | 21661 | colorDepth:24 11:43:55 INFO - PROCESS | 21661 | Browser inner width/height: 1024/697 11:43:55 INFO - PROCESS | 21661 | __metrics 11:43:56 INFO - PROCESS | 21661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:43:56 ERROR - PROCESS | 21661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:43:57 INFO - TEST-INFO | 21661: exit 0 11:43:57 INFO - Browser initialized. 11:43:57 INFO - Running cycle 1/1 for kraken test... 11:43:57 INFO - TEST-INFO | started process 21755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphLRWuO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:44:09 INFO - PROCESS | 21755 | RSS: Main: 141180928 11:44:09 INFO - PROCESS | 21755 | 11:44:12 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36656/tests/kraken/driver.html?testName=audio-beat-detection) 11:44:12 INFO - PROCESS | 21755 | RSS: Main: 191639552 11:44:12 INFO - PROCESS | 21755 | 11:44:15 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36656/tests/kraken/driver.html?testName=audio-dft) 11:44:16 INFO - PROCESS | 21755 | RSS: Main: 189526016 11:44:16 INFO - PROCESS | 21755 | 11:44:18 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36656/tests/kraken/driver.html?testName=audio-fft) 11:44:19 INFO - PROCESS | 21755 | RSS: Main: 276221952 11:44:19 INFO - PROCESS | 21755 | 11:44:21 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36656/tests/kraken/driver.html?testName=audio-oscillator) 11:44:22 INFO - PROCESS | 21755 | RSS: Main: 184365056 11:44:22 INFO - PROCESS | 21755 | 11:44:24 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36656/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:44:24 INFO - PROCESS | 21755 | RSS: Main: 185786368 11:44:24 INFO - PROCESS | 21755 | 11:44:27 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36656/tests/kraken/driver.html?testName=imaging-darkroom) 11:44:28 INFO - PROCESS | 21755 | RSS: Main: 220434432 11:44:28 INFO - PROCESS | 21755 | 11:44:31 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36656/tests/kraken/driver.html?testName=imaging-desaturate) 11:44:32 INFO - PROCESS | 21755 | RSS: Main: 219987968 11:44:32 INFO - PROCESS | 21755 | 11:44:35 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36656/tests/kraken/driver.html?testName=json-parse-financial) 11:44:35 INFO - PROCESS | 21755 | RSS: Main: 218853376 11:44:35 INFO - PROCESS | 21755 | 11:44:37 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36656/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:44:37 INFO - PROCESS | 21755 | RSS: Main: 198656000 11:44:37 INFO - PROCESS | 21755 | 11:44:39 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:44:40 INFO - PROCESS | 21755 | RSS: Main: 199094272 11:44:40 INFO - PROCESS | 21755 | 11:44:42 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:44:42 INFO - PROCESS | 21755 | RSS: Main: 207069184 11:44:42 INFO - PROCESS | 21755 | 11:44:45 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:44:45 INFO - PROCESS | 21755 | RSS: Main: 212561920 11:44:45 INFO - PROCESS | 21755 | 11:44:48 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:44:49 INFO - PROCESS | 21755 | RSS: Main: 213442560 11:44:49 INFO - PROCESS | 21755 | 11:44:50 INFO - PROCESS | 21755 | Cycle 1(1): loaded http://localhost:36656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36656/tests/kraken/driver.html?testName=ai-astar) 11:44:50 INFO - PROCESS | 21755 | RSS: Main: 255696896 11:44:50 INFO - PROCESS | 21755 | 11:44:50 INFO - PROCESS | 21755 | __start_tp_report 11:44:51 INFO - PROCESS | 21755 | _x_x_mozilla_page_load 11:44:51 INFO - PROCESS | 21755 | _x_x_mozilla_page_load_details 11:44:51 INFO - PROCESS | 21755 | |i|pagename|runs| 11:44:51 INFO - PROCESS | 21755 | |0;ai-astar;111;106;111;108;112;112;109;110;110;110 11:44:51 INFO - PROCESS | 21755 | |1;audio-beat-detection;149;121;124;177;125;124;151;125;162;123 11:44:51 INFO - PROCESS | 21755 | |2;audio-dft;160;161;162;161;161;161;162;159;160;160 11:44:51 INFO - PROCESS | 21755 | |3;audio-fft;74;73;75;105;76;75;76;76;97;76 11:44:51 INFO - PROCESS | 21755 | |4;audio-oscillator;84;84;120;84;85;84;124;85;84;127 11:44:51 INFO - PROCESS | 21755 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;117;116;116 11:44:51 INFO - PROCESS | 21755 | |6;imaging-darkroom;145;147;146;145;146;147;146;146;146;146 11:44:51 INFO - PROCESS | 21755 | |7;imaging-desaturate;94;94;95;95;94;94;95;95;94;95 11:44:51 INFO - PROCESS | 21755 | |8;json-parse-financial;51;52;54;51;51;55;52;52;52;52 11:44:51 INFO - PROCESS | 21755 | |9;json-stringify-tinderbox;59;58;59;59;59;58;60;59;60;59 11:44:51 INFO - PROCESS | 21755 | |10;stanford-crypto-aes;76;77;76;79;77;77;82;82;117;77 11:44:51 INFO - PROCESS | 21755 | |11;stanford-crypto-ccm;118;127;127;126;120;119;118;121;124;130 11:44:51 INFO - PROCESS | 21755 | |12;stanford-crypto-pbkdf2;154;154;158;163;156;154;158;159;153;152 11:44:51 INFO - PROCESS | 21755 | |13;stanford-crypto-sha256-iterative;66;61;62;61;62;61;64;62;61;65 11:44:51 INFO - PROCESS | 21755 | __end_tp_report 11:44:51 INFO - PROCESS | 21755 | __start_cc_report 11:44:51 INFO - PROCESS | 21755 | _x_x_mozilla_cycle_collect,1595 11:44:51 INFO - PROCESS | 21755 | __end_cc_report 11:44:51 INFO - PROCESS | 21755 | __startTimestamp1470077090998__endTimestamp 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | ------- Summary: start ------- 11:44:51 INFO - PROCESS | 21755 | Number of tests: 14 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:1.85 (1.7%) stddev-sans-first:1.92 11:44:51 INFO - PROCESS | 21755 | Values: 111.0 106.0 111.0 108.0 112.0 112.0 109.0 110.0 110.0 110.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:137.00 stddev:20.09 (14.7%) stddev-sans-first:20.91 11:44:51 INFO - PROCESS | 21755 | Values: 149.0 121.0 124.0 177.0 125.0 124.0 151.0 125.0 162.0 123.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 11:44:51 INFO - PROCESS | 21755 | Values: 160.0 161.0 162.0 161.0 161.0 161.0 162.0 159.0 160.0 160.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.00 stddev:11.12 (14.6%) stddev-sans-first:11.55 11:44:51 INFO - PROCESS | 21755 | Values: 74.0 73.0 75.0 105.0 76.0 75.0 76.0 76.0 97.0 76.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:19.10 (22.7%) stddev-sans-first:19.75 11:44:51 INFO - PROCESS | 21755 | Values: 84.0 84.0 120.0 84.0 85.0 84.0 124.0 85.0 84.0 127.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 11:44:51 INFO - PROCESS | 21755 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 11:44:51 INFO - PROCESS | 21755 | Values: 145.0 147.0 146.0 145.0 146.0 147.0 146.0 146.0 146.0 146.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 11:44:51 INFO - PROCESS | 21755 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:1.32 (2.5%) stddev-sans-first:1.32 11:44:51 INFO - PROCESS | 21755 | Values: 51.0 52.0 54.0 51.0 51.0 55.0 52.0 52.0 52.0 52.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 11:44:51 INFO - PROCESS | 21755 | Values: 59.0 58.0 59.0 59.0 59.0 58.0 60.0 59.0 60.0 59.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:77.00 stddev:12.50 (16.2%) stddev-sans-first:13.07 11:44:51 INFO - PROCESS | 21755 | Values: 76.0 77.0 76.0 79.0 77.0 77.0 82.0 82.0 117.0 77.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:125.00 stddev:4.35 (3.5%) stddev-sans-first:4.22 11:44:51 INFO - PROCESS | 21755 | Values: 118.0 127.0 127.0 126.0 120.0 119.0 118.0 121.0 124.0 130.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.10 Median:157.00 stddev:3.38 (2.2%) stddev-sans-first:3.50 11:44:51 INFO - PROCESS | 21755 | Values: 154.0 154.0 158.0 163.0 156.0 154.0 158.0 159.0 153.0 152.0 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - PROCESS | 21755 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:1.84 (3.0%) stddev-sans-first:1.45 11:44:51 INFO - PROCESS | 21755 | Values: 66.0 61.0 62.0 61.0 62.0 61.0 64.0 62.0 61.0 65.0 11:44:51 INFO - PROCESS | 21755 | -------- Summary: end -------- 11:44:51 INFO - PROCESS | 21755 | 11:44:51 INFO - TEST-INFO | 21755: exit 0 11:44:51 INFO - TEST-OK | kraken | took 59841ms 11:44:51 INFO - SUITE-END | took 1172s 11:44:52 INFO - Completed test suite (00:19:32) 11:44:52 INFO - javascript benchmark 11:44:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.0, 3449.8620055197775, 3457.4813777933305, 3478.5825700915893, 3492.5634295713035, 5763.827085187446, 5859.150423188635, 5882.0, 5892.587518873675, 5900.793472228467, 5797.507211886995, 5887.970560147204, 5889.175515427833, 5890.057590785475, 5892.764289428418, 5114.462981386957, 5159.561437277817, 5166.0701073806795, 5191.117510023286, 5238.161894096946, 5258.237555554442, 5292.5765938725, 5297.496737809921, 5301.204819277101, 5303.522682958533, 5206.531412172903, 5227.08526007949, 5254.343207099113, 5258.737063146847, 5289.0, 5344.946550534483, 5351.892962140774, 5360.0, 5361.115415956401, 5362.168863826113, 5024.497550244982, 5133.871653208662, 5171.586273098181, 5259.86850328741, 5268.946210757826, 5565.415631358689, 5609.747561359749, 5623.156526521014, 5632.662040277596, 5669.007923613392, 6222.0355844844125, 6225.252969643671, 6228.314885362606, 6230.221222347207, 6232.9376706232815, 6022.09668549721, 6066.827797573043, 6091.329953705089, 6142.907856382158, 6170.84572885677, 5767.452092051249, 5807.2160258365, 5808.215890854723, 5814.622049566765, 5823.475887170175, 6164.568480206342, 6286.937130628727, 6287.811365659038, 6289.213848268875, 6294.307626161119, 6040.09398590215, 6043.576949613484, 6097.207363042776, 6153.7230824613, 6165.938340616626, 6228.626282422979, 6235.220597425322, 6241.7191226394925, 6246.094316324107, 6254.0, 6036.094585812163, 6091.390860913873, 6180.7527698891545, 6185.84535386619, 6203.534734894901, 6116.2871805091745, 6215.254169499689, 6232.626042437378, 6232.750689972441, 6233.812985610439, 4652.232381657056, 4689.906201875943, 4691.601213896857, 4710.9764451177525, 4723.197056500403, 4117.094239267416, 4132.33882578786, 4160.4591403118, 4208.642265407413, 4228.492580890313, 5919.585629005928, 5925.377835327315, 5934.080217566284, 5936.554758393051, 5950.137230101611, 6201.224846894138, 6238.688065596702, 6247.3440288769025, 6252.937470625327, 6259.624422534572, 5782.479576838021, 5802.65184088956, 5809.070548712186, 5825.825225243249, 5912.881742365129, 5295.364556253275, 5298.470152984748, 5301.575873930076, 5302.416734159239, 5306.124489459196, 6425.871482570323, 6425.903611445832, 6429.196350456193, 6430.485561155097, 6433.678316084177, 6067.089936509559, 6105.236845394325, 6120.721420533169, 6141.048137538689, 6241.843953901189, 6467.676616169078, 6497.285298617054, 6509.414152726088, 6511.38141876521, 6514.0880276760345, 6021.397860213943, 6176.042713379434, 6177.1969643945995, 6185.350538193425, 6187.566870319034, 6112.837854638479, 6144.446999770043, 6248.281447633581, 6262.185915830913, 6274.435300822948, 6092.9086063709965, 6095.695215239309, 6096.177016102769, 6099.725512351866, 6102.755889764359, 5922.2597175353085, 6014.849628759316, 6024.865792572235, 6094.421030002142, 6130.63216207029, 5963.701814909324, 6030.638161710312, 6037.78867739627, 6044.33512313654, 6050.152978582913, 6164.969175154096, 6170.290416602148, 6176.474999624902, 6184.041473571604, 6188.226471691039, 5768.365479797303, 5883.058710606282, 5900.468957793819, 6031.4270144336215, 6059.242594675666], "value": 5713.2341562232605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.0866348126958, 654.110409842614, 665.0987911380076, 669.7555392281816, 680.87063457943, 33137.83431082844, 45409.77295113524, 47169.52830471694, 47189.29216061762, 47203.999999999956, 48611.756941215244, 48963.0, 48989.26516102252, 49064.26403603949, 49109.75445122778, 8190.590470476483, 9164.541772911345, 9304.395214311082, 9315.72052838416, 10859.83710244347, 10722.946385268102, 11053.336799791998, 11079.279846809932, 11112.0, 11205.103591712685, 11152.609658661948, 11210.27133236341, 11221.60724374647, 11269.830952535718, 11274.210805243636, 11104.0, 11144.777104457946, 11179.552817887356, 11188.104951603853, 11211.103111751121, 11085.168612354026, 11168.218224724273, 11169.385683787388, 11176.888231117666, 11187.608433704863, 45423.545764542265, 45811.77094114541, 45903.77048114738, 45935.0, 46028.3095753564, 3810.7713537187856, 3827.617238276178, 3944.7633142011573, 4141.295979683431, 4406.806631592533, 35560.0, 35674.28651426957, 35743.64256357429, 36235.09412264688, 36625.63374366249, 4759.690620109682, 4836.758162091881, 4853.369062021915, 4872.340107665553, 4961.751912404401, 362232.0, 364211.0, 364255.0, 364286.0, 366049.0, 65974.67012665002, 66244.66877665678, 66493.33506664968, 66821.0, 66832.66583667148, 40862.59137408647, 41990.37014444786, 42047.57952420437, 43855.12289754187, 43867.341989870176, 19109.80890191108, 19356.0, 19392.515187120152, 19405.0, 19599.804001960085, 12879.42042608085, 13815.930920345334, 14205.36075876588, 14207.218602976729, 14398.856011439751, 21572.137114515244, 21618.13527458884, 21759.129634814748, 21895.452613684465, 21916.0, 7610.467267291237, 7631.427642926803, 7711.0, 7721.343685786771, 7810.570418626917, 1029.4183786160795, 1139.4787958075897, 1213.3993673131679, 1236.6351926181799, 1256.296473974577, 33457.0, 34782.91211803988, 34992.475112873326, 35229.47155792665, 35538.82230588831, 1129.565117429779, 1176.9117316201318, 1181.8699943006263, 1185.2651356159236, 1273.0134146036896, 18137.36519221821, 18380.816191838177, 18492.81507184911, 18638.16128274231, 18724.344647937258, 563254.0, 567344.0, 568110.0, 569538.0, 570380.0, 67556.32443675598, 67593.0, 68040.3195968034, 68175.65912170506, 68269.3173068263, 1378.2281922123643, 1453.4331610672057, 1474.5576327102042, 1488.3823213366547, 1506.4501456968128, 34076.65923340834, 35493.1126721834, 35666.64333356737, 36171.45742813807, 36229.09427264339, 56417.435825641216, 56947.43052569421, 56951.14573281321, 56973.430265696814, 57041.0, 436336.0, 438126.0, 438197.0, 439668.0, 439771.0, 27751.0, 31182.844085779427, 31703.365932681678, 31738.682613173572, 33678.663213368534, 54313.0, 54484.45515544953, 54906.72546637241, 54907.725461372436, 55840.16239756488, 45054.77472612747, 45285.320720189884, 45332.54667453283, 46109.0, 47313.763431182626, 44051.11897762091, 44558.77720611376, 44719.0, 44911.32633010573, 45438.54561454343, 44460.77769611131, 44918.550814491435, 45600.7719961398, 45970.54029459663, 46264.53735462602, 38042.23915521729, 39900.0, 40190.59809401986, 40329.193416130925, 40469.392959106226, 29553.408931821672, 30155.849220753757, 30323.848380757954, 30441.695583043886, 30584.541231881984], "value": 22136.81482172822, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.2043196414937, 413.2891426745996, 423.5002696817755, 424.28296179456714, 436.1016306408798, 19282.710759338603, 20112.396628101145, 20910.89544552277, 21224.0, 21287.574248515022, 19846.40460786175, 20447.795522044777, 20524.486887827774, 20661.79338206621, 20779.480512987182, 5688.772449102031, 5895.498882594985, 5903.675297858616, 5937.495312898409, 5938.732757025944, 4219.451471308726, 5803.854903627422, 5811.0, 6337.651429171417, 6628.270890202074, 6629.403353698166, 6645.33546645334, 6657.700403481855, 6659.267480577133, 6681.064650948871, 6520.967395163041, 6616.470682345401, 6678.465722742169, 6700.329967003309, 6721.6975236114495, 3956.248312820555, 5469.644473109275, 5837.474627283565, 5837.824865254049, 5885.087811389241, 23342.066317347286, 23914.64128038081, 24148.758512414828, 24385.756142438702, 24405.633915491282, 2465.815063870199, 2687.704352521221, 2692.717264687199, 3111.299957509565, 3165.5568220449154, 10911.618093366773, 11015.614453494089, 14366.497172598909, 14512.274386280644, 15146.31841567121, 1959.8530110241788, 2110.883901385423, 2142.924997625076, 2263.2418139923147, 2301.528186721718, 75930.0, 76403.23596764017, 76988.0, 87490.56254718814, 89087.55456222677, 11047.944760276308, 11255.380954047607, 11450.54197832094, 11506.76986460266, 11567.595134170264, 15880.761788572949, 15886.0, 15963.600909977344, 15965.121918294606, 15976.600584985468, 8637.352198585131, 8771.38600297973, 8818.86771698425, 8929.866052009225, 8930.01769805334, 8541.743747687578, 8634.050254472004, 8663.003754568179, 8797.736067917973, 8868.467891926504, 4815.085133824562, 4867.0, 4920.114379411669, 4997.425296090996, 5060.089183946851, 1831.9633607328046, 1838.7299309355035, 1980.5642758593087, 2041.9693704594147, 2138.0, 951.2485136742034, 996.2279233593878, 1014.9644762433279, 1016.9847452288221, 1033.3851358441716, 19715.40853774389, 19801.10895009728, 19814.20743170286, 19853.0, 20088.799112008986, 1025.369397820335, 1039.750459889621, 1047.8323468245044, 1049.4070849969896, 1062.808694435009, 10849.712971875739, 10894.891051089546, 10951.123910087173, 11526.827097593376, 11707.88292117068, 508572.0, 532499.0, 546473.0, 551098.0, 552597.0, 98774.5061274689, 100738.496307518, 102671.0, 104102.47948760208, 104173.47913260537, 1091.345192884279, 1110.3559935237543, 1124.1905827803969, 1141.1270378160714, 1156.2195518025298, 18388.908055459637, 18522.907385463524, 18625.348112815987, 18685.252589896252, 18788.812111878706, 38749.0, 41876.371854421595, 42264.78867605642, 42921.785391072845, 43450.130997379245, 50356.0, 58555.121673174086, 59115.70442147762, 59376.70311648414, 60110.0, 8502.91497085046, 8985.640574376952, 8988.820223595621, 9001.729948101567, 9158.542072896194, 12602.11785175029, 13384.598462046155, 13410.12834165776, 13418.93290533541, 13427.798583021067, 11328.660140195807, 11334.94332528332, 11632.592859249886, 11720.70698232551, 11856.11079169083, 11512.36681982499, 11693.0, 11722.296662199955, 11739.23694959825, 11843.407829608313, 11933.940330298294, 12036.458359373673, 12325.815112773136, 12361.320127393079, 12652.620421387373, 7983.121856595886, 8285.0, 8795.648174073222, 8966.327525435749, 9045.185933265775, 10739.802255676392, 11112.0, 11374.488148033524, 11388.37363944991, 11443.313401195956], "value": 9728.688773598627, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.649684075822, 1615.5153453963808, 1663.2432243329285, 1837.3661086925015, 1954.1792447172195, 8548.059713431525, 9285.117913798194, 9549.045095490465, 9582.904170958296, 9583.424994500318, 9187.908120918788, 9427.010163932791, 9428.905710942889, 9727.902720972788, 9747.463889486075, 2309.930702078936, 2342.367560758594, 2342.672025916373, 2349.3539276698875, 2364.9645255321184, 2430.513897220554, 2436.6954130733657, 2510.3849556858595, 2511.5604769165416, 2513.258588716324, 2464.568700477418, 2481.0, 2483.7640424159677, 2527.166035208377, 2532.328932832801, 2374.4301367671806, 2397.5804234258935, 2409.6867407237037, 2411.5297516984197, 2449.7672721091467, 2316.027268547214, 2339.0760649543504, 2341.391238278043, 2344.4138965258685, 2388.235764555343, 8327.66689332426, 9131.543422828898, 9634.614615415376, 9730.562124704406, 9740.172085372751, 5587.8603034924045, 5835.212246346774, 5851.736671849777, 5851.76592936282, 5859.589828711992, 3529.1177205698577, 3623.6376362363817, 3678.9632103678887, 3715.8699445519546, 3716.3867961786273, 1126.1835169502128, 1130.5082289204222, 1142.9257098288585, 1153.2158132469917, 2380.857148571074, 13497.325133743372, 14096.08375455592, 14136.081154725012, 14157.362918668685, 14159.292035398188, 5151.459096794858, 5152.407473140562, 5357.4106848246665, 5385.946140538545, 5458.5087342139395, 11097.0, 11196.94401527987, 11197.16021298406, 11221.382823944761, 11228.214025018171, 5936.406359364029, 5980.461758441761, 5983.760649573968, 5989.2812862456785, 5993.580449368589, 5164.767585458663, 5245.318108645852, 5254.159334506537, 5254.211868219798, 5582.637128586629, 3858.266929283427, 3863.9227215456094, 3932.6853851691794, 3975.5229372475487, 4009.0, 1073.3559864081644, 1129.2772625519674, 1146.8553804385685, 1155.1105648650491, 1158.9826152607718, 1061.9415932123652, 1085.1915323084352, 1086.6794295682791, 1100.4827730966433, 1112.4660163121746, 6242.843928901813, 6388.968055159695, 6477.773277935344, 6582.835429114215, 6604.1084453599115, 486.1977736734346, 492.33534728116757, 500.5520059546685, 501.79426435161406, 505.59552358113365, 4432.357308190294, 4436.889077773017, 4446.088551846929, 4450.087732014929, 4582.908341833145, 12442.253464792142, 12780.360981950866, 12810.0, 12820.551280705131, 12840.357982100857, 11668.824967625493, 12504.062195335386, 12560.560380386643, 12630.179038362478, 12681.619551413472, 9058.048904864894, 9192.126747958799, 9265.397749146285, 9279.76800579991, 9280.350375473652, 6550.279469258473, 6711.429528490133, 6878.55289406187, 6920.446364290945, 6928.653567321715, 10927.726806829893, 11345.886541134483, 11421.885781142082, 11588.536458541901, 11606.593769218036, 11641.0, 11669.649910502698, 11678.416079196177, 11742.941285293518, 11757.294562326288, 8781.868271976053, 8893.911060889308, 8963.462192268486, 8966.955165224394, 8976.192142707188, 9409.776729025183, 9418.152366287068, 9516.0, 9549.761255968657, 9552.044795520393, 8302.086770455366, 8354.039285482004, 8402.24379805796, 8421.70524031656, 8439.029511606244, 8226.794330141794, 8441.113683063435, 8470.703525376583, 8585.699500517452, 8632.223099921037, 7884.960575197317, 7909.762707118795, 8026.43814932949, 8079.313258373104, 8084.39367047462, 4733.479317275028, 5245.947540524546, 5298.549623282064, 5330.866728331823, 5351.705656188947, 5537.5569954404355, 5600.159976003632, 5757.0788673811985, 5777.8555536111935, 5874.03078492044], "value": 4969.547751032318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.44582391086215, 355.79008385052816, 358.2816453011711, 358.4676755018795, 360.4395165517621, 5550.500454959053, 5624.128260119683, 5645.689487078209, 5654.321481422225, 5691.0, 5567.387587365387, 5585.832425027245, 5589.7205139743055, 5597.63615365002, 5603.635763675358, 3915.531675621642, 4115.382692596112, 4332.1335732853395, 4337.371081193241, 4346.695731298811, 4296.8281268749215, 4308.80610372534, 4321.719088259269, 4326.178026175033, 4340.891477713051, 4119.505659320886, 4278.871633850989, 4318.309070548713, 4327.632151267146, 4336.588024137702, 4185.686073544481, 4298.312270036779, 4300.290452075404, 4324.200022995756, 4331.566843315659, 3827.9808600957094, 3828.5214348206473, 3849.345611246095, 3858.9421158682644, 3880.20455806559, 5363.570914326844, 5363.892722145574, 5366.517013468807, 5388.703621300827, 5401.703591138138, 5014.724190169539, 5027.899442011176, 5031.773570189313, 5039.3196918415915, 5042.697438153723, 5531.917021244684, 5730.255066841326, 5738.368779434259, 5785.537157027427, 5848.210491583667, 4133.834646614132, 4142.979285103585, 4166.291730405838, 4260.2970509866145, 4279.54495471538, 5918.585699001072, 6045.274567051938, 6046.27444706638, 6078.483328917047, 6080.145555605539, 4143.316352801814, 4328.61042506176, 4342.392065110887, 4352.564743525622, 4357.237483440385, 4335.59374547123, 4379.562043795659, 4390.714603550759, 4391.494978077562, 4404.097160082176, 4970.577500912391, 5012.749362531932, 5032.220005899091, 5033.370828646419, 5035.874103147451, 5744.597878148573, 5779.768809247667, 5853.180554722342, 5866.0724991007755, 5883.823485295448, 1459.9489017884323, 1480.370842391979, 1493.2608358862215, 1499.7600383938518, 1501.527018989015, 929.9581518831669, 952.8665986761858, 955.8566215067796, 957.4919501784717, 961.2358175250693, 4949.876253093701, 5087.949120508822, 5098.796048158032, 5104.897902041938, 5111.897762044739, 5347.598930080259, 5351.973240133774, 5355.0, 5426.8371948841595, 5447.373552041566, 4842.4020073375705, 5064.151584671642, 5091.287219789232, 5108.514691104339, 5189.481051894856, 2841.602175695404, 2859.8999035033676, 2864.770818334528, 2866.441043996401, 2900.8984685536325, 5506.256655351476, 5519.172124181405, 5532.585056120807, 5552.833904880021, 5564.944350556442, 5422.349318081855, 5707.800226992035, 6204.448887778056, 6211.9689401552705, 6215.906761398583, 5988.940110598838, 6146.969265153735, 6279.340669229757, 6295.874082518416, 6337.778177763848, 6083.117947897618, 6083.878322433438, 6102.877942441038, 6109.816705498843, 6121.602095863755, 5728.427157284238, 5983.311919129182, 5984.102384642164, 5994.550408719277, 5994.610350327215, 6255.46828519581, 6257.217847769029, 6257.874842503215, 6301.243850737941, 6313.2424109107205, 4255.765827909871, 4259.61663450291, 4289.485261768607, 4301.161273551627, 4317.913641727211, 5870.765169393177, 6079.483243924316, 6086.360932102242, 6088.969555152196, 6096.506355942823, 5893.703385255237, 5908.2231351266555, 5909.6158749681135, 5966.343702192842, 5968.641881487125, 5821.970890145522, 5947.899638566879, 5965.582409231312, 5978.163057172086, 5981.996031329553, 5772.509336706259, 5825.155352473952, 5837.824865254049, 5848.970755146197, 5882.82327543139, 5850.391142435864, 5920.046384693127, 5947.286325640951, 5967.671778052249, 6010.4891084258325, 5666.320041595035, 5752.008955343282, 5828.76684932598, 5842.0, 5851.707414629166], "value": 4402.232379914628, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.3186059988409, 801.535109636411, 816.9591520423985, 853.5604163855616, 867.8784970104184, 15125.546233612995, 16291.103989280586, 16346.0, 16626.418075367365, 17194.39819606314, 15929.522114336589, 15955.680886382266, 16076.27656755446, 16078.59803504913, 16093.517194484155, 731.381982225019, 878.3587980774039, 881.5548148185158, 901.9909800902005, 904.2720609909021, 897.0894542039835, 897.5422534507383, 900.0279697926256, 901.134910485931, 901.197933838884, 947.6209516193543, 948.4404201521102, 949.7815502434444, 949.8670186173906, 949.8860136783561, 768.7078910014187, 909.9135582119721, 920.6179435534245, 931.1619542411815, 932.1098351074742, 683.9042534045237, 683.9794806155824, 684.2575805251308, 684.5892464521247, 684.852756657321, 15114.773278400833, 15175.696486070217, 15182.696346073128, 15205.467808626645, 15210.315535800915, 13951.372188251553, 14212.431502739879, 14351.569452916428, 14403.063800852911, 14755.55733328002, 13115.147515411563, 13169.617190194987, 13527.864721352758, 13533.255670938095, 13663.316834158253, 4655.301704744282, 4700.623950083985, 4759.238521836524, 4761.666683332168, 4801.7118972861745, 80262.19737802664, 80871.0, 82079.76880346739, 82255.58872205661, 82499.0, 12282.32447215394, 12402.503899843905, 12411.255324680367, 12559.497620095277, 12641.051921105955, 22214.888925555268, 22620.660690089662, 22655.773442265698, 22670.206542771255, 22977.88511057434, 8316.584170791437, 8335.416520843604, 8636.352273579507, 8733.257673097858, 8771.649134034567, 4724.811007559728, 4806.8077276909235, 4834.395700537433, 4887.120318342661, 4898.730569818694, 3728.0, 3837.4052021936086, 3844.231153769257, 4131.876043718693, 4313.762743049162, 662.0631805994464, 708.9929100708927, 748.046241042675, 766.3256334425781, 783.5572901310741, 653.7875190563086, 708.7271400510738, 709.1950636028042, 725.5102805606194, 730.9013283206806, 9596.66411675588, 9987.850182247272, 10007.09936105754, 10082.243831712503, 10116.140128089195, 669.7086767256259, 682.3585829320423, 719.70851805018, 725.74598890389, 730.8867125595541, 4448.221561226773, 4628.492134149686, 4721.7403042832975, 4737.360456338419, 4778.737169455713, 121646.0, 127211.36394317969, 129731.0, 133334.0, 134119.0, 108622.45688771506, 109848.4507577457, 111599.0, 112068.0, 113420.43289783498, 95962.52018739862, 97619.51190244146, 98176.01823981812, 98270.50864745774, 100758.0, 8817.735467935972, 8855.822883542422, 9178.908210917805, 9185.494797786187, 9204.217641500547, 45716.77141614271, 45802.77098614619, 48282.5171748278, 50515.2422713667, 57942.4205757937, 52255.73872130615, 52265.0, 58191.418085820296, 72569.0, 74228.25771742214, 12283.938580307042, 12367.62897113088, 12573.497060117494, 12641.304728240035, 12791.232526048467, 13703.246321452416, 13763.793543097061, 13986.300684965507, 14056.508022219174, 14281.14313141215, 12603.684907877376, 12776.872231277568, 12815.935920320711, 13231.206127632373, 13668.0, 12752.4261408235, 12909.0, 13201.60395188146, 13498.055136140376, 13518.121322114031, 12668.303243321705, 12784.808227876403, 12858.0999330046, 13073.607791766262, 13377.598672039854, 10460.63387781424, 10573.0, 10605.840912386475, 10655.627053053417, 10958.452077396258, 11291.322520648788, 11471.381480371858, 11623.12826537996, 11695.0, 11740.0], "value": 7847.36358920019, "unit": "score"}], "name": "dromaeo_css", "value": 7717.349679498579, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 111.0, 108.0, 112.0, 112.0, 109.0, 110.0, 110.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 124.0, 177.0, 125.0, 124.0, 151.0, 125.0, 162.0, 123.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 161.0, 161.0, 161.0, 162.0, 159.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 105.0, 76.0, 75.0, 76.0, 76.0, 97.0, 76.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 120.0, 84.0, 85.0, 84.0, 124.0, 85.0, 84.0, 127.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 54.0, 51.0, 51.0, 55.0, 52.0, 52.0, 52.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 60.0, 59.0, 60.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 79.0, 77.0, 77.0, 82.0, 82.0, 117.0, 77.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 127.0, 127.0, 126.0, 120.0, 119.0, 118.0, 121.0, 124.0, 130.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 158.0, 163.0, 156.0, 154.0, 158.0, 159.0, 153.0, 152.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 62.0, 61.0, 62.0, 61.0, 64.0, 62.0, 61.0, 65.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1476.9, "alertThreshold": 2.0}]} 11:44:52 INFO - Return code: 0 11:44:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:44:52 ERROR - # TBPL SUCCESS # 11:44:52 INFO - Running post-action listener: _resource_record_post_action 11:44:52 INFO - ##### 11:44:52 INFO - ##### Finished run-tests step (success) 11:44:52 INFO - ##### 11:44:52 INFO - Running post-run listener: _resource_record_post_run 11:44:52 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 457531392; Read time: 0; Write time: 288768 11:44:52 INFO - TinderboxPrint: CPU usage
13.1% 11:44:52 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:44:52 INFO - TinderboxPrint: I/O write bytes / time
457,531,392 / 288,768 11:44:52 INFO - TinderboxPrint: CPU idle
8,237.2 (86.9%) 11:44:52 INFO - TinderboxPrint: CPU user
1,196.1 (12.6%) 11:44:52 INFO - TinderboxPrint: Swap in / out
0 / 0 11:44:52 INFO - install - Wall time: 13s; CPU: 13.0%; Read bytes: 0; Write bytes: 167563264; Read time: 0; Write time: 139616 11:44:52 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 222056448; Read time: 0; Write time: 119440 11:44:52 INFO - Running post-run listener: _upload_blobber_files 11:44:52 INFO - Blob upload gear active. 11:44:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:44:52 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 11:44:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:44:53 INFO - (blobuploader) - INFO - Open directory for files ... 11:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:44:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:57 INFO - (blobuploader) - INFO - Done attempting. 11:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:57 INFO - (blobuploader) - INFO - Done attempting. 11:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:44:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:58 INFO - (blobuploader) - INFO - Done attempting. 11:44:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:44:58 INFO - Return code: 0 11:44:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:44:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:44:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f1a190ccd2c2fe2fea6e4fbe5caa2a4dc4cd45a2d4ca02b46196e0fddaf3ee23fc406bf9609ca1c57935ac2dbe3e9625c2ea6f057e68c1537cfecf506fb3086", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b30e889393dbd9fb54f4b69b16ed79acb93543babafef49f5b508101b87d4c51e6a7ef81b4084d1961a792d25ccd02b8969314aba638e2485f0d40ee173e4b5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a093933ddb62e9fce36250acd74026cb5f73942c494170c041c9e9afe36108317a2d1325eecd25e5691c2c93a1b927404f4389445ff4c3b74769ccadbc742fa"} 11:44:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:44:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:44:58 INFO - Contents: 11:44:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f1a190ccd2c2fe2fea6e4fbe5caa2a4dc4cd45a2d4ca02b46196e0fddaf3ee23fc406bf9609ca1c57935ac2dbe3e9625c2ea6f057e68c1537cfecf506fb3086", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b30e889393dbd9fb54f4b69b16ed79acb93543babafef49f5b508101b87d4c51e6a7ef81b4084d1961a792d25ccd02b8969314aba638e2485f0d40ee173e4b5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a093933ddb62e9fce36250acd74026cb5f73942c494170c041c9e9afe36108317a2d1325eecd25e5691c2c93a1b927404f4389445ff4c3b74769ccadbc742fa"} 11:44:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:44:58 INFO - Copying logs to upload dir... 11:44:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:44:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.408836 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-01 11:44:58.070409) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 11:44:58.074316) ========= 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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f1a190ccd2c2fe2fea6e4fbe5caa2a4dc4cd45a2d4ca02b46196e0fddaf3ee23fc406bf9609ca1c57935ac2dbe3e9625c2ea6f057e68c1537cfecf506fb3086", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b30e889393dbd9fb54f4b69b16ed79acb93543babafef49f5b508101b87d4c51e6a7ef81b4084d1961a792d25ccd02b8969314aba638e2485f0d40ee173e4b5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a093933ddb62e9fce36250acd74026cb5f73942c494170c041c9e9afe36108317a2d1325eecd25e5691c2c93a1b927404f4389445ff4c3b74769ccadbc742fa"} build_url:https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011313 build_url: 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f1a190ccd2c2fe2fea6e4fbe5caa2a4dc4cd45a2d4ca02b46196e0fddaf3ee23fc406bf9609ca1c57935ac2dbe3e9625c2ea6f057e68c1537cfecf506fb3086", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b30e889393dbd9fb54f4b69b16ed79acb93543babafef49f5b508101b87d4c51e6a7ef81b4084d1961a792d25ccd02b8969314aba638e2485f0d40ee173e4b5a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a093933ddb62e9fce36250acd74026cb5f73942c494170c041c9e9afe36108317a2d1325eecd25e5691c2c93a1b927404f4389445ff4c3b74769ccadbc742fa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 11:44:58.098728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:44:58.099028) ========= 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=41a7e32711b8e08598a63237000001d6-1470074786.729266-1524995500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:44:58.182609) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 11:44:58.183430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 11:44:58.183719) ========= ========= Total master_lag: 0.21 =========