builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1491755615.74 results: success (0) revision: efca7a198376e7800873e4612a8b34fc77022d4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.735341) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.735761) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.736047) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.751227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.751614) ========= 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=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-09 09:33:37-- 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% 42.2M=0s 2017-04-09 09:33:37 (42.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092410 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.882196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.882548) ========= 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=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016267 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.935970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:35.939387) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efca7a198376e7800873e4612a8b34fc77022d4d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efca7a198376e7800873e4612a8b34fc77022d4d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-09 09:33:37,566 truncating revision to first 12 chars 2017-04-09 09:33:37,566 Setting DEBUG logging. 2017-04-09 09:33:37,566 attempt 1/10 2017-04-09 09:33:37,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/efca7a198376?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-09 09:33:38,100 unpacking tar archive at: mozilla-aurora-efca7a198376/testing/mozharness/ program finished with exit code 0 elapsedTime=0.871453 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:36.824531) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:36.824874) ========= script_repo_revision: efca7a198376e7800873e4612a8b34fc77022d4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:36.825337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:36.825652) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 09:33:36.836113) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-04-09 09:33:36.836452) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] 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=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:38 INFO - MultiFileLogger online at 20170409 09:33:38 in /builds/slave/test 09:33:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 09:33:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:38 INFO - {'append_to_log': False, 09:33:38 INFO - 'base_work_dir': '/builds/slave/test', 09:33:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 09:33:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:38 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 09:33:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:38 INFO - 'config_files': ('talos/linux_config.py',), 09:33:38 INFO - 'default_actions': ('clobber', 09:33:38 INFO - 'read-buildbot-config', 09:33:38 INFO - 'download-and-extract', 09:33:38 INFO - 'populate-webroot', 09:33:38 INFO - 'create-virtualenv', 09:33:38 INFO - 'install', 09:33:38 INFO - 'run-tests'), 09:33:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:38 INFO - 'download_minidump_stackwalk': True, 09:33:38 INFO - 'download_symbols': 'ondemand', 09:33:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:38 INFO - 'gecko_profile': False, 09:33:38 INFO - 'gecko_profile_interval': 0, 09:33:38 INFO - 'installer_path': 'installer.exe', 09:33:38 INFO - 'log_level': 'info', 09:33:38 INFO - 'log_name': 'talos', 09:33:38 INFO - 'log_to_console': True, 09:33:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:38 INFO - 'opt_config_files': (), 09:33:38 INFO - 'pip_index': False, 09:33:38 INFO - 'suite': 'dromaeojs', 09:33:38 INFO - 'system_bits': '32', 09:33:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:38 INFO - 'title': 'talos-linux64-ix-035', 09:33:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:38 INFO - 'use_talos_json': True, 09:33:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:38 INFO - 'work_dir': 'build'} 09:33:38 INFO - [mozharness: 2017-04-09 16:33:38.564102Z] Running clobber step. 09:33:38 INFO - Running pre-action listener: _resource_record_pre_action 09:33:38 INFO - Running main action method: clobber 09:33:38 INFO - rmtree: /builds/slave/test/build 09:33:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:39 INFO - Running post-action listener: _resource_record_post_action 09:33:39 INFO - [mozharness: 2017-04-09 16:33:39.501097Z] Finished clobber step (success) 09:33:39 INFO - [mozharness: 2017-04-09 16:33:39.501196Z] Running read-buildbot-config step. 09:33:39 INFO - Running pre-action listener: _resource_record_pre_action 09:33:39 INFO - Running main action method: read_buildbot_config 09:33:39 INFO - Using buildbot properties: 09:33:39 INFO - { 09:33:39 INFO - "project": "", 09:33:39 INFO - "product": "firefox", 09:33:39 INFO - "who": "ffxbld@noreply.mozilla.org", 09:33:39 INFO - "installer_path": "public/build/target.tar.bz2", 09:33:39 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:33:39 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:33:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 09:33:39 INFO - "stage_platform": "linux64", 09:33:39 INFO - "basedir": "/builds/slave/test", 09:33:39 INFO - "buildnumber": 122, 09:33:39 INFO - "platform": "ubuntu64_hw", 09:33:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:33:39 INFO - "slavebuilddir": "test", 09:33:39 INFO - "taskId": "ZLgy_vpOSAeNJIqsjn0Lug", 09:33:39 INFO - "branch": "mozilla-aurora", 09:33:39 INFO - "script_repo_revision": "production", 09:33:39 INFO - "revision": "efca7a198376e7800873e4612a8b34fc77022d4d", 09:33:39 INFO - "slavename": "talos-linux64-ix-035", 09:33:39 INFO - "repo_path": "releases/mozilla-aurora" 09:33:39 INFO - } 09:33:39 INFO - Finding installer, test and symbols from parent task. 09:33:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZLgy_vpOSAeNJIqsjn0Lug'}, attempt #1 09:33:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZLgy_vpOSAeNJIqsjn0Lug'}, attempt #1 09:33:40 INFO - Task dependencies: RE5VT4CwQGynlxiR8I4w2Q 09:33:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q'}, attempt #1 09:33:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 09:33:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/test_packages.json 09:33:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.crashreporter-symbols.zip 09:33:40 INFO - Running post-action listener: _resource_record_post_action 09:33:40 INFO - [mozharness: 2017-04-09 16:33:40.909276Z] Finished read-buildbot-config step (success) 09:33:40 INFO - [mozharness: 2017-04-09 16:33:40.909493Z] Running download-and-extract step. 09:33:40 INFO - Running pre-action listener: _resource_record_pre_action 09:33:40 INFO - Running main action method: download_and_extract 09:33:40 INFO - mkdir: /builds/slave/test/build/tests 09:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:40 INFO - trying https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.test_packages.json 09:33:40 INFO - Downloading https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:33:41 INFO - Downloaded 896 bytes. 09:33:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:33:41 INFO - Using the following test package requirements: 09:33:41 INFO - {u'common': [u'target.common.tests.zip'], 09:33:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:33:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:33:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:33:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:33:41 INFO - u'mozbase': [u'target.common.tests.zip'], 09:33:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:33:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:33:41 INFO - u'web-platform': [u'target.common.tests.zip', 09:33:41 INFO - u'target.web-platform.tests.zip'], 09:33:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:33:41 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:33:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip 09:33:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:33:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip into memory 09:33:43 INFO - Expected file size: 59540685 09:33:43 INFO - Obtained file size: 59540685 09:33:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:33:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip 09:33:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:33:53 INFO - Fetch https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.common.tests.zip into memory 09:33:54 INFO - Expected file size: 59540685 09:33:54 INFO - Obtained file size: 59540685 09:34:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.talos.tests.zip 09:34:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:34:05 INFO - Fetch https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.talos.tests.zip into memory 09:34:06 INFO - Expected file size: 14404424 09:34:06 INFO - Obtained file size: 14404424 09:34: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')]} 09:34:07 INFO - trying https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 09:34:07 INFO - Downloading https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:34:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:34:09 INFO - Downloaded 66740792 bytes. 09:34:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 09:34:09 INFO - mkdir: /builds/slave/test/properties 09:34:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:09 INFO - Contents: 09:34:09 INFO - build_url:https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 09:34:09 INFO - Running post-action listener: _resource_record_post_action 09:34:09 INFO - Running post-action listener: set_extra_try_arguments 09:34:09 INFO - [mozharness: 2017-04-09 16:34:09.983277Z] Finished download-and-extract step (success) 09:34:09 INFO - [mozharness: 2017-04-09 16:34:09.983502Z] Running populate-webroot step. 09:34:09 INFO - Running pre-action listener: _resource_record_pre_action 09:34:09 INFO - Running main action method: populate_webroot 09:34:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:09 INFO - '--tppagecycles', 09:34:09 INFO - '7'], 09:34:09 INFO - 'tests': ['tsvgm']}}, 09:34:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:34:09 INFO - 'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:34:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:34:09 INFO - 'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['damp', 'tps']}, 09:34:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:34:09 INFO - 'tests': ['damp', 'tps']}, 09:34:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['dromaeo_dom']}, 09:34:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['basic_compositor_video']}, 09:34:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['a11yr', 09:34:09 INFO - 'ts_paint', 09:34:09 INFO - 'tpaint', 09:34:09 INFO - 'sessionrestore', 09:34:09 INFO - 'sessionrestore_no_auto_restore', 09:34:09 INFO - 'tabpaint']}, 09:34:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:09 INFO - 'tests': ['tsvgx', 09:34:09 INFO - 'tsvgr_opacity', 09:34:09 INFO - 'tart', 09:34:09 INFO - 'tscrollx', 09:34:10 INFO - 'cart', 09:34:10 INFO - 'tsvg_static']}, 09:34:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:10 INFO - 'tsvgr_opacity', 09:34:10 INFO - 'tart', 09:34:10 INFO - 'tscrollx', 09:34:10 INFO - 'cart', 09:34:10 INFO - 'tsvg_static']}, 09:34:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:34:10 INFO - 'talos_options': ['--disable-e10s'], 09:34:10 INFO - 'tests': ['tp5o']}, 09:34:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:34:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:34:10 INFO - 'talos_options': ['--disable-e10s', 09:34:10 INFO - '--xperf_path', 09:34:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:10 INFO - 'tests': ['tp5n']}, 09:34:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:34:10 INFO - 'talos_options': ['--xperf_path', 09:34:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:10 INFO - 'tests': ['tp5n']}}, 09:34:10 INFO - 'talos.zip': {'path': '', 09:34:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:10 INFO - Running post-action listener: _resource_record_post_action 09:34:10 INFO - [mozharness: 2017-04-09 16:34:10.001824Z] Finished populate-webroot step (success) 09:34:10 INFO - [mozharness: 2017-04-09 16:34:10.001913Z] Running create-virtualenv step. 09:34:10 INFO - Running pre-action listener: _resource_record_pre_action 09:34:10 INFO - Running main action method: create_virtualenv 09:34:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:34:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:10 INFO - Using real prefix '/usr' 09:34:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:12 INFO - Installing pip.................done. 09:34:12 INFO - Return code: 0 09:34:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:34:12 INFO - Reading from file tmpfile_stdout 09:34:12 INFO - Output received: 09:34:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:34:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:12 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:34:12 INFO - Using env: {'DISPLAY': ':0', 09:34:12 INFO - 'HOME': '/home/cltbld', 09:34:12 INFO - 'LANG': 'en_US.UTF-8', 09:34:12 INFO - 'LANGUAGE': 'en_US:en', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MAIL': '/var/mail/cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SHLVL': '1', 09:34:12 INFO - 'TERM': 'linux', 09:34:12 INFO - 'TMOUT': '86400', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - '_': '/tools/buildbot/bin/python'} 09:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:34:13 INFO - Cleaning up... 09:34:13 INFO - Return code: 0 09:34:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:13 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:34:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:34:13 INFO - Using env: {'DISPLAY': ':0', 09:34:13 INFO - 'HOME': '/home/cltbld', 09:34:13 INFO - 'LANG': 'en_US.UTF-8', 09:34:13 INFO - 'LANGUAGE': 'en_US:en', 09:34:13 INFO - 'LOGNAME': 'cltbld', 09:34:13 INFO - 'MAIL': '/var/mail/cltbld', 09:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:13 INFO - 'MOZ_NO_REMOTE': '1', 09:34:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:13 INFO - 'NO_EM_RESTART': '1', 09:34:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:13 INFO - 'PWD': '/builds/slave/test', 09:34:13 INFO - 'SHELL': '/bin/bash', 09:34:13 INFO - 'SHLVL': '1', 09:34:13 INFO - 'TERM': 'linux', 09:34:13 INFO - 'TMOUT': '86400', 09:34:13 INFO - 'USER': 'cltbld', 09:34:13 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:13 INFO - '_': '/tools/buildbot/bin/python'} 09:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:13 INFO - Downloading/unpacking psutil>=3.1.1 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:15 INFO - Installing collected packages: psutil 09:34:15 INFO - Running setup.py install for psutil 09:34:16 INFO - building 'psutil._psutil_linux' extension 09:34:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:34:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:34:16 INFO - building 'psutil._psutil_posix' extension 09:34:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:34:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:16 INFO - Successfully installed psutil 09:34:16 INFO - Cleaning up... 09:34:16 INFO - Return code: 0 09:34:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:16 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:34:16 INFO - Using env: {'DISPLAY': ':0', 09:34:16 INFO - 'HOME': '/home/cltbld', 09:34:16 INFO - 'LANG': 'en_US.UTF-8', 09:34:16 INFO - 'LANGUAGE': 'en_US:en', 09:34:16 INFO - 'LOGNAME': 'cltbld', 09:34:16 INFO - 'MAIL': '/var/mail/cltbld', 09:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:16 INFO - 'MOZ_NO_REMOTE': '1', 09:34:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:16 INFO - 'NO_EM_RESTART': '1', 09:34:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:16 INFO - 'PWD': '/builds/slave/test', 09:34:16 INFO - 'SHELL': '/bin/bash', 09:34:16 INFO - 'SHLVL': '1', 09:34:16 INFO - 'TERM': 'linux', 09:34:16 INFO - 'TMOUT': '86400', 09:34:16 INFO - 'USER': 'cltbld', 09:34:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:16 INFO - '_': '/tools/buildbot/bin/python'} 09:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:19 INFO - Installing collected packages: mozsystemmonitor 09:34:19 INFO - Running setup.py install for mozsystemmonitor 09:34:19 INFO - Successfully installed mozsystemmonitor 09:34:19 INFO - Cleaning up... 09:34:19 INFO - Return code: 0 09:34:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:34:20 INFO - Using env: {'DISPLAY': ':0', 09:34:20 INFO - 'HOME': '/home/cltbld', 09:34:20 INFO - 'LANG': 'en_US.UTF-8', 09:34:20 INFO - 'LANGUAGE': 'en_US:en', 09:34:20 INFO - 'LOGNAME': 'cltbld', 09:34:20 INFO - 'MAIL': '/var/mail/cltbld', 09:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:20 INFO - 'MOZ_NO_REMOTE': '1', 09:34:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:20 INFO - 'NO_EM_RESTART': '1', 09:34:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:20 INFO - 'PWD': '/builds/slave/test', 09:34:20 INFO - 'SHELL': '/bin/bash', 09:34:20 INFO - 'SHLVL': '1', 09:34:20 INFO - 'TERM': 'linux', 09:34:20 INFO - 'TMOUT': '86400', 09:34:20 INFO - 'USER': 'cltbld', 09:34:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:20 INFO - '_': '/tools/buildbot/bin/python'} 09:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:23 INFO - warning: no files found matching '*.txt' 09:34:23 INFO - no previously-included directories found matching 'build' 09:34:23 INFO - no previously-included directories found matching 'dist' 09:34:23 INFO - no previously-included directories found matching '.git*' 09:34:23 INFO - Installing collected packages: jsonschema, functools32 09:34:23 INFO - Running setup.py install for functools32 09:34:23 INFO - warning: no files found matching '*.txt' 09:34:23 INFO - no previously-included directories found matching 'build' 09:34:23 INFO - no previously-included directories found matching 'dist' 09:34:23 INFO - no previously-included directories found matching '.git*' 09:34:23 INFO - Successfully installed jsonschema functools32 09:34:23 INFO - Cleaning up... 09:34:23 INFO - Return code: 0 09:34:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:34:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:34:23 INFO - Using env: {'DISPLAY': ':0', 09:34:23 INFO - 'HOME': '/home/cltbld', 09:34:23 INFO - 'LANG': 'en_US.UTF-8', 09:34:23 INFO - 'LANGUAGE': 'en_US:en', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MAIL': '/var/mail/cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SHLVL': '1', 09:34:23 INFO - 'TERM': 'linux', 09:34:23 INFO - 'TMOUT': '86400', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - '_': '/tools/buildbot/bin/python'} 09:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:23 INFO - Cleaning up... 09:34:23 INFO - Return code: 0 09:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:23 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:34:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:34:23 INFO - Using env: {'DISPLAY': ':0', 09:34:23 INFO - 'HOME': '/home/cltbld', 09:34:23 INFO - 'LANG': 'en_US.UTF-8', 09:34:23 INFO - 'LANGUAGE': 'en_US:en', 09:34:23 INFO - 'LOGNAME': 'cltbld', 09:34:23 INFO - 'MAIL': '/var/mail/cltbld', 09:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:23 INFO - 'MOZ_NO_REMOTE': '1', 09:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:23 INFO - 'NO_EM_RESTART': '1', 09:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:23 INFO - 'PWD': '/builds/slave/test', 09:34:23 INFO - 'SHELL': '/bin/bash', 09:34:23 INFO - 'SHLVL': '1', 09:34:23 INFO - 'TERM': 'linux', 09:34:23 INFO - 'TMOUT': '86400', 09:34:23 INFO - 'USER': 'cltbld', 09:34:23 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:23 INFO - '_': '/tools/buildbot/bin/python'} 09:34:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:26 INFO - Downloading docopt-0.6.1.tar.gz 09:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:26 INFO - Running setup.py install for blobuploader 09:34:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:27 INFO - Running setup.py install for requests 09:34:27 INFO - Running setup.py install for docopt 09:34:27 INFO - Successfully installed blobuploader requests docopt 09:34:27 INFO - Cleaning up... 09:34:27 INFO - Return code: 0 09:34:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34: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:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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:34:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:27 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:27 INFO - Using env: {'DISPLAY': ':0', 09:34:27 INFO - 'HOME': '/home/cltbld', 09:34:27 INFO - 'LANG': 'en_US.UTF-8', 09:34:27 INFO - 'LANGUAGE': 'en_US:en', 09:34:27 INFO - 'LOGNAME': 'cltbld', 09:34:27 INFO - 'MAIL': '/var/mail/cltbld', 09:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:27 INFO - 'MOZ_NO_REMOTE': '1', 09:34:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:27 INFO - 'NO_EM_RESTART': '1', 09:34:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:27 INFO - 'PWD': '/builds/slave/test', 09:34:27 INFO - 'SHELL': '/bin/bash', 09:34:27 INFO - 'SHLVL': '1', 09:34:27 INFO - 'TERM': 'linux', 09:34:27 INFO - 'TMOUT': '86400', 09:34:27 INFO - 'USER': 'cltbld', 09:34:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:27 INFO - '_': '/tools/buildbot/bin/python'} 09:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:27 INFO - Running setup.py (path:/tmp/pip-8GuBDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:27 INFO - Running setup.py (path:/tmp/pip-kcEmW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:27 INFO - Running setup.py (path:/tmp/pip-dHTTIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:28 INFO - Running setup.py (path:/tmp/pip-EVolTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:28 INFO - Running setup.py (path:/tmp/pip-LolVoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:28 INFO - Running setup.py (path:/tmp/pip-OU3TpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:28 INFO - Running setup.py (path:/tmp/pip-vTXk86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:28 INFO - Running setup.py (path:/tmp/pip-j6UrGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:28 INFO - Running setup.py (path:/tmp/pip-7Du1UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:28 INFO - Running setup.py (path:/tmp/pip-XeUdSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:28 INFO - Running setup.py (path:/tmp/pip-bVv56d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:28 INFO - Running setup.py (path:/tmp/pip-_vaPXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:28 INFO - Running setup.py (path:/tmp/pip-yPBR09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:28 INFO - Running setup.py (path:/tmp/pip-b4q0V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:28 INFO - Running setup.py (path:/tmp/pip-q5ZfUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:28 INFO - Running setup.py (path:/tmp/pip-abGP9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:28 INFO - Running setup.py (path:/tmp/pip-AMseUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:29 INFO - Running setup.py install for manifestparser 09:34:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Running setup.py install for mozcrash 09:34:29 INFO - Running setup.py install for mozdebug 09:34:29 INFO - Running setup.py install for mozdevice 09:34:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Running setup.py install for mozfile 09:34:29 INFO - Running setup.py install for mozhttpd 09:34:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Running setup.py install for mozinfo 09:34:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Running setup.py install for mozInstall 09:34:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:29 INFO - Running setup.py install for mozleak 09:34:29 INFO - Running setup.py install for mozlog 09:34:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Running setup.py install for moznetwork 09:34:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Running setup.py install for mozprocess 09:34:30 INFO - Running setup.py install for mozprofile 09:34:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Running setup.py install for mozrunner 09:34:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Running setup.py install for mozscreenshot 09:34:30 INFO - Running setup.py install for moztest 09:34:30 INFO - Running setup.py install for mozversion 09:34:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:30 INFO - Cleaning up... 09:34:30 INFO - Return code: 0 09:34:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:30 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:30 INFO - Using env: {'DISPLAY': ':0', 09:34:30 INFO - 'HOME': '/home/cltbld', 09:34:30 INFO - 'LANG': 'en_US.UTF-8', 09:34:30 INFO - 'LANGUAGE': 'en_US:en', 09:34:30 INFO - 'LOGNAME': 'cltbld', 09:34:30 INFO - 'MAIL': '/var/mail/cltbld', 09:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:30 INFO - 'MOZ_NO_REMOTE': '1', 09:34:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:30 INFO - 'NO_EM_RESTART': '1', 09:34:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:30 INFO - 'PWD': '/builds/slave/test', 09:34:30 INFO - 'SHELL': '/bin/bash', 09:34:30 INFO - 'SHLVL': '1', 09:34:30 INFO - 'TERM': 'linux', 09:34:30 INFO - 'TMOUT': '86400', 09:34:30 INFO - 'USER': 'cltbld', 09:34:30 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:30 INFO - '_': '/tools/buildbot/bin/python'} 09:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:31 INFO - Running setup.py (path:/tmp/pip-an1N62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:31 INFO - Running setup.py (path:/tmp/pip-gl1oh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:31 INFO - Running setup.py (path:/tmp/pip-MuWB85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:31 INFO - Running setup.py (path:/tmp/pip-TIApqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:31 INFO - Running setup.py (path:/tmp/pip-HBfIWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:31 INFO - Running setup.py (path:/tmp/pip-UcQP6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:31 INFO - Running setup.py (path:/tmp/pip-RYgO8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:31 INFO - Running setup.py (path:/tmp/pip-ZN9M14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:31 INFO - Running setup.py (path:/tmp/pip-sm1YYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:31 INFO - Running setup.py (path:/tmp/pip-Ey5soi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:31 INFO - Running setup.py (path:/tmp/pip-BoTAAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:31 INFO - Running setup.py (path:/tmp/pip-DHRFQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:31 INFO - Running setup.py (path:/tmp/pip-NXZnqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:32 INFO - Running setup.py (path:/tmp/pip-y2kaXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:32 INFO - Running setup.py (path:/tmp/pip-NWivaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:32 INFO - Running setup.py (path:/tmp/pip-J6yf3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:32 INFO - Running setup.py (path:/tmp/pip-WqoH5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:34 INFO - Downloading blessings-1.6.tar.gz 09:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:34 INFO - Installing collected packages: blessings 09:34:34 INFO - Running setup.py install for blessings 09:34:34 INFO - Successfully installed blessings 09:34:34 INFO - Cleaning up... 09:34:34 INFO - Return code: 0 09:34:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:34 INFO - Reading from file tmpfile_stdout 09:34:34 INFO - Current package versions: 09:34:34 INFO - argparse == 1.2.1 09:34:34 INFO - blessings == 1.6 09:34:34 INFO - blobuploader == 1.2.4 09:34:34 INFO - docopt == 0.6.1 09:34:34 INFO - functools32 == 3.2.3-2 09:34:34 INFO - jsonschema == 2.5.1 09:34:34 INFO - manifestparser == 1.1 09:34:34 INFO - mozInstall == 1.12 09:34:34 INFO - mozcrash == 1.0 09:34:34 INFO - mozdebug == 0.1 09:34:34 INFO - mozdevice == 0.49 09:34:34 INFO - mozfile == 1.2 09:34:34 INFO - mozhttpd == 0.7 09:34:34 INFO - mozinfo == 0.9 09:34:34 INFO - mozleak == 0.1 09:34:34 INFO - mozlog == 3.4 09:34:34 INFO - moznetwork == 0.27 09:34:34 INFO - mozprocess == 0.25 09:34:34 INFO - mozprofile == 0.28 09:34:34 INFO - mozrunner == 6.13 09:34:34 INFO - mozscreenshot == 0.1 09:34:34 INFO - mozsystemmonitor == 0.3 09:34:34 INFO - moztest == 0.8 09:34:34 INFO - mozversion == 1.4 09:34:34 INFO - psutil == 3.1.1 09:34:34 INFO - requests == 1.2.3 09:34:34 INFO - wsgiref == 0.1.2 09:34:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:34 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:34 INFO - Using env: {'DISPLAY': ':0', 09:34:34 INFO - 'HOME': '/home/cltbld', 09:34:34 INFO - 'LANG': 'en_US.UTF-8', 09:34:34 INFO - 'LANGUAGE': 'en_US:en', 09:34:34 INFO - 'LOGNAME': 'cltbld', 09:34:34 INFO - 'MAIL': '/var/mail/cltbld', 09:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:34 INFO - 'MOZ_NO_REMOTE': '1', 09:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:34 INFO - 'NO_EM_RESTART': '1', 09:34:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:34 INFO - 'PWD': '/builds/slave/test', 09:34:34 INFO - 'SHELL': '/bin/bash', 09:34:34 INFO - 'SHLVL': '1', 09:34:34 INFO - 'TERM': 'linux', 09:34:34 INFO - 'TMOUT': '86400', 09:34:34 INFO - 'USER': 'cltbld', 09:34:34 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:34 INFO - '_': '/tools/buildbot/bin/python'} 09:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:34:34 INFO - Cleaning up... 09:34:35 INFO - Return code: 0 09:34:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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 0x27731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a11310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, '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': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:34:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:35 INFO - Using env: {'DISPLAY': ':0', 09:34:35 INFO - 'HOME': '/home/cltbld', 09:34:35 INFO - 'LANG': 'en_US.UTF-8', 09:34:35 INFO - 'LANGUAGE': 'en_US:en', 09:34:35 INFO - 'LOGNAME': 'cltbld', 09:34:35 INFO - 'MAIL': '/var/mail/cltbld', 09:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:35 INFO - 'MOZ_NO_REMOTE': '1', 09:34:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:35 INFO - 'NO_EM_RESTART': '1', 09:34:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:35 INFO - 'PWD': '/builds/slave/test', 09:34:35 INFO - 'SHELL': '/bin/bash', 09:34:35 INFO - 'SHLVL': '1', 09:34:35 INFO - 'TERM': 'linux', 09:34:35 INFO - 'TMOUT': '86400', 09:34:35 INFO - 'USER': 'cltbld', 09:34:35 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:35 INFO - '_': '/tools/buildbot/bin/python'} 09:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:35 INFO - Cleaning up... 09:34:35 INFO - Return code: 0 09:34:35 INFO - Running post-action listener: _resource_record_post_action 09:34:35 INFO - Running post-action listener: _start_resource_monitoring 09:34:35 INFO - Starting resource monitoring. 09:34:35 INFO - [mozharness: 2017-04-09 16:34:35.279609Z] Finished create-virtualenv step (success) 09:34:35 INFO - [mozharness: 2017-04-09 16:34:35.280140Z] Running install step. 09:34:35 INFO - Running pre-action listener: _resource_record_pre_action 09:34:35 INFO - Running main action method: install 09:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:35 INFO - Reading from file tmpfile_stdout 09:34:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:35 INFO - Reading from file tmpfile_stdout 09:34:35 INFO - Output received: 09:34:35 INFO - Usage: mozinstall [options] installer 09:34:35 INFO - Options: 09:34:35 INFO - -h, --help show this help message and exit 09:34:35 INFO - -d DEST, --destination=DEST 09:34:35 INFO - Directory to install application into. [default: 09:34:35 INFO - "/builds/slave/test"] 09:34:35 INFO - --app=APP Application being installed. [default: firefox] 09:34:35 INFO - mkdir: /builds/slave/test/build/application 09:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:34:52 INFO - Reading from file tmpfile_stdout 09:34:52 INFO - Output received: 09:34:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:52 INFO - Running post-action listener: _resource_record_post_action 09:34:52 INFO - [mozharness: 2017-04-09 16:34:52.112098Z] Finished install step (success) 09:34:52 INFO - [mozharness: 2017-04-09 16:34:52.112374Z] Running run-tests step. 09:34:52 INFO - Running pre-action listener: _resource_record_pre_action 09:34:52 INFO - Running main action method: run_tests 09:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:52 INFO - Python 2.7.3 09:34:52 INFO - Return code: 0 09:34:52 INFO - grabbing minidump binary from tooltool 09:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:52 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 0x2a0e400>, '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 0x2a0f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0f840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:34:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:34:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:34:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:52 INFO - Return code: 0 09:34:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:34:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:52 INFO - ENV: RUST_BACKTRACE is now 1 09:34:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:34:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:34:52 INFO - Using env: {'DISPLAY': ':0', 09:34:52 INFO - 'HOME': '/home/cltbld', 09:34:52 INFO - 'LANG': 'en_US.UTF-8', 09:34:52 INFO - 'LANGUAGE': 'en_US:en', 09:34:52 INFO - 'LOGNAME': 'cltbld', 09:34:52 INFO - 'MAIL': '/var/mail/cltbld', 09:34:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:52 INFO - 'MOZ_NO_REMOTE': '1', 09:34:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:52 INFO - 'NO_EM_RESTART': '1', 09:34:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:52 INFO - 'PWD': '/builds/slave/test', 09:34:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:52 INFO - 'RUST_BACKTRACE': '1', 09:34:52 INFO - 'SHELL': '/bin/bash', 09:34:52 INFO - 'SHLVL': '1', 09:34:52 INFO - 'TERM': 'linux', 09:34:52 INFO - 'TMOUT': '86400', 09:34:52 INFO - 'USER': 'cltbld', 09:34:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799', 09:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:52 INFO - '_': '/tools/buildbot/bin/python'} 09:34:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:34:52 INFO - mozversion application_buildid: 20170409154344 09:34:52 INFO - mozversion application_changeset: efca7a198376e7800873e4612a8b34fc77022d4d 09:34:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:34:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:52 INFO - mozversion application_name: Firefox 09:34:52 INFO - mozversion application_remotingname: firefox-dev 09:34:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:52 INFO - mozversion application_vendor: Mozilla 09:34:52 INFO - mozversion application_version: 54.0a2 09:34:52 INFO - mozversion platform_buildid: 20170409154344 09:34:52 INFO - mozversion platform_changeset: efca7a198376e7800873e4612a8b34fc77022d4d 09:34:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:52 INFO - mozversion platform_version: 54.0a2 09:34:52 INFO - using testdate: 1491755692 09:34:52 INFO - actual date: 1491755692 09:34:52 INFO - starting webserver on 'localhost:41804' 09:34:52 INFO - SUITE-START | Running 2 tests 09:34:52 INFO - TEST-START | dromaeo_css 09:34:52 INFO - operating with platform_type : linux_ 09:34:52 INFO - Initialising browser for dromaeo_css test... 09:34:52 INFO - TEST-INFO | started process 17736 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi3aE3d/profile http://localhost:41804/getInfo.html) 09:34:56 INFO - PROCESS | 17736 | __metrics Screen width/height:1600/1200 09:34:56 INFO - PROCESS | 17736 | colorDepth:24 09:34:56 INFO - PROCESS | 17736 | Browser inner width/height: 1024/697 09:34:56 INFO - PROCESS | 17736 | __metrics 09:34:57 INFO - PROCESS | 17736 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:57 INFO - PROCESS | 17736 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:58 INFO - TEST-INFO | 17736: exit 0 09:34:58 INFO - Browser initialized. 09:34:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:58 INFO - TEST-INFO | started process 17827 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi3aE3d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:09 INFO - PROCESS | 17827 | RSS: Main: 140079104 09:35:09 INFO - PROCESS | 17827 | 09:38:11 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41804/tests/dromaeo/cssquery-ext.html) 09:38:12 INFO - PROCESS | 17827 | RSS: Main: 236146688 09:38:12 INFO - PROCESS | 17827 | 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | A coding exception was thrown and uncaught in a Task. 09:39:59 INFO - PROCESS | 17827 | 09:39:59 ERROR - PROCESS | 17827 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:59 INFO - PROCESS | 17827 | Full stack: 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | A coding exception was thrown and uncaught in a Task. 09:39:59 INFO - PROCESS | 17827 | 09:39:59 ERROR - PROCESS | 17827 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:59 INFO - PROCESS | 17827 | Full stack: 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | A coding exception was thrown and uncaught in a Task. 09:39:59 INFO - PROCESS | 17827 | 09:39:59 ERROR - PROCESS | 17827 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:59 INFO - PROCESS | 17827 | Full stack: 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | ************************* 09:39:59 INFO - PROCESS | 17827 | A coding exception was thrown and uncaught in a Task. 09:39:59 INFO - PROCESS | 17827 | 09:39:59 ERROR - PROCESS | 17827 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:59 INFO - PROCESS | 17827 | Full stack: 09:39:59 INFO - PROCESS | 17827 | ************************* 09:41:14 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-ext.html (next: http://localhost:41804/tests/dromaeo/cssquery-jquery.html) 09:41:15 INFO - PROCESS | 17827 | RSS: Main: 226234368 09:41:15 INFO - PROCESS | 17827 | 09:44:17 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41804/tests/dromaeo/cssquery-mootools.html) 09:44:17 INFO - PROCESS | 17827 | RSS: Main: 171962368 09:44:17 INFO - PROCESS | 17827 | 09:47:19 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41804/tests/dromaeo/cssquery-prototype.html) 09:47:20 INFO - PROCESS | 17827 | RSS: Main: 199786496 09:47:20 INFO - PROCESS | 17827 | 09:50:23 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41804/tests/dromaeo/cssquery-yui.html) 09:50:23 INFO - PROCESS | 17827 | RSS: Main: 176873472 09:50:23 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | Cycle 1(1): loaded http://localhost:41804/tests/dromaeo/cssquery-yui.html (next: http://localhost:41804/tests/dromaeo/cssquery-dojo.html) 09:53:26 INFO - PROCESS | 17827 | RSS: Main: 261320704 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | __start_tp_report 09:53:26 INFO - PROCESS | 17827 | _x_x_mozilla_page_load 09:53:26 INFO - PROCESS | 17827 | _x_x_mozilla_page_load_details 09:53:26 INFO - PROCESS | 17827 | |i|pagename|runs| 09:53:26 INFO - PROCESS | 17827 | |0;dojo.html;3377.780444271122;3403.3023230237804;3408.3183363327335;3426.4175090234658;3449;5622.915656265154;5665.71671416429;5671.06427439472;5683.261176047114;5685.204071430003;5623.971880140593;5636.210930469727;5672.858178545538;5677.205191273225;5678.744456499458;5131.179011358193;5143.691378517282;5160.690358578498;5168.534831865131;5210.687358758487;4988.950110498885;5001.274815151801;5212.713300768456;5228.529432351088;5246.947530524703;5076.44159142494;5103.157978933472;5177.637565370425;5177.974110129444;5192.299039629658;5184.455632158607;5188.143956247215;5194.091034069042;5204.817831375903;5237.764300606482;4926.655134140611;5045.495450454961;5122.3853137623355;5170.327857378555;5177.818776342848;5446.101393270105;5536.252605898192;5588.524975377098;5589.776408943638;5611.6352437091455;6067.180930574405;6089.786857460012;6090.299615544225;6108.717169394433;6112.327643959161;6158.476529494998;6162.907556386658;6166.722497487579;6175.042868355412;6179.81460556184;5449.945500545023;5495.450454954513;5634.3520495143175;5674.347450043216;5674.914876276776;6251.343608920998;6252.124702541648;6257;6257.78097766576;6261.373862613702;5951.299382007387;6018.6990650467305;6020.247469066367;6021.578489505692;6070.7875224366935;5762.394948530344;5809.709514524341;5943.286805583358;6120.540959427972;6126.571140020156;5976.133460648269;5980.5215582754145;5998.070299103646;6001.189839371629;6029.969850150721;5758.193852860602;5792.4497172768515;5805.912911306334;6005.849853753603;6032.245969253843;4563.498015218323;4642.79107440166;4650.697704649187;4661.370714953506;4682;4011.5186177658866;4018.598140185958;4132.566080561558;4147.771872547039;4194.475690538683;5824.446677565625;5872.500837428781;5958.523318134538;5963.582549221513;5972.432618901197;6146.323904370515;6162.383761623892;6166.229221347332;6171.321154672983;6174.691265436711;5922.733476993546;5931.851703707442;5943.64338139713;5951.791687290924;5958.404159584007;4964.478729733398;5000.74996250186;5049.469805670426;5061.974690126526;5065.240213967934;6074.362191969868;6123.357047510036;6129.417705317987;6135.049067394561;6139.662318572432;5922.289325280994;5934.020886553671;6064.636121832704;6110.908336375047;6142.201513803177;6373.936260637334;6382;6395;6398.48812095022;6398.90401643985;6052.576319657758;6054.7275372607455;6061.363556826646;6063.181470501602;6063.211782468427;5881.147233651182;5912.379200183918;6058.666773327333;6167.105769663284;6179.196704428396;5975.790847320323;5981.521478281814;5985;5988.5508586855285;6021.976264035212;5968.820935371946;5968.880622387441;5969.164316995711;5969.522438204846;5972.701364931823;5655.1517272409465;5685.346185188756;5954.672493012753;5959.374265702038;5960.701964901824;5800.216970708901;5808.07070868659;5821.825345239649;5846.239988801429;5886.411358864079;5753.1945527627;5763.942360576509;5766.942330576809;5767.452092051249;5770.394108618702 09:53:26 INFO - PROCESS | 17827 | |1;ext.html;834.1950018232404;855.0167307596264;924.9768755781105;937.5734041011341;938.3525367496422;43349.34975975359;44148.55851441484;44229.55770442299;44268.3359749604;44338;45755.08489830201;45863.31205031919;46043;46153.7692311538;46247.76876115615;8998.460092394462;9137.086291370877;9161.17549420552;9161.72514824553;9167.724968250963;7654.196309387517;7822.765317040498;10547.47262636869;10755.247132700713;10770.784584308309;10774.730631734192;10790.51442685081;10800.243982921198;10804.783904321908;10805.891941080607;10736.409497477636;10770.838437423445;10771.353718776898;10777.245592808507;10801.135909127252;10549.261551691385;10613.41626210558;10616;10623.681289561326;10625.256232063759;43257.35113973293;43423;43454.13091738147;43567.34648980268;43610.56389436097;4245.936310955307;4304.7632380219075;4335.046289816235;4403.4495688039;4426.667999899988;29902.401951961056;30080.699193008226;30502;31042.689573104206;31761.36477270465;4339.804708788112;4437.689361744679;4451.354553589711;4480.977595112036;4485.282354823213;231795;232358;233119;233813.9999999983;233920;44884.326735099;45614.54385456103;52639;52646;52649;53352;53919.46080539223;54264.45735542594;54423.72788136034;60178.398216018155;15235.162066086472;15295.15876626774;15299.847001530065;15304.693906121815;15316.846831531542;13030.47878084866;13048.086633935724;13086;13096.869031309756;13108.475660973454;12740.426680799386;12854.807177892339;12874.718247624201;12938.870611293954;13033.087683862039;5468.7539060741565;5483.917741233885;5537.667739935617;5585.385607583163;5605.159226116115;1003.3127307794184;1016.0855230292796;1018.7758693087509;1026.8870424253325;1063.9308444951052;27764.444711105665;27873.860630696716;28129;28291.292717682223;28507.57238641422;1091.2034215023077;1092.9016388525072;1109.035139428686;1120.5181771838188;1132.043423307304;14133.15201087938;14954.102753834588;15065.397384104732;15105.244737763069;15214.619634509227;297731;298655;298848;299102;306657;54779.452205477435;54962.45037549573;55032;55050;55203;1508.1931166825696;1511.2368254031423;1543.5909483986752;1553.7047960887396;1558.0340189082842;25492.362690932132;26640.600390993764;26692.599611005462;26913.461730764884;27062.72937270602;40250.39624405577;40317.59682403138;40348;40439;40445.39331909965;266342;266730;266812;267178;267612;29444.411111778074;29754;33317;33319.167020823705;34072.82963585266;42856.35715464208;42888.57111428846;42908.78545607377;42909;43217;32724.50913236351;32933.17667058247;32963;33009.33981320408;33170.83414582912;30415;31058.534121987737;31405;32195;32642;35266.82366588151;35918.10204744902;36176.819115904254;36324.63675363213;36372.45441318435;30354.54468182935;30480.695193048672;30579.706522010492;30633.387332253675;30674.539881901343;25105;25127.623085653366;25195.24414267575;25210;25221.92951936025 09:53:26 INFO - PROCESS | 17827 | |2;jquery.html;407.18359688823904;422.7294531499839;452.30345268286817;469.7557270219485;485.03720115570593;19940.202391904324;19942.7008594871;21506;21752.673709894363;21787.782122178774;21723.348299551;22688.546229075408;22933.4266643334;23096.42258943527;23316;6641.999999999988;6952.478564107687;6988.6855091521;6998.265182155877;7403.481756277063;7443.106827180746;7520.924790752105;7543.924560754404;7552.093748750158;7555.206703296157;7489.988851505059;7552.509086909361;7618.9619051904665;7658.540487570735;7666.2717041881215;7548.320651141424;7709.2290770922455;7723.266289702469;7760.961195194016;7762.068551773767;7394.593297368642;7530.134034586038;7530.9623451882935;7537.698492060311;7540.396768258526;25444.618330725054;25551.361215969562;25979.22062338133;26068.34829129268;26451.603225951625;3118.5166299223656;3180.936381272362;3227.7256433203206;3558.2527669189503;3770.6606405423645;15601.765973510293;16112.919435402748;16320.591985200346;16506.83493165065;16767;2106.747190337154;2188.277868303456;2194.122351059573;2211.3587059752813;2253.808426283768;113943.43028284806;115124.42437787841;115280;115671;117722.41138794337;18149.455516334532;18227.817721822878;18415.53961150955;18440.538986525444;18471.16879740415;32158.517622235686;32333.838330808194;32379.028629140692;32425.189370265933;32629.836850815595;10614.917460411672;10812.891871081345;10903.618373356894;11066;11109.277896936674;9020.909790902138;9104.726858194264;9135.862962055575;9215.815683686289;9403.529823508798;9168.816623667622;9317.860232096524;9391.812163756687;9408.482533460725;9638.98790626988;3469.3581687387914;3542.2030043240065;3640.526731524885;3838.616138386139;3864.864729734446;952.7046615549064;973.9464329461806;975.9951200243953;977.3549457358109;980.9166220871306;20352.796472035385;22037.228696995528;22103.889480552494;22141.778582214294;22207.55584888293;984.7291994701515;990.5691024404407;997.870276864003;998.4658207858761;1001.979960400788;11079.944600276947;11519.76960460803;11953.940230298793;11964.521419143312;12034;673016;692967;700024;727412;731691;115935;116792;118050.40974795244;118516.40741796409;122388;1259.8866102050858;1274.6430999320205;1285.9614211573669;1288.2656885575132;1293.8188653588504;18274;18509.72235416497;19262.518437039187;19316.420507384802;19325;39593.80203099081;50438;51327;51627.48372516227;52121.7393913028;67110;67429;67806.32193678;68846.65576672285;69908.65045674739;11289.37908415045;11988.340641264815;12290.569830056264;12354.505819767108;12440.377981100728;14006.509772157926;14265.786013209603;14297.571072867831;14521.564353069425;14813.481528146463;12243.938780306042;12322.691932701755;12379.876201237872;12403.25580465175;12417;11384.14618903569;11514.078873690252;11516.712082198012;11538.480768365276;11615.186936914335;12225.266484010988;12284.447199876206;12317;12338.938305308417;13231.27228002469;12496.12527123129;12519.56181533642;12526.812097818358;12682.619521414372;12931;12039.217450865666;12049.638510844688;12434.191777534432;12619.684507887008;12646.114771965875 09:53:26 INFO - PROCESS | 17827 | |3;mootools.html;1449.3477934929285;1561.9219039048044;1602.991985040075;1615.176260107345;1616.4180894877845;8916.866247006301;11303.717407064827;11531.65405037849;11998.700032499191;12010.45952932118;11673.941630291858;11771.823422648667;11775.941120294408;11777.941110294458;11779.05767538597;2455.226603619854;2493.7506249375097;2494.638277449769;2496.276079936816;2497.775200231979;1964.9214031438726;2032.7865574114726;2393.605055165904;2465.2234546117916;2473.826832121752;2190.102058156156;2201.6587428948537;2205.658122990939;2209.0611490116717;2211.7345918489805;2394.413368724665;2427.3324835670155;2429.4047958250167;2430.8419952703016;2443.9144629937955;2292.4612716011725;2302.1366987379733;2303.4356582637192;2309.93070207894;2313.4794671199015;11254.718632034183;11430.142739294586;11444.94277528607;11448.084153267802;11458.369789661565;5760.107183386582;5922.2597175353085;5923.733431995527;5929.703514824285;5931.614445061057;3508.8947331580093;3524.4184709522897;3583.7849729016343;3588.4437912123662;3652.8356223969986;1308.1431662261234;1333.7999300104964;1367.863213678634;1410.238471225537;1421.6872288096602;16985.49043528696;17141.057241851693;17142.31430742769;17178.914105429518;17182.22679979404;4722.480527142012;4938.827141050046;4995.300657907896;5003.274525193827;5084.618653600921;14245.501407450689;14267;14272.429102835978;14298.28508574588;14298.356573954197;5911.9113213301835;5921.881562368729;6038.969805151034;6040.275166979903;6050.818475445743;4438.7114837535455;5240.266362709223;5344.251804747339;5397.271368365299;5436.728163591804;3743.401055831054;4058.553559108496;4152.941872620779;4194.958050419517;4204.768737719455;1138.8120960041665;1141.2467771270922;1144.0790163918125;1163.6043745126697;1165.2542372881362;1056.0759335581367;1059.6079450603327;1060.0724366179593;1070.743021674808;1071.7159952612565;7017.719291228395;7115.92884071163;7217.783466496014;7223.1693355264815;7311.670974806147;1047.3506426015767;1058.3544038136731;1070.2508244228916;1077.8059949209219;1089.5400163780573;4832;4832.49258827818;4877.146499362598;4878.56092951636;4954.777035033432;14085.577432677037;14595.85404145945;14647.26763661834;14716.484923027643;14727.410903563952;13631.591052268448;14195;14414.351354189086;14484.710305794037;14604.853951460562;9457.810843783085;9624.566894489764;9666;9674.03259674027;9674.129328360481;6845.897311540231;7031.824204394931;7136.7145314186855;7141.750038748827;7183.317584829433;11565.479553420284;11633.825492617787;11766.88233117658;11819.645410637695;11904.166708330335;11472.655820325404;11822.645320640395;11839;11907.46416411281;11956.402179890796;8445.282151016987;9594.568244429158;9941.453220072675;9943.950280248553;9954.55204515784;9274.814503710022;10523.579056837641;10941.234113612289;11122.110231181648;11137.83293250618;9349.439033658002;9388.202002829836;9409.811803764023;9415.952920235355;9591.232701384011;8870.73387798367;9285.396449230779;9297.72106836796;9458.054194580487;9864.654737084167;8193.344532437271;8352.415330926777;8356.498610083414;8399.49603023796;8458.661653533789;6364.77723279683;6396.072569477306;6434.420902118832;6455.096286519797;6476.902846457213;6297.874042519215;6358.491320694426;6369.33122022181;6482.870342593027;6528.477721782151 09:53:26 INFO - PROCESS | 17827 | |4;prototype.html;415.1924506834209;416.205048357637;437.2021061562649;446.0543647467369;448.79579791195;5278.868028299295;5285.788568457262;5308.867278318044;5345.812896548627;5359.38367087785;5129.794808207667;5173.430922598521;5175.223716442535;5226.268322434853;5267.55225805806;4547.408836851205;4549.589627215574;4556.794944227501;4557.225271703818;4593.586577208051;4574.627611716488;4597.977010114962;4612.677112602119;4616.538346165374;4616.792244349012;4358.8910277243;4550.362949187116;4600.240960241556;4600.240960241573;4605.401297831294;4570.948681803171;4586.243269860469;4597.862064138065;4598.862034138964;4604.631629469634;4470.351798989161;4474.149911516802;4480;4498.730076195438;4504.459464864237;4999.500049995007;5123.131046613871;5181.274621552986;5202.973985130088;5288.762005709714;5338.412774594792;5348.101423994503;5368.30212072432;5370.865728356823;5379.354477462691;5587.469190426944;5591.720413979285;5619.522340601054;5638.746256418471;5645.717714114319;4623.653226008029;4861.2222044473065;4870.464248932614;4873.707577545359;4890.633202509826;5525.668459892419;5661.2923384576925;5692.573057020699;5788.42115788422;5788.450097240755;4999.825006124768;5006.974965125151;5022.773975171053;5030.270610761419;5042.621803364762;5076.644634875597;5086.245245390334;5102.515261050195;5115.593211866766;5124.053669140259;5034.421041580192;5245.868853278622;5246.501582349671;5295.417504074549;5312.574994000471;5502.669839809624;5538.446155384429;5539.362973258047;5541.362743284493;5558.471945165203;1723.7931448226043;1776.8845025073588;1783.7413575031549;1796.7125259958343;1803.7373838312972;1405.5361730628867;1458.2562892924684;1476.9630759231104;1481.3408033425023;1506.028611545547;5341.7062061586985;5375.543078838343;5398.757055932492;5444.3194600674915;5456.263404440429;5589.916151257735;5640.8871822563315;5704.404495954331;5721.5708821838525;5729.942700572941;5205.895882082413;5335.573154147659;5413.485718856702;5451.809186678447;5477.3427188737605;2943.513525669523;2949.380630067667;2980.985095074511;3016.3967206558777;3025.4100450411956;5881.294244690579;5899.941000590025;5905.557083218776;5925.940740592625;5944.524438044946;5531.585131115183;5734.426557344233;5746.7931734335425;5774.653520788766;5783.306003279549;5886.676232807236;5887.4406931341455;5896.439838215449;5914.4381283777975;5915.171875937286;5419.83740487786;5690.573207009574;5700.3444603871085;5721;5736.397678243724;5408.999335123015;5536.861578460571;5626.35296940857;5642.322921249476;5650.660126180884;5798.33319168301;5902.940970590239;5909.261342332208;5911.5516698409165;5951.375105614022;4762.3332733416655;4765.9523404765505;4767.904641907073;4775.2598347256235;4776.522347765195;5554.50009499147;5677.51741102011;5710.7442560187665;5736.787399056541;5790.10253410722;5537.28015358017;5556.333240011225;5557.5831812613405;5559.777608895599;5569.602542449184;5319;5560.416156303691;5567.582431317748;5571.88562287536;5573.5819813513335;5507.925954438846;5523.530499907552;5542.9168562472405;5547.33431988164;5565.7217139143695;5552.888942221214;5567.8886422272135;5584.497395234286;5585.385607583082;5636.154576813511;5355.3305836770405;5451.4276001019325;5473;5482.32788999359;5515.503604675599 09:53:26 INFO - PROCESS | 17827 | |5;yui.html;804.8792681097839;878.6265837019265;893.4148132972905;917.8746368614865;950.2493030505899;17110.486685399443;18282.634347313062;18287.45137645871;18291.085445727727;18319.54201144969;17085.40201092962;17690.911545442254;17743.822561774377;17759.64480710385;17761.555961100978;869.6999535160376;882.3382463152635;891.393852180517;896.7354630384052;902.5938327752538;895.8029233568604;897.1566727276372;897.578138275013;898.27239935652;899.1547944931776;953.1897886796203;954.89973552777;956.0439560439561;956.4070276428624;956.784723437228;948.3693343926316;948.4641177734558;948.6252930092644;948.9430634161903;948.9525523723821;696.5298423564125;698.0227681246245;698.3784431855653;698.4970821008866;698.807827847341;17881.910590447096;17883;17939.192736326895;18554.44336669902;18704.158312875952;16701.415450459175;16932.322707091826;16974.575635609086;17099;17528.211230494657;13182.67043323915;13392.933035334761;13521.256330901797;13796.931015344959;14349.067310624776;4065.085355794952;4113.1568028554075;4126;4190.727602705814;4270.274053410928;52470.73764631191;53350.466495334935;53885.92228155454;54085;68540.3145968544;12827.603326300112;13625.454981800616;13695;13861.584152475441;14018.299085045706;22737.88631056834;24838.875805620857;24900;24977.87511062433;25032.37419064538;9213.861792073125;9602.471864047404;9609.135177833889;9648.083432073941;9743.464109473905;5024.0956627807345;5135.563477104413;5149.716765577929;5203.19350500673;5275.60432967529;3851.287511810324;3907.785071821077;3924.274009308287;3926.450296958428;3933.6459718624888;684.9623270720158;699.3251512290562;714.589111261027;724.3734169943067;728.1771598094119;813.5728742410164;835.3442547600105;836.7615229659518;851.2466467176594;865.6494119881459;11053.355376496173;11087.22389432732;11478.655640330804;12512.37438128108;12551.435185416678;837.2590257622048;841.8989721233369;854.1458541458542;860.3180781572012;872.8777971084053;5294.54996325309;5367.651102678314;5379.596530260246;5409.675419474844;5434.212039254286;81612.59193703994;86050.56974715211;86222;87133;88905;60188;61122.38877611256;70964.2903570968;74574;77626;69753;69755;69961.3003869965;70071.64964175249;70527.2947270531;10525.473726313501;10557.419341936267;10680;10736.570537178737;10804.459777011276;46392;46649.53350466452;47181.52818471771;47183.528164717914;48323.831810599935;49967.50032499628;51062.489375105775;51755.741221293654;52576.73711631417;53963.46036539584;13447.462101516221;13849.446022159002;13871.514496992557;14037;14135.363908623931;15115.244237788287;15168.24158792078;15253.847461525243;15369.615759606098;15387.307571159101;13595.456181753014;13645.658858528615;13784.379702913193;14050.648733781738;14559.27203639835;14038.1577105374;14137.222452765196;14232.478428090379;14387.208703521406;14672;12588.085355391187;12899.226046437243;12976.351182441029;13083;13161.210327380388;9754.31719779637;9875.111239988435;9981.800363992825;10407.791844163226;10480.213983951082;11921.82117268259;11934.22427542207;12070.75858482843;12128.332941688292;12216.938915305367 09:53:26 INFO - PROCESS | 17827 | __end_tp_report 09:53:26 INFO - PROCESS | 17827 | __start_cc_report 09:53:26 INFO - PROCESS | 17827 | _x_x_mozilla_cycle_collect,1175 09:53:26 INFO - PROCESS | 17827 | __end_cc_report 09:53:26 INFO - PROCESS | 17827 | __startTimestamp1491756806547__endTimestamp 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | ------- Summary: start ------- 09:53:26 INFO - PROCESS | 17827 | Number of tests: 6 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#0] dojo.html Cycles:165 Average:5630.67 Median:5872.50 stddev:639.42 (10.9%) stddev-sans-first:616.47 09:53:26 INFO - PROCESS | 17827 | Values: 3377.8 3403.3 3408.3 3426.4 3449.0 5622.9 5665.7 5671.1 5683.3 5685.2 5624.0 5636.2 5672.9 5677.2 5678.7 5131.2 5143.7 5160.7 5168.5 5210.7 4989.0 5001.3 5212.7 5228.5 5246.9 5076.4 5103.2 5177.6 5178.0 5192.3 5184.5 5188.1 5194.1 5204.8 5237.8 4926.7 5045.5 5122.4 5170.3 5177.8 5446.1 5536.3 5588.5 5589.8 5611.6 6067.2 6089.8 6090.3 6108.7 6112.3 6158.5 6162.9 6166.7 6175.0 6179.8 5449.9 5495.5 5634.4 5674.3 5674.9 6251.3 6252.1 6257.0 6257.8 6261.4 5951.3 6018.7 6020.2 6021.6 6070.8 5762.4 5809.7 5943.3 6120.5 6126.6 5976.1 5980.5 5998.1 6001.2 6030.0 5758.2 5792.4 5805.9 6005.8 6032.2 4563.5 4642.8 4650.7 4661.4 4682.0 4011.5 4018.6 4132.6 4147.8 4194.5 5824.4 5872.5 5958.5 5963.6 5972.4 6146.3 6162.4 6166.2 6171.3 6174.7 5922.7 5931.9 5943.6 5951.8 5958.4 4964.5 5000.7 5049.5 5062.0 5065.2 6074.4 6123.4 6129.4 6135.0 6139.7 5922.3 5934.0 6064.6 6110.9 6142.2 6373.9 6382.0 6395.0 6398.5 6398.9 6052.6 6054.7 6061.4 6063.2 6063.2 5881.1 5912.4 6058.7 6167.1 6179.2 5975.8 5981.5 5985.0 5988.6 6022.0 5968.8 5968.9 5969.2 5969.5 5972.7 5655.2 5685.3 5954.7 5959.4 5960.7 5800.2 5808.1 5821.8 5846.2 5886.4 5753.2 5763.9 5766.9 5767.5 5770.4 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#1] ext.html Cycles:180 Average:43786.04 Median:30606.55 stddev:69803.82 (228.1%) stddev-sans-first:69925.14 09:53:26 INFO - PROCESS | 17827 | Values: 834.2 855.0 925.0 937.6 938.4 43349.3 44148.6 44229.6 44268.3 44338.0 45755.1 45863.3 46043.0 46153.8 46247.8 8998.5 9137.1 9161.2 9161.7 9167.7 7654.2 7822.8 10547.5 10755.2 10770.8 10774.7 10790.5 10800.2 10804.8 10805.9 10736.4 10770.8 10771.4 10777.2 10801.1 10549.3 10613.4 10616.0 10623.7 10625.3 43257.4 43423.0 43454.1 43567.3 43610.6 4245.9 4304.8 4335.0 4403.4 4426.7 29902.4 30080.7 30502.0 31042.7 31761.4 4339.8 4437.7 4451.4 4481.0 4485.3 231795.0 232358.0 233119.0 233814.0 233920.0 44884.3 45614.5 52639.0 52646.0 52649.0 53352.0 53919.5 54264.5 54423.7 60178.4 15235.2 15295.2 15299.8 15304.7 15316.8 13030.5 13048.1 13086.0 13096.9 13108.5 12740.4 12854.8 12874.7 12938.9 13033.1 5468.8 5483.9 5537.7 5585.4 5605.2 1003.3 1016.1 1018.8 1026.9 1063.9 27764.4 27873.9 28129.0 28291.3 28507.6 1091.2 1092.9 1109.0 1120.5 1132.0 14133.2 14954.1 15065.4 15105.2 15214.6 297731.0 298655.0 298848.0 299102.0 306657.0 54779.5 54962.5 55032.0 55050.0 55203.0 1508.2 1511.2 1543.6 1553.7 1558.0 25492.4 26640.6 26692.6 26913.5 27062.7 40250.4 40317.6 40348.0 40439.0 40445.4 266342.0 266730.0 266812.0 267178.0 267612.0 29444.4 29754.0 33317.0 33319.2 34072.8 42856.4 42888.6 42908.8 42909.0 43217.0 32724.5 32933.2 32963.0 33009.3 33170.8 30415.0 31058.5 31405.0 32195.0 32642.0 35266.8 35918.1 36176.8 36324.6 36372.5 30354.5 30480.7 30579.7 30633.4 30674.5 25105.0 25127.6 25195.2 25210.0 25221.9 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#2] jquery.html Cycles:180 Average:39241.50 Median:12230.68 stddev:116093.72 (949.2%) stddev-sans-first:116382.77 09:53:26 INFO - PROCESS | 17827 | Values: 407.2 422.7 452.3 469.8 485.0 19940.2 19942.7 21506.0 21752.7 21787.8 21723.3 22688.5 22933.4 23096.4 23316.0 6642.0 6952.5 6988.7 6998.3 7403.5 7443.1 7520.9 7543.9 7552.1 7555.2 7490.0 7552.5 7619.0 7658.5 7666.3 7548.3 7709.2 7723.3 7761.0 7762.1 7394.6 7530.1 7531.0 7537.7 7540.4 25444.6 25551.4 25979.2 26068.3 26451.6 3118.5 3180.9 3227.7 3558.3 3770.7 15601.8 16112.9 16320.6 16506.8 16767.0 2106.7 2188.3 2194.1 2211.4 2253.8 113943.4 115124.4 115280.0 115671.0 117722.4 18149.5 18227.8 18415.5 18440.5 18471.2 32158.5 32333.8 32379.0 32425.2 32629.8 10614.9 10812.9 10903.6 11066.0 11109.3 9020.9 9104.7 9135.9 9215.8 9403.5 9168.8 9317.9 9391.8 9408.5 9639.0 3469.4 3542.2 3640.5 3838.6 3864.9 952.7 973.9 976.0 977.4 980.9 20352.8 22037.2 22103.9 22141.8 22207.6 984.7 990.6 997.9 998.5 1002.0 11079.9 11519.8 11953.9 11964.5 12034.0 673016.0 692967.0 700024.0 727412.0 731691.0 115935.0 116792.0 118050.4 118516.4 122388.0 1259.9 1274.6 1286.0 1288.3 1293.8 18274.0 18509.7 19262.5 19316.4 19325.0 39593.8 50438.0 51327.0 51627.5 52121.7 67110.0 67429.0 67806.3 68846.7 69908.7 11289.4 11988.3 12290.6 12354.5 12440.4 14006.5 14265.8 14297.6 14521.6 14813.5 12243.9 12322.7 12379.9 12403.3 12417.0 11384.1 11514.1 11516.7 11538.5 11615.2 12225.3 12284.4 12317.0 12338.9 13231.3 12496.1 12519.6 12526.8 12682.6 12931.0 12039.2 12049.6 12434.2 12619.7 12646.1 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#3] mootools.html Cycles:180 Average:7069.96 Median:2449.57 stddev:4492.17 (183.4%) stddev-sans-first:4484.92 09:53:26 INFO - PROCESS | 17827 | Values: 1449.3 1561.9 1603.0 1615.2 1616.4 8916.9 11303.7 11531.7 11998.7 12010.5 11673.9 11771.8 11775.9 11777.9 11779.1 2455.2 2493.8 2494.6 2496.3 2497.8 1964.9 2032.8 2393.6 2465.2 2473.8 2190.1 2201.7 2205.7 2209.1 2211.7 2394.4 2427.3 2429.4 2430.8 2443.9 2292.5 2302.1 2303.4 2309.9 2313.5 11254.7 11430.1 11444.9 11448.1 11458.4 5760.1 5922.3 5923.7 5929.7 5931.6 3508.9 3524.4 3583.8 3588.4 3652.8 1308.1 1333.8 1367.9 1410.2 1421.7 16985.5 17141.1 17142.3 17178.9 17182.2 4722.5 4938.8 4995.3 5003.3 5084.6 14245.5 14267.0 14272.4 14298.3 14298.4 5911.9 5921.9 6039.0 6040.3 6050.8 4438.7 5240.3 5344.3 5397.3 5436.7 3743.4 4058.6 4152.9 4195.0 4204.8 1138.8 1141.2 1144.1 1163.6 1165.3 1056.1 1059.6 1060.1 1070.7 1071.7 7017.7 7115.9 7217.8 7223.2 7311.7 1047.4 1058.4 1070.3 1077.8 1089.5 4832.0 4832.5 4877.1 4878.6 4954.8 14085.6 14595.9 14647.3 14716.5 14727.4 13631.6 14195.0 14414.4 14484.7 14604.9 9457.8 9624.6 9666.0 9674.0 9674.1 6845.9 7031.8 7136.7 7141.8 7183.3 11565.5 11633.8 11766.9 11819.6 11904.2 11472.7 11822.6 11839.0 11907.5 11956.4 8445.3 9594.6 9941.5 9944.0 9954.6 9274.8 10523.6 10941.2 11122.1 11137.8 9349.4 9388.2 9409.8 9416.0 9591.2 8870.7 9285.4 9297.7 9458.1 9864.7 8193.3 8352.4 8356.5 8399.5 8458.7 6364.8 6396.1 6434.4 6455.1 6476.9 6297.9 6358.5 6369.3 6482.9 6528.5 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#4] prototype.html Cycles:180 Average:4908.64 Median:5357.36 stddev:1257.12 (23.5%) stddev-sans-first:1214.57 09:53:26 INFO - PROCESS | 17827 | Values: 415.2 416.2 437.2 446.1 448.8 5278.9 5285.8 5308.9 5345.8 5359.4 5129.8 5173.4 5175.2 5226.3 5267.6 4547.4 4549.6 4556.8 4557.2 4593.6 4574.6 4598.0 4612.7 4616.5 4616.8 4358.9 4550.4 4600.2 4600.2 4605.4 4570.9 4586.2 4597.9 4598.9 4604.6 4470.4 4474.1 4480.0 4498.7 4504.5 4999.5 5123.1 5181.3 5203.0 5288.8 5338.4 5348.1 5368.3 5370.9 5379.4 5587.5 5591.7 5619.5 5638.7 5645.7 4623.7 4861.2 4870.5 4873.7 4890.6 5525.7 5661.3 5692.6 5788.4 5788.5 4999.8 5007.0 5022.8 5030.3 5042.6 5076.6 5086.2 5102.5 5115.6 5124.1 5034.4 5245.9 5246.5 5295.4 5312.6 5502.7 5538.4 5539.4 5541.4 5558.5 1723.8 1776.9 1783.7 1796.7 1803.7 1405.5 1458.3 1477.0 1481.3 1506.0 5341.7 5375.5 5398.8 5444.3 5456.3 5589.9 5640.9 5704.4 5721.6 5729.9 5205.9 5335.6 5413.5 5451.8 5477.3 2943.5 2949.4 2981.0 3016.4 3025.4 5881.3 5899.9 5905.6 5925.9 5944.5 5531.6 5734.4 5746.8 5774.7 5783.3 5886.7 5887.4 5896.4 5914.4 5915.2 5419.8 5690.6 5700.3 5721.0 5736.4 5409.0 5536.9 5626.4 5642.3 5650.7 5798.3 5902.9 5909.3 5911.6 5951.4 4762.3 4766.0 4767.9 4775.3 4776.5 5554.5 5677.5 5710.7 5736.8 5790.1 5537.3 5556.3 5557.6 5559.8 5569.6 5319.0 5560.4 5567.6 5571.9 5573.6 5507.9 5523.5 5542.9 5547.3 5565.7 5552.9 5567.9 5584.5 5585.4 5636.2 5355.3 5451.4 5473.0 5482.3 5515.5 09:53:26 INFO - PROCESS | 17827 | 09:53:26 INFO - PROCESS | 17827 | [#5] yui.html Cycles:180 Average:18158.63 Median:47753.68 stddev:21966.64 (46.0%) stddev-sans-first:21989.61 09:53:26 INFO - PROCESS | 17827 | Values: 804.9 878.6 893.4 917.9 950.2 17110.5 18282.6 18287.5 18291.1 18319.5 17085.4 17690.9 17743.8 17759.6 17761.6 869.7 882.3 891.4 896.7 902.6 895.8 897.2 897.6 898.3 899.2 953.2 954.9 956.0 956.4 956.8 948.4 948.5 948.6 948.9 949.0 696.5 698.0 698.4 698.5 698.8 17881.9 17883.0 17939.2 18554.4 18704.2 16701.4 16932.3 16974.6 17099.0 17528.2 13182.7 13392.9 13521.3 13796.9 14349.1 4065.1 4113.2 4126.0 4190.7 4270.3 52470.7 53350.5 53885.9 54085.0 68540.3 12827.6 13625.5 13695.0 13861.6 14018.3 22737.9 24838.9 24900.0 24977.9 25032.4 9213.9 9602.5 9609.1 9648.1 9743.5 5024.1 5135.6 5149.7 5203.2 5275.6 3851.3 3907.8 3924.3 3926.5 3933.6 685.0 699.3 714.6 724.4 728.2 813.6 835.3 836.8 851.2 865.6 11053.4 11087.2 11478.7 12512.4 12551.4 837.3 841.9 854.1 860.3 872.9 5294.5 5367.7 5379.6 5409.7 5434.2 81612.6 86050.6 86222.0 87133.0 88905.0 60188.0 61122.4 70964.3 74574.0 77626.0 69753.0 69755.0 69961.3 70071.6 70527.3 10525.5 10557.4 10680.0 10736.6 10804.5 46392.0 46649.5 47181.5 47183.5 48323.8 49967.5 51062.5 51755.7 52576.7 53963.5 13447.5 13849.4 13871.5 14037.0 14135.4 15115.2 15168.2 15253.8 15369.6 15387.3 13595.5 13645.7 13784.4 14050.6 14559.3 14038.2 14137.2 14232.5 14387.2 14672.0 12588.1 12899.2 12976.4 13083.0 13161.2 9754.3 9875.1 9981.8 10407.8 10480.2 11921.8 11934.2 12070.8 12128.3 12216.9 09:53:26 INFO - PROCESS | 17827 | -------- Summary: end -------- 09:53:26 INFO - PROCESS | 17827 | 09:53:27 INFO - TEST-INFO | 17827: exit 0 09:53:27 INFO - TEST-OK | dromaeo_css | took 1114946ms 09:53:27 INFO - TEST-START | kraken 09:53:27 INFO - operating with platform_type : linux_ 09:53:27 INFO - Initialising browser for kraken test... 09:53:27 INFO - TEST-INFO | started process 18151 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxYqGG/profile http://localhost:41804/getInfo.html) 09:53:31 INFO - PROCESS | 18151 | __metrics Screen width/height:1600/1200 09:53:31 INFO - PROCESS | 18151 | colorDepth:24 09:53:31 INFO - PROCESS | 18151 | Browser inner width/height: 1024/697 09:53:31 INFO - PROCESS | 18151 | __metrics 09:53:31 INFO - PROCESS | 18151 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:53:31 INFO - PROCESS | 18151 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:53:32 INFO - TEST-INFO | 18151: exit 0 09:53:32 INFO - Browser initialized. 09:53:32 INFO - Running cycle 1/1 for kraken test... 09:53:32 INFO - TEST-INFO | started process 18242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxYqGG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:44 INFO - PROCESS | 18242 | RSS: Main: 139812864 09:53:44 INFO - PROCESS | 18242 | 09:53:47 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41804/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:48 INFO - PROCESS | 18242 | RSS: Main: 183549952 09:53:48 INFO - PROCESS | 18242 | 09:53:50 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41804/tests/kraken/driver.html?testName=audio-dft) 09:53:51 INFO - PROCESS | 18242 | RSS: Main: 175009792 09:53:51 INFO - PROCESS | 18242 | 09:53:54 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41804/tests/kraken/driver.html?testName=audio-fft) 09:53:54 INFO - PROCESS | 18242 | RSS: Main: 266543104 09:53:54 INFO - PROCESS | 18242 | 09:53:56 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41804/tests/kraken/driver.html?testName=audio-oscillator) 09:53:57 INFO - PROCESS | 18242 | RSS: Main: 172617728 09:53:57 INFO - PROCESS | 18242 | 09:53:59 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41804/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:59 INFO - PROCESS | 18242 | RSS: Main: 175276032 09:53:59 INFO - PROCESS | 18242 | 09:54:02 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41804/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:03 INFO - PROCESS | 18242 | RSS: Main: 213872640 09:54:03 INFO - PROCESS | 18242 | 09:54:06 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41804/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:07 INFO - PROCESS | 18242 | RSS: Main: 212275200 09:54:07 INFO - PROCESS | 18242 | 09:54:10 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41804/tests/kraken/driver.html?testName=json-parse-financial) 09:54:10 INFO - PROCESS | 18242 | RSS: Main: 210800640 09:54:10 INFO - PROCESS | 18242 | 09:54:12 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41804/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:12 INFO - PROCESS | 18242 | RSS: Main: 173158400 09:54:12 INFO - PROCESS | 18242 | 09:54:14 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:15 INFO - PROCESS | 18242 | RSS: Main: 180604928 09:54:15 INFO - PROCESS | 18242 | 09:54:17 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:17 INFO - PROCESS | 18242 | RSS: Main: 189313024 09:54:17 INFO - PROCESS | 18242 | 09:54:20 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:21 INFO - PROCESS | 18242 | RSS: Main: 199032832 09:54:21 INFO - PROCESS | 18242 | 09:54:23 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:24 INFO - PROCESS | 18242 | RSS: Main: 195239936 09:54:24 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | Cycle 1(1): loaded http://localhost:41804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41804/tests/kraken/driver.html?testName=ai-astar) 09:54:26 INFO - PROCESS | 18242 | RSS: Main: 246001664 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | __start_tp_report 09:54:26 INFO - PROCESS | 18242 | _x_x_mozilla_page_load 09:54:26 INFO - PROCESS | 18242 | _x_x_mozilla_page_load_details 09:54:26 INFO - PROCESS | 18242 | |i|pagename|runs| 09:54:26 INFO - PROCESS | 18242 | |0;ai-astar;102;114;112;113;115;111;112;112;113;112 09:54:26 INFO - PROCESS | 18242 | |1;audio-beat-detection;143;116;115;163;118;116;117;115;142;119 09:54:26 INFO - PROCESS | 18242 | |2;audio-dft;163;162;160;160;160;161;162;161;161;160 09:54:26 INFO - PROCESS | 18242 | |3;audio-fft;75;78;75;107;74;76;76;76;88;76 09:54:26 INFO - PROCESS | 18242 | |4;audio-oscillator;82;82;123;81;82;82;92;82;81;111 09:54:26 INFO - PROCESS | 18242 | |5;imaging-gaussian-blur;112;113;113;113;113;112;113;112;114;113 09:54:26 INFO - PROCESS | 18242 | |6;imaging-darkroom;147;146;146;146;146;147;147;147;147;146 09:54:26 INFO - PROCESS | 18242 | |7;imaging-desaturate;94;94;94;93;94;93;94;93;94;94 09:54:26 INFO - PROCESS | 18242 | |8;json-parse-financial;53;54;54;53;54;53;55;54;54;54 09:54:26 INFO - PROCESS | 18242 | |9;json-stringify-tinderbox;52;54;53;52;52;51;51;51;52;53 09:54:26 INFO - PROCESS | 18242 | |10;stanford-crypto-aes;75;78;75;79;85;75;84;82;80;84 09:54:26 INFO - PROCESS | 18242 | |11;stanford-crypto-ccm;120;128;123;121;135;120;119;118;173;118 09:54:26 INFO - PROCESS | 18242 | |12;stanford-crypto-pbkdf2;161;163;167;162;162;160;160;164;165;157 09:54:26 INFO - PROCESS | 18242 | |13;stanford-crypto-sha256-iterative;61;60;61;60;60;59;60;64;60;64 09:54:26 INFO - PROCESS | 18242 | __end_tp_report 09:54:26 INFO - PROCESS | 18242 | __start_cc_report 09:54:26 INFO - PROCESS | 18242 | _x_x_mozilla_cycle_collect,1667 09:54:26 INFO - PROCESS | 18242 | __end_cc_report 09:54:26 INFO - PROCESS | 18242 | __startTimestamp1491756866289__endTimestamp 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | ------- Summary: start ------- 09:54:26 INFO - PROCESS | 18242 | Number of tests: 14 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.57 (3.2%) stddev-sans-first:1.22 09:54:26 INFO - PROCESS | 18242 | Values: 102.0 114.0 112.0 113.0 115.0 111.0 112.0 112.0 113.0 112.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:118.50 stddev:16.83 (14.2%) stddev-sans-first:16.74 09:54:26 INFO - PROCESS | 18242 | Values: 143.0 116.0 115.0 163.0 118.0 116.0 117.0 115.0 142.0 119.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:0.83 09:54:26 INFO - PROCESS | 18242 | Values: 163.0 162.0 160.0 160.0 160.0 161.0 162.0 161.0 161.0 160.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#3] audio-fft Cycles:10 Average:80.10 Median:76.00 stddev:10.26 (13.5%) stddev-sans-first:10.71 09:54:26 INFO - PROCESS | 18242 | Values: 75.0 78.0 75.0 107.0 74.0 76.0 76.0 76.0 88.0 76.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:82.00 stddev:14.97 (18.3%) stddev-sans-first:15.60 09:54:26 INFO - PROCESS | 18242 | Values: 82.0 82.0 123.0 81.0 82.0 82.0 92.0 82.0 81.0 111.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 09:54:26 INFO - PROCESS | 18242 | Values: 112.0 113.0 113.0 113.0 113.0 112.0 113.0 112.0 114.0 113.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:54:26 INFO - PROCESS | 18242 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 147.0 146.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:54:26 INFO - PROCESS | 18242 | Values: 94.0 94.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 94.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 09:54:26 INFO - PROCESS | 18242 | Values: 53.0 54.0 54.0 53.0 54.0 53.0 55.0 54.0 54.0 54.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#9] json-stringify-tinderbox Cycles:10 Average:52.10 Median:52.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 09:54:26 INFO - PROCESS | 18242 | Values: 52.0 54.0 53.0 52.0 52.0 51.0 51.0 51.0 52.0 53.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:81.00 stddev:3.95 (4.9%) stddev-sans-first:3.80 09:54:26 INFO - PROCESS | 18242 | Values: 75.0 78.0 75.0 79.0 85.0 75.0 84.0 82.0 80.0 84.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:122.00 stddev:16.85 (13.8%) stddev-sans-first:17.65 09:54:26 INFO - PROCESS | 18242 | Values: 120.0 128.0 123.0 121.0 135.0 120.0 119.0 118.0 173.0 118.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:2.85 (1.8%) stddev-sans-first:2.99 09:54:26 INFO - PROCESS | 18242 | Values: 161.0 163.0 167.0 162.0 162.0 160.0 160.0 164.0 165.0 157.0 09:54:26 INFO - PROCESS | 18242 | 09:54:26 INFO - PROCESS | 18242 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:60.50 stddev:1.73 (2.9%) stddev-sans-first:1.83 09:54:26 INFO - PROCESS | 18242 | Values: 61.0 60.0 61.0 60.0 60.0 59.0 60.0 64.0 60.0 64.0 09:54:26 INFO - PROCESS | 18242 | -------- Summary: end -------- 09:54:26 INFO - PROCESS | 18242 | 09:54:27 INFO - TEST-INFO | 18242: exit 0 09:54:27 INFO - TEST-OK | kraken | took 60018ms 09:54:27 INFO - SUITE-END | took 1174s 09:54:27 INFO - Completed test suite (00:19:35) 09:54:27 INFO - javascript benchmark 09:54:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7866.176511692792, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.780444271122, 3403.3023230237804, 3408.3183363327335, 3426.4175090234658, 3449.0, 5622.915656265154, 5665.71671416429, 5671.06427439472, 5683.261176047114, 5685.204071430003, 5623.971880140593, 5636.210930469727, 5672.858178545538, 5677.205191273225, 5678.744456499458, 5131.179011358193, 5143.691378517282, 5160.690358578498, 5168.534831865131, 5210.687358758487, 4988.950110498885, 5001.274815151801, 5212.713300768456, 5228.529432351088, 5246.947530524703, 5076.44159142494, 5103.157978933472, 5177.637565370425, 5177.974110129444, 5192.299039629658, 5184.455632158607, 5188.143956247215, 5194.091034069042, 5204.817831375903, 5237.764300606482, 4926.655134140611, 5045.495450454961, 5122.3853137623355, 5170.327857378555, 5177.818776342848, 5446.101393270105, 5536.252605898192, 5588.524975377098, 5589.776408943638, 5611.6352437091455, 6067.180930574405, 6089.786857460012, 6090.299615544225, 6108.717169394433, 6112.327643959161, 6158.476529494998, 6162.907556386658, 6166.722497487579, 6175.042868355412, 6179.81460556184, 5449.945500545023, 5495.450454954513, 5634.3520495143175, 5674.347450043216, 5674.914876276776, 6251.343608920998, 6252.124702541648, 6257.0, 6257.78097766576, 6261.373862613702, 5951.299382007387, 6018.6990650467305, 6020.247469066367, 6021.578489505692, 6070.7875224366935, 5762.394948530344, 5809.709514524341, 5943.286805583358, 6120.540959427972, 6126.571140020156, 5976.133460648269, 5980.5215582754145, 5998.070299103646, 6001.189839371629, 6029.969850150721, 5758.193852860602, 5792.4497172768515, 5805.912911306334, 6005.849853753603, 6032.245969253843, 4563.498015218323, 4642.79107440166, 4650.697704649187, 4661.370714953506, 4682.0, 4011.5186177658866, 4018.598140185958, 4132.566080561558, 4147.771872547039, 4194.475690538683, 5824.446677565625, 5872.500837428781, 5958.523318134538, 5963.582549221513, 5972.432618901197, 6146.323904370515, 6162.383761623892, 6166.229221347332, 6171.321154672983, 6174.691265436711, 5922.733476993546, 5931.851703707442, 5943.64338139713, 5951.791687290924, 5958.404159584007, 4964.478729733398, 5000.74996250186, 5049.469805670426, 5061.974690126526, 5065.240213967934, 6074.362191969868, 6123.357047510036, 6129.417705317987, 6135.049067394561, 6139.662318572432, 5922.289325280994, 5934.020886553671, 6064.636121832704, 6110.908336375047, 6142.201513803177, 6373.936260637334, 6382.0, 6395.0, 6398.48812095022, 6398.90401643985, 6052.576319657758, 6054.7275372607455, 6061.363556826646, 6063.181470501602, 6063.211782468427, 5881.147233651182, 5912.379200183918, 6058.666773327333, 6167.105769663284, 6179.196704428396, 5975.790847320323, 5981.521478281814, 5985.0, 5988.5508586855285, 6021.976264035212, 5968.820935371946, 5968.880622387441, 5969.164316995711, 5969.522438204846, 5972.701364931823, 5655.1517272409465, 5685.346185188756, 5954.672493012753, 5959.374265702038, 5960.701964901824, 5800.216970708901, 5808.07070868659, 5821.825345239649, 5846.239988801429, 5886.411358864079, 5753.1945527627, 5763.942360576509, 5766.942330576809, 5767.452092051249, 5770.394108618702], "value": 5588.576765035765, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.1950018232404, 855.0167307596264, 924.9768755781105, 937.5734041011341, 938.3525367496422, 43349.34975975359, 44148.55851441484, 44229.55770442299, 44268.3359749604, 44338.0, 45755.08489830201, 45863.31205031919, 46043.0, 46153.7692311538, 46247.76876115615, 8998.460092394462, 9137.086291370877, 9161.17549420552, 9161.72514824553, 9167.724968250963, 7654.196309387517, 7822.765317040498, 10547.47262636869, 10755.247132700713, 10770.784584308309, 10774.730631734192, 10790.51442685081, 10800.243982921198, 10804.783904321908, 10805.891941080607, 10736.409497477636, 10770.838437423445, 10771.353718776898, 10777.245592808507, 10801.135909127252, 10549.261551691385, 10613.41626210558, 10616.0, 10623.681289561326, 10625.256232063759, 43257.35113973293, 43423.0, 43454.13091738147, 43567.34648980268, 43610.56389436097, 4245.936310955307, 4304.7632380219075, 4335.046289816235, 4403.4495688039, 4426.667999899988, 29902.401951961056, 30080.699193008226, 30502.0, 31042.689573104206, 31761.36477270465, 4339.804708788112, 4437.689361744679, 4451.354553589711, 4480.977595112036, 4485.282354823213, 231795.0, 232358.0, 233119.0, 233813.9999999983, 233920.0, 44884.326735099, 45614.54385456103, 52639.0, 52646.0, 52649.0, 53352.0, 53919.46080539223, 54264.45735542594, 54423.72788136034, 60178.398216018155, 15235.162066086472, 15295.15876626774, 15299.847001530065, 15304.693906121815, 15316.846831531542, 13030.47878084866, 13048.086633935724, 13086.0, 13096.869031309756, 13108.475660973454, 12740.426680799386, 12854.807177892339, 12874.718247624201, 12938.870611293954, 13033.087683862039, 5468.7539060741565, 5483.917741233885, 5537.667739935617, 5585.385607583163, 5605.159226116115, 1003.3127307794184, 1016.0855230292796, 1018.7758693087509, 1026.8870424253325, 1063.9308444951052, 27764.444711105665, 27873.860630696716, 28129.0, 28291.292717682223, 28507.57238641422, 1091.2034215023077, 1092.9016388525072, 1109.035139428686, 1120.5181771838188, 1132.043423307304, 14133.15201087938, 14954.102753834588, 15065.397384104732, 15105.244737763069, 15214.619634509227, 297731.0, 298655.0, 298848.0, 299102.0, 306657.0, 54779.452205477435, 54962.45037549573, 55032.0, 55050.0, 55203.0, 1508.1931166825696, 1511.2368254031423, 1543.5909483986752, 1553.7047960887396, 1558.0340189082842, 25492.362690932132, 26640.600390993764, 26692.599611005462, 26913.461730764884, 27062.72937270602, 40250.39624405577, 40317.59682403138, 40348.0, 40439.0, 40445.39331909965, 266342.0, 266730.0, 266812.0, 267178.0, 267612.0, 29444.411111778074, 29754.0, 33317.0, 33319.167020823705, 34072.82963585266, 42856.35715464208, 42888.57111428846, 42908.78545607377, 42909.0, 43217.0, 32724.50913236351, 32933.17667058247, 32963.0, 33009.33981320408, 33170.83414582912, 30415.0, 31058.534121987737, 31405.0, 32195.0, 32642.0, 35266.82366588151, 35918.10204744902, 36176.819115904254, 36324.63675363213, 36372.45441318435, 30354.54468182935, 30480.695193048672, 30579.706522010492, 30633.387332253675, 30674.539881901343, 25105.0, 25127.623085653366, 25195.24414267575, 25210.0, 25221.92951936025], "value": 18712.378641882646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.18359688823904, 422.7294531499839, 452.30345268286817, 469.7557270219485, 485.03720115570593, 19940.202391904324, 19942.7008594871, 21506.0, 21752.673709894363, 21787.782122178774, 21723.348299551, 22688.546229075408, 22933.4266643334, 23096.42258943527, 23316.0, 6641.999999999988, 6952.478564107687, 6988.6855091521, 6998.265182155877, 7403.481756277063, 7443.106827180746, 7520.924790752105, 7543.924560754404, 7552.093748750158, 7555.206703296157, 7489.988851505059, 7552.509086909361, 7618.9619051904665, 7658.540487570735, 7666.2717041881215, 7548.320651141424, 7709.2290770922455, 7723.266289702469, 7760.961195194016, 7762.068551773767, 7394.593297368642, 7530.134034586038, 7530.9623451882935, 7537.698492060311, 7540.396768258526, 25444.618330725054, 25551.361215969562, 25979.22062338133, 26068.34829129268, 26451.603225951625, 3118.5166299223656, 3180.936381272362, 3227.7256433203206, 3558.2527669189503, 3770.6606405423645, 15601.765973510293, 16112.919435402748, 16320.591985200346, 16506.83493165065, 16767.0, 2106.747190337154, 2188.277868303456, 2194.122351059573, 2211.3587059752813, 2253.808426283768, 113943.43028284806, 115124.42437787841, 115280.0, 115671.0, 117722.41138794337, 18149.455516334532, 18227.817721822878, 18415.53961150955, 18440.538986525444, 18471.16879740415, 32158.517622235686, 32333.838330808194, 32379.028629140692, 32425.189370265933, 32629.836850815595, 10614.917460411672, 10812.891871081345, 10903.618373356894, 11066.0, 11109.277896936674, 9020.909790902138, 9104.726858194264, 9135.862962055575, 9215.815683686289, 9403.529823508798, 9168.816623667622, 9317.860232096524, 9391.812163756687, 9408.482533460725, 9638.98790626988, 3469.3581687387914, 3542.2030043240065, 3640.526731524885, 3838.616138386139, 3864.864729734446, 952.7046615549064, 973.9464329461806, 975.9951200243953, 977.3549457358109, 980.9166220871306, 20352.796472035385, 22037.228696995528, 22103.889480552494, 22141.778582214294, 22207.55584888293, 984.7291994701515, 990.5691024404407, 997.870276864003, 998.4658207858761, 1001.979960400788, 11079.944600276947, 11519.76960460803, 11953.940230298793, 11964.521419143312, 12034.0, 673016.0, 692967.0, 700024.0, 727412.0, 731691.0, 115935.0, 116792.0, 118050.40974795244, 118516.40741796409, 122388.0, 1259.8866102050858, 1274.6430999320205, 1285.9614211573669, 1288.2656885575132, 1293.8188653588504, 18274.0, 18509.72235416497, 19262.518437039187, 19316.420507384802, 19325.0, 39593.80203099081, 50438.0, 51327.0, 51627.48372516227, 52121.7393913028, 67110.0, 67429.0, 67806.32193678, 68846.65576672285, 69908.65045674739, 11289.37908415045, 11988.340641264815, 12290.569830056264, 12354.505819767108, 12440.377981100728, 14006.509772157926, 14265.786013209603, 14297.571072867831, 14521.564353069425, 14813.481528146463, 12243.938780306042, 12322.691932701755, 12379.876201237872, 12403.25580465175, 12417.0, 11384.14618903569, 11514.078873690252, 11516.712082198012, 11538.480768365276, 11615.186936914335, 12225.266484010988, 12284.447199876206, 12317.0, 12338.938305308417, 13231.27228002469, 12496.12527123129, 12519.56181533642, 12526.812097818358, 12682.619521414372, 12931.0, 12039.217450865666, 12049.638510844688, 12434.191777534432, 12619.684507887008, 12646.114771965875], "value": 11709.139756282611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.3477934929285, 1561.9219039048044, 1602.991985040075, 1615.176260107345, 1616.4180894877845, 8916.866247006301, 11303.717407064827, 11531.65405037849, 11998.700032499191, 12010.45952932118, 11673.941630291858, 11771.823422648667, 11775.941120294408, 11777.941110294458, 11779.05767538597, 2455.226603619854, 2493.7506249375097, 2494.638277449769, 2496.276079936816, 2497.775200231979, 1964.9214031438726, 2032.7865574114726, 2393.605055165904, 2465.2234546117916, 2473.826832121752, 2190.102058156156, 2201.6587428948537, 2205.658122990939, 2209.0611490116717, 2211.7345918489805, 2394.413368724665, 2427.3324835670155, 2429.4047958250167, 2430.8419952703016, 2443.9144629937955, 2292.4612716011725, 2302.1366987379733, 2303.4356582637192, 2309.93070207894, 2313.4794671199015, 11254.718632034183, 11430.142739294586, 11444.94277528607, 11448.084153267802, 11458.369789661565, 5760.107183386582, 5922.2597175353085, 5923.733431995527, 5929.703514824285, 5931.614445061057, 3508.8947331580093, 3524.4184709522897, 3583.7849729016343, 3588.4437912123662, 3652.8356223969986, 1308.1431662261234, 1333.7999300104964, 1367.863213678634, 1410.238471225537, 1421.6872288096602, 16985.49043528696, 17141.057241851693, 17142.31430742769, 17178.914105429518, 17182.22679979404, 4722.480527142012, 4938.827141050046, 4995.300657907896, 5003.274525193827, 5084.618653600921, 14245.501407450689, 14267.0, 14272.429102835978, 14298.28508574588, 14298.356573954197, 5911.9113213301835, 5921.881562368729, 6038.969805151034, 6040.275166979903, 6050.818475445743, 4438.7114837535455, 5240.266362709223, 5344.251804747339, 5397.271368365299, 5436.728163591804, 3743.401055831054, 4058.553559108496, 4152.941872620779, 4194.958050419517, 4204.768737719455, 1138.8120960041665, 1141.2467771270922, 1144.0790163918125, 1163.6043745126697, 1165.2542372881362, 1056.0759335581367, 1059.6079450603327, 1060.0724366179593, 1070.743021674808, 1071.7159952612565, 7017.719291228395, 7115.92884071163, 7217.783466496014, 7223.1693355264815, 7311.670974806147, 1047.3506426015767, 1058.3544038136731, 1070.2508244228916, 1077.8059949209219, 1089.5400163780573, 4832.0, 4832.49258827818, 4877.146499362598, 4878.56092951636, 4954.777035033432, 14085.577432677037, 14595.85404145945, 14647.26763661834, 14716.484923027643, 14727.410903563952, 13631.591052268448, 14195.0, 14414.351354189086, 14484.710305794037, 14604.853951460562, 9457.810843783085, 9624.566894489764, 9666.0, 9674.03259674027, 9674.129328360481, 6845.897311540231, 7031.824204394931, 7136.7145314186855, 7141.750038748827, 7183.317584829433, 11565.479553420284, 11633.825492617787, 11766.88233117658, 11819.645410637695, 11904.166708330335, 11472.655820325404, 11822.645320640395, 11839.0, 11907.46416411281, 11956.402179890796, 8445.282151016987, 9594.568244429158, 9941.453220072675, 9943.950280248553, 9954.55204515784, 9274.814503710022, 10523.579056837641, 10941.234113612289, 11122.110231181648, 11137.83293250618, 9349.439033658002, 9388.202002829836, 9409.811803764023, 9415.952920235355, 9591.232701384011, 8870.73387798367, 9285.396449230779, 9297.72106836796, 9458.054194580487, 9864.654737084167, 8193.344532437271, 8352.415330926777, 8356.498610083414, 8399.49603023796, 8458.661653533789, 6364.77723279683, 6396.072569477306, 6434.420902118832, 6455.096286519797, 6476.902846457213, 6297.874042519215, 6358.491320694426, 6369.33122022181, 6482.870342593027, 6528.477721782151], "value": 5376.000590025755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.1924506834209, 416.205048357637, 437.2021061562649, 446.0543647467369, 448.79579791195, 5278.868028299295, 5285.788568457262, 5308.867278318044, 5345.812896548627, 5359.38367087785, 5129.794808207667, 5173.430922598521, 5175.223716442535, 5226.268322434853, 5267.55225805806, 4547.408836851205, 4549.589627215574, 4556.794944227501, 4557.225271703818, 4593.586577208051, 4574.627611716488, 4597.977010114962, 4612.677112602119, 4616.538346165374, 4616.792244349012, 4358.8910277243, 4550.362949187116, 4600.240960241556, 4600.240960241573, 4605.401297831294, 4570.948681803171, 4586.243269860469, 4597.862064138065, 4598.862034138964, 4604.631629469634, 4470.351798989161, 4474.149911516802, 4480.0, 4498.730076195438, 4504.459464864237, 4999.500049995007, 5123.131046613871, 5181.274621552986, 5202.973985130088, 5288.762005709714, 5338.412774594792, 5348.101423994503, 5368.30212072432, 5370.865728356823, 5379.354477462691, 5587.469190426944, 5591.720413979285, 5619.522340601054, 5638.746256418471, 5645.717714114319, 4623.653226008029, 4861.2222044473065, 4870.464248932614, 4873.707577545359, 4890.633202509826, 5525.668459892419, 5661.2923384576925, 5692.573057020699, 5788.42115788422, 5788.450097240755, 4999.825006124768, 5006.974965125151, 5022.773975171053, 5030.270610761419, 5042.621803364762, 5076.644634875597, 5086.245245390334, 5102.515261050195, 5115.593211866766, 5124.053669140259, 5034.421041580192, 5245.868853278622, 5246.501582349671, 5295.417504074549, 5312.574994000471, 5502.669839809624, 5538.446155384429, 5539.362973258047, 5541.362743284493, 5558.471945165203, 1723.7931448226043, 1776.8845025073588, 1783.7413575031549, 1796.7125259958343, 1803.7373838312972, 1405.5361730628867, 1458.2562892924684, 1476.9630759231104, 1481.3408033425023, 1506.028611545547, 5341.7062061586985, 5375.543078838343, 5398.757055932492, 5444.3194600674915, 5456.263404440429, 5589.916151257735, 5640.8871822563315, 5704.404495954331, 5721.5708821838525, 5729.942700572941, 5205.895882082413, 5335.573154147659, 5413.485718856702, 5451.809186678447, 5477.3427188737605, 2943.513525669523, 2949.380630067667, 2980.985095074511, 3016.3967206558777, 3025.4100450411956, 5881.294244690579, 5899.941000590025, 5905.557083218776, 5925.940740592625, 5944.524438044946, 5531.585131115183, 5734.426557344233, 5746.7931734335425, 5774.653520788766, 5783.306003279549, 5886.676232807236, 5887.4406931341455, 5896.439838215449, 5914.4381283777975, 5915.171875937286, 5419.83740487786, 5690.573207009574, 5700.3444603871085, 5721.0, 5736.397678243724, 5408.999335123015, 5536.861578460571, 5626.35296940857, 5642.322921249476, 5650.660126180884, 5798.33319168301, 5902.940970590239, 5909.261342332208, 5911.5516698409165, 5951.375105614022, 4762.3332733416655, 4765.9523404765505, 4767.904641907073, 4775.2598347256235, 4776.522347765195, 5554.50009499147, 5677.51741102011, 5710.7442560187665, 5736.787399056541, 5790.10253410722, 5537.28015358017, 5556.333240011225, 5557.5831812613405, 5559.777608895599, 5569.602542449184, 5319.0, 5560.416156303691, 5567.582431317748, 5571.88562287536, 5573.5819813513335, 5507.925954438846, 5523.530499907552, 5542.9168562472405, 5547.33431988164, 5565.7217139143695, 5552.888942221214, 5567.8886422272135, 5584.497395234286, 5585.385607583082, 5636.154576813511, 5355.3305836770405, 5451.4276001019325, 5473.0, 5482.32788999359, 5515.503604675599], "value": 4551.9187099596, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.8792681097839, 878.6265837019265, 893.4148132972905, 917.8746368614865, 950.2493030505899, 17110.486685399443, 18282.634347313062, 18287.45137645871, 18291.085445727727, 18319.54201144969, 17085.40201092962, 17690.911545442254, 17743.822561774377, 17759.64480710385, 17761.555961100978, 869.6999535160376, 882.3382463152635, 891.393852180517, 896.7354630384052, 902.5938327752538, 895.8029233568604, 897.1566727276372, 897.578138275013, 898.27239935652, 899.1547944931776, 953.1897886796203, 954.89973552777, 956.0439560439561, 956.4070276428624, 956.784723437228, 948.3693343926316, 948.4641177734558, 948.6252930092644, 948.9430634161903, 948.9525523723821, 696.5298423564125, 698.0227681246245, 698.3784431855653, 698.4970821008866, 698.807827847341, 17881.910590447096, 17883.0, 17939.192736326895, 18554.44336669902, 18704.158312875952, 16701.415450459175, 16932.322707091826, 16974.575635609086, 17099.0, 17528.211230494657, 13182.67043323915, 13392.933035334761, 13521.256330901797, 13796.931015344959, 14349.067310624776, 4065.085355794952, 4113.1568028554075, 4126.0, 4190.727602705814, 4270.274053410928, 52470.73764631191, 53350.466495334935, 53885.92228155454, 54085.0, 68540.3145968544, 12827.603326300112, 13625.454981800616, 13695.0, 13861.584152475441, 14018.299085045706, 22737.88631056834, 24838.875805620857, 24900.0, 24977.87511062433, 25032.37419064538, 9213.861792073125, 9602.471864047404, 9609.135177833889, 9648.083432073941, 9743.464109473905, 5024.0956627807345, 5135.563477104413, 5149.716765577929, 5203.19350500673, 5275.60432967529, 3851.287511810324, 3907.785071821077, 3924.274009308287, 3926.450296958428, 3933.6459718624888, 684.9623270720158, 699.3251512290562, 714.589111261027, 724.3734169943067, 728.1771598094119, 813.5728742410164, 835.3442547600105, 836.7615229659518, 851.2466467176594, 865.6494119881459, 11053.355376496173, 11087.22389432732, 11478.655640330804, 12512.37438128108, 12551.435185416678, 837.2590257622048, 841.8989721233369, 854.1458541458542, 860.3180781572012, 872.8777971084053, 5294.54996325309, 5367.651102678314, 5379.596530260246, 5409.675419474844, 5434.212039254286, 81612.59193703994, 86050.56974715211, 86222.0, 87133.0, 88905.0, 60188.0, 61122.38877611256, 70964.2903570968, 74574.0, 77626.0, 69753.0, 69755.0, 69961.3003869965, 70071.64964175249, 70527.2947270531, 10525.473726313501, 10557.419341936267, 10680.0, 10736.570537178737, 10804.459777011276, 46392.0, 46649.53350466452, 47181.52818471771, 47183.528164717914, 48323.831810599935, 49967.50032499628, 51062.489375105775, 51755.741221293654, 52576.73711631417, 53963.46036539584, 13447.462101516221, 13849.446022159002, 13871.514496992557, 14037.0, 14135.363908623931, 15115.244237788287, 15168.24158792078, 15253.847461525243, 15369.615759606098, 15387.307571159101, 13595.456181753014, 13645.658858528615, 13784.379702913193, 14050.648733781738, 14559.27203639835, 14038.1577105374, 14137.222452765196, 14232.478428090379, 14387.208703521406, 14672.0, 12588.085355391187, 12899.226046437243, 12976.351182441029, 13083.0, 13161.210327380388, 9754.31719779637, 9875.111239988435, 9981.800363992825, 10407.791844163226, 10480.213983951082, 11921.82117268259, 11934.22427542207, 12070.75858482843, 12128.332941688292, 12216.938915305367], "value": 7905.607447443493, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1458.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 112.0, 113.0, 115.0, 111.0, 112.0, 112.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 115.0, 163.0, 118.0, 116.0, 117.0, 115.0, 142.0, 119.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0, 161.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 107.0, 74.0, 76.0, 76.0, 76.0, 88.0, 76.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 123.0, 81.0, 82.0, 82.0, 92.0, 82.0, 81.0, 111.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 114.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 55.0, 54.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 52.0, 52.0, 51.0, 51.0, 51.0, 52.0, 53.0], "value": 52.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 79.0, 85.0, 75.0, 84.0, 82.0, 80.0, 84.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 128.0, 123.0, 121.0, 135.0, 120.0, 119.0, 118.0, 173.0, 118.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 167.0, 162.0, 162.0, 160.0, 160.0, 164.0, 165.0, 157.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 60.0, 60.0, 59.0, 60.0, 64.0, 60.0, 64.0], "value": 60.9, "unit": "score"}]}]} 09:54:27 INFO - Return code: 0 09:54:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:27 ERROR - # TBPL SUCCESS # 09:54:27 INFO - Running post-action listener: _resource_record_post_action 09:54:27 INFO - [mozharness: 2017-04-09 16:54:27.592766Z] Finished run-tests step (success) 09:54:27 INFO - Running post-run listener: _resource_record_post_run 09:54:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 366796800; Read time: 164; Write time: 239328 09:54:27 INFO - TinderboxPrint: CPU usage
13.1% 09:54:27 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 164 09:54:27 INFO - TinderboxPrint: I/O write bytes / time
366,796,800 / 239,328 09:54:27 INFO - TinderboxPrint: CPU idle
8,264.0 (86.9%) 09:54:27 INFO - TinderboxPrint: CPU user
1,203.3 (12.6%) 09:54:27 INFO - TinderboxPrint: Swap in / out
0 / 0 09:54:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110977024; Read time: 0; Write time: 89320 09:54:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 255819776; Read time: 164; Write time: 150008 09:54:28 INFO - Running post-run listener: _upload_blobber_files 09:54:28 INFO - Blob upload gear active. 09:54:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:28 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:28 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:54:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:54:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:29 INFO - (blobuploader) - INFO - Done attempting. 09:54:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:32 INFO - (blobuploader) - INFO - Done attempting. 09:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:33 INFO - (blobuploader) - INFO - Done attempting. 09:54:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:54:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:54:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:34 INFO - (blobuploader) - INFO - Done attempting. 09:54:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:34 INFO - Return code: 0 09:54:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0806916bf304c87425a0b03ce3dc1c45e19bdfb34600d8529f8875b1da055e3ddc01623873cd0350835dba0103a2726bf58e890765ad98d1bc7b28f595135146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/396f089590e1efe749787071f85f3e374884145b75105c4b3a354bc3f91ce621d6683b0a4c845d62cedc3b5cc87fb06805882915518fba14bdd91b331aadfb7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/77b59a6f8406013c90f75215f565d50d5a0a94034e809e5e703d7ba4a56cc0668c28afb15d27ff7eff83e31a92f62814986f5d057eea1a2a0145e58c19cb9892", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dd47824149591fc2334c640639ab1df28f2f81951c9e87fdd2a69feae5616d25dde356cdc59780556e024520f28dfbb014c5ba0c6afc091ee527905d16e8eb05"} 09:54:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:34 INFO - Contents: 09:54:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0806916bf304c87425a0b03ce3dc1c45e19bdfb34600d8529f8875b1da055e3ddc01623873cd0350835dba0103a2726bf58e890765ad98d1bc7b28f595135146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/396f089590e1efe749787071f85f3e374884145b75105c4b3a354bc3f91ce621d6683b0a4c845d62cedc3b5cc87fb06805882915518fba14bdd91b331aadfb7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/77b59a6f8406013c90f75215f565d50d5a0a94034e809e5e703d7ba4a56cc0668c28afb15d27ff7eff83e31a92f62814986f5d057eea1a2a0145e58c19cb9892", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dd47824149591fc2334c640639ab1df28f2f81951c9e87fdd2a69feae5616d25dde356cdc59780556e024520f28dfbb014c5ba0c6afc091ee527905d16e8eb05"} 09:54:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:34 INFO - Copying logs to upload dir... 09:54:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.825990 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-04-09 09:54:32.929858) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:32.937694) ========= 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=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0806916bf304c87425a0b03ce3dc1c45e19bdfb34600d8529f8875b1da055e3ddc01623873cd0350835dba0103a2726bf58e890765ad98d1bc7b28f595135146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/396f089590e1efe749787071f85f3e374884145b75105c4b3a354bc3f91ce621d6683b0a4c845d62cedc3b5cc87fb06805882915518fba14bdd91b331aadfb7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/77b59a6f8406013c90f75215f565d50d5a0a94034e809e5e703d7ba4a56cc0668c28afb15d27ff7eff83e31a92f62814986f5d057eea1a2a0145e58c19cb9892", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dd47824149591fc2334c640639ab1df28f2f81951c9e87fdd2a69feae5616d25dde356cdc59780556e024520f28dfbb014c5ba0c6afc091ee527905d16e8eb05"} build_url:https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.010506 build_url: 'https://queue.taskcluster.net/v1/task/RE5VT4CwQGynlxiR8I4w2Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0806916bf304c87425a0b03ce3dc1c45e19bdfb34600d8529f8875b1da055e3ddc01623873cd0350835dba0103a2726bf58e890765ad98d1bc7b28f595135146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/396f089590e1efe749787071f85f3e374884145b75105c4b3a354bc3f91ce621d6683b0a4c845d62cedc3b5cc87fb06805882915518fba14bdd91b331aadfb7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/77b59a6f8406013c90f75215f565d50d5a0a94034e809e5e703d7ba4a56cc0668c28afb15d27ff7eff83e31a92f62814986f5d057eea1a2a0145e58c19cb9892", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dd47824149591fc2334c640639ab1df28f2f81951c9e87fdd2a69feae5616d25dde356cdc59780556e024520f28dfbb014c5ba0c6afc091ee527905d16e8eb05"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:33.017398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:33.017737) ========= 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=ed14279e38b05397cf4aa391000001a4-1491755221.361291-968156799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 09:54:33.044575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 09:54:33.044886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 09:54:33.045184) ========= ========= Total master_lag: 0.45 =========