builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1491755615.63 results: success (0) revision: efca7a198376e7800873e4612a8b34fc77022d4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.631185) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.631690) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.631971) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.650999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.651413) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-09 09:33:35-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-04-09 09:33:35 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134071 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.802367) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.802723) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021044 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.876042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 09:33:35.876474) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efca7a198376e7800873e4612a8b34fc77022d4d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efca7a198376e7800873e4612a8b34fc77022d4d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-09 09:33:35,997 truncating revision to first 12 chars 2017-04-09 09:33:35,997 Setting DEBUG logging. 2017-04-09 09:33:35,997 attempt 1/10 2017-04-09 09:33:35,997 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/efca7a198376?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-09 09:33:36,958 unpacking tar archive at: mozilla-aurora-efca7a198376/testing/mozharness/ program finished with exit code 0 elapsedTime=1.264610 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 09:33:37.165411) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:37.165773) ========= script_repo_revision: efca7a198376e7800873e4612a8b34fc77022d4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:37.166226) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:37.166547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:37.176442) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-04-09 09:33:37.176770) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:37 INFO - MultiFileLogger online at 20170409 09:33:37 in /builds/slave/test 09:33:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:33:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:37 INFO - {'append_to_log': False, 09:33:37 INFO - 'base_work_dir': '/builds/slave/test', 09:33:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:33:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:37 INFO - 'branch': 'Mozilla-Aurora', 09:33:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:37 INFO - 'config_files': ('talos/linux_config.py',), 09:33:37 INFO - 'default_actions': ('clobber', 09:33:37 INFO - 'read-buildbot-config', 09:33:37 INFO - 'download-and-extract', 09:33:37 INFO - 'populate-webroot', 09:33:37 INFO - 'create-virtualenv', 09:33:37 INFO - 'install', 09:33:37 INFO - 'run-tests'), 09:33:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:37 INFO - 'download_minidump_stackwalk': True, 09:33:37 INFO - 'download_symbols': 'ondemand', 09:33:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:37 INFO - 'gecko_profile': False, 09:33:37 INFO - 'gecko_profile_interval': 0, 09:33:37 INFO - 'installer_path': 'installer.exe', 09:33:37 INFO - 'log_level': 'info', 09:33:37 INFO - 'log_name': 'talos', 09:33:37 INFO - 'log_to_console': True, 09:33:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:37 INFO - 'opt_config_files': (), 09:33:37 INFO - 'pip_index': False, 09:33:37 INFO - 'suite': 'dromaeojs', 09:33:37 INFO - 'system_bits': '32', 09:33:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:37 INFO - 'title': 'talos-linux64-ix-040', 09:33:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:37 INFO - 'use_talos_json': True, 09:33:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:37 INFO - 'work_dir': 'build'} 09:33:37 INFO - [mozharness: 2017-04-09 16:33:37.392597Z] Running clobber step. 09:33:37 INFO - Running pre-action listener: _resource_record_pre_action 09:33:37 INFO - Running main action method: clobber 09:33:37 INFO - rmtree: /builds/slave/test/build 09:33:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:38 INFO - Running post-action listener: _resource_record_post_action 09:33:38 INFO - [mozharness: 2017-04-09 16:33:38.300214Z] Finished clobber step (success) 09:33:38 INFO - [mozharness: 2017-04-09 16:33:38.300313Z] Running read-buildbot-config step. 09:33:38 INFO - Running pre-action listener: _resource_record_pre_action 09:33:38 INFO - Running main action method: read_buildbot_config 09:33:38 INFO - Using buildbot properties: 09:33:38 INFO - { 09:33:38 INFO - "project": "", 09:33:38 INFO - "product": "firefox", 09:33:38 INFO - "who": "ffxbld@noreply.mozilla.org", 09:33:38 INFO - "installer_path": "public/build/target.tar.bz2", 09:33:38 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:33:38 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:33:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:33:38 INFO - "stage_platform": "linux64-pgo", 09:33:38 INFO - "basedir": "/builds/slave/test", 09:33:38 INFO - "buildnumber": 1149, 09:33:38 INFO - "platform": "ubuntu64_hw", 09:33:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:33:38 INFO - "slavebuilddir": "test", 09:33:38 INFO - "taskId": "ZCS-BdSlTV6zEep_9INI-Q", 09:33:38 INFO - "branch": "mozilla-aurora", 09:33:38 INFO - "script_repo_revision": "production", 09:33:38 INFO - "revision": "efca7a198376e7800873e4612a8b34fc77022d4d", 09:33:38 INFO - "slavename": "talos-linux64-ix-040", 09:33:38 INFO - "repo_path": "releases/mozilla-aurora" 09:33:38 INFO - } 09:33:38 INFO - Finding installer, test and symbols from parent task. 09:33:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZCS-BdSlTV6zEep_9INI-Q'}, attempt #1 09:33:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZCS-BdSlTV6zEep_9INI-Q'}, attempt #1 09:33:39 INFO - Task dependencies: Cjf6pe65RV-94D_L70RxiQ 09:33:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ'}, attempt #1 09:33:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 09:33:39 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/test_packages.json 09:33:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.crashreporter-symbols.zip 09:33:39 INFO - Running post-action listener: _resource_record_post_action 09:33:39 INFO - [mozharness: 2017-04-09 16:33:39.671494Z] Finished read-buildbot-config step (success) 09:33:39 INFO - [mozharness: 2017-04-09 16:33:39.671711Z] Running download-and-extract step. 09:33:39 INFO - Running pre-action listener: _resource_record_pre_action 09:33:39 INFO - Running main action method: download_and_extract 09:33:39 INFO - mkdir: /builds/slave/test/build/tests 09:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:39 INFO - trying https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.test_packages.json 09:33:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:33:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:33:40 INFO - Downloaded 896 bytes. 09:33:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:33:40 INFO - Using the following test package requirements: 09:33:40 INFO - {u'common': [u'target.common.tests.zip'], 09:33:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:33:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:33:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:33:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:33:40 INFO - u'mozbase': [u'target.common.tests.zip'], 09:33:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:33:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:33:40 INFO - u'web-platform': [u'target.common.tests.zip', 09:33:40 INFO - u'target.web-platform.tests.zip'], 09:33:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:33:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:33:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip 09:33:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:33:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip into memory 09:33:41 INFO - Expected file size: 59547355 09:33:41 INFO - Obtained file size: 59547355 09:33:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:33:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip 09:33:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:33:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.common.tests.zip into memory 09:33:52 INFO - Expected file size: 59547355 09:33:52 INFO - Obtained file size: 59547355 09:34:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.talos.tests.zip 09:34:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:34:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.talos.tests.zip into memory 09:34:04 INFO - Expected file size: 14404424 09:34:04 INFO - Obtained file size: 14404424 09:34:04 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:04 INFO - trying https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 09:34:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:34:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:34:06 INFO - Downloaded 66705267 bytes. 09:34:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 09:34:06 INFO - mkdir: /builds/slave/test/properties 09:34:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:06 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:06 INFO - Contents: 09:34:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 09:34:06 INFO - Running post-action listener: _resource_record_post_action 09:34:06 INFO - Running post-action listener: set_extra_try_arguments 09:34:06 INFO - [mozharness: 2017-04-09 16:34:06.330921Z] Finished download-and-extract step (success) 09:34:06 INFO - [mozharness: 2017-04-09 16:34:06.331048Z] Running populate-webroot step. 09:34:06 INFO - Running pre-action listener: _resource_record_pre_action 09:34:06 INFO - Running main action method: populate_webroot 09:34:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:06 INFO - '--tppagecycles', 09:34:06 INFO - '7'], 09:34:06 INFO - 'tests': ['tsvgm']}}, 09:34:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['damp', 'tps']}, 09:34:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'tests': ['damp', 'tps']}, 09:34:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['dromaeo_dom']}, 09:34:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['basic_compositor_video']}, 09:34:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['a11yr', 09:34:06 INFO - 'ts_paint', 09:34:06 INFO - 'tpaint', 09:34:06 INFO - 'sessionrestore', 09:34:06 INFO - 'sessionrestore_no_auto_restore', 09:34:06 INFO - 'tabpaint']}, 09:34:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['tsvgx', 09:34:06 INFO - 'tsvgr_opacity', 09:34:06 INFO - 'tart', 09:34:06 INFO - 'tscrollx', 09:34:06 INFO - 'cart', 09:34:06 INFO - 'tsvg_static']}, 09:34:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:06 INFO - 'tsvgr_opacity', 09:34:06 INFO - 'tart', 09:34:06 INFO - 'tscrollx', 09:34:06 INFO - 'cart', 09:34:06 INFO - 'tsvg_static']}, 09:34:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'talos_options': ['--disable-e10s'], 09:34:06 INFO - 'tests': ['tp5o']}, 09:34:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:34:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'talos_options': ['--disable-e10s', 09:34:06 INFO - '--xperf_path', 09:34:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:06 INFO - 'tests': ['tp5n']}, 09:34:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:34:06 INFO - 'talos_options': ['--xperf_path', 09:34:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:06 INFO - 'tests': ['tp5n']}}, 09:34:06 INFO - 'talos.zip': {'path': '', 09:34:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:06 INFO - Running post-action listener: _resource_record_post_action 09:34:06 INFO - [mozharness: 2017-04-09 16:34:06.348052Z] Finished populate-webroot step (success) 09:34:06 INFO - [mozharness: 2017-04-09 16:34:06.348141Z] Running create-virtualenv step. 09:34:06 INFO - Running pre-action listener: _resource_record_pre_action 09:34:06 INFO - Running main action method: create_virtualenv 09:34:06 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:06 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 09:34:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:06 INFO - Using real prefix '/usr' 09:34:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:08 INFO - Installing pip.................done. 09:34:08 INFO - Return code: 0 09:34:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:34:09 INFO - Reading from file tmpfile_stdout 09:34:09 INFO - Output received: 09:34:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:34:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:09 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:09 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 09:34:09 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 09:34:09 INFO - Using env: {'DISPLAY': ':0', 09:34:09 INFO - 'HOME': '/home/cltbld', 09:34:09 INFO - 'LANG': 'en_US.UTF-8', 09:34:09 INFO - 'LANGUAGE': 'en_US:en', 09:34:09 INFO - 'LOGNAME': 'cltbld', 09:34:09 INFO - 'MAIL': '/var/mail/cltbld', 09:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:09 INFO - 'MOZ_NO_REMOTE': '1', 09:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:09 INFO - 'NO_EM_RESTART': '1', 09:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:09 INFO - 'PWD': '/builds/slave/test', 09:34:09 INFO - 'SHELL': '/bin/bash', 09:34:09 INFO - 'SHLVL': '1', 09:34:09 INFO - 'TERM': 'linux', 09:34:09 INFO - 'TMOUT': '86400', 09:34:09 INFO - 'USER': 'cltbld', 09:34:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:09 INFO - '_': '/tools/buildbot/bin/python'} 09:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:09 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 09:34:09 INFO - Cleaning up... 09:34:09 INFO - Return code: 0 09:34:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:09 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:09 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 09:34:09 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 09:34:09 INFO - Using env: {'DISPLAY': ':0', 09:34:09 INFO - 'HOME': '/home/cltbld', 09:34:09 INFO - 'LANG': 'en_US.UTF-8', 09:34:09 INFO - 'LANGUAGE': 'en_US:en', 09:34:09 INFO - 'LOGNAME': 'cltbld', 09:34:09 INFO - 'MAIL': '/var/mail/cltbld', 09:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:09 INFO - 'MOZ_NO_REMOTE': '1', 09:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:09 INFO - 'NO_EM_RESTART': '1', 09:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:09 INFO - 'PWD': '/builds/slave/test', 09:34:09 INFO - 'SHELL': '/bin/bash', 09:34:09 INFO - 'SHLVL': '1', 09:34:09 INFO - 'TERM': 'linux', 09:34:09 INFO - 'TMOUT': '86400', 09:34:09 INFO - 'USER': 'cltbld', 09:34:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:09 INFO - '_': '/tools/buildbot/bin/python'} 09:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:10 INFO - Downloading/unpacking psutil>=3.1.1 09:34:10 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 09:34:10 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 09:34:10 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 09:34:10 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 09:34:10 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 09:34:10 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 09:34:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:13 INFO - Installing collected packages: psutil 09:34:13 INFO - Running setup.py install for psutil 09:34:13 INFO - building 'psutil._psutil_linux' extension 09:34:13 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 09:34:13 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 09:34:13 INFO - building 'psutil._psutil_posix' extension 09:34:13 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 09:34:13 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 09:34:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:13 INFO - Successfully installed psutil 09:34:13 INFO - Cleaning up... 09:34:13 INFO - Return code: 0 09:34:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:14 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:14 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 09:34:14 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 09:34:14 INFO - Using env: {'DISPLAY': ':0', 09:34:14 INFO - 'HOME': '/home/cltbld', 09:34:14 INFO - 'LANG': 'en_US.UTF-8', 09:34:14 INFO - 'LANGUAGE': 'en_US:en', 09:34:14 INFO - 'LOGNAME': 'cltbld', 09:34:14 INFO - 'MAIL': '/var/mail/cltbld', 09:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:14 INFO - 'MOZ_NO_REMOTE': '1', 09:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:14 INFO - 'NO_EM_RESTART': '1', 09:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:14 INFO - 'PWD': '/builds/slave/test', 09:34:14 INFO - 'SHELL': '/bin/bash', 09:34:14 INFO - 'SHLVL': '1', 09:34:14 INFO - 'TERM': 'linux', 09:34:14 INFO - 'TMOUT': '86400', 09:34:14 INFO - 'USER': 'cltbld', 09:34:14 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:14 INFO - '_': '/tools/buildbot/bin/python'} 09:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:14 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 09:34:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:17 INFO - Installing collected packages: mozsystemmonitor 09:34:17 INFO - Running setup.py install for mozsystemmonitor 09:34:17 INFO - Successfully installed mozsystemmonitor 09:34:17 INFO - Cleaning up... 09:34:17 INFO - Return code: 0 09:34:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:17 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:17 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 09:34:17 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 09:34:17 INFO - Using env: {'DISPLAY': ':0', 09:34:17 INFO - 'HOME': '/home/cltbld', 09:34:17 INFO - 'LANG': 'en_US.UTF-8', 09:34:17 INFO - 'LANGUAGE': 'en_US:en', 09:34:17 INFO - 'LOGNAME': 'cltbld', 09:34:17 INFO - 'MAIL': '/var/mail/cltbld', 09:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:17 INFO - 'MOZ_NO_REMOTE': '1', 09:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:17 INFO - 'NO_EM_RESTART': '1', 09:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:17 INFO - 'PWD': '/builds/slave/test', 09:34:17 INFO - 'SHELL': '/bin/bash', 09:34:17 INFO - 'SHLVL': '1', 09:34:17 INFO - 'TERM': 'linux', 09:34:17 INFO - 'TMOUT': '86400', 09:34:17 INFO - 'USER': 'cltbld', 09:34:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:17 INFO - '_': '/tools/buildbot/bin/python'} 09:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:17 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 09:34:17 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 09:34:17 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 09:34:17 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 09:34:17 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 09:34:17 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 09:34:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 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 09:34:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:20 INFO - warning: no files found matching '*.txt' 09:34:20 INFO - no previously-included directories found matching 'build' 09:34:20 INFO - no previously-included directories found matching 'dist' 09:34:20 INFO - no previously-included directories found matching '.git*' 09:34:20 INFO - Installing collected packages: jsonschema, functools32 09:34:20 INFO - Running setup.py install for functools32 09:34:20 INFO - warning: no files found matching '*.txt' 09:34:20 INFO - no previously-included directories found matching 'build' 09:34:20 INFO - no previously-included directories found matching 'dist' 09:34:20 INFO - no previously-included directories found matching '.git*' 09:34:20 INFO - Successfully installed jsonschema functools32 09:34:20 INFO - Cleaning up... 09:34:20 INFO - Return code: 0 09:34:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:20 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:20 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:34:20 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 functools32==3.2.3-2 09:34:20 INFO - Using env: {'DISPLAY': ':0', 09:34:20 INFO - 'HOME': '/home/cltbld', 09:34:20 INFO - 'LANG': 'en_US.UTF-8', 09:34:20 INFO - 'LANGUAGE': 'en_US:en', 09:34:20 INFO - 'LOGNAME': 'cltbld', 09:34:20 INFO - 'MAIL': '/var/mail/cltbld', 09:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:20 INFO - 'MOZ_NO_REMOTE': '1', 09:34:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:20 INFO - 'NO_EM_RESTART': '1', 09:34:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:20 INFO - 'PWD': '/builds/slave/test', 09:34:20 INFO - 'SHELL': '/bin/bash', 09:34:20 INFO - 'SHLVL': '1', 09:34:20 INFO - 'TERM': 'linux', 09:34:20 INFO - 'TMOUT': '86400', 09:34:20 INFO - 'USER': 'cltbld', 09:34:20 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:20 INFO - '_': '/tools/buildbot/bin/python'} 09:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:21 INFO - Cleaning up... 09:34:21 INFO - Return code: 0 09:34:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:34:21 INFO - Using env: {'DISPLAY': ':0', 09:34:21 INFO - 'HOME': '/home/cltbld', 09:34:21 INFO - 'LANG': 'en_US.UTF-8', 09:34:21 INFO - 'LANGUAGE': 'en_US:en', 09:34:21 INFO - 'LOGNAME': 'cltbld', 09:34:21 INFO - 'MAIL': '/var/mail/cltbld', 09:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:21 INFO - 'MOZ_NO_REMOTE': '1', 09:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:21 INFO - 'NO_EM_RESTART': '1', 09:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:21 INFO - 'PWD': '/builds/slave/test', 09:34:21 INFO - 'SHELL': '/bin/bash', 09:34:21 INFO - 'SHLVL': '1', 09:34:21 INFO - 'TERM': 'linux', 09:34:21 INFO - 'TMOUT': '86400', 09:34:21 INFO - 'USER': 'cltbld', 09:34:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:21 INFO - '_': '/tools/buildbot/bin/python'} 09:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:23 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 09:34:24 INFO - Downloading docopt-0.6.1.tar.gz 09:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:24 INFO - Running setup.py install for blobuploader 09:34:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:24 INFO - Running setup.py install for requests 09:34:24 INFO - Running setup.py install for docopt 09:34:24 INFO - Successfully installed blobuploader requests docopt 09:34:24 INFO - Cleaning up... 09:34:24 INFO - Return code: 0 09:34:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:24 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:24 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 09:34:24 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 09:34:24 INFO - Using env: {'DISPLAY': ':0', 09:34:24 INFO - 'HOME': '/home/cltbld', 09:34:24 INFO - 'LANG': 'en_US.UTF-8', 09:34:24 INFO - 'LANGUAGE': 'en_US:en', 09:34:24 INFO - 'LOGNAME': 'cltbld', 09:34:24 INFO - 'MAIL': '/var/mail/cltbld', 09:34:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:24 INFO - 'MOZ_NO_REMOTE': '1', 09:34:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:24 INFO - 'NO_EM_RESTART': '1', 09:34:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:24 INFO - 'PWD': '/builds/slave/test', 09:34:24 INFO - 'SHELL': '/bin/bash', 09:34:24 INFO - 'SHLVL': '1', 09:34:24 INFO - 'TERM': 'linux', 09:34:24 INFO - 'TMOUT': '86400', 09:34:24 INFO - 'USER': 'cltbld', 09:34:24 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:24 INFO - '_': '/tools/buildbot/bin/python'} 09:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:25 INFO - Running setup.py (path:/tmp/pip-eLkJ_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:25 INFO - Running setup.py (path:/tmp/pip-giRndC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:25 INFO - Running setup.py (path:/tmp/pip-pNJjTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:25 INFO - Running setup.py (path:/tmp/pip-2du5YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:25 INFO - Running setup.py (path:/tmp/pip-Nq69WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:25 INFO - Running setup.py (path:/tmp/pip-uQWXBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:25 INFO - Running setup.py (path:/tmp/pip-DhO02B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:25 INFO - Running setup.py (path:/tmp/pip-aL2SJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:25 INFO - Running setup.py (path:/tmp/pip-_fCSvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:25 INFO - Running setup.py (path:/tmp/pip-hW78Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:25 INFO - Running setup.py (path:/tmp/pip-1NL9ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:25 INFO - Running setup.py (path:/tmp/pip-mfmTx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:26 INFO - Running setup.py (path:/tmp/pip-nLyayJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:26 INFO - Running setup.py (path:/tmp/pip-413nyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:26 INFO - Running setup.py (path:/tmp/pip-xAIk_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:26 INFO - Running setup.py (path:/tmp/pip-c1c7mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:26 INFO - Running setup.py (path:/tmp/pip-GD9u5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:26 INFO - Running setup.py install for manifestparser 09:34:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:26 INFO - Running setup.py install for mozcrash 09:34:26 INFO - Running setup.py install for mozdebug 09:34:26 INFO - Running setup.py install for mozdevice 09:34:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:26 INFO - Running setup.py install for mozfile 09:34:26 INFO - Running setup.py install for mozhttpd 09:34:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozinfo 09:34:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozInstall 09:34:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozleak 09:34:27 INFO - Running setup.py install for mozlog 09:34:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for moznetwork 09:34:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozprocess 09:34:27 INFO - Running setup.py install for mozprofile 09:34:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozrunner 09:34:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:27 INFO - Running setup.py install for mozscreenshot 09:34:27 INFO - Running setup.py install for moztest 09:34:28 INFO - Running setup.py install for mozversion 09:34:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:28 INFO - Cleaning up... 09:34:28 INFO - Return code: 0 09:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:28 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:28 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 09:34:28 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 09:34:28 INFO - Using env: {'DISPLAY': ':0', 09:34:28 INFO - 'HOME': '/home/cltbld', 09:34:28 INFO - 'LANG': 'en_US.UTF-8', 09:34:28 INFO - 'LANGUAGE': 'en_US:en', 09:34:28 INFO - 'LOGNAME': 'cltbld', 09:34:28 INFO - 'MAIL': '/var/mail/cltbld', 09:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:28 INFO - 'MOZ_NO_REMOTE': '1', 09:34:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:28 INFO - 'NO_EM_RESTART': '1', 09:34:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:28 INFO - 'PWD': '/builds/slave/test', 09:34:28 INFO - 'SHELL': '/bin/bash', 09:34:28 INFO - 'SHLVL': '1', 09:34:28 INFO - 'TERM': 'linux', 09:34:28 INFO - 'TMOUT': '86400', 09:34:28 INFO - 'USER': 'cltbld', 09:34:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:28 INFO - '_': '/tools/buildbot/bin/python'} 09:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:28 INFO - Running setup.py (path:/tmp/pip-i0P4t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:28 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:28 INFO - Running setup.py (path:/tmp/pip-7rQSFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:28 INFO - Running setup.py (path:/tmp/pip-VOBfz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:28 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:28 INFO - Running setup.py (path:/tmp/pip-89qi1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:28 INFO - Running setup.py (path:/tmp/pip-Ni7TdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:28 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:28 INFO - Running setup.py (path:/tmp/pip-Ijonu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:28 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)) 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:28 INFO - Running setup.py (path:/tmp/pip-SR15rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:29 INFO - Running setup.py (path:/tmp/pip-oeTAIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:29 INFO - Running setup.py (path:/tmp/pip-sOAK3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:29 INFO - Running setup.py (path:/tmp/pip-Smo6PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:29 INFO - Running setup.py (path:/tmp/pip-bvHbSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:29 INFO - Running setup.py (path:/tmp/pip-CYUvcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:29 INFO - Running setup.py (path:/tmp/pip-98d4oE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:29 INFO - Running setup.py (path:/tmp/pip-Bucgsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:29 INFO - Running setup.py (path:/tmp/pip-Vt3k2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:29 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:29 INFO - Running setup.py (path:/tmp/pip-7pnTTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:29 INFO - Running setup.py (path:/tmp/pip-UK95ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:29 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)) 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:29 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)) 09:34:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:31 INFO - Downloading blessings-1.6.tar.gz 09:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:31 INFO - Installing collected packages: blessings 09:34:31 INFO - Running setup.py install for blessings 09:34:32 INFO - Successfully installed blessings 09:34:32 INFO - Cleaning up... 09:34:32 INFO - Return code: 0 09:34:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:32 INFO - Reading from file tmpfile_stdout 09:34:32 INFO - Current package versions: 09:34:32 INFO - argparse == 1.2.1 09:34:32 INFO - blessings == 1.6 09:34:32 INFO - blobuploader == 1.2.4 09:34:32 INFO - docopt == 0.6.1 09:34:32 INFO - functools32 == 3.2.3-2 09:34:32 INFO - jsonschema == 2.5.1 09:34:32 INFO - manifestparser == 1.1 09:34:32 INFO - mozInstall == 1.12 09:34:32 INFO - mozcrash == 1.0 09:34:32 INFO - mozdebug == 0.1 09:34:32 INFO - mozdevice == 0.49 09:34:32 INFO - mozfile == 1.2 09:34:32 INFO - mozhttpd == 0.7 09:34:32 INFO - mozinfo == 0.9 09:34:32 INFO - mozleak == 0.1 09:34:32 INFO - mozlog == 3.4 09:34:32 INFO - moznetwork == 0.27 09:34:32 INFO - mozprocess == 0.25 09:34:32 INFO - mozprofile == 0.28 09:34:32 INFO - mozrunner == 6.13 09:34:32 INFO - mozscreenshot == 0.1 09:34:32 INFO - mozsystemmonitor == 0.3 09:34:32 INFO - moztest == 0.8 09:34:32 INFO - mozversion == 1.4 09:34:32 INFO - psutil == 3.1.1 09:34:32 INFO - requests == 1.2.3 09:34:32 INFO - wsgiref == 0.1.2 09:34:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:32 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:32 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 09:34:32 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 09:34:32 INFO - Using env: {'DISPLAY': ':0', 09:34:32 INFO - 'HOME': '/home/cltbld', 09:34:32 INFO - 'LANG': 'en_US.UTF-8', 09:34:32 INFO - 'LANGUAGE': 'en_US:en', 09:34:32 INFO - 'LOGNAME': 'cltbld', 09:34:32 INFO - 'MAIL': '/var/mail/cltbld', 09:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:32 INFO - 'MOZ_NO_REMOTE': '1', 09:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:32 INFO - 'NO_EM_RESTART': '1', 09:34:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:32 INFO - 'PWD': '/builds/slave/test', 09:34:32 INFO - 'SHELL': '/bin/bash', 09:34:32 INFO - 'SHLVL': '1', 09:34:32 INFO - 'TERM': 'linux', 09:34:32 INFO - 'TMOUT': '86400', 09:34:32 INFO - 'USER': 'cltbld', 09:34:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:32 INFO - '_': '/tools/buildbot/bin/python'} 09:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 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)) 09:34:32 INFO - Cleaning up... 09:34:32 INFO - Return code: 0 09:34:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:32 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 0x23111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2409a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', '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 09:34:32 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 09:34:32 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 09:34:32 INFO - Using env: {'DISPLAY': ':0', 09:34:32 INFO - 'HOME': '/home/cltbld', 09:34:32 INFO - 'LANG': 'en_US.UTF-8', 09:34:32 INFO - 'LANGUAGE': 'en_US:en', 09:34:32 INFO - 'LOGNAME': 'cltbld', 09:34:32 INFO - 'MAIL': '/var/mail/cltbld', 09:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:32 INFO - 'MOZ_NO_REMOTE': '1', 09:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:32 INFO - 'NO_EM_RESTART': '1', 09:34:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:32 INFO - 'PWD': '/builds/slave/test', 09:34:32 INFO - 'SHELL': '/bin/bash', 09:34:32 INFO - 'SHLVL': '1', 09:34:32 INFO - 'TERM': 'linux', 09:34:32 INFO - 'TMOUT': '86400', 09:34:32 INFO - 'USER': 'cltbld', 09:34:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:32 INFO - '_': '/tools/buildbot/bin/python'} 09:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:32 INFO - Cleaning up... 09:34:32 INFO - Return code: 0 09:34:32 INFO - Running post-action listener: _resource_record_post_action 09:34:32 INFO - Running post-action listener: _start_resource_monitoring 09:34:32 INFO - Starting resource monitoring. 09:34:32 INFO - [mozharness: 2017-04-09 16:34:32.844412Z] Finished create-virtualenv step (success) 09:34:32 INFO - [mozharness: 2017-04-09 16:34:32.844871Z] Running install step. 09:34:32 INFO - Running pre-action listener: _resource_record_pre_action 09:34:32 INFO - Running main action method: install 09:34:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:33 INFO - Reading from file tmpfile_stdout 09:34:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:33 INFO - Reading from file tmpfile_stdout 09:34:33 INFO - Output received: 09:34:33 INFO - Usage: mozinstall [options] installer 09:34:33 INFO - Options: 09:34:33 INFO - -h, --help show this help message and exit 09:34:33 INFO - -d DEST, --destination=DEST 09:34:33 INFO - Directory to install application into. [default: 09:34:33 INFO - "/builds/slave/test"] 09:34:33 INFO - --app=APP Application being installed. [default: firefox] 09:34:33 INFO - mkdir: /builds/slave/test/build/application 09:34:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:34:49 INFO - Reading from file tmpfile_stdout 09:34:49 INFO - Output received: 09:34:49 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:49 INFO - Running post-action listener: _resource_record_post_action 09:34:49 INFO - [mozharness: 2017-04-09 16:34:49.694937Z] Finished install step (success) 09:34:49 INFO - [mozharness: 2017-04-09 16:34:49.695088Z] Running run-tests step. 09:34:49 INFO - Running pre-action listener: _resource_record_pre_action 09:34:49 INFO - Running main action method: run_tests 09:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:49 INFO - Python 2.7.3 09:34:49 INFO - Return code: 0 09:34:49 INFO - grabbing minidump binary from tooltool 09:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:49 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 0x25ac290>, '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 0x25acb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:49 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 09:34:49 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 09:34:49 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 09:34:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:49 INFO - Return code: 0 09:34:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:34:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - ENV: RUST_BACKTRACE is now 1 09:34:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:34:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:34:49 INFO - Using env: {'DISPLAY': ':0', 09:34:49 INFO - 'HOME': '/home/cltbld', 09:34:49 INFO - 'LANG': 'en_US.UTF-8', 09:34:49 INFO - 'LANGUAGE': 'en_US:en', 09:34:49 INFO - 'LOGNAME': 'cltbld', 09:34:49 INFO - 'MAIL': '/var/mail/cltbld', 09:34:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:49 INFO - 'MOZ_NO_REMOTE': '1', 09:34:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:49 INFO - 'NO_EM_RESTART': '1', 09:34:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:49 INFO - 'PWD': '/builds/slave/test', 09:34:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:49 INFO - 'RUST_BACKTRACE': '1', 09:34:49 INFO - 'SHELL': '/bin/bash', 09:34:49 INFO - 'SHLVL': '1', 09:34:49 INFO - 'TERM': 'linux', 09:34:49 INFO - 'TMOUT': '86400', 09:34:49 INFO - 'USER': 'cltbld', 09:34:49 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780', 09:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:49 INFO - '_': '/tools/buildbot/bin/python'} 09:34:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:34:49 INFO - mozversion application_buildid: 20170409154344 09:34:49 INFO - mozversion application_changeset: efca7a198376e7800873e4612a8b34fc77022d4d 09:34:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:34:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:49 INFO - mozversion application_name: Firefox 09:34:49 INFO - mozversion application_remotingname: firefox-dev 09:34:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:49 INFO - mozversion application_vendor: Mozilla 09:34:49 INFO - mozversion application_version: 54.0a2 09:34:49 INFO - mozversion platform_buildid: 20170409154344 09:34:49 INFO - mozversion platform_changeset: efca7a198376e7800873e4612a8b34fc77022d4d 09:34:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:49 INFO - mozversion platform_version: 54.0a2 09:34:49 INFO - using testdate: 1491755689 09:34:49 INFO - actual date: 1491755689 09:34:49 INFO - starting webserver on 'localhost:47748' 09:34:49 INFO - SUITE-START | Running 2 tests 09:34:49 INFO - TEST-START | dromaeo_css 09:34:49 INFO - operating with platform_type : linux_ 09:34:49 INFO - Initialising browser for dromaeo_css test... 09:34:49 INFO - TEST-INFO | started process 5570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKsrDmR/profile http://localhost:47748/getInfo.html) 09:34:53 INFO - PROCESS | 5570 | __metrics Screen width/height:1600/1200 09:34:53 INFO - PROCESS | 5570 | colorDepth:24 09:34:53 INFO - PROCESS | 5570 | Browser inner width/height: 1024/697 09:34:53 INFO - PROCESS | 5570 | __metrics 09:34:54 INFO - PROCESS | 5570 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:54 INFO - PROCESS | 5570 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:55 INFO - TEST-INFO | 5570: exit 0 09:34:55 INFO - Browser initialized. 09:34:55 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:55 INFO - TEST-INFO | started process 5658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKsrDmR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:07 INFO - PROCESS | 5658 | RSS: Main: 140128256 09:35:07 INFO - PROCESS | 5658 | 09:38:08 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47748/tests/dromaeo/cssquery-ext.html) 09:38:10 INFO - PROCESS | 5658 | RSS: Main: 300748800 09:38:10 INFO - PROCESS | 5658 | 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | A coding exception was thrown and uncaught in a Task. 09:39:56 INFO - PROCESS | 5658 | 09:39:56 ERROR - PROCESS | 5658 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:56 INFO - PROCESS | 5658 | Full stack: 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | A coding exception was thrown and uncaught in a Task. 09:39:56 INFO - PROCESS | 5658 | 09:39:56 ERROR - PROCESS | 5658 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:56 INFO - PROCESS | 5658 | Full stack: 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | A coding exception was thrown and uncaught in a Task. 09:39:56 INFO - PROCESS | 5658 | 09:39:56 ERROR - PROCESS | 5658 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:56 INFO - PROCESS | 5658 | Full stack: 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | ************************* 09:39:56 INFO - PROCESS | 5658 | A coding exception was thrown and uncaught in a Task. 09:39:56 INFO - PROCESS | 5658 | 09:39:56 ERROR - PROCESS | 5658 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:56 INFO - PROCESS | 5658 | Full stack: 09:39:56 INFO - PROCESS | 5658 | ************************* 09:41:12 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-ext.html (next: http://localhost:47748/tests/dromaeo/cssquery-jquery.html) 09:41:13 INFO - PROCESS | 5658 | RSS: Main: 220962816 09:41:13 INFO - PROCESS | 5658 | 09:44:14 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47748/tests/dromaeo/cssquery-mootools.html) 09:44:15 INFO - PROCESS | 5658 | RSS: Main: 173674496 09:44:15 INFO - PROCESS | 5658 | 09:47:17 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47748/tests/dromaeo/cssquery-prototype.html) 09:47:17 INFO - PROCESS | 5658 | RSS: Main: 170844160 09:47:17 INFO - PROCESS | 5658 | 09:50:20 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47748/tests/dromaeo/cssquery-yui.html) 09:50:21 INFO - PROCESS | 5658 | RSS: Main: 177111040 09:50:21 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | Cycle 1(1): loaded http://localhost:47748/tests/dromaeo/cssquery-yui.html (next: http://localhost:47748/tests/dromaeo/cssquery-dojo.html) 09:53:23 INFO - PROCESS | 5658 | RSS: Main: 264921088 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | __start_tp_report 09:53:23 INFO - PROCESS | 5658 | _x_x_mozilla_page_load 09:53:23 INFO - PROCESS | 5658 | _x_x_mozilla_page_load_details 09:53:23 INFO - PROCESS | 5658 | |i|pagename|runs| 09:53:23 INFO - PROCESS | 5658 | |0;dojo.html;3350.4806754952983;3378.7128094112;3454;3474.513568100468;3500.422430299001;5848.268966379197;5884.588078834483;5896.941030589693;5915.083162109874;5916.171735956958;5912.88174236515;5951.256092988377;5953.047512398017;5957.195778569891;5960;5351.224072509483;5380.596455265852;5417.160340147283;5419.214213938976;5469.945300547003;5163.277141200235;5201.609879259052;5451.291332126838;5462;5463.699496527689;5301.257823904656;5351.197320401951;5439.891202175974;5448.891022179554;5455.399906010336;5409.756560954766;5452.700101494416;5467.261919640838;5470.398256191816;5471.37079235887;5151.098557752378;5162.096633089232;5311.920321195185;5410.783568657249;5437.619366644337;5759.049756790167;5795.275590551181;5837.50381217597;5912.9113063304085;5915.911261331084;6326.2092238470195;6327.4938004959495;6350.4602108820345;6376.139221205124;6376.553641245115;6134.539909506805;6410.487161027107;6417;6422.357764223587;6425.068365087036;5818.505427038707;5834.824955251349;5848.473637372615;5887.676177810262;5896.469317761422;6449.580777249557;6493.935060649427;6495.188101487314;6496.058071579594;6496.512761542903;6212.751489940351;6266.905996410058;6271.560990730696;6274.341194174637;6278.905816412757;6085.330613632497;6113.449789518876;6135.263289243823;6196.783112591038;6218.906716399258;6344.206974128234;6346.746130154835;6347.079673447324;6347.841303967345;6349.619022858643;5999.760009599567;6018.337982821886;6027.065804800263;6221.751129954841;6327.905081423783;4704.458987216514;4792.329073929653;4798.520147985243;4820.373351464287;4823.131836269435;4129.2773764591075;4150.335946248646;4172.499300083949;4176.916461670749;4236.809343579546;6085.908711369333;6104.572679912452;6110.194261626863;6113.111460343324;6119.816405507842;6287.434130928147;6304.590201636879;6335.3981371769605;6375.6174629522375;6390.616563006234;5855.092460668603;5896.734646940812;6045.546584006217;6109.755609775648;6116.877662446726;5363.892722145613;5368.2752828367675;5394;5395.946040539623;5400.486953739389;6589.835254118685;6613.702383392759;6628.933710662928;6633.402993730491;6638.900416493757;6103.389661033861;6119.502553941512;6274.717637706405;6385.8722825544155;6391.296957334596;6476.4495017924;6534.803955881141;6600.372964568358;6663.36698013688;6665.800025999228;6301.842453938688;6303.212098487689;6303.401176888372;6304.117423560797;6305.243370795534;5996.940030599812;6338.492920566251;6399.584027038228;6410.935890641033;6412.070249813844;5919.052951527735;6117.908231376622;6120.721420533169;6124.564025909317;6134;6160.9383906160365;6208.348123446973;6220.346863579259;6221.129041934042;6224.533160012927;5919.378465261086;5933.228680271537;6167.753289868535;6193.659348735863;6202.937970620236;6086.421789929949;6086.847828804315;6098.664573448503;6121.051237058263;6125.264968203844;5913.940860591339;5952.136940143741;5961.523078153824;5965.135055417027;5969.164316995537 09:53:23 INFO - PROCESS | 5658 | |1;ext.html;759.5404780108034;842.7808769719873;863.4905405810571;902.810409813939;920.696170263813;31537;43623.78188109059;44195.558044419544;44243.55756442434;44299.335509967335;45064.32403513942;45268.320975185314;45276.773616131955;45327.77336113323;45410.31884521735;7533.246675332451;7679.923200768005;9143.679971200992;9273.258139348836;9284.907150928471;10568.735781605483;10790.352578845255;10799.298045627049;10799.352038877692;11138.610148644799;10875.83686244707;10913.34519928803;10923.617673381434;10934.070603998669;10936.726581835477;10850.891491085067;10877.02106810383;10887.945560272226;10891.83662245067;10897.346159230472;10572.947135264352;10607.204459665556;10627.574897004111;10632.46837658114;10651.25441219115;41890;42052.78973605143;42063.10716017756;42071.57928420707;42080.579194207974;4211.263028969948;4264.402983582301;4278.764667943262;4373.2565463871215;4395.1868904252815;29691.554626680616;30254.69745302541;30337.089887303417;30769;31752.523712144335;4291.527931927517;4298;4390.8024138913825;4391.969084352428;4418.756968366739;230547;230809;230972;231124;231575;54436.63908402322;54469.182962255596;54586.18120728192;54656.72671636617;54712.726436367564;56203.156952645746;56334;56361.71819140878;56438;56458.43541564614;16650.417235396704;16874.578135546708;16938.49184524466;17047.744283835753;17075;12962.546310879074;13172.407241674147;14472.782908256384;15020.774688379683;15074.849251507563;13315.467381304834;13382.933085334706;13433.126846754929;13458.461661533625;13474.595762127152;5265.894682106336;5281.9207711884355;5302;5346.97326513365;5386.730663466811;983.7638966647953;999.4003597841237;1001.5643195210107;1027.7482016905756;1030.2118879057527;28362.85818570894;28772.568411473843;28909.56635650467;28955.56566651502;28969.85515072411;1069.2354966199205;1079.4009324824797;1082.6968448834339;1085.066842515436;1090.1442367741286;14640.780388294184;15552.300146493435;15572.532824015298;15632.2183890805;15863.603409914844;307742;308670;308899;309117;309490;58430.415695843345;58515;58571;58576.707116465;58584.41415585875;1485.688005518853;1518.3698765012396;1529.3729570875887;1547.7987861577924;1547.8761699064048;26258.343541411617;27874.860625696743;27923.04538682354;27923.301917452227;28057.859710701316;42083;42088.78955605325;42142.789286053376;42198;42202.788986054875;278470;281148;281556;282091;283194;28290;34464.65535344614;34531;34633.48049779205;34839.65160348466;43822.34266486069;44265.11469770651;45112.54887451084;45132.87167820831;45243.547564523935;33740.49389259212;34386.484202737476;34437.31125377528;34577.13557161091;35225;33817.32365352728;34103;34209;34408.48387274243;35080.12299692426;38694.419583705705;39328.41007384834;39356.409653855786;39398.80300598479;39885.80057099696;32583.674163258063;32709.672903270664;32797.508037379936;32966.50550241796;33415.83292083524;25701.87149064243;27152;27332.180034598994;27347.31631709223;27402.725972740016 09:53:23 INFO - PROCESS | 5658 | |2;jquery.html;449.96400287976974;451.52138732943104;460.7650098449791;470.2969061253427;483.41748193426923;19483.707744383828;20353.084111214994;20440.386788396354;21150;21268.14927402902;21833.126674932984;22332.441688957784;22618.09527618893;22695.319140425774;22801.201957931476;6580.407763301302;6695.698693558777;6892.655367231619;6931.618760968145;6998.65006749663;6805.217399999014;6900.102986611759;6909.032735417046;6909.136357955255;6923.5499692519825;6651.434628056621;6887.449004079686;6913.27410621885;6924.169099708042;6941.028256044158;6646.136002319668;6851.760188393432;6871.793846184598;6922.896156557656;6926.965365173167;6712.362325579063;6738.157730283715;6752.628605426699;6758.729650813962;6761.661916904184;25067.24798256055;25171.496570068495;25197.118100866566;25206.495870082497;25417.491650166892;3062.8162310261455;3090.922726931822;3099.671412924416;3109.6112985876766;3148.763842711806;16524.504264872074;16531.421400250932;16594.917025414794;16622.66754664912;16777.91611041937;2223.377454312779;2260.9321720348416;2268.9886550567307;2311.1448763957274;2330.6037973544367;85570;95386.52306738422;96673;96689.5165524175;97369.51315243378;18428.723569146474;18637.81362186388;18849.340273090376;19284.80715192858;19872.801271987097;34403.311933761186;34444.65555344414;34477.48283775745;34493.65506344954;34922;11328.263662862053;11375.772484550263;11455.942720286344;11535.884641153481;11617.709557261136;9506.144446999802;9551.42691438516;9604.51977401141;9638.759031024281;9796.314258002014;9902;10041.995800420045;10148.188144948386;10171.999260218254;10540.420276884692;3532.646735326447;3578.499010138583;3817.7930538347923;3829.368154254517;4057.229126465962;971.6307803034942;978.4422878958925;988.7231575158967;996.3225006995312;996.5913975270105;21553.892230538746;21936.780632193473;22121.33635990923;22286.888565557394;22538.21116260923;1011.9139873110721;1012.7113772574928;1014.1633152648947;1015.1117771949545;1022.703416009349;12234.08244381675;12458.252504849737;12628.368581570885;12649.747005060031;12840.55058072963;641045;642995;669695;700618;707432;85755;85935;86499;86597;86736.56631716927;1356.186288227056;1365.1877133105786;1378.9586312410643;1390.4785705360287;1399.1045730732135;18667.533311667317;19025.52436189051;19527.902360488108;19534.609307814047;19575.510612234808;41769;44131.338029928935;44202.3369649462;44248.33627495654;45304.86737831581;56148.719256403456;56675.71662141663;56765.43234567602;57096;57246.71376643257;9249.398789078958;9677.516124193902;12400.19398739079;12535.185212961129;12794.808077878653;13652.453901843815;13753.724925501234;13931;13987.930060349634;14307.35616897263;12290.81563776562;12420.689482763004;12483.251004939733;12531;12716.109872308849;11562.595309164139;11632.941835290769;11736.530538778354;11955.462004210005;12345.382730863601;13017.284049377375;13029.218246905217;13042;13105.737885242052;13114.344282786013;12527.436265368262;12709;12921.676958076123;13002.674933126369;13426.731465370833;12550.623481295575;12675.80986285188;12738.235705857678;12738.363081846055;13197.80203296932 09:53:23 INFO - PROCESS | 5658 | |3;mootools.html;1413.590058882924;1501.6996600679868;1515.9772603410947;1517.5143953934733;1523.520091171281;9404.576794044267;11504.194706370557;11830.940845295761;12190.634280971562;12196.085293602993;11831.7633647327;11947.522099116024;11960.1627886048;11969.341686207255;11982.760344793121;2005.5587770690424;2070.937871863839;2294.9770502294973;2341.496578235679;2348.6242201247805;2444.303373538542;2497.775200231979;2503.5243303772227;2513.321403221129;2523.936901577457;2295.3114065780283;2320.083566991046;2328.4062564046144;2332.1487657005155;2336.8714720690355;2237.7090978172732;2253.0874995626714;2253.4028482452163;2254.1772253127656;2258.164479142711;2168.2844661261747;2185.519185779126;2187.2344679362254;2190.868547887132;2192.123150739701;11451.770964580744;11604.825927611093;11616.825747613708;11733.647990560212;11866.050715942703;5816.767329306823;5944.732487038051;5968.970155149196;5969.671668058299;5984.281886173687;3388.7289016878835;3533.6466353364717;3599.3341231872187;3608.1881576645305;3613.439916812898;1283.85235697895;1312.4356526693518;1344.0793056756056;1354.1130559483554;1368.8768010879069;17594.560135996573;17661.293548258054;17678.381256655954;17705.114744262737;17726.91136544309;4967;5039.370078740158;5084.084864724385;5258.526732594085;5340.946590534123;13628;13689.726205476034;13696.315184240948;13700.109492882932;13766.31168441574;6123.877522449526;6148.477379422709;6166.260048794084;6166.845828854225;6169.475594574422;5494.066008778517;5576.996140694714;5599.944000560024;5606.60753747242;5615.382307946123;4167.5832416758685;4252.936205956913;4256.851010214627;4286.078493123993;4302.548232435612;1209.8124790657462;1214.2593018258856;1217.7686239614445;1219.6767856517852;1232.3222227774688;1007.0835539658875;1022.8312328465869;1036.5801850250662;1037.4190453346;1038.7506998320348;6755.2906944771075;6902.758403455855;6981.5112942093565;7123.608201548861;7172.82067948295;1026.645807196516;1060.6817954613584;1061.5594528270835;1064.095518809006;1064.8455973883742;4288.2495563276925;4571.954280457219;4629.953700463019;4639.164950308977;4642.744649044267;13895.305234738222;14294.642633934027;14673.853261467462;14970.326335314938;14980.550583482513;14682.486112985995;14687.412503499954;14692.779608305884;14714.264286785832;14730.852691473163;10625.787484250272;10751.032407083243;10760.515776790064;10774.892251077388;10778;7047.894281585883;7177.6052317120975;7262.455315851438;7389.519681220703;7487.176410594939;13219.867801321863;13240.139390939557;13337.599872003855;13482.258475783927;13564.050516463753;11067.94466027697;12236.510539578318;13430.597082087554;13568.660783480493;13819.792703109662;10020.899791002288;10106.595736170471;10110.09009189147;10229.897701023192;10254.333468324536;10546.630867919586;10730.463476826284;10790.838137428102;10814.134869210602;10814.621488248189;9175.128362805523;9444.763880903032;9939.850902236616;10099.495025248854;10216.029477199654;9511.191548718436;9690.418574885529;9726.173275271398;9766.803840877916;9915.801683966136;8658.350623703122;8863.867041994505;8879.689210877588;8881.42270752399;8883.600237989176;6666.666666666744;6724.226713927766;6747.190337159573;6763.729450821912;6842.349976752201;6431.48548116159;6517.511186661114;6555.967220163869;6650.933490665225;6678.966605166943 09:53:23 INFO - PROCESS | 5658 | |4;prototype.html;411.5616868035544;413.40676129753797;414.2109281818136;415.32095024634697;434.3593200029956;5217.765200565974;5330.840074797753;5332.600054995881;5334.412809787255;5336.67979921204;5041.924371134427;5115.232715092738;5234.450382709808;5245.609913372949;5273.34083239595;4242.724222925515;4271.722338048033;4280.807363668627;4323.286657701476;4348.804303806336;4295.570442955695;4296.935545966798;4309.935350969738;4310.224159651261;4328.35522501449;4076.7553946763287;4197.307444271692;4200.285951388256;4224.809883555247;4279.679024073192;4190.28765109932;4198.664106871473;4208.621224089831;4209.726367786084;4221.831126754942;3830.2148059647707;3851.133494963639;3853.7687738735767;3863.4011728182177;3869.4389313549377;4808.447028591721;5012.373453318335;5135.948640513622;5164.288748407079;5231.3119250928;5113.159262665437;5122.718250496221;5128.769095417073;5149.665271757323;5151.227315902607;5556.944430555724;5560.221568980346;5583.413741557119;5597.74810133545;5606.691631960241;4329.393884856123;4530;4571.428571428572;4600.470945841204;4634.6524010698995;5395.892082158374;5541.667499950016;5663.235463212455;5670.886582268373;5709.371969083398;4546.954530454719;4554.474251125844;4563.931541026821;4567.36056952027;4588.449386073693;4845.563899249085;4848.200046992207;4855.975720121377;4857.465678775403;4864.73243971585;4889.926651100236;5046.091703493406;5115.514026167508;5138.58891288696;5144.536991670693;5451.672899626035;5462.781488740486;5488.231647569343;5492.752826122834;5493.2034854946605;1652.644831238373;1672.991635041817;1675.7235056215588;1676.3462249722618;1682.6887025900248;1224.6668974292818;1285.794272916329;1302.5245785288303;1311.7110641929894;1313.1136482874022;5206.245094461282;5410.810621628224;5411.404745477994;5435.972820135953;5513.345996201124;5607.859803504945;5639.548836093103;5685.914711279334;5688.601797874192;5724.969505488969;5477.589180811376;5505.669659820343;5507.476789704971;5515.393306736256;5542.8337149885565;2863.7708983281286;2957.438086763508;2961.955570666442;2980.925476863096;3002.789804713649;5793;5813.418658134153;5818.592698511064;5866.384029676908;5883.852903677443;5510.393856675762;5747.8563035924435;5760.942390576124;5807.738651760596;5832.825015249549;5806.838632273562;5822.970885145632;5856.385079566669;5877.621172226718;5899.203607512931;5637.210790489253;5652.50208694688;5653.632513886584;5657.858553536194;5682.886342273214;5453.836384908301;5569.888602228014;5604.327480702342;5608.579356548194;5610.85972850682;5566.693831839126;5662.85842853932;5738.282714660668;5881.617694849821;5883.35283118848;4737.692050016738;4752.310914917387;4756.357891684716;4774.474807771212;4794.161021821237;5479.424660410599;5638.182463542846;5645.802396916089;5683.914741278802;5737.971310143589;5458.444343362123;5474.220878214516;5479.643823151482;5486.396496385474;5505.119180931032;5465.617406781487;5485.232067510631;5500.5874559409;5505.834824955257;5578.609497335147;5225.779043906401;5424.864378390572;5491.478309560585;5502.8349149525575;5548.278723765885;5528.668279903219;5552.194931734795;5556.861078473071;5564.33228012641;5572.86067848307;5237.45495078945;5438.864028399322;5447.645903016291;5462;5475.698836564027 09:53:23 INFO - PROCESS | 5658 | |5;yui.html;766.2108028730406;804.899387576553;884.5179377239405;901.6685822995519;912.9865848907707;15713.528594142179;18137.727934081;18301.90849045753;18310;18494;16196.514104576881;16500.17499125045;16677.583060423494;16718.66562668746;16722.24749886255;854.995725021374;861.4572819123944;863.6372723456163;864.2696921101659;870.6299822575412;873.1007062725371;889.5063239901851;889.8442772514817;895.6417433026776;896.4038914122134;945.6122989574276;947.166493485735;947.2895328503622;947.729896979361;948.3029972969862;941.5480569326727;943.8914524829629;944.1266828183936;946.195733626412;947.166493485735;688.5490004047357;689.5483458334799;693.6670398208837;694.5485434467586;695.1935754524727;16069.276882540196;16128.919355403264;16202.675946481122;16986.150692465326;17207.225674844663;15488.380464781396;15579.688406231811;15722.606934826606;15843.762343564857;16168.029918204827;12525.874741252563;13011.34943252843;13048.54330098442;13064.21614703121;13191.2744799036;4026.355783074694;4051.9189616207805;4074.633283004544;4123.0516981094215;4184.372344148372;49700.75149624229;51967.480325196644;71206.64396678035;75110.62444687741;85889;12090.455929483194;12268.509259629693;12285.815712764315;12315.876841231653;12576.30830304342;22070.88964555167;22174.778252217595;22323.888380558317;22444.66333005006;22565.4358641032;8389.748307550783;8500.192481714228;8554.230010214183;8602;8716.78208044804;4772.76136193189;4804.95195048052;4813.879653008703;4842.782074806642;4966.1309270877455;3598.064503229179;3667.211549516867;3668.5781135169264;3717.312297225022;3729.384651532521;647.7409036385484;652.4649787174566;656.4223483334634;669.0165456778528;670.7987603718865;782.4992005116729;793.4961299574696;805.578456984486;815.7430409421013;826.7974346285199;10798.352098874093;10898;11225.943870280596;11356.26184298018;11487.540498380138;736.2012216744891;743.5352904434728;753.506453273107;755.2296657409521;768.4236822383212;5139.357580302462;5336.599755018389;5427.213054107132;5437.564994800407;5459.5632349412745;79423.60288198522;81249.59375203204;81398;82635.5868220667;84226.57886710527;58302.7084864573;60101.398986010456;67880.6605966977;74921.25078749252;75027;63138.36861631325;63906.36093639097;64621.03068453977;64678.353216468175;64769;10237.164816054777;10330.225233107698;10359.741006474898;10520.316179448617;10532.947335263274;47247.29129063136;47411.28883066826;47844.5215547854;47908.28137578009;48522.02955940791;53197.734011329696;53689.46310537001;54021.72989135029;54196;55014.724926375115;12011.75976480483;12212.145149839425;12472.688182795502;12568.874311257136;12680.175788573713;13508;13758;13812.792808107686;13851.584452466443;13947.58157255284;13364.331783410596;13718.17690938547;13852.792208117087;13931.373088210852;14364.928175359057;13014.609561713163;13539.390727417092;13546.390412431267;13576.93211533936;13812;13272.933635331761;13276.80084798748;13418;13464.326783660581;13555.186688798703;9756.51217439111;9795.9020409795;9820;10271.589136434757;10425.843612345972;10258.897411026093;11384.203105782515;11537.82693259585;11543.422828858691;11634.825477617673 09:53:23 INFO - PROCESS | 5658 | __end_tp_report 09:53:23 INFO - PROCESS | 5658 | __start_cc_report 09:53:23 INFO - PROCESS | 5658 | _x_x_mozilla_cycle_collect,1592 09:53:23 INFO - PROCESS | 5658 | __end_cc_report 09:53:23 INFO - PROCESS | 5658 | __startTimestamp1491756803682__endTimestamp 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | ------- Summary: start ------- 09:53:23 INFO - PROCESS | 5658 | Number of tests: 6 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#0] dojo.html Cycles:165 Average:5856.29 Median:6086.42 stddev:679.84 (11.2%) stddev-sans-first:652.89 09:53:23 INFO - PROCESS | 5658 | Values: 3350.5 3378.7 3454.0 3474.5 3500.4 5848.3 5884.6 5896.9 5915.1 5916.2 5912.9 5951.3 5953.0 5957.2 5960.0 5351.2 5380.6 5417.2 5419.2 5469.9 5163.3 5201.6 5451.3 5462.0 5463.7 5301.3 5351.2 5439.9 5448.9 5455.4 5409.8 5452.7 5467.3 5470.4 5471.4 5151.1 5162.1 5311.9 5410.8 5437.6 5759.0 5795.3 5837.5 5912.9 5915.9 6326.2 6327.5 6350.5 6376.1 6376.6 6134.5 6410.5 6417.0 6422.4 6425.1 5818.5 5834.8 5848.5 5887.7 5896.5 6449.6 6493.9 6495.2 6496.1 6496.5 6212.8 6266.9 6271.6 6274.3 6278.9 6085.3 6113.4 6135.3 6196.8 6218.9 6344.2 6346.7 6347.1 6347.8 6349.6 5999.8 6018.3 6027.1 6221.8 6327.9 4704.5 4792.3 4798.5 4820.4 4823.1 4129.3 4150.3 4172.5 4176.9 4236.8 6085.9 6104.6 6110.2 6113.1 6119.8 6287.4 6304.6 6335.4 6375.6 6390.6 5855.1 5896.7 6045.5 6109.8 6116.9 5363.9 5368.3 5394.0 5395.9 5400.5 6589.8 6613.7 6628.9 6633.4 6638.9 6103.4 6119.5 6274.7 6385.9 6391.3 6476.4 6534.8 6600.4 6663.4 6665.8 6301.8 6303.2 6303.4 6304.1 6305.2 5996.9 6338.5 6399.6 6410.9 6412.1 5919.1 6117.9 6120.7 6124.6 6134.0 6160.9 6208.3 6220.3 6221.1 6224.5 5919.4 5933.2 6167.8 6193.7 6202.9 6086.4 6086.8 6098.7 6121.1 6125.3 5913.9 5952.1 5961.5 5965.1 5969.2 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#1] ext.html Cycles:180 Average:45133.53 Median:32646.67 stddev:71753.97 (219.8%) stddev-sans-first:71877.90 09:53:23 INFO - PROCESS | 5658 | Values: 759.5 842.8 863.5 902.8 920.7 31537.0 43623.8 44195.6 44243.6 44299.3 45064.3 45268.3 45276.8 45327.8 45410.3 7533.2 7679.9 9143.7 9273.3 9284.9 10568.7 10790.4 10799.3 10799.4 11138.6 10875.8 10913.3 10923.6 10934.1 10936.7 10850.9 10877.0 10887.9 10891.8 10897.3 10572.9 10607.2 10627.6 10632.5 10651.3 41890.0 42052.8 42063.1 42071.6 42080.6 4211.3 4264.4 4278.8 4373.3 4395.2 29691.6 30254.7 30337.1 30769.0 31752.5 4291.5 4298.0 4390.8 4392.0 4418.8 230547.0 230809.0 230972.0 231124.0 231575.0 54436.6 54469.2 54586.2 54656.7 54712.7 56203.2 56334.0 56361.7 56438.0 56458.4 16650.4 16874.6 16938.5 17047.7 17075.0 12962.5 13172.4 14472.8 15020.8 15074.8 13315.5 13382.9 13433.1 13458.5 13474.6 5265.9 5281.9 5302.0 5347.0 5386.7 983.8 999.4 1001.6 1027.7 1030.2 28362.9 28772.6 28909.6 28955.6 28969.9 1069.2 1079.4 1082.7 1085.1 1090.1 14640.8 15552.3 15572.5 15632.2 15863.6 307742.0 308670.0 308899.0 309117.0 309490.0 58430.4 58515.0 58571.0 58576.7 58584.4 1485.7 1518.4 1529.4 1547.8 1547.9 26258.3 27874.9 27923.0 27923.3 28057.9 42083.0 42088.8 42142.8 42198.0 42202.8 278470.0 281148.0 281556.0 282091.0 283194.0 28290.0 34464.7 34531.0 34633.5 34839.7 43822.3 44265.1 45112.5 45132.9 45243.5 33740.5 34386.5 34437.3 34577.1 35225.0 33817.3 34103.0 34209.0 34408.5 35080.1 38694.4 39328.4 39356.4 39398.8 39885.8 32583.7 32709.7 32797.5 32966.5 33415.8 25701.9 27152.0 27332.2 27347.3 27402.7 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#2] jquery.html Cycles:180 Average:36465.47 Median:2320.87 stddev:109928.89 (4736.5%) stddev-sans-first:110204.00 09:53:23 INFO - PROCESS | 5658 | Values: 450.0 451.5 460.8 470.3 483.4 19483.7 20353.1 20440.4 21150.0 21268.1 21833.1 22332.4 22618.1 22695.3 22801.2 6580.4 6695.7 6892.7 6931.6 6998.7 6805.2 6900.1 6909.0 6909.1 6923.5 6651.4 6887.4 6913.3 6924.2 6941.0 6646.1 6851.8 6871.8 6922.9 6927.0 6712.4 6738.2 6752.6 6758.7 6761.7 25067.2 25171.5 25197.1 25206.5 25417.5 3062.8 3090.9 3099.7 3109.6 3148.8 16524.5 16531.4 16594.9 16622.7 16777.9 2223.4 2260.9 2269.0 2311.1 2330.6 85570.0 95386.5 96673.0 96689.5 97369.5 18428.7 18637.8 18849.3 19284.8 19872.8 34403.3 34444.7 34477.5 34493.7 34922.0 11328.3 11375.8 11455.9 11535.9 11617.7 9506.1 9551.4 9604.5 9638.8 9796.3 9902.0 10042.0 10148.2 10172.0 10540.4 3532.6 3578.5 3817.8 3829.4 4057.2 971.6 978.4 988.7 996.3 996.6 21553.9 21936.8 22121.3 22286.9 22538.2 1011.9 1012.7 1014.2 1015.1 1022.7 12234.1 12458.3 12628.4 12649.7 12840.6 641045.0 642995.0 669695.0 700618.0 707432.0 85755.0 85935.0 86499.0 86597.0 86736.6 1356.2 1365.2 1379.0 1390.5 1399.1 18667.5 19025.5 19527.9 19534.6 19575.5 41769.0 44131.3 44202.3 44248.3 45304.9 56148.7 56675.7 56765.4 57096.0 57246.7 9249.4 9677.5 12400.2 12535.2 12794.8 13652.5 13753.7 13931.0 13987.9 14307.4 12290.8 12420.7 12483.3 12531.0 12716.1 11562.6 11632.9 11736.5 11955.5 12345.4 13017.3 13029.2 13042.0 13105.7 13114.3 12527.4 12709.0 12921.7 13002.7 13426.7 12550.6 12675.8 12738.2 12738.4 13197.8 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#3] mootools.html Cycles:180 Average:7281.56 Median:2189.05 stddev:4674.69 (213.5%) stddev-sans-first:4667.01 09:53:23 INFO - PROCESS | 5658 | Values: 1413.6 1501.7 1516.0 1517.5 1523.5 9404.6 11504.2 11830.9 12190.6 12196.1 11831.8 11947.5 11960.2 11969.3 11982.8 2005.6 2070.9 2295.0 2341.5 2348.6 2444.3 2497.8 2503.5 2513.3 2523.9 2295.3 2320.1 2328.4 2332.1 2336.9 2237.7 2253.1 2253.4 2254.2 2258.2 2168.3 2185.5 2187.2 2190.9 2192.1 11451.8 11604.8 11616.8 11733.6 11866.1 5816.8 5944.7 5969.0 5969.7 5984.3 3388.7 3533.6 3599.3 3608.2 3613.4 1283.9 1312.4 1344.1 1354.1 1368.9 17594.6 17661.3 17678.4 17705.1 17726.9 4967.0 5039.4 5084.1 5258.5 5340.9 13628.0 13689.7 13696.3 13700.1 13766.3 6123.9 6148.5 6166.3 6166.8 6169.5 5494.1 5577.0 5599.9 5606.6 5615.4 4167.6 4252.9 4256.9 4286.1 4302.5 1209.8 1214.3 1217.8 1219.7 1232.3 1007.1 1022.8 1036.6 1037.4 1038.8 6755.3 6902.8 6981.5 7123.6 7172.8 1026.6 1060.7 1061.6 1064.1 1064.8 4288.2 4572.0 4630.0 4639.2 4642.7 13895.3 14294.6 14673.9 14970.3 14980.6 14682.5 14687.4 14692.8 14714.3 14730.9 10625.8 10751.0 10760.5 10774.9 10778.0 7047.9 7177.6 7262.5 7389.5 7487.2 13219.9 13240.1 13337.6 13482.3 13564.1 11067.9 12236.5 13430.6 13568.7 13819.8 10020.9 10106.6 10110.1 10229.9 10254.3 10546.6 10730.5 10790.8 10814.1 10814.6 9175.1 9444.8 9939.9 10099.5 10216.0 9511.2 9690.4 9726.2 9766.8 9915.8 8658.4 8863.9 8879.7 8881.4 8883.6 6666.7 6724.2 6747.2 6763.7 6842.3 6431.5 6517.5 6556.0 6650.9 6679.0 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#4] prototype.html Cycles:180 Average:4786.63 Median:5333.51 stddev:1288.03 (24.1%) stddev-sans-first:1249.08 09:53:23 INFO - PROCESS | 5658 | Values: 411.6 413.4 414.2 415.3 434.4 5217.8 5330.8 5332.6 5334.4 5336.7 5041.9 5115.2 5234.5 5245.6 5273.3 4242.7 4271.7 4280.8 4323.3 4348.8 4295.6 4296.9 4309.9 4310.2 4328.4 4076.8 4197.3 4200.3 4224.8 4279.7 4190.3 4198.7 4208.6 4209.7 4221.8 3830.2 3851.1 3853.8 3863.4 3869.4 4808.4 5012.4 5135.9 5164.3 5231.3 5113.2 5122.7 5128.8 5149.7 5151.2 5556.9 5560.2 5583.4 5597.7 5606.7 4329.4 4530.0 4571.4 4600.5 4634.7 5395.9 5541.7 5663.2 5670.9 5709.4 4547.0 4554.5 4563.9 4567.4 4588.4 4845.6 4848.2 4856.0 4857.5 4864.7 4889.9 5046.1 5115.5 5138.6 5144.5 5451.7 5462.8 5488.2 5492.8 5493.2 1652.6 1673.0 1675.7 1676.3 1682.7 1224.7 1285.8 1302.5 1311.7 1313.1 5206.2 5410.8 5411.4 5436.0 5513.3 5607.9 5639.5 5685.9 5688.6 5725.0 5477.6 5505.7 5507.5 5515.4 5542.8 2863.8 2957.4 2962.0 2980.9 3002.8 5793.0 5813.4 5818.6 5866.4 5883.9 5510.4 5747.9 5760.9 5807.7 5832.8 5806.8 5823.0 5856.4 5877.6 5899.2 5637.2 5652.5 5653.6 5657.9 5682.9 5453.8 5569.9 5604.3 5608.6 5610.9 5566.7 5662.9 5738.3 5881.6 5883.4 4737.7 4752.3 4756.4 4774.5 4794.2 5479.4 5638.2 5645.8 5683.9 5738.0 5458.4 5474.2 5479.6 5486.4 5505.1 5465.6 5485.2 5500.6 5505.8 5578.6 5225.8 5424.9 5491.5 5502.8 5548.3 5528.7 5552.2 5556.9 5564.3 5572.9 5237.5 5438.9 5447.6 5462.0 5475.7 09:53:23 INFO - PROCESS | 5658 | 09:53:23 INFO - PROCESS | 5658 | [#5] yui.html Cycles:180 Average:17770.41 Median:47876.40 stddev:21998.84 (45.9%) stddev-sans-first:22023.49 09:53:23 INFO - PROCESS | 5658 | Values: 766.2 804.9 884.5 901.7 913.0 15713.5 18137.7 18301.9 18310.0 18494.0 16196.5 16500.2 16677.6 16718.7 16722.2 855.0 861.5 863.6 864.3 870.6 873.1 889.5 889.8 895.6 896.4 945.6 947.2 947.3 947.7 948.3 941.5 943.9 944.1 946.2 947.2 688.5 689.5 693.7 694.5 695.2 16069.3 16128.9 16202.7 16986.2 17207.2 15488.4 15579.7 15722.6 15843.8 16168.0 12525.9 13011.3 13048.5 13064.2 13191.3 4026.4 4051.9 4074.6 4123.1 4184.4 49700.8 51967.5 71206.6 75110.6 85889.0 12090.5 12268.5 12285.8 12315.9 12576.3 22070.9 22174.8 22323.9 22444.7 22565.4 8389.7 8500.2 8554.2 8602.0 8716.8 4772.8 4805.0 4813.9 4842.8 4966.1 3598.1 3667.2 3668.6 3717.3 3729.4 647.7 652.5 656.4 669.0 670.8 782.5 793.5 805.6 815.7 826.8 10798.4 10898.0 11225.9 11356.3 11487.5 736.2 743.5 753.5 755.2 768.4 5139.4 5336.6 5427.2 5437.6 5459.6 79423.6 81249.6 81398.0 82635.6 84226.6 58302.7 60101.4 67880.7 74921.3 75027.0 63138.4 63906.4 64621.0 64678.4 64769.0 10237.2 10330.2 10359.7 10520.3 10532.9 47247.3 47411.3 47844.5 47908.3 48522.0 53197.7 53689.5 54021.7 54196.0 55014.7 12011.8 12212.1 12472.7 12568.9 12680.2 13508.0 13758.0 13812.8 13851.6 13947.6 13364.3 13718.2 13852.8 13931.4 14364.9 13014.6 13539.4 13546.4 13576.9 13812.0 13272.9 13276.8 13418.0 13464.3 13555.2 9756.5 9795.9 9820.0 10271.6 10425.8 10258.9 11384.2 11537.8 11543.4 11634.8 09:53:23 INFO - PROCESS | 5658 | -------- Summary: end -------- 09:53:23 INFO - PROCESS | 5658 | 09:53:24 INFO - TEST-INFO | 5658: exit 0 09:53:24 INFO - TEST-OK | dromaeo_css | took 1114571ms 09:53:24 INFO - TEST-START | kraken 09:53:24 INFO - operating with platform_type : linux_ 09:53:24 INFO - Initialising browser for kraken test... 09:53:24 INFO - TEST-INFO | started process 5984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWx0Kp/profile http://localhost:47748/getInfo.html) 09:53:28 INFO - PROCESS | 5984 | __metrics Screen width/height:1600/1200 09:53:28 INFO - PROCESS | 5984 | colorDepth:24 09:53:28 INFO - PROCESS | 5984 | Browser inner width/height: 1024/697 09:53:28 INFO - PROCESS | 5984 | __metrics 09:53:29 INFO - PROCESS | 5984 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:53:29 INFO - PROCESS | 5984 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:53:30 INFO - TEST-INFO | 5984: exit 0 09:53:30 INFO - Browser initialized. 09:53:30 INFO - Running cycle 1/1 for kraken test... 09:53:30 INFO - TEST-INFO | started process 6075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWx0Kp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:42 INFO - PROCESS | 6075 | RSS: Main: 140099584 09:53:42 INFO - PROCESS | 6075 | 09:53:44 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47748/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:45 INFO - PROCESS | 6075 | RSS: Main: 183263232 09:53:45 INFO - PROCESS | 6075 | 09:53:48 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47748/tests/kraken/driver.html?testName=audio-dft) 09:53:48 INFO - PROCESS | 6075 | RSS: Main: 173916160 09:53:48 INFO - PROCESS | 6075 | 09:53:51 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47748/tests/kraken/driver.html?testName=audio-fft) 09:53:52 INFO - PROCESS | 6075 | RSS: Main: 265809920 09:53:52 INFO - PROCESS | 6075 | 09:53:54 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47748/tests/kraken/driver.html?testName=audio-oscillator) 09:53:54 INFO - PROCESS | 6075 | RSS: Main: 172683264 09:53:54 INFO - PROCESS | 6075 | 09:53:56 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47748/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:57 INFO - PROCESS | 6075 | RSS: Main: 175161344 09:53:57 INFO - PROCESS | 6075 | 09:54:00 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47748/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:00 INFO - PROCESS | 6075 | RSS: Main: 214224896 09:54:00 INFO - PROCESS | 6075 | 09:54:04 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47748/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:04 INFO - PROCESS | 6075 | RSS: Main: 212123648 09:54:04 INFO - PROCESS | 6075 | 09:54:07 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47748/tests/kraken/driver.html?testName=json-parse-financial) 09:54:08 INFO - PROCESS | 6075 | RSS: Main: 211156992 09:54:08 INFO - PROCESS | 6075 | 09:54:09 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47748/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:10 INFO - PROCESS | 6075 | RSS: Main: 173260800 09:54:10 INFO - PROCESS | 6075 | 09:54:12 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:12 INFO - PROCESS | 6075 | RSS: Main: 179945472 09:54:12 INFO - PROCESS | 6075 | 09:54:14 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:15 INFO - PROCESS | 6075 | RSS: Main: 189169664 09:54:15 INFO - PROCESS | 6075 | 09:54:17 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:18 INFO - PROCESS | 6075 | RSS: Main: 198909952 09:54:18 INFO - PROCESS | 6075 | 09:54:21 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:21 INFO - PROCESS | 6075 | RSS: Main: 195141632 09:54:21 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | Cycle 1(1): loaded http://localhost:47748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47748/tests/kraken/driver.html?testName=ai-astar) 09:54:23 INFO - PROCESS | 6075 | RSS: Main: 247693312 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | __start_tp_report 09:54:23 INFO - PROCESS | 6075 | _x_x_mozilla_page_load 09:54:23 INFO - PROCESS | 6075 | _x_x_mozilla_page_load_details 09:54:23 INFO - PROCESS | 6075 | |i|pagename|runs| 09:54:23 INFO - PROCESS | 6075 | |0;ai-astar;102;110;112;112;112;110;112;110;110;112 09:54:23 INFO - PROCESS | 6075 | |1;audio-beat-detection;139;116;114;151;118;117;120;116;135;115 09:54:23 INFO - PROCESS | 6075 | |2;audio-dft;160;161;160;162;162;161;162;161;160;161 09:54:23 INFO - PROCESS | 6075 | |3;audio-fft;74;76;76;114;76;78;77;75;89;74 09:54:23 INFO - PROCESS | 6075 | |4;audio-oscillator;82;82;107;83;83;83;96;82;82;107 09:54:23 INFO - PROCESS | 6075 | |5;imaging-gaussian-blur;113;112;113;113;113;113;113;113;112;113 09:54:23 INFO - PROCESS | 6075 | |6;imaging-darkroom;146;146;147;146;147;147;145;146;146;146 09:54:23 INFO - PROCESS | 6075 | |7;imaging-desaturate;94;94;95;93;94;93;94;94;94;93 09:54:23 INFO - PROCESS | 6075 | |8;json-parse-financial;58;57;57;56;56;56;57;56;57;56 09:54:23 INFO - PROCESS | 6075 | |9;json-stringify-tinderbox;52;53;52;54;54;53;53;53;53;54 09:54:23 INFO - PROCESS | 6075 | |10;stanford-crypto-aes;76;81;77;78;81;75;77;76;80;79 09:54:23 INFO - PROCESS | 6075 | |11;stanford-crypto-ccm;124;132;121;124;123;126;117;118;161;131 09:54:23 INFO - PROCESS | 6075 | |12;stanford-crypto-pbkdf2;161;160;157;163;156;158;159;156;158;158 09:54:23 INFO - PROCESS | 6075 | |13;stanford-crypto-sha256-iterative;60;59;59;59;62;60;64;58;59;58 09:54:23 INFO - PROCESS | 6075 | __end_tp_report 09:54:23 INFO - PROCESS | 6075 | __start_cc_report 09:54:23 INFO - PROCESS | 6075 | _x_x_mozilla_cycle_collect,1631 09:54:23 INFO - PROCESS | 6075 | __end_cc_report 09:54:23 INFO - PROCESS | 6075 | __startTimestamp1491756863731__endTimestamp 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | ------- Summary: start ------- 09:54:23 INFO - PROCESS | 6075 | Number of tests: 14 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#0] ai-astar Cycles:10 Average:110.20 Median:112.00 stddev:3.05 (2.7%) stddev-sans-first:1.05 09:54:23 INFO - PROCESS | 6075 | Values: 102.0 110.0 112.0 112.0 112.0 110.0 112.0 110.0 110.0 112.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:119.00 stddev:12.84 (10.8%) stddev-sans-first:12.44 09:54:23 INFO - PROCESS | 6075 | Values: 139.0 116.0 114.0 151.0 118.0 117.0 120.0 116.0 135.0 115.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 09:54:23 INFO - PROCESS | 6075 | Values: 160.0 161.0 160.0 162.0 162.0 161.0 162.0 161.0 160.0 161.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:12.41 (16.3%) stddev-sans-first:12.91 09:54:23 INFO - PROCESS | 6075 | Values: 74.0 76.0 76.0 114.0 76.0 78.0 77.0 75.0 89.0 74.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:82.50 stddev:10.54 (12.8%) stddev-sans-first:10.90 09:54:23 INFO - PROCESS | 6075 | Values: 82.0 82.0 107.0 83.0 83.0 83.0 96.0 82.0 82.0 107.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:54:23 INFO - PROCESS | 6075 | Values: 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:54:23 INFO - PROCESS | 6075 | Values: 146.0 146.0 147.0 146.0 147.0 147.0 145.0 146.0 146.0 146.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:54:23 INFO - PROCESS | 6075 | Values: 94.0 94.0 95.0 93.0 94.0 93.0 94.0 94.0 94.0 93.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.70 (1.2%) stddev-sans-first:0.53 09:54:23 INFO - PROCESS | 6075 | Values: 58.0 57.0 57.0 56.0 56.0 56.0 57.0 56.0 57.0 56.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#9] json-stringify-tinderbox Cycles:10 Average:53.10 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.67 09:54:23 INFO - PROCESS | 6075 | Values: 52.0 53.0 52.0 54.0 54.0 53.0 53.0 53.0 53.0 54.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.50 stddev:2.16 (2.8%) stddev-sans-first:2.17 09:54:23 INFO - PROCESS | 6075 | Values: 76.0 81.0 77.0 78.0 81.0 75.0 77.0 76.0 80.0 79.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:125.00 stddev:12.67 (10.1%) stddev-sans-first:13.36 09:54:23 INFO - PROCESS | 6075 | Values: 124.0 132.0 121.0 124.0 123.0 126.0 117.0 118.0 161.0 131.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:158.50 stddev:2.22 (1.4%) stddev-sans-first:2.18 09:54:23 INFO - PROCESS | 6075 | Values: 161.0 160.0 157.0 163.0 156.0 158.0 159.0 156.0 158.0 158.0 09:54:23 INFO - PROCESS | 6075 | 09:54:23 INFO - PROCESS | 6075 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:59.50 stddev:1.87 (3.1%) stddev-sans-first:1.99 09:54:23 INFO - PROCESS | 6075 | Values: 60.0 59.0 59.0 59.0 62.0 60.0 64.0 58.0 59.0 58.0 09:54:23 INFO - PROCESS | 6075 | -------- Summary: end -------- 09:54:23 INFO - PROCESS | 6075 | 09:54:24 INFO - TEST-INFO | 6075: exit 0 09:54:24 INFO - TEST-OK | kraken | took 60284ms 09:54:24 INFO - SUITE-END | took 1174s 09:54:24 INFO - Completed test suite (00:19:34) 09:54:24 INFO - javascript benchmark 09:54:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7851.250803561928, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.4806754952983, 3378.7128094112, 3454.0, 3474.513568100468, 3500.422430299001, 5848.268966379197, 5884.588078834483, 5896.941030589693, 5915.083162109874, 5916.171735956958, 5912.88174236515, 5951.256092988377, 5953.047512398017, 5957.195778569891, 5960.0, 5351.224072509483, 5380.596455265852, 5417.160340147283, 5419.214213938976, 5469.945300547003, 5163.277141200235, 5201.609879259052, 5451.291332126838, 5462.0, 5463.699496527689, 5301.257823904656, 5351.197320401951, 5439.891202175974, 5448.891022179554, 5455.399906010336, 5409.756560954766, 5452.700101494416, 5467.261919640838, 5470.398256191816, 5471.37079235887, 5151.098557752378, 5162.096633089232, 5311.920321195185, 5410.783568657249, 5437.619366644337, 5759.049756790167, 5795.275590551181, 5837.50381217597, 5912.9113063304085, 5915.911261331084, 6326.2092238470195, 6327.4938004959495, 6350.4602108820345, 6376.139221205124, 6376.553641245115, 6134.539909506805, 6410.487161027107, 6417.0, 6422.357764223587, 6425.068365087036, 5818.505427038707, 5834.824955251349, 5848.473637372615, 5887.676177810262, 5896.469317761422, 6449.580777249557, 6493.935060649427, 6495.188101487314, 6496.058071579594, 6496.512761542903, 6212.751489940351, 6266.905996410058, 6271.560990730696, 6274.341194174637, 6278.905816412757, 6085.330613632497, 6113.449789518876, 6135.263289243823, 6196.783112591038, 6218.906716399258, 6344.206974128234, 6346.746130154835, 6347.079673447324, 6347.841303967345, 6349.619022858643, 5999.760009599567, 6018.337982821886, 6027.065804800263, 6221.751129954841, 6327.905081423783, 4704.458987216514, 4792.329073929653, 4798.520147985243, 4820.373351464287, 4823.131836269435, 4129.2773764591075, 4150.335946248646, 4172.499300083949, 4176.916461670749, 4236.809343579546, 6085.908711369333, 6104.572679912452, 6110.194261626863, 6113.111460343324, 6119.816405507842, 6287.434130928147, 6304.590201636879, 6335.3981371769605, 6375.6174629522375, 6390.616563006234, 5855.092460668603, 5896.734646940812, 6045.546584006217, 6109.755609775648, 6116.877662446726, 5363.892722145613, 5368.2752828367675, 5394.0, 5395.946040539623, 5400.486953739389, 6589.835254118685, 6613.702383392759, 6628.933710662928, 6633.402993730491, 6638.900416493757, 6103.389661033861, 6119.502553941512, 6274.717637706405, 6385.8722825544155, 6391.296957334596, 6476.4495017924, 6534.803955881141, 6600.372964568358, 6663.36698013688, 6665.800025999228, 6301.842453938688, 6303.212098487689, 6303.401176888372, 6304.117423560797, 6305.243370795534, 5996.940030599812, 6338.492920566251, 6399.584027038228, 6410.935890641033, 6412.070249813844, 5919.052951527735, 6117.908231376622, 6120.721420533169, 6124.564025909317, 6134.0, 6160.9383906160365, 6208.348123446973, 6220.346863579259, 6221.129041934042, 6224.533160012927, 5919.378465261086, 5933.228680271537, 6167.753289868535, 6193.659348735863, 6202.937970620236, 6086.421789929949, 6086.847828804315, 6098.664573448503, 6121.051237058263, 6125.264968203844, 5913.940860591339, 5952.136940143741, 5961.523078153824, 5965.135055417027, 5969.164316995537], "value": 5809.668612872067, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.5404780108034, 842.7808769719873, 863.4905405810571, 902.810409813939, 920.696170263813, 31537.0, 43623.78188109059, 44195.558044419544, 44243.55756442434, 44299.335509967335, 45064.32403513942, 45268.320975185314, 45276.773616131955, 45327.77336113323, 45410.31884521735, 7533.246675332451, 7679.923200768005, 9143.679971200992, 9273.258139348836, 9284.907150928471, 10568.735781605483, 10790.352578845255, 10799.298045627049, 10799.352038877692, 11138.610148644799, 10875.83686244707, 10913.34519928803, 10923.617673381434, 10934.070603998669, 10936.726581835477, 10850.891491085067, 10877.02106810383, 10887.945560272226, 10891.83662245067, 10897.346159230472, 10572.947135264352, 10607.204459665556, 10627.574897004111, 10632.46837658114, 10651.25441219115, 41890.0, 42052.78973605143, 42063.10716017756, 42071.57928420707, 42080.579194207974, 4211.263028969948, 4264.402983582301, 4278.764667943262, 4373.2565463871215, 4395.1868904252815, 29691.554626680616, 30254.69745302541, 30337.089887303417, 30769.0, 31752.523712144335, 4291.527931927517, 4298.0, 4390.8024138913825, 4391.969084352428, 4418.756968366739, 230547.0, 230809.0, 230972.0, 231124.0, 231575.0, 54436.63908402322, 54469.182962255596, 54586.18120728192, 54656.72671636617, 54712.726436367564, 56203.156952645746, 56334.0, 56361.71819140878, 56438.0, 56458.43541564614, 16650.417235396704, 16874.578135546708, 16938.49184524466, 17047.744283835753, 17075.0, 12962.546310879074, 13172.407241674147, 14472.782908256384, 15020.774688379683, 15074.849251507563, 13315.467381304834, 13382.933085334706, 13433.126846754929, 13458.461661533625, 13474.595762127152, 5265.894682106336, 5281.9207711884355, 5302.0, 5346.97326513365, 5386.730663466811, 983.7638966647953, 999.4003597841237, 1001.5643195210107, 1027.7482016905756, 1030.2118879057527, 28362.85818570894, 28772.568411473843, 28909.56635650467, 28955.56566651502, 28969.85515072411, 1069.2354966199205, 1079.4009324824797, 1082.6968448834339, 1085.066842515436, 1090.1442367741286, 14640.780388294184, 15552.300146493435, 15572.532824015298, 15632.2183890805, 15863.603409914844, 307742.0, 308670.0, 308899.0, 309117.0, 309490.0, 58430.415695843345, 58515.0, 58571.0, 58576.707116465, 58584.41415585875, 1485.688005518853, 1518.3698765012396, 1529.3729570875887, 1547.7987861577924, 1547.8761699064048, 26258.343541411617, 27874.860625696743, 27923.04538682354, 27923.301917452227, 28057.859710701316, 42083.0, 42088.78955605325, 42142.789286053376, 42198.0, 42202.788986054875, 278470.0, 281148.0, 281556.0, 282091.0, 283194.0, 28290.0, 34464.65535344614, 34531.0, 34633.48049779205, 34839.65160348466, 43822.34266486069, 44265.11469770651, 45112.54887451084, 45132.87167820831, 45243.547564523935, 33740.49389259212, 34386.484202737476, 34437.31125377528, 34577.13557161091, 35225.0, 33817.32365352728, 34103.0, 34209.0, 34408.48387274243, 35080.12299692426, 38694.419583705705, 39328.41007384834, 39356.409653855786, 39398.80300598479, 39885.80057099696, 32583.674163258063, 32709.672903270664, 32797.508037379936, 32966.50550241796, 33415.83292083524, 25701.87149064243, 27152.0, 27332.180034598994, 27347.31631709223, 27402.725972740016], "value": 19170.73739678439, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.96400287976974, 451.52138732943104, 460.7650098449791, 470.2969061253427, 483.41748193426923, 19483.707744383828, 20353.084111214994, 20440.386788396354, 21150.0, 21268.14927402902, 21833.126674932984, 22332.441688957784, 22618.09527618893, 22695.319140425774, 22801.201957931476, 6580.407763301302, 6695.698693558777, 6892.655367231619, 6931.618760968145, 6998.65006749663, 6805.217399999014, 6900.102986611759, 6909.032735417046, 6909.136357955255, 6923.5499692519825, 6651.434628056621, 6887.449004079686, 6913.27410621885, 6924.169099708042, 6941.028256044158, 6646.136002319668, 6851.760188393432, 6871.793846184598, 6922.896156557656, 6926.965365173167, 6712.362325579063, 6738.157730283715, 6752.628605426699, 6758.729650813962, 6761.661916904184, 25067.24798256055, 25171.496570068495, 25197.118100866566, 25206.495870082497, 25417.491650166892, 3062.8162310261455, 3090.922726931822, 3099.671412924416, 3109.6112985876766, 3148.763842711806, 16524.504264872074, 16531.421400250932, 16594.917025414794, 16622.66754664912, 16777.91611041937, 2223.377454312779, 2260.9321720348416, 2268.9886550567307, 2311.1448763957274, 2330.6037973544367, 85570.0, 95386.52306738422, 96673.0, 96689.5165524175, 97369.51315243378, 18428.723569146474, 18637.81362186388, 18849.340273090376, 19284.80715192858, 19872.801271987097, 34403.311933761186, 34444.65555344414, 34477.48283775745, 34493.65506344954, 34922.0, 11328.263662862053, 11375.772484550263, 11455.942720286344, 11535.884641153481, 11617.709557261136, 9506.144446999802, 9551.42691438516, 9604.51977401141, 9638.759031024281, 9796.314258002014, 9902.0, 10041.995800420045, 10148.188144948386, 10171.999260218254, 10540.420276884692, 3532.646735326447, 3578.499010138583, 3817.7930538347923, 3829.368154254517, 4057.229126465962, 971.6307803034942, 978.4422878958925, 988.7231575158967, 996.3225006995312, 996.5913975270105, 21553.892230538746, 21936.780632193473, 22121.33635990923, 22286.888565557394, 22538.21116260923, 1011.9139873110721, 1012.7113772574928, 1014.1633152648947, 1015.1117771949545, 1022.703416009349, 12234.08244381675, 12458.252504849737, 12628.368581570885, 12649.747005060031, 12840.55058072963, 641045.0, 642995.0, 669695.0, 700618.0, 707432.0, 85755.0, 85935.0, 86499.0, 86597.0, 86736.56631716927, 1356.186288227056, 1365.1877133105786, 1378.9586312410643, 1390.4785705360287, 1399.1045730732135, 18667.533311667317, 19025.52436189051, 19527.902360488108, 19534.609307814047, 19575.510612234808, 41769.0, 44131.338029928935, 44202.3369649462, 44248.33627495654, 45304.86737831581, 56148.719256403456, 56675.71662141663, 56765.43234567602, 57096.0, 57246.71376643257, 9249.398789078958, 9677.516124193902, 12400.19398739079, 12535.185212961129, 12794.808077878653, 13652.453901843815, 13753.724925501234, 13931.0, 13987.930060349634, 14307.35616897263, 12290.81563776562, 12420.689482763004, 12483.251004939733, 12531.0, 12716.109872308849, 11562.595309164139, 11632.941835290769, 11736.530538778354, 11955.462004210005, 12345.382730863601, 13017.284049377375, 13029.218246905217, 13042.0, 13105.737885242052, 13114.344282786013, 12527.436265368262, 12709.0, 12921.676958076123, 13002.674933126369, 13426.731465370833, 12550.623481295575, 12675.80986285188, 12738.235705857678, 12738.363081846055, 13197.80203296932], "value": 11454.809335997928, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.590058882924, 1501.6996600679868, 1515.9772603410947, 1517.5143953934733, 1523.520091171281, 9404.576794044267, 11504.194706370557, 11830.940845295761, 12190.634280971562, 12196.085293602993, 11831.7633647327, 11947.522099116024, 11960.1627886048, 11969.341686207255, 11982.760344793121, 2005.5587770690424, 2070.937871863839, 2294.9770502294973, 2341.496578235679, 2348.6242201247805, 2444.303373538542, 2497.775200231979, 2503.5243303772227, 2513.321403221129, 2523.936901577457, 2295.3114065780283, 2320.083566991046, 2328.4062564046144, 2332.1487657005155, 2336.8714720690355, 2237.7090978172732, 2253.0874995626714, 2253.4028482452163, 2254.1772253127656, 2258.164479142711, 2168.2844661261747, 2185.519185779126, 2187.2344679362254, 2190.868547887132, 2192.123150739701, 11451.770964580744, 11604.825927611093, 11616.825747613708, 11733.647990560212, 11866.050715942703, 5816.767329306823, 5944.732487038051, 5968.970155149196, 5969.671668058299, 5984.281886173687, 3388.7289016878835, 3533.6466353364717, 3599.3341231872187, 3608.1881576645305, 3613.439916812898, 1283.85235697895, 1312.4356526693518, 1344.0793056756056, 1354.1130559483554, 1368.8768010879069, 17594.560135996573, 17661.293548258054, 17678.381256655954, 17705.114744262737, 17726.91136544309, 4967.0, 5039.370078740158, 5084.084864724385, 5258.526732594085, 5340.946590534123, 13628.0, 13689.726205476034, 13696.315184240948, 13700.109492882932, 13766.31168441574, 6123.877522449526, 6148.477379422709, 6166.260048794084, 6166.845828854225, 6169.475594574422, 5494.066008778517, 5576.996140694714, 5599.944000560024, 5606.60753747242, 5615.382307946123, 4167.5832416758685, 4252.936205956913, 4256.851010214627, 4286.078493123993, 4302.548232435612, 1209.8124790657462, 1214.2593018258856, 1217.7686239614445, 1219.6767856517852, 1232.3222227774688, 1007.0835539658875, 1022.8312328465869, 1036.5801850250662, 1037.4190453346, 1038.7506998320348, 6755.2906944771075, 6902.758403455855, 6981.5112942093565, 7123.608201548861, 7172.82067948295, 1026.645807196516, 1060.6817954613584, 1061.5594528270835, 1064.095518809006, 1064.8455973883742, 4288.2495563276925, 4571.954280457219, 4629.953700463019, 4639.164950308977, 4642.744649044267, 13895.305234738222, 14294.642633934027, 14673.853261467462, 14970.326335314938, 14980.550583482513, 14682.486112985995, 14687.412503499954, 14692.779608305884, 14714.264286785832, 14730.852691473163, 10625.787484250272, 10751.032407083243, 10760.515776790064, 10774.892251077388, 10778.0, 7047.894281585883, 7177.6052317120975, 7262.455315851438, 7389.519681220703, 7487.176410594939, 13219.867801321863, 13240.139390939557, 13337.599872003855, 13482.258475783927, 13564.050516463753, 11067.94466027697, 12236.510539578318, 13430.597082087554, 13568.660783480493, 13819.792703109662, 10020.899791002288, 10106.595736170471, 10110.09009189147, 10229.897701023192, 10254.333468324536, 10546.630867919586, 10730.463476826284, 10790.838137428102, 10814.134869210602, 10814.621488248189, 9175.128362805523, 9444.763880903032, 9939.850902236616, 10099.495025248854, 10216.029477199654, 9511.191548718436, 9690.418574885529, 9726.173275271398, 9766.803840877916, 9915.801683966136, 8658.350623703122, 8863.867041994505, 8879.689210877588, 8881.42270752399, 8883.600237989176, 6666.666666666744, 6724.226713927766, 6747.190337159573, 6763.729450821912, 6842.349976752201, 6431.48548116159, 6517.511186661114, 6555.967220163869, 6650.933490665225, 6678.966605166943], "value": 5472.329407942535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.5616868035544, 413.40676129753797, 414.2109281818136, 415.32095024634697, 434.3593200029956, 5217.765200565974, 5330.840074797753, 5332.600054995881, 5334.412809787255, 5336.67979921204, 5041.924371134427, 5115.232715092738, 5234.450382709808, 5245.609913372949, 5273.34083239595, 4242.724222925515, 4271.722338048033, 4280.807363668627, 4323.286657701476, 4348.804303806336, 4295.570442955695, 4296.935545966798, 4309.935350969738, 4310.224159651261, 4328.35522501449, 4076.7553946763287, 4197.307444271692, 4200.285951388256, 4224.809883555247, 4279.679024073192, 4190.28765109932, 4198.664106871473, 4208.621224089831, 4209.726367786084, 4221.831126754942, 3830.2148059647707, 3851.133494963639, 3853.7687738735767, 3863.4011728182177, 3869.4389313549377, 4808.447028591721, 5012.373453318335, 5135.948640513622, 5164.288748407079, 5231.3119250928, 5113.159262665437, 5122.718250496221, 5128.769095417073, 5149.665271757323, 5151.227315902607, 5556.944430555724, 5560.221568980346, 5583.413741557119, 5597.74810133545, 5606.691631960241, 4329.393884856123, 4530.0, 4571.428571428572, 4600.470945841204, 4634.6524010698995, 5395.892082158374, 5541.667499950016, 5663.235463212455, 5670.886582268373, 5709.371969083398, 4546.954530454719, 4554.474251125844, 4563.931541026821, 4567.36056952027, 4588.449386073693, 4845.563899249085, 4848.200046992207, 4855.975720121377, 4857.465678775403, 4864.73243971585, 4889.926651100236, 5046.091703493406, 5115.514026167508, 5138.58891288696, 5144.536991670693, 5451.672899626035, 5462.781488740486, 5488.231647569343, 5492.752826122834, 5493.2034854946605, 1652.644831238373, 1672.991635041817, 1675.7235056215588, 1676.3462249722618, 1682.6887025900248, 1224.6668974292818, 1285.794272916329, 1302.5245785288303, 1311.7110641929894, 1313.1136482874022, 5206.245094461282, 5410.810621628224, 5411.404745477994, 5435.972820135953, 5513.345996201124, 5607.859803504945, 5639.548836093103, 5685.914711279334, 5688.601797874192, 5724.969505488969, 5477.589180811376, 5505.669659820343, 5507.476789704971, 5515.393306736256, 5542.8337149885565, 2863.7708983281286, 2957.438086763508, 2961.955570666442, 2980.925476863096, 3002.789804713649, 5793.0, 5813.418658134153, 5818.592698511064, 5866.384029676908, 5883.852903677443, 5510.393856675762, 5747.8563035924435, 5760.942390576124, 5807.738651760596, 5832.825015249549, 5806.838632273562, 5822.970885145632, 5856.385079566669, 5877.621172226718, 5899.203607512931, 5637.210790489253, 5652.50208694688, 5653.632513886584, 5657.858553536194, 5682.886342273214, 5453.836384908301, 5569.888602228014, 5604.327480702342, 5608.579356548194, 5610.85972850682, 5566.693831839126, 5662.85842853932, 5738.282714660668, 5881.617694849821, 5883.35283118848, 4737.692050016738, 4752.310914917387, 4756.357891684716, 4774.474807771212, 4794.161021821237, 5479.424660410599, 5638.182463542846, 5645.802396916089, 5683.914741278802, 5737.971310143589, 5458.444343362123, 5474.220878214516, 5479.643823151482, 5486.396496385474, 5505.119180931032, 5465.617406781487, 5485.232067510631, 5500.5874559409, 5505.834824955257, 5578.609497335147, 5225.779043906401, 5424.864378390572, 5491.478309560585, 5502.8349149525575, 5548.278723765885, 5528.668279903219, 5552.194931734795, 5556.861078473071, 5564.33228012641, 5572.86067848307, 5237.45495078945, 5438.864028399322, 5447.645903016291, 5462.0, 5475.698836564027], "value": 4415.21230299431, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.2108028730406, 804.899387576553, 884.5179377239405, 901.6685822995519, 912.9865848907707, 15713.528594142179, 18137.727934081, 18301.90849045753, 18310.0, 18494.0, 16196.514104576881, 16500.17499125045, 16677.583060423494, 16718.66562668746, 16722.24749886255, 854.995725021374, 861.4572819123944, 863.6372723456163, 864.2696921101659, 870.6299822575412, 873.1007062725371, 889.5063239901851, 889.8442772514817, 895.6417433026776, 896.4038914122134, 945.6122989574276, 947.166493485735, 947.2895328503622, 947.729896979361, 948.3029972969862, 941.5480569326727, 943.8914524829629, 944.1266828183936, 946.195733626412, 947.166493485735, 688.5490004047357, 689.5483458334799, 693.6670398208837, 694.5485434467586, 695.1935754524727, 16069.276882540196, 16128.919355403264, 16202.675946481122, 16986.150692465326, 17207.225674844663, 15488.380464781396, 15579.688406231811, 15722.606934826606, 15843.762343564857, 16168.029918204827, 12525.874741252563, 13011.34943252843, 13048.54330098442, 13064.21614703121, 13191.2744799036, 4026.355783074694, 4051.9189616207805, 4074.633283004544, 4123.0516981094215, 4184.372344148372, 49700.75149624229, 51967.480325196644, 71206.64396678035, 75110.62444687741, 85889.0, 12090.455929483194, 12268.509259629693, 12285.815712764315, 12315.876841231653, 12576.30830304342, 22070.88964555167, 22174.778252217595, 22323.888380558317, 22444.66333005006, 22565.4358641032, 8389.748307550783, 8500.192481714228, 8554.230010214183, 8602.0, 8716.78208044804, 4772.76136193189, 4804.95195048052, 4813.879653008703, 4842.782074806642, 4966.1309270877455, 3598.064503229179, 3667.211549516867, 3668.5781135169264, 3717.312297225022, 3729.384651532521, 647.7409036385484, 652.4649787174566, 656.4223483334634, 669.0165456778528, 670.7987603718865, 782.4992005116729, 793.4961299574696, 805.578456984486, 815.7430409421013, 826.7974346285199, 10798.352098874093, 10898.0, 11225.943870280596, 11356.26184298018, 11487.540498380138, 736.2012216744891, 743.5352904434728, 753.506453273107, 755.2296657409521, 768.4236822383212, 5139.357580302462, 5336.599755018389, 5427.213054107132, 5437.564994800407, 5459.5632349412745, 79423.60288198522, 81249.59375203204, 81398.0, 82635.5868220667, 84226.57886710527, 58302.7084864573, 60101.398986010456, 67880.6605966977, 74921.25078749252, 75027.0, 63138.36861631325, 63906.36093639097, 64621.03068453977, 64678.353216468175, 64769.0, 10237.164816054777, 10330.225233107698, 10359.741006474898, 10520.316179448617, 10532.947335263274, 47247.29129063136, 47411.28883066826, 47844.5215547854, 47908.28137578009, 48522.02955940791, 53197.734011329696, 53689.46310537001, 54021.72989135029, 54196.0, 55014.724926375115, 12011.75976480483, 12212.145149839425, 12472.688182795502, 12568.874311257136, 12680.175788573713, 13508.0, 13758.0, 13812.792808107686, 13851.584452466443, 13947.58157255284, 13364.331783410596, 13718.17690938547, 13852.792208117087, 13931.373088210852, 14364.928175359057, 13014.609561713163, 13539.390727417092, 13546.390412431267, 13576.93211533936, 13812.0, 13272.933635331761, 13276.80084798748, 13418.0, 13464.326783660581, 13555.186688798703, 9756.51217439111, 9795.9020409795, 9820.0, 10271.589136434757, 10425.843612345972, 10258.897411026093, 11384.203105782515, 11537.82693259585, 11543.422828858691, 11634.825477617673], "value": 7597.888806455869, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 112.0, 112.0, 112.0, 110.0, 112.0, 110.0, 110.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 114.0, 151.0, 118.0, 117.0, 120.0, 116.0, 135.0, 115.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 162.0, 162.0, 161.0, 162.0, 161.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 114.0, 76.0, 78.0, 77.0, 75.0, 89.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 107.0, 83.0, 83.0, 83.0, 96.0, 82.0, 82.0, 107.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 145.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 56.0, 56.0, 56.0, 57.0, 56.0, 57.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 54.0, 54.0, 53.0, 53.0, 53.0, 53.0, 54.0], "value": 53.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 77.0, 78.0, 81.0, 75.0, 77.0, 76.0, 80.0, 79.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 132.0, 121.0, 124.0, 123.0, 126.0, 117.0, 118.0, 161.0, 131.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 157.0, 163.0, 156.0, 158.0, 159.0, 156.0, 158.0, 158.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 62.0, 60.0, 64.0, 58.0, 59.0, 58.0], "value": 59.8, "unit": "score"}]}]} 09:54:24 INFO - Return code: 0 09:54:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:25 ERROR - # TBPL SUCCESS # 09:54:25 INFO - Running post-action listener: _resource_record_post_action 09:54:25 INFO - [mozharness: 2017-04-09 16:54:25.047123Z] Finished run-tests step (success) 09:54:25 INFO - Running post-run listener: _resource_record_post_run 09:54:25 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 761856; Write bytes: 364949504; Read time: 192; Write time: 188276 09:54:25 INFO - TinderboxPrint: CPU usage
13.1% 09:54:25 INFO - TinderboxPrint: I/O read bytes / time
761,856 / 192 09:54:25 INFO - TinderboxPrint: I/O write bytes / time
364,949,504 / 188,276 09:54:25 INFO - TinderboxPrint: CPU idle
8,265.9 (86.9%) 09:54:25 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 09:54:25 INFO - TinderboxPrint: Swap in / out
0 / 0 09:54:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 110850048; Read time: 120; Write time: 87296 09:54:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 757760; Write bytes: 254099456; Read time: 72; Write time: 100980 09:54:25 INFO - Running post-run listener: _upload_blobber_files 09:54:25 INFO - Blob upload gear active. 09:54:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:26 INFO - (blobuploader) - INFO - Done attempting. 09:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:30 INFO - (blobuploader) - INFO - Done attempting. 09:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:54:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:54:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:30 INFO - (blobuploader) - INFO - Done attempting. 09:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:54:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:54:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:31 INFO - (blobuploader) - INFO - Done attempting. 09:54:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:31 INFO - Return code: 0 09:54:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:31 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7f495e25c37c77f00c40355c9975f315d86682fa590260f08afcf051717f8f77bdb2ba5d2c138718a402e3004784a801b8eb36e7ffab33ff00a34384e7f99f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0e38a89fa6fb28f4b39a23ac5950db02579b18edf28f3f95667b6b608b38622a536e721ac0177731628a8d342df722aac75f42bbf4b7f83408b95c18ff383e1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d5625dd42bf34fa3f6520525354a125f2196efec1dc309889f0b7b164108eed95e57332feff0151ee8e2ae78f7a90d8338d123d27c71aac6736f3bc8d013ca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b151a2ec6fcc52566b0bbbbcfef33b736714eee9cf9c8e5f18de3e5cba9d9872adc3f6cff3e21be669fd21ec792fb69478b9441e1c4c057bedab419d2a09fcb3"} 09:54:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:31 INFO - Contents: 09:54:31 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7f495e25c37c77f00c40355c9975f315d86682fa590260f08afcf051717f8f77bdb2ba5d2c138718a402e3004784a801b8eb36e7ffab33ff00a34384e7f99f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0e38a89fa6fb28f4b39a23ac5950db02579b18edf28f3f95667b6b608b38622a536e721ac0177731628a8d342df722aac75f42bbf4b7f83408b95c18ff383e1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d5625dd42bf34fa3f6520525354a125f2196efec1dc309889f0b7b164108eed95e57332feff0151ee8e2ae78f7a90d8338d123d27c71aac6736f3bc8d013ca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b151a2ec6fcc52566b0bbbbcfef33b736714eee9cf9c8e5f18de3e5cba9d9872adc3f6cff3e21be669fd21ec792fb69478b9441e1c4c057bedab419d2a09fcb3"} 09:54:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:31 INFO - Copying logs to upload dir... 09:54:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.645053 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-04-09 09:54:31.859968) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:31.863624) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7f495e25c37c77f00c40355c9975f315d86682fa590260f08afcf051717f8f77bdb2ba5d2c138718a402e3004784a801b8eb36e7ffab33ff00a34384e7f99f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0e38a89fa6fb28f4b39a23ac5950db02579b18edf28f3f95667b6b608b38622a536e721ac0177731628a8d342df722aac75f42bbf4b7f83408b95c18ff383e1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d5625dd42bf34fa3f6520525354a125f2196efec1dc309889f0b7b164108eed95e57332feff0151ee8e2ae78f7a90d8338d123d27c71aac6736f3bc8d013ca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b151a2ec6fcc52566b0bbbbcfef33b736714eee9cf9c8e5f18de3e5cba9d9872adc3f6cff3e21be669fd21ec792fb69478b9441e1c4c057bedab419d2a09fcb3"} build_url:https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/Cjf6pe65RV-94D_L70RxiQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7f495e25c37c77f00c40355c9975f315d86682fa590260f08afcf051717f8f77bdb2ba5d2c138718a402e3004784a801b8eb36e7ffab33ff00a34384e7f99f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0e38a89fa6fb28f4b39a23ac5950db02579b18edf28f3f95667b6b608b38622a536e721ac0177731628a8d342df722aac75f42bbf4b7f83408b95c18ff383e1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d5625dd42bf34fa3f6520525354a125f2196efec1dc309889f0b7b164108eed95e57332feff0151ee8e2ae78f7a90d8338d123d27c71aac6736f3bc8d013ca6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b151a2ec6fcc52566b0bbbbcfef33b736714eee9cf9c8e5f18de3e5cba9d9872adc3f6cff3e21be669fd21ec792fb69478b9441e1c4c057bedab419d2a09fcb3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:31.888998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:31.889348) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1491755092.808531-1007945780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:31.974635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 09:54:31.974988) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 09:54:31.975336) ========= ========= Total master_lag: 0.23 =========