builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1491584363.5 results: success (0) revision: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.496071) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.496573) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.496857) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.511586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.511943) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-07 09:59:23-- 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% 111M=0s 2017-04-07 09:59:23 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084493 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.627970) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.628394) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018124 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:23.678859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 09:59:23.679211) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad --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 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad --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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-07 09:59:24,047 truncating revision to first 12 chars 2017-04-07 09:59:24,047 Setting DEBUG logging. 2017-04-07 09:59:24,047 attempt 1/10 2017-04-07 09:59:24,047 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2e7b7b293fdc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 09:59:24,951 unpacking tar archive at: mozilla-aurora-2e7b7b293fdc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.203867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 09:59:24.902279) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:24.902932) ========= script_repo_revision: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:24.906730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:24.907330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 09:59:24.918982) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-07 09:59:24.919387) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:59:25 INFO - MultiFileLogger online at 20170407 09:59:25 in /builds/slave/test 09:59:25 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:59:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:59:25 INFO - {'append_to_log': False, 09:59:25 INFO - 'base_work_dir': '/builds/slave/test', 09:59:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:59:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:59:25 INFO - 'branch': 'Mozilla-Aurora', 09:59:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:25 INFO - 'config_files': ('talos/linux_config.py',), 09:59:25 INFO - 'default_actions': ('clobber', 09:59:25 INFO - 'read-buildbot-config', 09:59:25 INFO - 'download-and-extract', 09:59:25 INFO - 'populate-webroot', 09:59:25 INFO - 'create-virtualenv', 09:59:25 INFO - 'install', 09:59:25 INFO - 'run-tests'), 09:59:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:25 INFO - 'download_minidump_stackwalk': True, 09:59:25 INFO - 'download_symbols': 'ondemand', 09:59:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:25 INFO - 'gecko_profile': False, 09:59:25 INFO - 'gecko_profile_interval': 0, 09:59:25 INFO - 'installer_path': 'installer.exe', 09:59:25 INFO - 'log_level': 'info', 09:59:25 INFO - 'log_name': 'talos', 09:59:25 INFO - 'log_to_console': True, 09:59:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:59:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:59:25 INFO - 'opt_config_files': (), 09:59:25 INFO - 'pip_index': False, 09:59:25 INFO - 'suite': 'dromaeojs', 09:59:25 INFO - 'system_bits': '32', 09:59:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:25 INFO - 'title': 'talos-linux64-ix-064', 09:59:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:25 INFO - 'use_talos_json': True, 09:59:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:59:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:25 INFO - 'work_dir': 'build'} 09:59:25 INFO - [mozharness: 2017-04-07 16:59:25.389955Z] Running clobber step. 09:59:25 INFO - Running pre-action listener: _resource_record_pre_action 09:59:25 INFO - Running main action method: clobber 09:59:25 INFO - rmtree: /builds/slave/test/build 09:59:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:59:26 INFO - Running post-action listener: _resource_record_post_action 09:59:26 INFO - [mozharness: 2017-04-07 16:59:26.410942Z] Finished clobber step (success) 09:59:26 INFO - [mozharness: 2017-04-07 16:59:26.411042Z] Running read-buildbot-config step. 09:59:26 INFO - Running pre-action listener: _resource_record_pre_action 09:59:26 INFO - Running main action method: read_buildbot_config 09:59:26 INFO - Using buildbot properties: 09:59:26 INFO - { 09:59:26 INFO - "project": "", 09:59:26 INFO - "product": "firefox", 09:59:26 INFO - "who": "ffxbld@noreply.mozilla.org", 09:59:26 INFO - "installer_path": "public/build/target.tar.bz2", 09:59:26 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:59:26 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:59:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:59:26 INFO - "stage_platform": "linux64-pgo", 09:59:26 INFO - "basedir": "/builds/slave/test", 09:59:26 INFO - "buildnumber": 875, 09:59:26 INFO - "platform": "ubuntu64_hw", 09:59:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:59:26 INFO - "slavebuilddir": "test", 09:59:26 INFO - "taskId": "ayGxjxmPR5aJc8g3xSa82Q", 09:59:26 INFO - "branch": "mozilla-aurora", 09:59:26 INFO - "script_repo_revision": "production", 09:59:26 INFO - "revision": "2e7b7b293fdc1444430855ffb1b3d88925c9d5ad", 09:59:26 INFO - "slavename": "talos-linux64-ix-064", 09:59:26 INFO - "repo_path": "releases/mozilla-aurora" 09:59:26 INFO - } 09:59:26 INFO - Finding installer, test and symbols from parent task. 09:59:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ayGxjxmPR5aJc8g3xSa82Q'}, attempt #1 09:59:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ayGxjxmPR5aJc8g3xSa82Q'}, attempt #1 09:59:27 INFO - Task dependencies: TWJpmx7vRyGM8NV0vA9xnw 09:59:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw'}, attempt #1 09:59:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 09:59:27 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/test_packages.json 09:59:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.crashreporter-symbols.zip 09:59:27 INFO - Running post-action listener: _resource_record_post_action 09:59:27 INFO - [mozharness: 2017-04-07 16:59:27.784667Z] Finished read-buildbot-config step (success) 09:59:27 INFO - [mozharness: 2017-04-07 16:59:27.784885Z] Running download-and-extract step. 09:59:27 INFO - Running pre-action listener: _resource_record_pre_action 09:59:27 INFO - Running main action method: download_and_extract 09:59:27 INFO - mkdir: /builds/slave/test/build/tests 09:59:27 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:59:27 INFO - trying https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.test_packages.json 09:59:27 INFO - Downloading https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:59:28 INFO - Downloaded 896 bytes. 09:59:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:59:28 INFO - Using the following test package requirements: 09:59:28 INFO - {u'common': [u'target.common.tests.zip'], 09:59:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:59:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:59:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:59:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:59:28 INFO - u'mozbase': [u'target.common.tests.zip'], 09:59:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:59:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:59:28 INFO - u'web-platform': [u'target.common.tests.zip', 09:59:28 INFO - u'target.web-platform.tests.zip'], 09:59:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:59:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:59:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip 09:59:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:59:28 INFO - Fetch https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip into memory 09:59:29 INFO - Expected file size: 59539722 09:59:29 INFO - Obtained file size: 59539722 09:59:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:59:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip 09:59:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:59:39 INFO - Fetch https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.common.tests.zip into memory 09:59:40 INFO - Expected file size: 59539722 09:59:40 INFO - Obtained file size: 59539722 09:59:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.talos.tests.zip 09:59:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:59:50 INFO - Fetch https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.talos.tests.zip into memory 09:59:51 INFO - Expected file size: 14404424 09:59:51 INFO - Obtained file size: 14404424 09:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:51 INFO - trying https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 09:59:51 INFO - Downloading https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:59:52 INFO - Downloaded 66810053 bytes. 09:59:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 09:59:52 INFO - mkdir: /builds/slave/test/properties 09:59:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:52 INFO - Contents: 09:59:52 INFO - build_url:https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 09:59:52 INFO - Running post-action listener: _resource_record_post_action 09:59:52 INFO - Running post-action listener: set_extra_try_arguments 09:59:52 INFO - [mozharness: 2017-04-07 16:59:52.866113Z] Finished download-and-extract step (success) 09:59:52 INFO - [mozharness: 2017-04-07 16:59:52.866209Z] Running populate-webroot step. 09:59:52 INFO - Running pre-action listener: _resource_record_pre_action 09:59:52 INFO - Running main action method: populate_webroot 09:59:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:52 INFO - '--tppagecycles', 09:59:52 INFO - '7'], 09:59:52 INFO - 'tests': ['tsvgm']}}, 09:59:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['damp', 'tps']}, 09:59:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'tests': ['damp', 'tps']}, 09:59:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['dromaeo_dom']}, 09:59:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['basic_compositor_video']}, 09:59:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tsvgx', 09:59:52 INFO - 'tsvgr_opacity', 09:59:52 INFO - 'tart', 09:59:52 INFO - 'tscrollx', 09:59:52 INFO - 'cart', 09:59:52 INFO - 'tsvg_static']}, 09:59:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:52 INFO - 'tsvgr_opacity', 09:59:52 INFO - 'tart', 09:59:52 INFO - 'tscrollx', 09:59:52 INFO - 'cart', 09:59:52 INFO - 'tsvg_static']}, 09:59:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tp5o']}, 09:59:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:59:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s', 09:59:52 INFO - '--xperf_path', 09:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:52 INFO - 'tests': ['tp5n']}, 09:59:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--xperf_path', 09:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:52 INFO - 'tests': ['tp5n']}}, 09:59:52 INFO - 'talos.zip': {'path': '', 09:59:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:52 INFO - Running post-action listener: _resource_record_post_action 09:59:52 INFO - [mozharness: 2017-04-07 16:59:52.879354Z] Finished populate-webroot step (success) 09:59:52 INFO - [mozharness: 2017-04-07 16:59:52.879444Z] Running create-virtualenv step. 09:59:52 INFO - Running pre-action listener: _resource_record_pre_action 09:59:52 INFO - Running main action method: create_virtualenv 09:59:52 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:52 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:59:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:52 INFO - Using real prefix '/usr' 09:59:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:53 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:55 INFO - Installing pip.................done. 09:59:55 INFO - Return code: 0 09:59:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:55 INFO - Reading from file tmpfile_stdout 09:59:55 INFO - Output received: 09:59:55 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:59:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:59:55 INFO - Using env: {'DISPLAY': ':0', 09:59:55 INFO - 'HOME': '/home/cltbld', 09:59:55 INFO - 'LANG': 'en_US.UTF-8', 09:59:55 INFO - 'LANGUAGE': 'en_US:en', 09:59:55 INFO - 'LOGNAME': 'cltbld', 09:59:55 INFO - 'MAIL': '/var/mail/cltbld', 09:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:55 INFO - 'MOZ_NO_REMOTE': '1', 09:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:55 INFO - 'NO_EM_RESTART': '1', 09:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:55 INFO - 'PWD': '/builds/slave/test', 09:59:55 INFO - 'SHELL': '/bin/bash', 09:59:55 INFO - 'SHLVL': '1', 09:59:55 INFO - 'TERM': 'linux', 09:59:55 INFO - 'TMOUT': '86400', 09:59:55 INFO - 'USER': 'cltbld', 09:59:55 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 09:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:55 INFO - '_': '/tools/buildbot/bin/python'} 09:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:55 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:59:55 INFO - Cleaning up... 09:59:55 INFO - Return code: 0 09:59:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:59:55 INFO - Using env: {'DISPLAY': ':0', 09:59:55 INFO - 'HOME': '/home/cltbld', 09:59:55 INFO - 'LANG': 'en_US.UTF-8', 09:59:55 INFO - 'LANGUAGE': 'en_US:en', 09:59:55 INFO - 'LOGNAME': 'cltbld', 09:59:55 INFO - 'MAIL': '/var/mail/cltbld', 09:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:55 INFO - 'MOZ_NO_REMOTE': '1', 09:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:55 INFO - 'NO_EM_RESTART': '1', 09:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:55 INFO - 'PWD': '/builds/slave/test', 09:59:55 INFO - 'SHELL': '/bin/bash', 09:59:55 INFO - 'SHLVL': '1', 09:59:55 INFO - 'TERM': 'linux', 09:59:55 INFO - 'TMOUT': '86400', 09:59:55 INFO - 'USER': 'cltbld', 09:59:55 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 09:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:55 INFO - '_': '/tools/buildbot/bin/python'} 09:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:56 INFO - Downloading/unpacking psutil>=3.1.1 09:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:58 INFO - Installing collected packages: psutil 09:59:58 INFO - Running setup.py install for psutil 09:59:58 INFO - building 'psutil._psutil_linux' extension 09:59:58 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:59:58 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:59:58 INFO - building 'psutil._psutil_posix' extension 09:59:58 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:59:58 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:59:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:58 INFO - Successfully installed psutil 09:59:58 INFO - Cleaning up... 09:59:58 INFO - Return code: 0 09:59:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:58 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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:59:58 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:59:58 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:59:58 INFO - Using env: {'DISPLAY': ':0', 09:59:58 INFO - 'HOME': '/home/cltbld', 09:59:58 INFO - 'LANG': 'en_US.UTF-8', 09:59:58 INFO - 'LANGUAGE': 'en_US:en', 09:59:58 INFO - 'LOGNAME': 'cltbld', 09:59:58 INFO - 'MAIL': '/var/mail/cltbld', 09:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:58 INFO - 'MOZ_NO_REMOTE': '1', 09:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:58 INFO - 'NO_EM_RESTART': '1', 09:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:58 INFO - 'PWD': '/builds/slave/test', 09:59:58 INFO - 'SHELL': '/bin/bash', 09:59:58 INFO - 'SHLVL': '1', 09:59:58 INFO - 'TERM': 'linux', 09:59:58 INFO - 'TMOUT': '86400', 09:59:58 INFO - 'USER': 'cltbld', 09:59:58 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 09:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:58 INFO - '_': '/tools/buildbot/bin/python'} 09:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:59 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:59:59 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:59:59 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:59:59 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:59:59 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:59:59 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 10:00:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:00:01 INFO - Installing collected packages: mozsystemmonitor 10:00:01 INFO - Running setup.py install for mozsystemmonitor 10:00:01 INFO - Successfully installed mozsystemmonitor 10:00:01 INFO - Cleaning up... 10:00:01 INFO - Return code: 0 10:00:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:00:01 INFO - Using env: {'DISPLAY': ':0', 10:00:01 INFO - 'HOME': '/home/cltbld', 10:00:01 INFO - 'LANG': 'en_US.UTF-8', 10:00:01 INFO - 'LANGUAGE': 'en_US:en', 10:00:01 INFO - 'LOGNAME': 'cltbld', 10:00:01 INFO - 'MAIL': '/var/mail/cltbld', 10:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:01 INFO - 'MOZ_NO_REMOTE': '1', 10:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:01 INFO - 'NO_EM_RESTART': '1', 10:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:01 INFO - 'PWD': '/builds/slave/test', 10:00:01 INFO - 'SHELL': '/bin/bash', 10:00:01 INFO - 'SHLVL': '1', 10:00:01 INFO - 'TERM': 'linux', 10:00:01 INFO - 'TMOUT': '86400', 10:00:01 INFO - 'USER': 'cltbld', 10:00:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:01 INFO - '_': '/tools/buildbot/bin/python'} 10:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:01 INFO - Downloading/unpacking jsonschema==2.5.1 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:00:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:00:03 INFO - warning: no files found matching '*.txt' 10:00:03 INFO - no previously-included directories found matching 'build' 10:00:03 INFO - no previously-included directories found matching 'dist' 10:00:03 INFO - no previously-included directories found matching '.git*' 10:00:03 INFO - Installing collected packages: jsonschema, functools32 10:00:03 INFO - Running setup.py install for functools32 10:00:03 INFO - warning: no files found matching '*.txt' 10:00:03 INFO - no previously-included directories found matching 'build' 10:00:03 INFO - no previously-included directories found matching 'dist' 10:00:03 INFO - no previously-included directories found matching '.git*' 10:00:03 INFO - Successfully installed jsonschema functools32 10:00:03 INFO - Cleaning up... 10:00:03 INFO - Return code: 0 10:00:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:04 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:04 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 10:00:04 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 10:00:04 INFO - Using env: {'DISPLAY': ':0', 10:00:04 INFO - 'HOME': '/home/cltbld', 10:00:04 INFO - 'LANG': 'en_US.UTF-8', 10:00:04 INFO - 'LANGUAGE': 'en_US:en', 10:00:04 INFO - 'LOGNAME': 'cltbld', 10:00:04 INFO - 'MAIL': '/var/mail/cltbld', 10:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:04 INFO - 'MOZ_NO_REMOTE': '1', 10:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:04 INFO - 'NO_EM_RESTART': '1', 10:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:04 INFO - 'PWD': '/builds/slave/test', 10:00:04 INFO - 'SHELL': '/bin/bash', 10:00:04 INFO - 'SHLVL': '1', 10:00:04 INFO - 'TERM': 'linux', 10:00:04 INFO - 'TMOUT': '86400', 10:00:04 INFO - 'USER': 'cltbld', 10:00:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:04 INFO - '_': '/tools/buildbot/bin/python'} 10:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:00:04 INFO - Cleaning up... 10:00:04 INFO - Return code: 0 10:00:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:00: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')]} 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00: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')]} 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:04 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:04 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 10:00:04 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 10:00:04 INFO - Using env: {'DISPLAY': ':0', 10:00:04 INFO - 'HOME': '/home/cltbld', 10:00:04 INFO - 'LANG': 'en_US.UTF-8', 10:00:04 INFO - 'LANGUAGE': 'en_US:en', 10:00:04 INFO - 'LOGNAME': 'cltbld', 10:00:04 INFO - 'MAIL': '/var/mail/cltbld', 10:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:04 INFO - 'MOZ_NO_REMOTE': '1', 10:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:04 INFO - 'NO_EM_RESTART': '1', 10:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:04 INFO - 'PWD': '/builds/slave/test', 10:00:04 INFO - 'SHELL': '/bin/bash', 10:00:04 INFO - 'SHLVL': '1', 10:00:04 INFO - 'TERM': 'linux', 10:00:04 INFO - 'TMOUT': '86400', 10:00:04 INFO - 'USER': 'cltbld', 10:00:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:04 INFO - '_': '/tools/buildbot/bin/python'} 10:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:06 INFO - Downloading blobuploader-1.2.4.tar.gz 10:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:00:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:00:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:06 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 10:00:07 INFO - Downloading docopt-0.6.1.tar.gz 10:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:00:07 INFO - Installing collected packages: blobuploader, requests, docopt 10:00:07 INFO - Running setup.py install for blobuploader 10:00:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:00:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:00:07 INFO - Running setup.py install for requests 10:00:07 INFO - Running setup.py install for docopt 10:00:07 INFO - Successfully installed blobuploader requests docopt 10:00:07 INFO - Cleaning up... 10:00:07 INFO - Return code: 0 10:00:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:07 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')]} 10:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:07 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')]} 10:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:07 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:07 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 10:00:07 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 10:00:07 INFO - Using env: {'DISPLAY': ':0', 10:00:07 INFO - 'HOME': '/home/cltbld', 10:00:07 INFO - 'LANG': 'en_US.UTF-8', 10:00:07 INFO - 'LANGUAGE': 'en_US:en', 10:00:07 INFO - 'LOGNAME': 'cltbld', 10:00:07 INFO - 'MAIL': '/var/mail/cltbld', 10:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:07 INFO - 'MOZ_NO_REMOTE': '1', 10:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:07 INFO - 'NO_EM_RESTART': '1', 10:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:07 INFO - 'PWD': '/builds/slave/test', 10:00:07 INFO - 'SHELL': '/bin/bash', 10:00:07 INFO - 'SHLVL': '1', 10:00:07 INFO - 'TERM': 'linux', 10:00:07 INFO - 'TMOUT': '86400', 10:00:07 INFO - 'USER': 'cltbld', 10:00:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:07 INFO - '_': '/tools/buildbot/bin/python'} 10:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:00:08 INFO - Running setup.py (path:/tmp/pip-EoOKbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:00:08 INFO - Running setup.py (path:/tmp/pip-v6p5A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:00:08 INFO - Running setup.py (path:/tmp/pip-soBuHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:00:08 INFO - Running setup.py (path:/tmp/pip-56jTH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:00:08 INFO - Running setup.py (path:/tmp/pip-dubu16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:00:08 INFO - Running setup.py (path:/tmp/pip-Z6OgDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:08 INFO - Running setup.py (path:/tmp/pip-IHD_XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:08 INFO - Running setup.py (path:/tmp/pip-FSAk6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:08 INFO - Running setup.py (path:/tmp/pip-uN60Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:08 INFO - Running setup.py (path:/tmp/pip-yLz8Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:08 INFO - Running setup.py (path:/tmp/pip-EZ4OPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:08 INFO - Running setup.py (path:/tmp/pip-xGyjyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:08 INFO - Running setup.py (path:/tmp/pip-_5Ique-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:09 INFO - Running setup.py (path:/tmp/pip-UcSFG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:09 INFO - Running setup.py (path:/tmp/pip-SZTBgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:09 INFO - Running setup.py (path:/tmp/pip-FtE8zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:09 INFO - Running setup.py (path:/tmp/pip-pFbA3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:00:09 INFO - Running setup.py install for manifestparser 10:00:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:00:09 INFO - Running setup.py install for mozcrash 10:00:09 INFO - Running setup.py install for mozdebug 10:00:09 INFO - Running setup.py install for mozdevice 10:00:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:00:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:00:09 INFO - Running setup.py install for mozfile 10:00:09 INFO - Running setup.py install for mozhttpd 10:00:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:00:09 INFO - Running setup.py install for mozinfo 10:00:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for mozInstall 10:00:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for mozleak 10:00:10 INFO - Running setup.py install for mozlog 10:00:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for moznetwork 10:00:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for mozprocess 10:00:10 INFO - Running setup.py install for mozprofile 10:00:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for mozrunner 10:00:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:00:10 INFO - Running setup.py install for mozscreenshot 10:00:10 INFO - Running setup.py install for moztest 10:00:11 INFO - Running setup.py install for mozversion 10:00:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:00:11 INFO - Cleaning up... 10:00:11 INFO - Return code: 0 10:00:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:11 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')]} 10:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:11 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')]} 10:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:11 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:11 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 10:00:11 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 10:00:11 INFO - Using env: {'DISPLAY': ':0', 10:00:11 INFO - 'HOME': '/home/cltbld', 10:00:11 INFO - 'LANG': 'en_US.UTF-8', 10:00:11 INFO - 'LANGUAGE': 'en_US:en', 10:00:11 INFO - 'LOGNAME': 'cltbld', 10:00:11 INFO - 'MAIL': '/var/mail/cltbld', 10:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:11 INFO - 'MOZ_NO_REMOTE': '1', 10:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:11 INFO - 'NO_EM_RESTART': '1', 10:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:11 INFO - 'PWD': '/builds/slave/test', 10:00:11 INFO - 'SHELL': '/bin/bash', 10:00:11 INFO - 'SHLVL': '1', 10:00:11 INFO - 'TERM': 'linux', 10:00:11 INFO - 'TMOUT': '86400', 10:00:11 INFO - 'USER': 'cltbld', 10:00:11 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:11 INFO - '_': '/tools/buildbot/bin/python'} 10:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:00:11 INFO - Running setup.py (path:/tmp/pip-FUZ8MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:00:11 INFO - Running setup.py (path:/tmp/pip-DKZa9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:00:11 INFO - Running setup.py (path:/tmp/pip-9AJ9RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:00:11 INFO - Running setup.py (path:/tmp/pip-4Cxcko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:00:11 INFO - Running setup.py (path:/tmp/pip-E89lSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:00:11 INFO - Running setup.py (path:/tmp/pip-JBsBZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:11 INFO - Running setup.py (path:/tmp/pip-2KIyJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:11 INFO - Running setup.py (path:/tmp/pip-RUsPzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:11 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)) 10:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:11 INFO - Running setup.py (path:/tmp/pip-p4MqNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:12 INFO - Running setup.py (path:/tmp/pip-BaIMz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:12 INFO - Running setup.py (path:/tmp/pip-XhBXBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:12 INFO - Running setup.py (path:/tmp/pip-6hQ4fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:12 INFO - Running setup.py (path:/tmp/pip-2QUvXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:12 INFO - Running setup.py (path:/tmp/pip-mTF3Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:12 INFO - Running setup.py (path:/tmp/pip-w3T0vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:12 INFO - Running setup.py (path:/tmp/pip-0lBXc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:12 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)) 10:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:12 INFO - Running setup.py (path:/tmp/pip-Sf7t2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:12 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)) 10:00:12 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)) 10:00:12 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)) 10:00:12 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)) 10:00:12 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)) 10:00:12 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)) 10:00:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:00:12 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 10:00:12 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 10:00:12 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 10:00:12 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 10:00:12 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 10:00:12 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 10:00:14 INFO - Downloading blessings-1.6.tar.gz 10:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:00:14 INFO - Installing collected packages: blessings 10:00:14 INFO - Running setup.py install for blessings 10:00:14 INFO - Successfully installed blessings 10:00:14 INFO - Cleaning up... 10:00:14 INFO - Return code: 0 10:00:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:00:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:15 INFO - Reading from file tmpfile_stdout 10:00:15 INFO - Current package versions: 10:00:15 INFO - argparse == 1.2.1 10:00:15 INFO - blessings == 1.6 10:00:15 INFO - blobuploader == 1.2.4 10:00:15 INFO - docopt == 0.6.1 10:00:15 INFO - functools32 == 3.2.3-2 10:00:15 INFO - jsonschema == 2.5.1 10:00:15 INFO - manifestparser == 1.1 10:00:15 INFO - mozInstall == 1.12 10:00:15 INFO - mozcrash == 1.0 10:00:15 INFO - mozdebug == 0.1 10:00:15 INFO - mozdevice == 0.49 10:00:15 INFO - mozfile == 1.2 10:00:15 INFO - mozhttpd == 0.7 10:00:15 INFO - mozinfo == 0.9 10:00:15 INFO - mozleak == 0.1 10:00:15 INFO - mozlog == 3.4 10:00:15 INFO - moznetwork == 0.27 10:00:15 INFO - mozprocess == 0.25 10:00:15 INFO - mozprofile == 0.28 10:00:15 INFO - mozrunner == 6.13 10:00:15 INFO - mozscreenshot == 0.1 10:00:15 INFO - mozsystemmonitor == 0.3 10:00:15 INFO - moztest == 0.8 10:00:15 INFO - mozversion == 1.4 10:00:15 INFO - psutil == 3.1.1 10:00:15 INFO - requests == 1.2.3 10:00:15 INFO - wsgiref == 0.1.2 10:00:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:15 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 10:00:15 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 10:00:15 INFO - Using env: {'DISPLAY': ':0', 10:00:15 INFO - 'HOME': '/home/cltbld', 10:00:15 INFO - 'LANG': 'en_US.UTF-8', 10:00:15 INFO - 'LANGUAGE': 'en_US:en', 10:00:15 INFO - 'LOGNAME': 'cltbld', 10:00:15 INFO - 'MAIL': '/var/mail/cltbld', 10:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:15 INFO - 'MOZ_NO_REMOTE': '1', 10:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:15 INFO - 'NO_EM_RESTART': '1', 10:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:15 INFO - 'PWD': '/builds/slave/test', 10:00:15 INFO - 'SHELL': '/bin/bash', 10:00:15 INFO - 'SHLVL': '1', 10:00:15 INFO - 'TERM': 'linux', 10:00:15 INFO - 'TMOUT': '86400', 10:00:15 INFO - 'USER': 'cltbld', 10:00:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:15 INFO - '_': '/tools/buildbot/bin/python'} 10:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 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)) 10:00:15 INFO - Cleaning up... 10:00:15 INFO - Return code: 0 10:00:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 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 0x25321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', '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 10:00:15 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 10:00:15 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 10:00:15 INFO - Using env: {'DISPLAY': ':0', 10:00:15 INFO - 'HOME': '/home/cltbld', 10:00:15 INFO - 'LANG': 'en_US.UTF-8', 10:00:15 INFO - 'LANGUAGE': 'en_US:en', 10:00:15 INFO - 'LOGNAME': 'cltbld', 10:00:15 INFO - 'MAIL': '/var/mail/cltbld', 10:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:15 INFO - 'MOZ_NO_REMOTE': '1', 10:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:15 INFO - 'NO_EM_RESTART': '1', 10:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:15 INFO - 'PWD': '/builds/slave/test', 10:00:15 INFO - 'SHELL': '/bin/bash', 10:00:15 INFO - 'SHLVL': '1', 10:00:15 INFO - 'TERM': 'linux', 10:00:15 INFO - 'TMOUT': '86400', 10:00:15 INFO - 'USER': 'cltbld', 10:00:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:15 INFO - '_': '/tools/buildbot/bin/python'} 10:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:00:15 INFO - Cleaning up... 10:00:15 INFO - Return code: 0 10:00:15 INFO - Running post-action listener: _resource_record_post_action 10:00:15 INFO - Running post-action listener: _start_resource_monitoring 10:00:15 INFO - Starting resource monitoring. 10:00:15 INFO - [mozharness: 2017-04-07 17:00:15.590119Z] Finished create-virtualenv step (success) 10:00:15 INFO - [mozharness: 2017-04-07 17:00:15.590626Z] Running install step. 10:00:15 INFO - Running pre-action listener: _resource_record_pre_action 10:00:15 INFO - Running main action method: install 10:00:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:15 INFO - Reading from file tmpfile_stdout 10:00:15 INFO - Detecting whether we're running mozinstall >=1.0... 10:00:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:00:15 INFO - Reading from file tmpfile_stdout 10:00:15 INFO - Output received: 10:00:15 INFO - Usage: mozinstall [options] installer 10:00:15 INFO - Options: 10:00:15 INFO - -h, --help show this help message and exit 10:00:15 INFO - -d DEST, --destination=DEST 10:00:15 INFO - Directory to install application into. [default: 10:00:15 INFO - "/builds/slave/test"] 10:00:15 INFO - --app=APP Application being installed. [default: firefox] 10:00:15 INFO - mkdir: /builds/slave/test/build/application 10:00:15 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'] 10:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:00:32 INFO - Reading from file tmpfile_stdout 10:00:32 INFO - Output received: 10:00:32 INFO - /builds/slave/test/build/application/firefox/firefox 10:00:32 INFO - Running post-action listener: _resource_record_post_action 10:00:32 INFO - [mozharness: 2017-04-07 17:00:32.445238Z] Finished install step (success) 10:00:32 INFO - [mozharness: 2017-04-07 17:00:32.445514Z] Running run-tests step. 10:00:32 INFO - Running pre-action listener: _resource_record_pre_action 10:00:32 INFO - Running main action method: run_tests 10:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:32 INFO - Python 2.7.3 10:00:32 INFO - Return code: 0 10:00:32 INFO - grabbing minidump binary from tooltool 10:00: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')]} 10:00:32 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 0x27cd290>, '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 0x27cdb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ce040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:32 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 10:00:32 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 10:00:32 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 10:00:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:32 INFO - Return code: 0 10:00:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:00:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:32 INFO - ENV: RUST_BACKTRACE is now 1 10:00:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:00:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:32 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/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 10:00:32 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/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 10:00:32 INFO - Using env: {'DISPLAY': ':0', 10:00:32 INFO - 'HOME': '/home/cltbld', 10:00:32 INFO - 'LANG': 'en_US.UTF-8', 10:00:32 INFO - 'LANGUAGE': 'en_US:en', 10:00:32 INFO - 'LOGNAME': 'cltbld', 10:00:32 INFO - 'MAIL': '/var/mail/cltbld', 10:00:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:32 INFO - 'MOZ_NO_REMOTE': '1', 10:00:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:32 INFO - 'NO_EM_RESTART': '1', 10:00:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:32 INFO - 'PWD': '/builds/slave/test', 10:00:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:32 INFO - 'RUST_BACKTRACE': '1', 10:00:32 INFO - 'SHELL': '/bin/bash', 10:00:32 INFO - 'SHLVL': '1', 10:00:32 INFO - 'TERM': 'linux', 10:00:32 INFO - 'TMOUT': '86400', 10:00:32 INFO - 'USER': 'cltbld', 10:00:32 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706', 10:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:32 INFO - '_': '/tools/buildbot/bin/python'} 10:00:32 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/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 10:00:32 INFO - mozversion application_buildid: 20170407160422 10:00:32 INFO - mozversion application_changeset: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad 10:00:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:00:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:32 INFO - mozversion application_name: Firefox 10:00:32 INFO - mozversion application_remotingname: firefox-dev 10:00:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:32 INFO - mozversion application_vendor: Mozilla 10:00:32 INFO - mozversion application_version: 54.0a2 10:00:32 INFO - mozversion platform_buildid: 20170407160422 10:00:32 INFO - mozversion platform_changeset: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad 10:00:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:32 INFO - mozversion platform_version: 54.0a2 10:00:32 INFO - using testdate: 1491584432 10:00:32 INFO - actual date: 1491584432 10:00:32 INFO - starting webserver on 'localhost:51037' 10:00:32 INFO - SUITE-START | Running 2 tests 10:00:32 INFO - TEST-START | dromaeo_css 10:00:32 INFO - operating with platform_type : linux_ 10:00:32 INFO - Initialising browser for dromaeo_css test... 10:00:32 INFO - TEST-INFO | started process 16157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUFEFIo/profile http://localhost:51037/getInfo.html) 10:00:37 INFO - PROCESS | 16157 | __metrics Screen width/height:1600/1200 10:00:37 INFO - PROCESS | 16157 | colorDepth:24 10:00:37 INFO - PROCESS | 16157 | Browser inner width/height: 1024/697 10:00:37 INFO - PROCESS | 16157 | __metrics 10:00:38 INFO - PROCESS | 16157 | 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" 10:00:38 INFO - PROCESS | 16157 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:00:38 INFO - PROCESS | 16157 | console.error: PushService: 10:00:38 INFO - PROCESS | 16157 | stateChangeProcessEnqueue: Error transitioning state 10:00:38 INFO - PROCESS | 16157 | UnknownError 10:00:39 INFO - TEST-INFO | 16157: exit 0 10:00:39 INFO - Browser initialized. 10:00:39 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:39 INFO - TEST-INFO | started process 16253 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUFEFIo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:51 INFO - PROCESS | 16253 | RSS: Main: 139980800 10:00:51 INFO - PROCESS | 16253 | 10:03:53 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51037/tests/dromaeo/cssquery-ext.html) 10:03:54 INFO - PROCESS | 16253 | RSS: Main: 274149376 10:03:54 INFO - PROCESS | 16253 | 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | A coding exception was thrown and uncaught in a Task. 10:05:41 INFO - PROCESS | 16253 | 10:05:41 ERROR - PROCESS | 16253 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:05:41 INFO - PROCESS | 16253 | Full stack: 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | A coding exception was thrown and uncaught in a Task. 10:05:41 INFO - PROCESS | 16253 | 10:05:41 ERROR - PROCESS | 16253 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:05:41 INFO - PROCESS | 16253 | Full stack: 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | A coding exception was thrown and uncaught in a Task. 10:05:41 INFO - PROCESS | 16253 | 10:05:41 ERROR - PROCESS | 16253 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:05:41 INFO - PROCESS | 16253 | Full stack: 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | ************************* 10:05:41 INFO - PROCESS | 16253 | A coding exception was thrown and uncaught in a Task. 10:05:41 INFO - PROCESS | 16253 | 10:05:41 ERROR - PROCESS | 16253 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:05:41 INFO - PROCESS | 16253 | Full stack: 10:05:41 INFO - PROCESS | 16253 | ************************* 10:06:57 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-ext.html (next: http://localhost:51037/tests/dromaeo/cssquery-jquery.html) 10:06:57 INFO - PROCESS | 16253 | RSS: Main: 221028352 10:06:57 INFO - PROCESS | 16253 | 10:09:59 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51037/tests/dromaeo/cssquery-mootools.html) 10:10:00 INFO - PROCESS | 16253 | RSS: Main: 224190464 10:10:00 INFO - PROCESS | 16253 | 10:13:01 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51037/tests/dromaeo/cssquery-prototype.html) 10:13:02 INFO - PROCESS | 16253 | RSS: Main: 202457088 10:13:02 INFO - PROCESS | 16253 | 10:16:05 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51037/tests/dromaeo/cssquery-yui.html) 10:16:06 INFO - PROCESS | 16253 | RSS: Main: 174403584 10:16:06 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | Cycle 1(1): loaded http://localhost:51037/tests/dromaeo/cssquery-yui.html (next: http://localhost:51037/tests/dromaeo/cssquery-dojo.html) 10:19:08 INFO - PROCESS | 16253 | RSS: Main: 267751424 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | __start_tp_report 10:19:08 INFO - PROCESS | 16253 | _x_x_mozilla_page_load 10:19:08 INFO - PROCESS | 16253 | _x_x_mozilla_page_load_details 10:19:08 INFO - PROCESS | 16253 | |i|pagename|runs| 10:19:08 INFO - PROCESS | 16253 | |0;dojo.html;3414.5390372299735;3459.1352161959503;3460.6366331535205;3477.687008169264;3495.8951231463047;5740.655560666363;5829.53363730901;5833.358330583633;5837.970810145948;5844.357120716718;5862.677552734598;5931.88136237275;5932.911006334908;5950.434708702666;5951.018082016473;5213.843584692465;5215.1655735082395;5231;5255.211718242256;5340.06548853951;5083.517065878736;5312.309399778023;5326.041312563756;5330.173823057432;5331.280277162592;5110.233464980245;5182.274481572564;5221.71280579568;5295.708736019517;5313.20301954708;5306.655067420625;5326.840194794162;5338.145896656555;5338.466153384669;5339.866503337409;5191.792328306863;5231.947680523184;5242.868928276824;5289.471052894718;5298.231756395339;5581.5255703265275;5598.776048958037;5747.798827041033;5793.884122317572;5810.796622118206;6298.2442106947;6301.180846489973;6301.338359472281;6301.59039662425;6301.684915754194;6336.936630633681;6353.205849268841;6373.553851230416;6375.458086062644;6375.553711240215;5670.064439367499;5722.084466485384;5847.26909136358;5885.293764748258;5917.733701983421;6409.455196308366;6457.192850893638;6475.4819614430735;6476.579022363531;6483.902741458882;6089.665068421279;6159.599626024294;6189.071639254148;6193.84515387119;6214.347493513116;6006.879862402728;6038.033914573647;6264.123022776815;6269.090981807703;6308.0537919311555;6133.457080270857;6181.227346581677;6185.350538193515;6196.1635179251125;6196.504279657706;5999.670018148957;6053.697315134225;6055.394460553909;6153.384661533901;6269.905951410732;4340.934885976713;4379.211741886491;4393.51671316155;4402.471703395613;4415.425994620743;3952.150287688161;3954.9802250989137;3976.8011599419915;3980.800959951991;3993.7004724645767;5859.589828712034;5919.319278283053;6067.969660151759;6079.179310793074;6132.846678832976;6315.052742088724;6346.397092276318;6354.904676429857;6354.936450635527;6355.809325720235;6148.938510614837;6156.168917196122;6158.414950579688;6162.106494558264;6167.506599472032;5103.081445339801;5149.716765577929;5273.1035723927025;5294.364676238799;5388.13789793639;6561.770338038145;6571.605703657796;6579.144711187515;6588.242352129375;6592.76925307612;6205.6586887721605;6217.502599791916;6272.8431789203805;6274.278457977391;6276.058591211355;6172.938270617416;6221.2845522763655;6504.089427480062;6575.046618240232;6581.934180658323;6210.627362358273;6242.968785156227;6245.156903818108;6245.687715614292;6246.875062498815;6317.431431171217;6335.4931605472375;6342.460890824332;6349.555531112777;6353.364663533795;5899.4985426239255;5936.079907614327;6085.9695701521205;6143.692815359124;6150.04674275488;6046.123312119806;6051.606645568024;6119.755209791559;6129.847850912788;6130.489860652556;6084.209052823105;6095.695215239309;6100.542459315658;6104.816855494342;6117.663528505975;5928.9407105928385;5964.343922168645;5979.521638269015;6127.14220009208;6155.599886007395;6086.695665216633;6110.725017374318;6114.602550834182;6134.631922084689;6136.539759517965 10:19:08 INFO - PROCESS | 16253 | |1;ext.html;737.8450525389668;809.5345176523499;817.5871185051548;858.2533196119377;878.3807415771881;30453.238669033257;30752.38495230097;33263.33473330532;43455.34816977748;44838;44668.77665611675;44799.55200447995;44811.551884481145;44838.327425088566;44885.32672009914;9064.773380665487;9209.309301802357;9210.67762628308;9217.170454659079;9219.585118669642;5123;5213.244079608455;10683.572657093708;10895.128389728803;10900.07349375304;11020.44897755113;11029.779404411907;11036.11711063113;11041.668749937515;11043.613473528429;10910.5090270938;10950.288231264942;10959.83560246597;10972.890271097267;10974;10771.353718776898;10831.620893268699;10831.945840270828;10833.83749243762;10845;42342.153156936685;42594.57405425968;42626.57373426257;42646.573534264884;42713.786431067645;3850.6726928211137;3893.80530973453;3922.0194951262183;3949.407588861665;3997.060690737674;29859.70140298591;29931.850340748373;30228;30310.84844575785;30924.22689432782;4362.432883725127;4468.240399132155;4468.240399132155;4484.775761211927;4507.198998400225;229765;231783;232040;232477;233952;55505.722471388195;55511.72244138754;55547;55574.72212638911;55670.443295566525;57474.7126264366;57712.42287577155;57792;57859.42140578625;57980;16571.751423728652;16959.660806784043;17010.48968530946;17035.14824258782;17048.659026819394;11347.886521134847;15074;15106.54680359591;15172.696546069017;15180;12041.277523348626;13074.411651475706;13322.134061286179;13436.731265374834;13498.59504214875;5603.355614104348;5654.953833540808;5727.828165155052;5797.507211886952;5807.419258074159;1010.4442556593845;1016.5527168046036;1020.9183265338903;1022.6778564752079;1024.3137098144261;28963.275918102216;28992.710072899;29030;29122.708772912;29140.562891556645;1081.9937458163827;1091.1052936592077;1096.0573906440454;1098.549594666183;1123.3484578944192;14462.20457874827;15102.244887755569;15200.163990980382;15294.77057844111;15554.22228888551;291138;291923;292116;292471;292669;56590.15114773282;56691.14963275554;56857.71571142118;56859;56866;1346.73065386923;1393.2267591486823;1424.8147740793838;1440.2150827798876;1445.7253121906804;26298.86850565735;26556.203313900613;27008.729912700623;27368.305553062357;27920.30199244954;41047.17905641811;41062.79468602758;41138.79430602828;41193;41227.38158927559;260248;260410;260468;260493;260569;35522.64477355193;36003.099922502144;36290.63709362978;36414.63585364113;36426.8178659105;42779.358309626004;43417.782911086506;43560.56439435565;43589.34615980699;43706.12587748164;32845.50731739074;33225.47785966816;33332.66667333393;33539.161520962174;34142;33627;33819;33927;34139.14652133716;34940.47589286214;38320.6167938317;38668.2266354677;39414.80292598519;39759;39989.2002159961;32312.83843580767;32399.838000809847;32512.187195320304;32587;32751.672483274862;25091.623625645996;25474.617880732174;25521.361965951;25568.10511632084;26047.348816278987 10:19:08 INFO - PROCESS | 16253 | |2;jquery.html;401.70675406952944;461.56151655926857;463.81215607678894;475.2965610895874;486.84177642266263;17972.91013544932;18980.335688250914;19797.802021979795;20688.689669654967;20804.89597552014;14434.927825360859;22932.88533557334;23096;23118.190863319785;23673;6571.769988050419;6712.228093769206;6764.864702705943;6773.695183716733;6826.829329266783;6736.29268926763;6842.281560436158;6860.897086543706;6866.107406037208;6869.931300686979;6644.900326495106;6759.695813688371;6795.93204067958;6800.727970881158;6868.828279293033;6604.570702904344;6672.966635166842;6759.729610815537;6793.150856142982;6822.965885170567;6605.570637908569;6607.6696165192025;6609.669516524203;6614.404703576702;6615.17310336208;25768.355791105187;26308.73691263082;26352.73647263522;26468.86765566179;26714.465710685872;3294.159989202738;3312.635610082889;3318.7510936679846;3354.077628652116;3385.8984230473125;16364.672706545922;16797.41209057677;16799.32802687891;17317.913410432866;17399.65200695979;2140.743110826695;2190.6218347202707;2240.5070884405404;2243.4839986803127;2268.3421807675754;100533.4973325136;100710.49644751729;101233.9876601232;102285;102445;18783.624327513648;19319.613607728046;19617.901910490356;19666.181785462653;19698.31055913036;37599.62400376016;37806;37810.24379512394;37851.43222851659;37912;9552.522373881417;10136.442495662663;11090.057345125755;11536.134789890792;11857.347845868388;9852.704418867303;9907.554160062671;9927.15619172364;10115.241356898263;10118.392896426238;10032.899671003197;10600;10712.94643526793;10833.404162771123;10865.891341086646;3720.813959302024;3806.71449641278;3848.634379733921;3946.585608511065;3981.820818063194;967.685502211784;983.0709979069853;983.9262055345878;988.3575675810637;995.6117114325419;20561;20716.067776950073;20753.377398678065;20759.273425430038;20776.272830451166;990.6087095597343;1002.0931057393071;1007.1137399088755;1016.9542370593341;1026.6663334416344;10691.465426728633;11519;11625.360605166798;11668.824967625493;11757.412129393497;517100;576903;799424;817559;818675;119725;125675.3716231413;126124;126598;128292.35853820671;1442.1923722715312;1472.477270568954;1474.6018574984694;1491.8358980512135;1507.464849978242;18639.90680046591;18997.90501047486;19202.423927281645;19206.423807285802;19490.12294446725;37842.243155136195;37877;53908.460915391915;54630.45369546254;54878.90242195054;66201.33798661952;66554.33445665482;67436.325636743;67525.66237168979;67769.3223067763;12690.809637855624;12707;12854.485820567446;12858.549950751678;12884.742305153657;13650.112742671694;13658.931705341807;13664.111832730841;13763.655908602364;13816.516421925184;11973.880261197277;12421;12902.67743306425;12981.4158362872;13163.078584498993;11557.133215009077;11666.70833229176;11672.649820505398;11699.88300116988;11863.822042669195;12693.619191424272;12699.809502857279;12719.93640031794;12743.617691469271;12871.292078935374;12478.750424991267;12539.623811285675;12618.242905425705;12803.29581873006;12995.28525931083;12715.682107947376;12875.163114397534;12962.287074210632;13054.216746995211;13266.336683165997 10:19:08 INFO - PROCESS | 16253 | |3;mootools.html;1549.442200807709;1550.3643506162475;1574.6771911758087;1578.6053486628343;1581.114575837531;10747.946260268698;11454;11463.082953363732;11466.598669046574;11475.196736228467;11274.718132046683;11482.885171148286;11712.121590880697;11806.114541409386;11830.58592949247;2357.339944815446;2395.1257790906366;2395.2335252719135;2397;2398.328468028955;2347.6361164019604;2357.528494301138;2470.493548822487;2472.3695457658273;2473.530029294437;2354.058376649337;2404.290734233405;2414.0343862454984;2439.7926176275037;2440.8535487870695;2274.2153956885013;2324.174917904145;2330.149495434163;2332.265336419023;2389.8566086034893;2272.8409011369213;2302.4243939015246;2305.1586171047616;2306.2965795432387;2311.0524684879288;11555.19113662044;11776.234544754649;11778.469968851423;11785.587504437304;11787;5647.632903861277;5773.595848290622;5790;5791.913121303184;5796.797112101056;3453.2575496268423;3491.7555771096036;3538.716902647782;3550.414181660023;3611;1409.6405416618697;1444.2995147353524;1493.895427320088;1503.1882783297006;1506.7513860213053;17333.04668243246;17392.478225653125;17480.47558573245;17494.65010699779;17577;4379.693421460467;4570.200214962435;4616.561426664462;4691.648126390534;4693.929591056137;13158.078934474686;13160.934195328962;13163.53927612548;13168.868311316764;13171.209727416386;5725.971370143123;5756.194132821408;5774.509166720875;5784.045632470596;5802.6808525531505;5200.97399513;5273.34083239595;5283.894322113536;5380.24676545284;5422.728863556807;3967.563568007517;4070.104576993057;4076.5923407658997;4132.91734165315;4169.666426685919;1055.3878750324793;1056.6460235820932;1072.495926914349;1073.8765042020111;1075.634284343327;1090.225939582905;1107.8615173103362;1108.534415545473;1116.3302018788825;1122.2761318949395;7178.964105179441;7225.927740722525;7291.8906216406795;7349;7364.22675619063;1067.765091679829;1094.102835674755;1094.3816743539874;1117.8821178821179;1129.1531351486385;4573.245414506569;4597.95402045982;4664.207084795593;4710.528947105262;4762.595179409789;14397.42410303576;14750.926245368919;14873.628159296104;15133;15181.468648597245;13992.440302387995;14186.716265674835;14709.632259193606;15008.47470338533;15047.774283385757;10748.623798167184;10894.074003709615;10897.782044359068;11021.228514004104;11056.723581910517;7582.62086895664;7719.4210434218785;7755.302022817974;7883.6846526138315;7915.960420197862;13190.670233244246;13355.666108347368;13395.12931659439;13650;13899.791503127259;13662.31688415595;13706.588802335946;13809.51666691661;13811.654708631962;13981;9979.251556133464;10029.04724051214;10111.7472063199;10207.948960255151;10216.438095904796;10944.507497162806;10959.12327013821;11010.449477526252;11051.115910726961;11145.275557088764;9360.251179905456;9511.762205944906;9559.952200238955;9643;9720.027997200223;8978.102189781232;9052.456852588866;9077.818443631222;9110.908890911007;9162.816743665222;8423.199796019368;8625.39622226438;8632.741017769225;8635.654573816977;8639;6696.430803381572;6767.763128290486;6815.011823285497;6830.351116643911;6915.861682766417;6640.435562977201;6717;6850.451963842781;6921.204061532988;6995.930040699528 10:19:08 INFO - PROCESS | 16253 | |4;prototype.html;368.86352049741566;414.52950900727683;431.3034449364276;445.8550970934446;451.9050999290149;5070.797168113276;5190.922136167956;5254.632175747699;5266.708307307933;5279.260903473507;5014.849554513361;5127.512886275807;5179.870503237421;5195.168772996321;5200.765965531551;4415.278041563784;4417.867463976086;4424.84513042042;4433.334999750031;4455.420795296607;4225.978870105645;4339.566043395651;4343.305071188611;4455.754933478657;4461.866144015685;4418.9394545309215;4431.933520997188;4438.844640437585;4438.8693427154985;4469.128519938613;4435.135148646015;4461.286194208943;4462.464504259493;4477;4481.484629267643;3904.2386734586767;4067.837286508536;4105.4662893823615;4127.153933443637;4330.112326972963;5024.221245706921;5037.773300201463;5053.216751403538;5061.44324124346;5081.196175357732;5017.247412888059;5062.620303477217;5080.288759573663;5084.898302033938;5102.260172275037;5259.973700131475;5444.863878403032;5482.616216864821;5483.725813709299;5487.067198576252;4572.536788227769;4577.9542204577865;4583.564561366698;4614.192516309666;4653.464851542082;5725.456081672234;5730.312362516483;5746.310442746855;5806.158107074492;5821.446962538552;4525.637948964075;4720.33915251865;4733.763311834395;4764.425622022242;4778.880527986828;4576.565226303496;4864.221724524059;4864.683795553277;4872.8781780455065;4874.293227482066;4927.507249275044;4989.501049894981;5000.724960127228;5059.848204553869;5122.154844450699;5494.2582751328855;5507.284053073155;5510.862228444321;5516.738137363662;5526.889462210734;1586.8095828500655;1643.9013659180248;1648.2994727240875;1661.8421249981232;1668.9833101669071;1263.3091515072836;1279.5073896549895;1285.6785803549112;1288.6971561682997;1291.3543228385806;5430.375506816767;5441.782328706808;5491.203775452617;5503.889922201533;5529.373547564955;5523.889522209533;5585.636933599303;5667.858303542362;5695.316562012502;5711.200431939532;5226.980738755982;5460.208269800936;5468.863278418072;5470.425605311465;5482.725863706799;2927.487689654302;2940.3678209185127;2942.29384947611;2969.7475714564503;2990.626171728534;5629.284672116465;5891.3224979127945;5899.195019212584;5918.112283157475;5946.613470124428;5641.8589535261945;5705.429457054262;5722.798212375448;5741.598088133791;5749.741261643236;5874.265716785402;5889.087191485327;5915.792947246826;5929.525637948867;5946.226990491208;5333.493318134771;5579.553635709215;5600.075987462186;5660.669742610401;5701.857453563694;5468;5492.340919089735;5528.861778455571;5559.694216818114;5602.299712535933;5840.240768700041;5900.852478688067;5918.277592307587;5955.344912059757;5959.970200148971;4547.363369128391;4713.505081966344;4771.809127634856;4774.3554620127225;4787.904241915073;5434.891302173855;5527.44725527444;5588.497035266845;5635.830925072254;5696.1455781633085;5434.402215756185;5445.794453246442;5483.314585676791;5495.807646732345;5535.142052981794;5267.657602255841;5403.972980135074;5422.102264207496;5456.945430545644;5476.479734425383;5407.378151512627;5450.31871016123;5463.426340234377;5482.342118945752;5492.945070549403;5518.944810551843;5528.55771538284;5546.226886556722;5567.526760225264;5575.804846830341;5144.845654630367;5409.756560954688;5411.540019098421;5420.430854760351;5452.727363631882 10:19:08 INFO - PROCESS | 16253 | |5;yui.html;860.9655613775449;871.5467956662536;914.4696076275761;928.2713070239862;933.5798890499273;16390.918045409788;16732.66534669306;17336.13319334033;17975.19111639976;18187.363442279522;16555.337786488526;16872.578185545368;17075.82924170758;17195.484135475956;17428.3028678853;858.4119877883638;871.372611719561;873.2402809555664;876.7063033883657;897.8967418746862;889.6219106879582;890.1899271662788;890.341147550811;892.6830975003877;893.258595365848;946.394307643109;947.9099485548896;947.924166066713;949.6011675096476;949.6818565780471;943.1228957069923;943.3726571829725;944.9055094490565;945.7588314979671;946.2430055955208;695.2630211975322;696.7282759723712;696.8954656801469;697.086816270682;698.3191388396324;18196.727049094276;18202.45392638223;18268.451946441495;18950.715739263924;19124.330648427378;16125.516234512983;16352.509424717278;16395.344186232538;16402.83597164037;16576.17119144038;13054.216746995211;13239.20564766117;13472;13505.122167059208;13521.459141634421;4030.979845100785;4075.1034772350035;4075.7887174984453;4123.195976784528;4206.221848957922;91456.54271728665;92664;93113.5344323274;93135;94119;11006.779864402828;11297.209195356247;11409.315441073562;11449.313041217554;11455.713607159722;24531.754682452945;24547.77261136936;24840.627390589154;24977;25120.12079577206;9343.159115679622;9379.20276776468;9490.90509094914;9500.762480938032;9503.239740820718;5140.614453915971;5150.793968241303;5190.24741412493;5251.2123181523075;5311.468853114658;3984.541777695602;4051.068254301468;4068.572799856032;4071.0840060986043;4083.530394004668;610.5603965145089;634.1660716158237;641.4483544152025;644.5037321262602;645.7481582182952;702.5447400931668;716.8422946951662;719.3058698356111;721.5670597641478;851.2508992086924;10381.584736610603;10699;11218.943905280585;11264.774704506028;11465.369404682655;632.2570979099631;633.3729607688356;638.6295948349946;661.7419206509466;867.5796815226412;5152.819651312186;5487.6981766002455;5584.441555844464;5586.636868603528;5607.551395888319;79794.6010269945;80672.59663701644;81226.18773812187;82263.5886820562;83516.58241708753;70104.29895700977;70728;71812;72107.27892721111;72771.63614181895;63221;63356;64994.708889562426;65050.67474662596;67175;10332.896671033193;10366.326188797704;10446.947765261126;10540.631077912236;10587.417692026704;38452.615473844904;42939.35590966201;43613.56386436222;43676.781616091714;44243.33634995413;50538;54513;61038;62640.37359626528;64123;14473.638159046108;14868;15677.921610391875;16114.033158010558;16543.586410339838;16073.35706571724;16094.195290235675;16175.514734557983;16291.348346066025;16364.590885227964;15409.614759631098;15480.303386347416;15653.608659783597;15723.842761572238;15874.126923018883;16439;16666.166691665127;17241.56896077608;17969.460916172535;18006.72989905179;16076.598085047968;16220.27008784584;16504.66990660156;16792.160391980597;17294.567635809206;10859.366803391165;10962.945185274342;11169.720756980816;11373.488193031499;11538.538458461904;11836.171467997501;12148.392580371123;13369.598912032654;13734.725305494034;13954.651133721738 10:19:08 INFO - PROCESS | 16253 | __end_tp_report 10:19:08 INFO - PROCESS | 16253 | __start_cc_report 10:19:08 INFO - PROCESS | 16253 | _x_x_mozilla_cycle_collect,1410 10:19:08 INFO - PROCESS | 16253 | __end_cc_report 10:19:08 INFO - PROCESS | 16253 | __startTimestamp1491585548638__endTimestamp 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | ------- Summary: start ------- 10:19:08 INFO - PROCESS | 16253 | Number of tests: 6 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#0] dojo.html Cycles:165 Average:5791.76 Median:6085.97 stddev:714.94 (11.7%) stddev-sans-first:692.38 10:19:08 INFO - PROCESS | 16253 | Values: 3414.5 3459.1 3460.6 3477.7 3495.9 5740.7 5829.5 5833.4 5838.0 5844.4 5862.7 5931.9 5932.9 5950.4 5951.0 5213.8 5215.2 5231.0 5255.2 5340.1 5083.5 5312.3 5326.0 5330.2 5331.3 5110.2 5182.3 5221.7 5295.7 5313.2 5306.7 5326.8 5338.1 5338.5 5339.9 5191.8 5231.9 5242.9 5289.5 5298.2 5581.5 5598.8 5747.8 5793.9 5810.8 6298.2 6301.2 6301.3 6301.6 6301.7 6336.9 6353.2 6373.6 6375.5 6375.6 5670.1 5722.1 5847.3 5885.3 5917.7 6409.5 6457.2 6475.5 6476.6 6483.9 6089.7 6159.6 6189.1 6193.8 6214.3 6006.9 6038.0 6264.1 6269.1 6308.1 6133.5 6181.2 6185.4 6196.2 6196.5 5999.7 6053.7 6055.4 6153.4 6269.9 4340.9 4379.2 4393.5 4402.5 4415.4 3952.2 3955.0 3976.8 3980.8 3993.7 5859.6 5919.3 6068.0 6079.2 6132.8 6315.1 6346.4 6354.9 6354.9 6355.8 6148.9 6156.2 6158.4 6162.1 6167.5 5103.1 5149.7 5273.1 5294.4 5388.1 6561.8 6571.6 6579.1 6588.2 6592.8 6205.7 6217.5 6272.8 6274.3 6276.1 6172.9 6221.3 6504.1 6575.0 6581.9 6210.6 6243.0 6245.2 6245.7 6246.9 6317.4 6335.5 6342.5 6349.6 6353.4 5899.5 5936.1 6086.0 6143.7 6150.0 6046.1 6051.6 6119.8 6129.8 6130.5 6084.2 6095.7 6100.5 6104.8 6117.7 5928.9 5964.3 5979.5 6127.1 6155.6 6086.7 6110.7 6114.6 6134.6 6136.5 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#1] ext.html Cycles:180 Average:43856.73 Median:32356.34 stddev:68289.15 (211.1%) stddev-sans-first:68403.97 10:19:08 INFO - PROCESS | 16253 | Values: 737.8 809.5 817.6 858.3 878.4 30453.2 30752.4 33263.3 43455.3 44838.0 44668.8 44799.6 44811.6 44838.3 44885.3 9064.8 9209.3 9210.7 9217.2 9219.6 5123.0 5213.2 10683.6 10895.1 10900.1 11020.4 11029.8 11036.1 11041.7 11043.6 10910.5 10950.3 10959.8 10972.9 10974.0 10771.4 10831.6 10831.9 10833.8 10845.0 42342.2 42594.6 42626.6 42646.6 42713.8 3850.7 3893.8 3922.0 3949.4 3997.1 29859.7 29931.9 30228.0 30310.8 30924.2 4362.4 4468.2 4468.2 4484.8 4507.2 229765.0 231783.0 232040.0 232477.0 233952.0 55505.7 55511.7 55547.0 55574.7 55670.4 57474.7 57712.4 57792.0 57859.4 57980.0 16571.8 16959.7 17010.5 17035.1 17048.7 11347.9 15074.0 15106.5 15172.7 15180.0 12041.3 13074.4 13322.1 13436.7 13498.6 5603.4 5655.0 5727.8 5797.5 5807.4 1010.4 1016.6 1020.9 1022.7 1024.3 28963.3 28992.7 29030.0 29122.7 29140.6 1082.0 1091.1 1096.1 1098.5 1123.3 14462.2 15102.2 15200.2 15294.8 15554.2 291138.0 291923.0 292116.0 292471.0 292669.0 56590.2 56691.1 56857.7 56859.0 56866.0 1346.7 1393.2 1424.8 1440.2 1445.7 26298.9 26556.2 27008.7 27368.3 27920.3 41047.2 41062.8 41138.8 41193.0 41227.4 260248.0 260410.0 260468.0 260493.0 260569.0 35522.6 36003.1 36290.6 36414.6 36426.8 42779.4 43417.8 43560.6 43589.3 43706.1 32845.5 33225.5 33332.7 33539.2 34142.0 33627.0 33819.0 33927.0 34139.1 34940.5 38320.6 38668.2 39414.8 39759.0 39989.2 32312.8 32399.8 32512.2 32587.0 32751.7 25091.6 25474.6 25521.4 25568.1 26047.3 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#2] jquery.html Cycles:180 Average:39115.84 Median:20702.38 stddev:118139.55 (570.7%) stddev-sans-first:118435.19 10:19:08 INFO - PROCESS | 16253 | Values: 401.7 461.6 463.8 475.3 486.8 17972.9 18980.3 19797.8 20688.7 20804.9 14434.9 22932.9 23096.0 23118.2 23673.0 6571.8 6712.2 6764.9 6773.7 6826.8 6736.3 6842.3 6860.9 6866.1 6869.9 6644.9 6759.7 6795.9 6800.7 6868.8 6604.6 6673.0 6759.7 6793.2 6823.0 6605.6 6607.7 6609.7 6614.4 6615.2 25768.4 26308.7 26352.7 26468.9 26714.5 3294.2 3312.6 3318.8 3354.1 3385.9 16364.7 16797.4 16799.3 17317.9 17399.7 2140.7 2190.6 2240.5 2243.5 2268.3 100533.5 100710.5 101234.0 102285.0 102445.0 18783.6 19319.6 19617.9 19666.2 19698.3 37599.6 37806.0 37810.2 37851.4 37912.0 9552.5 10136.4 11090.1 11536.1 11857.3 9852.7 9907.6 9927.2 10115.2 10118.4 10032.9 10600.0 10712.9 10833.4 10865.9 3720.8 3806.7 3848.6 3946.6 3981.8 967.7 983.1 983.9 988.4 995.6 20561.0 20716.1 20753.4 20759.3 20776.3 990.6 1002.1 1007.1 1017.0 1026.7 10691.5 11519.0 11625.4 11668.8 11757.4 517100.0 576903.0 799424.0 817559.0 818675.0 119725.0 125675.4 126124.0 126598.0 128292.4 1442.2 1472.5 1474.6 1491.8 1507.5 18639.9 18997.9 19202.4 19206.4 19490.1 37842.2 37877.0 53908.5 54630.5 54878.9 66201.3 66554.3 67436.3 67525.7 67769.3 12690.8 12707.0 12854.5 12858.5 12884.7 13650.1 13658.9 13664.1 13763.7 13816.5 11973.9 12421.0 12902.7 12981.4 13163.1 11557.1 11666.7 11672.6 11699.9 11863.8 12693.6 12699.8 12719.9 12743.6 12871.3 12478.8 12539.6 12618.2 12803.3 12995.3 12715.7 12875.2 12962.3 13054.2 13266.3 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#3] mootools.html Cycles:180 Average:7274.62 Median:2327.16 stddev:4669.60 (200.7%) stddev-sans-first:4662.89 10:19:08 INFO - PROCESS | 16253 | Values: 1549.4 1550.4 1574.7 1578.6 1581.1 10747.9 11454.0 11463.1 11466.6 11475.2 11274.7 11482.9 11712.1 11806.1 11830.6 2357.3 2395.1 2395.2 2397.0 2398.3 2347.6 2357.5 2470.5 2472.4 2473.5 2354.1 2404.3 2414.0 2439.8 2440.9 2274.2 2324.2 2330.1 2332.3 2389.9 2272.8 2302.4 2305.2 2306.3 2311.1 11555.2 11776.2 11778.5 11785.6 11787.0 5647.6 5773.6 5790.0 5791.9 5796.8 3453.3 3491.8 3538.7 3550.4 3611.0 1409.6 1444.3 1493.9 1503.2 1506.8 17333.0 17392.5 17480.5 17494.7 17577.0 4379.7 4570.2 4616.6 4691.6 4693.9 13158.1 13160.9 13163.5 13168.9 13171.2 5726.0 5756.2 5774.5 5784.0 5802.7 5201.0 5273.3 5283.9 5380.2 5422.7 3967.6 4070.1 4076.6 4132.9 4169.7 1055.4 1056.6 1072.5 1073.9 1075.6 1090.2 1107.9 1108.5 1116.3 1122.3 7179.0 7225.9 7291.9 7349.0 7364.2 1067.8 1094.1 1094.4 1117.9 1129.2 4573.2 4598.0 4664.2 4710.5 4762.6 14397.4 14750.9 14873.6 15133.0 15181.5 13992.4 14186.7 14709.6 15008.5 15047.8 10748.6 10894.1 10897.8 11021.2 11056.7 7582.6 7719.4 7755.3 7883.7 7916.0 13190.7 13355.7 13395.1 13650.0 13899.8 13662.3 13706.6 13809.5 13811.7 13981.0 9979.3 10029.0 10111.7 10207.9 10216.4 10944.5 10959.1 11010.4 11051.1 11145.3 9360.3 9511.8 9560.0 9643.0 9720.0 8978.1 9052.5 9077.8 9110.9 9162.8 8423.2 8625.4 8632.7 8635.7 8639.0 6696.4 6767.8 6815.0 6830.4 6915.9 6640.4 6717.0 6850.5 6921.2 6995.9 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#4] prototype.html Cycles:180 Average:4806.09 Median:5263.34 stddev:1276.76 (24.3%) stddev-sans-first:1236.14 10:19:08 INFO - PROCESS | 16253 | Values: 368.9 414.5 431.3 445.9 451.9 5070.8 5190.9 5254.6 5266.7 5279.3 5014.8 5127.5 5179.9 5195.2 5200.8 4415.3 4417.9 4424.8 4433.3 4455.4 4226.0 4339.6 4343.3 4455.8 4461.9 4418.9 4431.9 4438.8 4438.9 4469.1 4435.1 4461.3 4462.5 4477.0 4481.5 3904.2 4067.8 4105.5 4127.2 4330.1 5024.2 5037.8 5053.2 5061.4 5081.2 5017.2 5062.6 5080.3 5084.9 5102.3 5260.0 5444.9 5482.6 5483.7 5487.1 4572.5 4578.0 4583.6 4614.2 4653.5 5725.5 5730.3 5746.3 5806.2 5821.4 4525.6 4720.3 4733.8 4764.4 4778.9 4576.6 4864.2 4864.7 4872.9 4874.3 4927.5 4989.5 5000.7 5059.8 5122.2 5494.3 5507.3 5510.9 5516.7 5526.9 1586.8 1643.9 1648.3 1661.8 1669.0 1263.3 1279.5 1285.7 1288.7 1291.4 5430.4 5441.8 5491.2 5503.9 5529.4 5523.9 5585.6 5667.9 5695.3 5711.2 5227.0 5460.2 5468.9 5470.4 5482.7 2927.5 2940.4 2942.3 2969.7 2990.6 5629.3 5891.3 5899.2 5918.1 5946.6 5641.9 5705.4 5722.8 5741.6 5749.7 5874.3 5889.1 5915.8 5929.5 5946.2 5333.5 5579.6 5600.1 5660.7 5701.9 5468.0 5492.3 5528.9 5559.7 5602.3 5840.2 5900.9 5918.3 5955.3 5960.0 4547.4 4713.5 4771.8 4774.4 4787.9 5434.9 5527.4 5588.5 5635.8 5696.1 5434.4 5445.8 5483.3 5495.8 5535.1 5267.7 5404.0 5422.1 5456.9 5476.5 5407.4 5450.3 5463.4 5482.3 5492.9 5518.9 5528.6 5546.2 5567.5 5575.8 5144.8 5409.8 5411.5 5420.4 5452.7 10:19:08 INFO - PROCESS | 16253 | 10:19:08 INFO - PROCESS | 16253 | [#5] yui.html Cycles:180 Average:19250.98 Median:23572.79 stddev:24031.82 (101.9%) stddev-sans-first:24059.56 10:19:08 INFO - PROCESS | 16253 | Values: 861.0 871.5 914.5 928.3 933.6 16390.9 16732.7 17336.1 17975.2 18187.4 16555.3 16872.6 17075.8 17195.5 17428.3 858.4 871.4 873.2 876.7 897.9 889.6 890.2 890.3 892.7 893.3 946.4 947.9 947.9 949.6 949.7 943.1 943.4 944.9 945.8 946.2 695.3 696.7 696.9 697.1 698.3 18196.7 18202.5 18268.5 18950.7 19124.3 16125.5 16352.5 16395.3 16402.8 16576.2 13054.2 13239.2 13472.0 13505.1 13521.5 4031.0 4075.1 4075.8 4123.2 4206.2 91456.5 92664.0 93113.5 93135.0 94119.0 11006.8 11297.2 11409.3 11449.3 11455.7 24531.8 24547.8 24840.6 24977.0 25120.1 9343.2 9379.2 9490.9 9500.8 9503.2 5140.6 5150.8 5190.2 5251.2 5311.5 3984.5 4051.1 4068.6 4071.1 4083.5 610.6 634.2 641.4 644.5 645.7 702.5 716.8 719.3 721.6 851.3 10381.6 10699.0 11218.9 11264.8 11465.4 632.3 633.4 638.6 661.7 867.6 5152.8 5487.7 5584.4 5586.6 5607.6 79794.6 80672.6 81226.2 82263.6 83516.6 70104.3 70728.0 71812.0 72107.3 72771.6 63221.0 63356.0 64994.7 65050.7 67175.0 10332.9 10366.3 10446.9 10540.6 10587.4 38452.6 42939.4 43613.6 43676.8 44243.3 50538.0 54513.0 61038.0 62640.4 64123.0 14473.6 14868.0 15677.9 16114.0 16543.6 16073.4 16094.2 16175.5 16291.3 16364.6 15409.6 15480.3 15653.6 15723.8 15874.1 16439.0 16666.2 17241.6 17969.5 18006.7 16076.6 16220.3 16504.7 16792.2 17294.6 10859.4 10962.9 11169.7 11373.5 11538.5 11836.2 12148.4 13369.6 13734.7 13954.7 10:19:08 INFO - PROCESS | 16253 | -------- Summary: end -------- 10:19:08 INFO - PROCESS | 16253 | 10:19:09 INFO - TEST-INFO | 16253: exit 0 10:19:09 INFO - TEST-OK | dromaeo_css | took 1116719ms 10:19:09 INFO - TEST-START | kraken 10:19:09 INFO - operating with platform_type : linux_ 10:19:09 INFO - Initialising browser for kraken test... 10:19:09 INFO - TEST-INFO | started process 16579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgdVwj6/profile http://localhost:51037/getInfo.html) 10:19:13 INFO - PROCESS | 16579 | __metrics Screen width/height:1600/1200 10:19:13 INFO - PROCESS | 16579 | colorDepth:24 10:19:13 INFO - PROCESS | 16579 | Browser inner width/height: 1024/697 10:19:13 INFO - PROCESS | 16579 | __metrics 10:19:14 INFO - PROCESS | 16579 | 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" 10:19:14 INFO - PROCESS | 16579 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:15 INFO - TEST-INFO | 16579: exit 0 10:19:15 INFO - Browser initialized. 10:19:15 INFO - Running cycle 1/1 for kraken test... 10:19:15 INFO - TEST-INFO | started process 16673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgdVwj6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:27 INFO - PROCESS | 16673 | RSS: Main: 139800576 10:19:27 INFO - PROCESS | 16673 | 10:19:30 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51037/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:30 INFO - PROCESS | 16673 | RSS: Main: 183504896 10:19:30 INFO - PROCESS | 16673 | 10:19:33 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51037/tests/kraken/driver.html?testName=audio-dft) 10:19:34 INFO - PROCESS | 16673 | RSS: Main: 174727168 10:19:34 INFO - PROCESS | 16673 | 10:19:36 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51037/tests/kraken/driver.html?testName=audio-fft) 10:19:37 INFO - PROCESS | 16673 | RSS: Main: 265723904 10:19:37 INFO - PROCESS | 16673 | 10:19:39 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51037/tests/kraken/driver.html?testName=audio-oscillator) 10:19:40 INFO - PROCESS | 16673 | RSS: Main: 171692032 10:19:40 INFO - PROCESS | 16673 | 10:19:42 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51037/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:42 INFO - PROCESS | 16673 | RSS: Main: 174374912 10:19:42 INFO - PROCESS | 16673 | 10:19:45 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51037/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:46 INFO - PROCESS | 16673 | RSS: Main: 212668416 10:19:46 INFO - PROCESS | 16673 | 10:19:49 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51037/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:50 INFO - PROCESS | 16673 | RSS: Main: 211771392 10:19:50 INFO - PROCESS | 16673 | 10:19:53 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51037/tests/kraken/driver.html?testName=json-parse-financial) 10:19:53 INFO - PROCESS | 16673 | RSS: Main: 210313216 10:19:53 INFO - PROCESS | 16673 | 10:19:55 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51037/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:55 INFO - PROCESS | 16673 | RSS: Main: 172675072 10:19:55 INFO - PROCESS | 16673 | 10:19:57 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:58 INFO - PROCESS | 16673 | RSS: Main: 179499008 10:19:58 INFO - PROCESS | 16673 | 10:20:00 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:20:00 INFO - PROCESS | 16673 | RSS: Main: 189370368 10:20:00 INFO - PROCESS | 16673 | 10:20:03 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:20:03 INFO - PROCESS | 16673 | RSS: Main: 198737920 10:20:03 INFO - PROCESS | 16673 | 10:20:06 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:20:07 INFO - PROCESS | 16673 | RSS: Main: 195215360 10:20:07 INFO - PROCESS | 16673 | 10:20:08 INFO - PROCESS | 16673 | Cycle 1(1): loaded http://localhost:51037/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51037/tests/kraken/driver.html?testName=ai-astar) 10:20:09 INFO - PROCESS | 16673 | RSS: Main: 244555776 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | __start_tp_report 10:20:09 INFO - PROCESS | 16673 | _x_x_mozilla_page_load 10:20:09 INFO - PROCESS | 16673 | _x_x_mozilla_page_load_details 10:20:09 INFO - PROCESS | 16673 | |i|pagename|runs| 10:20:09 INFO - PROCESS | 16673 | |0;ai-astar;101;113;113;113;112;112;112;113;113;112 10:20:09 INFO - PROCESS | 16673 | |1;audio-beat-detection;144;115;115;158;116;116;116;116;136;116 10:20:09 INFO - PROCESS | 16673 | |2;audio-dft;161;160;159;163;161;161;162;160;160;161 10:20:09 INFO - PROCESS | 16673 | |3;audio-fft;74;76;76;105;76;76;76;76;101;76 10:20:09 INFO - PROCESS | 16673 | |4;audio-oscillator;83;82;105;81;82;82;94;82;82;107 10:20:09 INFO - PROCESS | 16673 | |5;imaging-gaussian-blur;112;113;113;113;113;113;112;113;113;114 10:20:09 INFO - PROCESS | 16673 | |6;imaging-darkroom;152;147;146;147;148;147;146;147;147;147 10:20:09 INFO - PROCESS | 16673 | |7;imaging-desaturate;95;93;94;93;94;94;93;94;94;94 10:20:09 INFO - PROCESS | 16673 | |8;json-parse-financial;55;56;55;54;56;54;56;55;55;55 10:20:09 INFO - PROCESS | 16673 | |9;json-stringify-tinderbox;53;52;53;53;53;53;52;54;53;53 10:20:09 INFO - PROCESS | 16673 | |10;stanford-crypto-aes;80;78;82;76;79;86;81;75;75;75 10:20:09 INFO - PROCESS | 16673 | |11;stanford-crypto-ccm;122;122;131;121;126;118;117;123;163;119 10:20:09 INFO - PROCESS | 16673 | |12;stanford-crypto-pbkdf2;157;158;160;161;158;159;158;162;160;160 10:20:09 INFO - PROCESS | 16673 | |13;stanford-crypto-sha256-iterative;63;60;60;59;60;61;60;60;61;58 10:20:09 INFO - PROCESS | 16673 | __end_tp_report 10:20:09 INFO - PROCESS | 16673 | __start_cc_report 10:20:09 INFO - PROCESS | 16673 | _x_x_mozilla_cycle_collect,1660 10:20:09 INFO - PROCESS | 16673 | __end_cc_report 10:20:09 INFO - PROCESS | 16673 | __startTimestamp1491585609043__endTimestamp 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | ------- Summary: start ------- 10:20:09 INFO - PROCESS | 16673 | Number of tests: 14 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:3.69 (3.3%) stddev-sans-first:0.53 10:20:09 INFO - PROCESS | 16673 | Values: 101.0 113.0 113.0 113.0 112.0 112.0 112.0 113.0 113.0 112.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:116.00 stddev:15.55 (13.4%) stddev-sans-first:14.86 10:20:09 INFO - PROCESS | 16673 | Values: 144.0 115.0 115.0 158.0 116.0 116.0 116.0 116.0 136.0 116.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 10:20:09 INFO - PROCESS | 16673 | Values: 161.0 160.0 159.0 163.0 161.0 161.0 162.0 160.0 160.0 161.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.55 (15.2%) stddev-sans-first:11.95 10:20:09 INFO - PROCESS | 16673 | Values: 74.0 76.0 76.0 105.0 76.0 76.0 76.0 76.0 101.0 76.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:82.00 stddev:10.22 (12.5%) stddev-sans-first:10.68 10:20:09 INFO - PROCESS | 16673 | Values: 83.0 82.0 105.0 81.0 82.0 82.0 94.0 82.0 82.0 107.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 10:20:09 INFO - PROCESS | 16673 | Values: 112.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 114.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.00 stddev:1.71 (1.2%) stddev-sans-first:0.60 10:20:09 INFO - PROCESS | 16673 | Values: 152.0 147.0 146.0 147.0 148.0 147.0 146.0 147.0 147.0 147.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 10:20:09 INFO - PROCESS | 16673 | Values: 95.0 93.0 94.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 10:20:09 INFO - PROCESS | 16673 | Values: 55.0 56.0 55.0 54.0 56.0 54.0 56.0 55.0 55.0 55.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#9] json-stringify-tinderbox Cycles:10 Average:52.90 Median:53.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 10:20:09 INFO - PROCESS | 16673 | Values: 53.0 52.0 53.0 53.0 53.0 53.0 52.0 54.0 53.0 53.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.50 stddev:3.65 (4.6%) stddev-sans-first:3.84 10:20:09 INFO - PROCESS | 16673 | Values: 80.0 78.0 82.0 76.0 79.0 86.0 81.0 75.0 75.0 75.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:122.50 stddev:13.55 (11.1%) stddev-sans-first:14.29 10:20:09 INFO - PROCESS | 16673 | Values: 122.0 122.0 131.0 121.0 126.0 118.0 117.0 123.0 163.0 119.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.30 Median:160.00 stddev:1.57 (1.0%) stddev-sans-first:1.42 10:20:09 INFO - PROCESS | 16673 | Values: 157.0 158.0 160.0 161.0 158.0 159.0 158.0 162.0 160.0 160.0 10:20:09 INFO - PROCESS | 16673 | 10:20:09 INFO - PROCESS | 16673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:1.32 (2.2%) stddev-sans-first:0.93 10:20:09 INFO - PROCESS | 16673 | Values: 63.0 60.0 60.0 59.0 60.0 61.0 60.0 60.0 61.0 58.0 10:20:09 INFO - PROCESS | 16673 | -------- Summary: end -------- 10:20:09 INFO - PROCESS | 16673 | 10:20:10 INFO - TEST-INFO | 16673: exit 0 10:20:10 INFO - TEST-OK | kraken | took 60717ms 10:20:10 INFO - SUITE-END | took 1177s 10:20:10 INFO - Completed test suite (00:19:37) 10:20:10 INFO - javascript benchmark 10:20:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7924.783936631257, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.5390372299735, 3459.1352161959503, 3460.6366331535205, 3477.687008169264, 3495.8951231463047, 5740.655560666363, 5829.53363730901, 5833.358330583633, 5837.970810145948, 5844.357120716718, 5862.677552734598, 5931.88136237275, 5932.911006334908, 5950.434708702666, 5951.018082016473, 5213.843584692465, 5215.1655735082395, 5231.0, 5255.211718242256, 5340.06548853951, 5083.517065878736, 5312.309399778023, 5326.041312563756, 5330.173823057432, 5331.280277162592, 5110.233464980245, 5182.274481572564, 5221.71280579568, 5295.708736019517, 5313.20301954708, 5306.655067420625, 5326.840194794162, 5338.145896656555, 5338.466153384669, 5339.866503337409, 5191.792328306863, 5231.947680523184, 5242.868928276824, 5289.471052894718, 5298.231756395339, 5581.5255703265275, 5598.776048958037, 5747.798827041033, 5793.884122317572, 5810.796622118206, 6298.2442106947, 6301.180846489973, 6301.338359472281, 6301.59039662425, 6301.684915754194, 6336.936630633681, 6353.205849268841, 6373.553851230416, 6375.458086062644, 6375.553711240215, 5670.064439367499, 5722.084466485384, 5847.26909136358, 5885.293764748258, 5917.733701983421, 6409.455196308366, 6457.192850893638, 6475.4819614430735, 6476.579022363531, 6483.902741458882, 6089.665068421279, 6159.599626024294, 6189.071639254148, 6193.84515387119, 6214.347493513116, 6006.879862402728, 6038.033914573647, 6264.123022776815, 6269.090981807703, 6308.0537919311555, 6133.457080270857, 6181.227346581677, 6185.350538193515, 6196.1635179251125, 6196.504279657706, 5999.670018148957, 6053.697315134225, 6055.394460553909, 6153.384661533901, 6269.905951410732, 4340.934885976713, 4379.211741886491, 4393.51671316155, 4402.471703395613, 4415.425994620743, 3952.150287688161, 3954.9802250989137, 3976.8011599419915, 3980.800959951991, 3993.7004724645767, 5859.589828712034, 5919.319278283053, 6067.969660151759, 6079.179310793074, 6132.846678832976, 6315.052742088724, 6346.397092276318, 6354.904676429857, 6354.936450635527, 6355.809325720235, 6148.938510614837, 6156.168917196122, 6158.414950579688, 6162.106494558264, 6167.506599472032, 5103.081445339801, 5149.716765577929, 5273.1035723927025, 5294.364676238799, 5388.13789793639, 6561.770338038145, 6571.605703657796, 6579.144711187515, 6588.242352129375, 6592.76925307612, 6205.6586887721605, 6217.502599791916, 6272.8431789203805, 6274.278457977391, 6276.058591211355, 6172.938270617416, 6221.2845522763655, 6504.089427480062, 6575.046618240232, 6581.934180658323, 6210.627362358273, 6242.968785156227, 6245.156903818108, 6245.687715614292, 6246.875062498815, 6317.431431171217, 6335.4931605472375, 6342.460890824332, 6349.555531112777, 6353.364663533795, 5899.4985426239255, 5936.079907614327, 6085.9695701521205, 6143.692815359124, 6150.04674275488, 6046.123312119806, 6051.606645568024, 6119.755209791559, 6129.847850912788, 6130.489860652556, 6084.209052823105, 6095.695215239309, 6100.542459315658, 6104.816855494342, 6117.663528505975, 5928.9407105928385, 5964.343922168645, 5979.521638269015, 6127.14220009208, 6155.599886007395, 6086.695665216633, 6110.725017374318, 6114.602550834182, 6134.631922084689, 6136.539759517965], "value": 5739.928482088235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.8450525389668, 809.5345176523499, 817.5871185051548, 858.2533196119377, 878.3807415771881, 30453.238669033257, 30752.38495230097, 33263.33473330532, 43455.34816977748, 44838.0, 44668.77665611675, 44799.55200447995, 44811.551884481145, 44838.327425088566, 44885.32672009914, 9064.773380665487, 9209.309301802357, 9210.67762628308, 9217.170454659079, 9219.585118669642, 5123.0, 5213.244079608455, 10683.572657093708, 10895.128389728803, 10900.07349375304, 11020.44897755113, 11029.779404411907, 11036.11711063113, 11041.668749937515, 11043.613473528429, 10910.5090270938, 10950.288231264942, 10959.83560246597, 10972.890271097267, 10974.0, 10771.353718776898, 10831.620893268699, 10831.945840270828, 10833.83749243762, 10845.0, 42342.153156936685, 42594.57405425968, 42626.57373426257, 42646.573534264884, 42713.786431067645, 3850.6726928211137, 3893.80530973453, 3922.0194951262183, 3949.407588861665, 3997.060690737674, 29859.70140298591, 29931.850340748373, 30228.0, 30310.84844575785, 30924.22689432782, 4362.432883725127, 4468.240399132155, 4468.240399132155, 4484.775761211927, 4507.198998400225, 229765.0, 231783.0, 232040.0, 232477.0, 233952.0, 55505.722471388195, 55511.72244138754, 55547.0, 55574.72212638911, 55670.443295566525, 57474.7126264366, 57712.42287577155, 57792.0, 57859.42140578625, 57980.0, 16571.751423728652, 16959.660806784043, 17010.48968530946, 17035.14824258782, 17048.659026819394, 11347.886521134847, 15074.0, 15106.54680359591, 15172.696546069017, 15180.0, 12041.277523348626, 13074.411651475706, 13322.134061286179, 13436.731265374834, 13498.59504214875, 5603.355614104348, 5654.953833540808, 5727.828165155052, 5797.507211886952, 5807.419258074159, 1010.4442556593845, 1016.5527168046036, 1020.9183265338903, 1022.6778564752079, 1024.3137098144261, 28963.275918102216, 28992.710072899, 29030.0, 29122.708772912, 29140.562891556645, 1081.9937458163827, 1091.1052936592077, 1096.0573906440454, 1098.549594666183, 1123.3484578944192, 14462.20457874827, 15102.244887755569, 15200.163990980382, 15294.77057844111, 15554.22228888551, 291138.0, 291923.0, 292116.0, 292471.0, 292669.0, 56590.15114773282, 56691.14963275554, 56857.71571142118, 56859.0, 56866.0, 1346.73065386923, 1393.2267591486823, 1424.8147740793838, 1440.2150827798876, 1445.7253121906804, 26298.86850565735, 26556.203313900613, 27008.729912700623, 27368.305553062357, 27920.30199244954, 41047.17905641811, 41062.79468602758, 41138.79430602828, 41193.0, 41227.38158927559, 260248.0, 260410.0, 260468.0, 260493.0, 260569.0, 35522.64477355193, 36003.099922502144, 36290.63709362978, 36414.63585364113, 36426.8178659105, 42779.358309626004, 43417.782911086506, 43560.56439435565, 43589.34615980699, 43706.12587748164, 32845.50731739074, 33225.47785966816, 33332.66667333393, 33539.161520962174, 34142.0, 33627.0, 33819.0, 33927.0, 34139.14652133716, 34940.47589286214, 38320.6167938317, 38668.2266354677, 39414.80292598519, 39759.0, 39989.2002159961, 32312.83843580767, 32399.838000809847, 32512.187195320304, 32587.0, 32751.672483274862, 25091.623625645996, 25474.617880732174, 25521.361965951, 25568.10511632084, 26047.348816278987], "value": 18853.265365935942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.70675406952944, 461.56151655926857, 463.81215607678894, 475.2965610895874, 486.84177642266263, 17972.91013544932, 18980.335688250914, 19797.802021979795, 20688.689669654967, 20804.89597552014, 14434.927825360859, 22932.88533557334, 23096.0, 23118.190863319785, 23673.0, 6571.769988050419, 6712.228093769206, 6764.864702705943, 6773.695183716733, 6826.829329266783, 6736.29268926763, 6842.281560436158, 6860.897086543706, 6866.107406037208, 6869.931300686979, 6644.900326495106, 6759.695813688371, 6795.93204067958, 6800.727970881158, 6868.828279293033, 6604.570702904344, 6672.966635166842, 6759.729610815537, 6793.150856142982, 6822.965885170567, 6605.570637908569, 6607.6696165192025, 6609.669516524203, 6614.404703576702, 6615.17310336208, 25768.355791105187, 26308.73691263082, 26352.73647263522, 26468.86765566179, 26714.465710685872, 3294.159989202738, 3312.635610082889, 3318.7510936679846, 3354.077628652116, 3385.8984230473125, 16364.672706545922, 16797.41209057677, 16799.32802687891, 17317.913410432866, 17399.65200695979, 2140.743110826695, 2190.6218347202707, 2240.5070884405404, 2243.4839986803127, 2268.3421807675754, 100533.4973325136, 100710.49644751729, 101233.9876601232, 102285.0, 102445.0, 18783.624327513648, 19319.613607728046, 19617.901910490356, 19666.181785462653, 19698.31055913036, 37599.62400376016, 37806.0, 37810.24379512394, 37851.43222851659, 37912.0, 9552.522373881417, 10136.442495662663, 11090.057345125755, 11536.134789890792, 11857.347845868388, 9852.704418867303, 9907.554160062671, 9927.15619172364, 10115.241356898263, 10118.392896426238, 10032.899671003197, 10600.0, 10712.94643526793, 10833.404162771123, 10865.891341086646, 3720.813959302024, 3806.71449641278, 3848.634379733921, 3946.585608511065, 3981.820818063194, 967.685502211784, 983.0709979069853, 983.9262055345878, 988.3575675810637, 995.6117114325419, 20561.0, 20716.067776950073, 20753.377398678065, 20759.273425430038, 20776.272830451166, 990.6087095597343, 1002.0931057393071, 1007.1137399088755, 1016.9542370593341, 1026.6663334416344, 10691.465426728633, 11519.0, 11625.360605166798, 11668.824967625493, 11757.412129393497, 517100.0, 576903.0, 799424.0, 817559.0, 818675.0, 119725.0, 125675.3716231413, 126124.0, 126598.0, 128292.35853820671, 1442.1923722715312, 1472.477270568954, 1474.6018574984694, 1491.8358980512135, 1507.464849978242, 18639.90680046591, 18997.90501047486, 19202.423927281645, 19206.423807285802, 19490.12294446725, 37842.243155136195, 37877.0, 53908.460915391915, 54630.45369546254, 54878.90242195054, 66201.33798661952, 66554.33445665482, 67436.325636743, 67525.66237168979, 67769.3223067763, 12690.809637855624, 12707.0, 12854.485820567446, 12858.549950751678, 12884.742305153657, 13650.112742671694, 13658.931705341807, 13664.111832730841, 13763.655908602364, 13816.516421925184, 11973.880261197277, 12421.0, 12902.67743306425, 12981.4158362872, 13163.078584498993, 11557.133215009077, 11666.70833229176, 11672.649820505398, 11699.88300116988, 11863.822042669195, 12693.619191424272, 12699.809502857279, 12719.93640031794, 12743.617691469271, 12871.292078935374, 12478.750424991267, 12539.623811285675, 12618.242905425705, 12803.29581873006, 12995.28525931083, 12715.682107947376, 12875.163114397534, 12962.287074210632, 13054.216746995211, 13266.336683165997], "value": 11717.843128353596, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1549.442200807709, 1550.3643506162475, 1574.6771911758087, 1578.6053486628343, 1581.114575837531, 10747.946260268698, 11454.0, 11463.082953363732, 11466.598669046574, 11475.196736228467, 11274.718132046683, 11482.885171148286, 11712.121590880697, 11806.114541409386, 11830.58592949247, 2357.339944815446, 2395.1257790906366, 2395.2335252719135, 2397.0, 2398.328468028955, 2347.6361164019604, 2357.528494301138, 2470.493548822487, 2472.3695457658273, 2473.530029294437, 2354.058376649337, 2404.290734233405, 2414.0343862454984, 2439.7926176275037, 2440.8535487870695, 2274.2153956885013, 2324.174917904145, 2330.149495434163, 2332.265336419023, 2389.8566086034893, 2272.8409011369213, 2302.4243939015246, 2305.1586171047616, 2306.2965795432387, 2311.0524684879288, 11555.19113662044, 11776.234544754649, 11778.469968851423, 11785.587504437304, 11787.0, 5647.632903861277, 5773.595848290622, 5790.0, 5791.913121303184, 5796.797112101056, 3453.2575496268423, 3491.7555771096036, 3538.716902647782, 3550.414181660023, 3611.0, 1409.6405416618697, 1444.2995147353524, 1493.895427320088, 1503.1882783297006, 1506.7513860213053, 17333.04668243246, 17392.478225653125, 17480.47558573245, 17494.65010699779, 17577.0, 4379.693421460467, 4570.200214962435, 4616.561426664462, 4691.648126390534, 4693.929591056137, 13158.078934474686, 13160.934195328962, 13163.53927612548, 13168.868311316764, 13171.209727416386, 5725.971370143123, 5756.194132821408, 5774.509166720875, 5784.045632470596, 5802.6808525531505, 5200.97399513, 5273.34083239595, 5283.894322113536, 5380.24676545284, 5422.728863556807, 3967.563568007517, 4070.104576993057, 4076.5923407658997, 4132.91734165315, 4169.666426685919, 1055.3878750324793, 1056.6460235820932, 1072.495926914349, 1073.8765042020111, 1075.634284343327, 1090.225939582905, 1107.8615173103362, 1108.534415545473, 1116.3302018788825, 1122.2761318949395, 7178.964105179441, 7225.927740722525, 7291.8906216406795, 7349.0, 7364.22675619063, 1067.765091679829, 1094.102835674755, 1094.3816743539874, 1117.8821178821179, 1129.1531351486385, 4573.245414506569, 4597.95402045982, 4664.207084795593, 4710.528947105262, 4762.595179409789, 14397.42410303576, 14750.926245368919, 14873.628159296104, 15133.0, 15181.468648597245, 13992.440302387995, 14186.716265674835, 14709.632259193606, 15008.47470338533, 15047.774283385757, 10748.623798167184, 10894.074003709615, 10897.782044359068, 11021.228514004104, 11056.723581910517, 7582.62086895664, 7719.4210434218785, 7755.302022817974, 7883.6846526138315, 7915.960420197862, 13190.670233244246, 13355.666108347368, 13395.12931659439, 13650.0, 13899.791503127259, 13662.31688415595, 13706.588802335946, 13809.51666691661, 13811.654708631962, 13981.0, 9979.251556133464, 10029.04724051214, 10111.7472063199, 10207.948960255151, 10216.438095904796, 10944.507497162806, 10959.12327013821, 11010.449477526252, 11051.115910726961, 11145.275557088764, 9360.251179905456, 9511.762205944906, 9559.952200238955, 9643.0, 9720.027997200223, 8978.102189781232, 9052.456852588866, 9077.818443631222, 9110.908890911007, 9162.816743665222, 8423.199796019368, 8625.39622226438, 8632.741017769225, 8635.654573816977, 8639.0, 6696.430803381572, 6767.763128290486, 6815.011823285497, 6830.351116643911, 6915.861682766417, 6640.435562977201, 6717.0, 6850.451963842781, 6921.204061532988, 6995.930040699528], "value": 5489.1445423591895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.86352049741566, 414.52950900727683, 431.3034449364276, 445.8550970934446, 451.9050999290149, 5070.797168113276, 5190.922136167956, 5254.632175747699, 5266.708307307933, 5279.260903473507, 5014.849554513361, 5127.512886275807, 5179.870503237421, 5195.168772996321, 5200.765965531551, 4415.278041563784, 4417.867463976086, 4424.84513042042, 4433.334999750031, 4455.420795296607, 4225.978870105645, 4339.566043395651, 4343.305071188611, 4455.754933478657, 4461.866144015685, 4418.9394545309215, 4431.933520997188, 4438.844640437585, 4438.8693427154985, 4469.128519938613, 4435.135148646015, 4461.286194208943, 4462.464504259493, 4477.0, 4481.484629267643, 3904.2386734586767, 4067.837286508536, 4105.4662893823615, 4127.153933443637, 4330.112326972963, 5024.221245706921, 5037.773300201463, 5053.216751403538, 5061.44324124346, 5081.196175357732, 5017.247412888059, 5062.620303477217, 5080.288759573663, 5084.898302033938, 5102.260172275037, 5259.973700131475, 5444.863878403032, 5482.616216864821, 5483.725813709299, 5487.067198576252, 4572.536788227769, 4577.9542204577865, 4583.564561366698, 4614.192516309666, 4653.464851542082, 5725.456081672234, 5730.312362516483, 5746.310442746855, 5806.158107074492, 5821.446962538552, 4525.637948964075, 4720.33915251865, 4733.763311834395, 4764.425622022242, 4778.880527986828, 4576.565226303496, 4864.221724524059, 4864.683795553277, 4872.8781780455065, 4874.293227482066, 4927.507249275044, 4989.501049894981, 5000.724960127228, 5059.848204553869, 5122.154844450699, 5494.2582751328855, 5507.284053073155, 5510.862228444321, 5516.738137363662, 5526.889462210734, 1586.8095828500655, 1643.9013659180248, 1648.2994727240875, 1661.8421249981232, 1668.9833101669071, 1263.3091515072836, 1279.5073896549895, 1285.6785803549112, 1288.6971561682997, 1291.3543228385806, 5430.375506816767, 5441.782328706808, 5491.203775452617, 5503.889922201533, 5529.373547564955, 5523.889522209533, 5585.636933599303, 5667.858303542362, 5695.316562012502, 5711.200431939532, 5226.980738755982, 5460.208269800936, 5468.863278418072, 5470.425605311465, 5482.725863706799, 2927.487689654302, 2940.3678209185127, 2942.29384947611, 2969.7475714564503, 2990.626171728534, 5629.284672116465, 5891.3224979127945, 5899.195019212584, 5918.112283157475, 5946.613470124428, 5641.8589535261945, 5705.429457054262, 5722.798212375448, 5741.598088133791, 5749.741261643236, 5874.265716785402, 5889.087191485327, 5915.792947246826, 5929.525637948867, 5946.226990491208, 5333.493318134771, 5579.553635709215, 5600.075987462186, 5660.669742610401, 5701.857453563694, 5468.0, 5492.340919089735, 5528.861778455571, 5559.694216818114, 5602.299712535933, 5840.240768700041, 5900.852478688067, 5918.277592307587, 5955.344912059757, 5959.970200148971, 4547.363369128391, 4713.505081966344, 4771.809127634856, 4774.3554620127225, 4787.904241915073, 5434.891302173855, 5527.44725527444, 5588.497035266845, 5635.830925072254, 5696.1455781633085, 5434.402215756185, 5445.794453246442, 5483.314585676791, 5495.807646732345, 5535.142052981794, 5267.657602255841, 5403.972980135074, 5422.102264207496, 5456.945430545644, 5476.479734425383, 5407.378151512627, 5450.31871016123, 5463.426340234377, 5482.342118945752, 5492.945070549403, 5518.944810551843, 5528.55771538284, 5546.226886556722, 5567.526760225264, 5575.804846830341, 5144.845654630367, 5409.756560954688, 5411.540019098421, 5420.430854760351, 5452.727363631882], "value": 4436.3537481520825, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.9655613775449, 871.5467956662536, 914.4696076275761, 928.2713070239862, 933.5798890499273, 16390.918045409788, 16732.66534669306, 17336.13319334033, 17975.19111639976, 18187.363442279522, 16555.337786488526, 16872.578185545368, 17075.82924170758, 17195.484135475956, 17428.3028678853, 858.4119877883638, 871.372611719561, 873.2402809555664, 876.7063033883657, 897.8967418746862, 889.6219106879582, 890.1899271662788, 890.341147550811, 892.6830975003877, 893.258595365848, 946.394307643109, 947.9099485548896, 947.924166066713, 949.6011675096476, 949.6818565780471, 943.1228957069923, 943.3726571829725, 944.9055094490565, 945.7588314979671, 946.2430055955208, 695.2630211975322, 696.7282759723712, 696.8954656801469, 697.086816270682, 698.3191388396324, 18196.727049094276, 18202.45392638223, 18268.451946441495, 18950.715739263924, 19124.330648427378, 16125.516234512983, 16352.509424717278, 16395.344186232538, 16402.83597164037, 16576.17119144038, 13054.216746995211, 13239.20564766117, 13472.0, 13505.122167059208, 13521.459141634421, 4030.979845100785, 4075.1034772350035, 4075.7887174984453, 4123.195976784528, 4206.221848957922, 91456.54271728665, 92664.0, 93113.5344323274, 93135.0, 94119.0, 11006.779864402828, 11297.209195356247, 11409.315441073562, 11449.313041217554, 11455.713607159722, 24531.754682452945, 24547.77261136936, 24840.627390589154, 24977.0, 25120.12079577206, 9343.159115679622, 9379.20276776468, 9490.90509094914, 9500.762480938032, 9503.239740820718, 5140.614453915971, 5150.793968241303, 5190.24741412493, 5251.2123181523075, 5311.468853114658, 3984.541777695602, 4051.068254301468, 4068.572799856032, 4071.0840060986043, 4083.530394004668, 610.5603965145089, 634.1660716158237, 641.4483544152025, 644.5037321262602, 645.7481582182952, 702.5447400931668, 716.8422946951662, 719.3058698356111, 721.5670597641478, 851.2508992086924, 10381.584736610603, 10699.0, 11218.943905280585, 11264.774704506028, 11465.369404682655, 632.2570979099631, 633.3729607688356, 638.6295948349946, 661.7419206509466, 867.5796815226412, 5152.819651312186, 5487.6981766002455, 5584.441555844464, 5586.636868603528, 5607.551395888319, 79794.6010269945, 80672.59663701644, 81226.18773812187, 82263.5886820562, 83516.58241708753, 70104.29895700977, 70728.0, 71812.0, 72107.27892721111, 72771.63614181895, 63221.0, 63356.0, 64994.708889562426, 65050.67474662596, 67175.0, 10332.896671033193, 10366.326188797704, 10446.947765261126, 10540.631077912236, 10587.417692026704, 38452.615473844904, 42939.35590966201, 43613.56386436222, 43676.781616091714, 44243.33634995413, 50538.0, 54513.0, 61038.0, 62640.37359626528, 64123.0, 14473.638159046108, 14868.0, 15677.921610391875, 16114.033158010558, 16543.586410339838, 16073.35706571724, 16094.195290235675, 16175.514734557983, 16291.348346066025, 16364.590885227964, 15409.614759631098, 15480.303386347416, 15653.608659783597, 15723.842761572238, 15874.126923018883, 16439.0, 16666.166691665127, 17241.56896077608, 17969.460916172535, 18006.72989905179, 16076.598085047968, 16220.27008784584, 16504.66990660156, 16792.160391980597, 17294.567635809206, 10859.366803391165, 10962.945185274342, 11169.720756980816, 11373.488193031499, 11538.538458461904, 11836.171467997501, 12148.392580371123, 13369.598912032654, 13734.725305494034, 13954.651133721738], "value": 8020.748654960806, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 115.0, 158.0, 116.0, 116.0, 116.0, 116.0, 136.0, 116.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 163.0, 161.0, 161.0, 162.0, 160.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 105.0, 76.0, 76.0, 76.0, 76.0, 101.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 105.0, 81.0, 82.0, 82.0, 94.0, 82.0, 82.0, 107.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 146.0, 147.0, 148.0, 147.0, 146.0, 147.0, 147.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 56.0, 54.0, 56.0, 55.0, 55.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 53.0, 53.0, 52.0, 54.0, 53.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 82.0, 76.0, 79.0, 86.0, 81.0, 75.0, 75.0, 75.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 131.0, 121.0, 126.0, 118.0, 117.0, 123.0, 163.0, 119.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 160.0, 161.0, 158.0, 159.0, 158.0, 162.0, 160.0, 160.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 59.0, 60.0, 61.0, 60.0, 60.0, 61.0, 58.0], "value": 60.2, "unit": "score"}]}]} 10:20:10 INFO - Return code: 0 10:20:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:20:10 ERROR - # TBPL SUCCESS # 10:20:10 INFO - Running post-action listener: _resource_record_post_action 10:20:10 INFO - [mozharness: 2017-04-07 17:20:10.355874Z] Finished run-tests step (success) 10:20:10 INFO - Running post-run listener: _resource_record_post_run 10:20:10 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 266240; Write bytes: 374808576; Read time: 28; Write time: 377716 10:20:10 INFO - TinderboxPrint: CPU usage
13.1% 10:20:10 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 10:20:10 INFO - TinderboxPrint: I/O write bytes / time
374,808,576 / 377,716 10:20:10 INFO - TinderboxPrint: CPU idle
8,276.4 (86.8%) 10:20:10 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 10:20:10 INFO - TinderboxPrint: Swap in / out
0 / 0 10:20:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 122142720; Read time: 0; Write time: 141368 10:20:10 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 266240; Write bytes: 252665856; Read time: 28; Write time: 236348 10:20:10 INFO - Running post-run listener: _upload_blobber_files 10:20:10 INFO - Blob upload gear active. 10:20:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:20:10 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 10:20:10 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'] 10:20:10 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 10:20:11 INFO - (blobuploader) - INFO - Open directory for files ... 10:20:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:20:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:20:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:12 INFO - (blobuploader) - INFO - Done attempting. 10:20:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:20:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:13 INFO - (blobuploader) - INFO - Done attempting. 10:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:16 INFO - (blobuploader) - INFO - Done attempting. 10:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:20:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:17 INFO - (blobuploader) - INFO - Done attempting. 10:20:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:20:17 INFO - Return code: 0 10:20:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:20:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:20:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb894a1d0ccc77cd0fe2a11cb9c0aa177ba578dc7617fd435f679bd03ea28c5d765c876d0e83bcbd671239aea328b282e716b69f96eead8278a9100253570e4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/986bf6591f4946cc7441325b789c662e4ac66add783e164339102e32ba0ecbf26e27bf723362655b2f173e780aaabe7f8dc6e541c47dd694f26e4e234e724d35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1ba898969943b5d7acdcbad757c509b16f75141e163a9420a1d50573cf6fbde063006b230493816eb755721603389a617c119c51af49653d3100d4306ee563b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e337438bcfb9c18a2f30c580925b6c7ca2fcccaa1c0ab2c6c3838924bda60325cf443869604e5f45a6f13ce40af15f01a33c6ce8e937bea14c4b14bf8851235a"} 10:20:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:20:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:20:17 INFO - Contents: 10:20:17 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb894a1d0ccc77cd0fe2a11cb9c0aa177ba578dc7617fd435f679bd03ea28c5d765c876d0e83bcbd671239aea328b282e716b69f96eead8278a9100253570e4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/986bf6591f4946cc7441325b789c662e4ac66add783e164339102e32ba0ecbf26e27bf723362655b2f173e780aaabe7f8dc6e541c47dd694f26e4e234e724d35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1ba898969943b5d7acdcbad757c509b16f75141e163a9420a1d50573cf6fbde063006b230493816eb755721603389a617c119c51af49653d3100d4306ee563b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e337438bcfb9c18a2f30c580925b6c7ca2fcccaa1c0ab2c6c3838924bda60325cf443869604e5f45a6f13ce40af15f01a33c6ce8e937bea14c4b14bf8851235a"} 10:20:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:20:17 INFO - Copying logs to upload dir... 10:20:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:20:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.922140 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-07 10:20:16.848511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:16.851961) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 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/efb894a1d0ccc77cd0fe2a11cb9c0aa177ba578dc7617fd435f679bd03ea28c5d765c876d0e83bcbd671239aea328b282e716b69f96eead8278a9100253570e4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/986bf6591f4946cc7441325b789c662e4ac66add783e164339102e32ba0ecbf26e27bf723362655b2f173e780aaabe7f8dc6e541c47dd694f26e4e234e724d35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1ba898969943b5d7acdcbad757c509b16f75141e163a9420a1d50573cf6fbde063006b230493816eb755721603389a617c119c51af49653d3100d4306ee563b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e337438bcfb9c18a2f30c580925b6c7ca2fcccaa1c0ab2c6c3838924bda60325cf443869604e5f45a6f13ce40af15f01a33c6ce8e937bea14c4b14bf8851235a"} build_url:https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/TWJpmx7vRyGM8NV0vA9xnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb894a1d0ccc77cd0fe2a11cb9c0aa177ba578dc7617fd435f679bd03ea28c5d765c876d0e83bcbd671239aea328b282e716b69f96eead8278a9100253570e4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/986bf6591f4946cc7441325b789c662e4ac66add783e164339102e32ba0ecbf26e27bf723362655b2f173e780aaabe7f8dc6e541c47dd694f26e4e234e724d35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1ba898969943b5d7acdcbad757c509b16f75141e163a9420a1d50573cf6fbde063006b230493816eb755721603389a617c119c51af49653d3100d4306ee563b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e337438bcfb9c18a2f30c580925b6c7ca2fcccaa1c0ab2c6c3838924bda60325cf443869604e5f45a6f13ce40af15f01a33c6ce8e937bea14c4b14bf8851235a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:16.881688) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:16.882019) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1491584363.100-613634706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:20:16.966338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:20:16.966657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 10:20:16.966963) ========= ========= Total master_lag: 0.19 =========