builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1492159186.25 results: success (0) revision: 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.246650) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.247140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.247459) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.262551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.262893) ========= 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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-14 01:39:46-- 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% 98.4M=0s 2017-04-14 01:39:46 (98.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126199 ========= master_lag: 0.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.842638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.843028) ========= 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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016560 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.875436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:46.876278) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 --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 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 --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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-14 01:39:47,157 truncating revision to first 12 chars 2017-04-14 01:39:47,157 Setting DEBUG logging. 2017-04-14 01:39:47,157 attempt 1/10 2017-04-14 01:39:47,157 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/189ed7b2306f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-14 01:39:47,714 unpacking tar archive at: mozilla-aurora-189ed7b2306f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.930286 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:47.825529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:47.825925) ========= script_repo_revision: 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:47.826416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:47.826755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 01:39:47.838134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-14 01:39:47.838516) ========= /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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:39:48 INFO - MultiFileLogger online at 20170414 01:39:48 in /builds/slave/test 01:39:48 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 01:39:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:39:48 INFO - {'append_to_log': False, 01:39:48 INFO - 'base_work_dir': '/builds/slave/test', 01:39:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:39:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:39:48 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:39:48 INFO - 'buildbot_json_path': 'buildprops.json', 01:39:48 INFO - 'config_files': ('talos/linux_config.py',), 01:39:48 INFO - 'default_actions': ('clobber', 01:39:48 INFO - 'read-buildbot-config', 01:39:48 INFO - 'download-and-extract', 01:39:48 INFO - 'populate-webroot', 01:39:48 INFO - 'create-virtualenv', 01:39:48 INFO - 'install', 01:39:48 INFO - 'run-tests'), 01:39:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:39:48 INFO - 'download_minidump_stackwalk': True, 01:39:48 INFO - 'download_symbols': 'ondemand', 01:39:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:39:48 INFO - 'tooltool.py': '/tools/tooltool.py', 01:39:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:39:48 INFO - '/tools/misc-python/virtualenv.py')}, 01:39:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:39:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:39:48 INFO - 'gecko_profile': False, 01:39:48 INFO - 'gecko_profile_interval': 0, 01:39:48 INFO - 'installer_path': 'installer.exe', 01:39:48 INFO - 'log_level': 'info', 01:39:48 INFO - 'log_name': 'talos', 01:39:48 INFO - 'log_to_console': True, 01:39:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:39:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:39:48 INFO - 'opt_config_files': (), 01:39:48 INFO - 'pip_index': False, 01:39:48 INFO - 'suite': 'dromaeojs', 01:39:48 INFO - 'system_bits': '32', 01:39:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:39:48 INFO - 'title': 'talos-linux64-ix-038', 01:39:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:39:48 INFO - 'use_talos_json': True, 01:39:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:39:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:39:48 INFO - 'work_dir': 'build'} 01:39:48 INFO - [mozharness: 2017-04-14 08:39:48.217346Z] Running clobber step. 01:39:48 INFO - Running pre-action listener: _resource_record_pre_action 01:39:48 INFO - Running main action method: clobber 01:39:48 INFO - rmtree: /builds/slave/test/build 01:39:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:39:49 INFO - Running post-action listener: _resource_record_post_action 01:39:49 INFO - [mozharness: 2017-04-14 08:39:49.160214Z] Finished clobber step (success) 01:39:49 INFO - [mozharness: 2017-04-14 08:39:49.160316Z] Running read-buildbot-config step. 01:39:49 INFO - Running pre-action listener: _resource_record_pre_action 01:39:49 INFO - Running main action method: read_buildbot_config 01:39:49 INFO - Using buildbot properties: 01:39:49 INFO - { 01:39:49 INFO - "project": "", 01:39:49 INFO - "product": "firefox", 01:39:49 INFO - "who": "nobody@mozilla.org", 01:39:49 INFO - "installer_path": "public/build/target.tar.bz2", 01:39:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:39:49 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:39:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:39:49 INFO - "stage_platform": "linux64", 01:39:49 INFO - "basedir": "/builds/slave/test", 01:39:49 INFO - "buildnumber": 130, 01:39:49 INFO - "platform": "ubuntu64_hw", 01:39:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:39:49 INFO - "slavebuilddir": "test", 01:39:49 INFO - "taskId": "LqBf1APTTuqV2LCwivglLQ", 01:39:49 INFO - "branch": "mozilla-aurora", 01:39:49 INFO - "script_repo_revision": "production", 01:39:49 INFO - "revision": "189ed7b2306fcf0a7683df6959b78fdb94bb34e7", 01:39:49 INFO - "slavename": "talos-linux64-ix-038", 01:39:49 INFO - "repo_path": "releases/mozilla-aurora" 01:39:49 INFO - } 01:39:49 INFO - Finding installer, test and symbols from parent task. 01:39:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LqBf1APTTuqV2LCwivglLQ'}, attempt #1 01:39:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LqBf1APTTuqV2LCwivglLQ'}, attempt #1 01:39:50 INFO - Task dependencies: dP5Pp2IKTD2Lc8no8gZFcg 01:39:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg'}, attempt #1 01:39:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 01:39:50 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/test_packages.json 01:39:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.crashreporter-symbols.zip 01:39:50 INFO - Running post-action listener: _resource_record_post_action 01:39:50 INFO - [mozharness: 2017-04-14 08:39:50.539561Z] Finished read-buildbot-config step (success) 01:39:50 INFO - [mozharness: 2017-04-14 08:39:50.539781Z] Running download-and-extract step. 01:39:50 INFO - Running pre-action listener: _resource_record_pre_action 01:39:50 INFO - Running main action method: download_and_extract 01:39:50 INFO - mkdir: /builds/slave/test/build/tests 01:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:50 INFO - trying https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.test_packages.json 01:39:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:39:51 INFO - Downloaded 896 bytes. 01:39:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:39:51 INFO - Using the following test package requirements: 01:39:51 INFO - {u'common': [u'target.common.tests.zip'], 01:39:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:39:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:39:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:39:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:39:51 INFO - u'mozbase': [u'target.common.tests.zip'], 01:39:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:39:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:39:51 INFO - u'web-platform': [u'target.common.tests.zip', 01:39:51 INFO - u'target.web-platform.tests.zip'], 01:39:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:39:51 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:39:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip 01:39:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:39:51 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip into memory 01:39:52 INFO - Expected file size: 59543219 01:39:52 INFO - Obtained file size: 59543219 01:40:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:40:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip 01:40:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:40:01 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.common.tests.zip into memory 01:40:03 INFO - Expected file size: 59543219 01:40:03 INFO - Obtained file size: 59543219 01:40:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.talos.tests.zip 01:40:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:40:13 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.talos.tests.zip into memory 01:40:14 INFO - Expected file size: 14404424 01:40:14 INFO - Obtained file size: 14404424 01:40:14 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')]} 01:40:14 INFO - trying https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 01:40:14 INFO - Downloading https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:40:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:40:15 INFO - Downloaded 66693286 bytes. 01:40:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 01:40:15 INFO - mkdir: /builds/slave/test/properties 01:40:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:15 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:15 INFO - Contents: 01:40:15 INFO - build_url:https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 01:40:15 INFO - Running post-action listener: _resource_record_post_action 01:40:15 INFO - Running post-action listener: set_extra_try_arguments 01:40:15 INFO - [mozharness: 2017-04-14 08:40:15.412904Z] Finished download-and-extract step (success) 01:40:15 INFO - [mozharness: 2017-04-14 08:40:15.413001Z] Running populate-webroot step. 01:40:15 INFO - Running pre-action listener: _resource_record_pre_action 01:40:15 INFO - Running main action method: populate_webroot 01:40:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:15 INFO - '--tppagecycles', 01:40:15 INFO - '7'], 01:40:15 INFO - 'tests': ['tsvgm']}}, 01:40:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['damp', 'tps']}, 01:40:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'tests': ['damp', 'tps']}, 01:40:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['dromaeo_dom']}, 01:40:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['basic_compositor_video']}, 01:40:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:40:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['a11yr', 01:40:15 INFO - 'ts_paint', 01:40:15 INFO - 'tpaint', 01:40:15 INFO - 'sessionrestore', 01:40:15 INFO - 'sessionrestore_no_auto_restore', 01:40:15 INFO - 'tabpaint']}, 01:40:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['tsvgx', 01:40:15 INFO - 'tsvgr_opacity', 01:40:15 INFO - 'tart', 01:40:15 INFO - 'tscrollx', 01:40:15 INFO - 'cart', 01:40:15 INFO - 'tsvg_static']}, 01:40:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:15 INFO - 'tsvgr_opacity', 01:40:15 INFO - 'tart', 01:40:15 INFO - 'tscrollx', 01:40:15 INFO - 'cart', 01:40:15 INFO - 'tsvg_static']}, 01:40:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'talos_options': ['--disable-e10s'], 01:40:15 INFO - 'tests': ['tp5o']}, 01:40:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:40:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'talos_options': ['--disable-e10s', 01:40:15 INFO - '--xperf_path', 01:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:15 INFO - 'tests': ['tp5n']}, 01:40:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:40:15 INFO - 'talos_options': ['--xperf_path', 01:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:15 INFO - 'tests': ['tp5n']}}, 01:40:15 INFO - 'talos.zip': {'path': '', 01:40:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:15 INFO - Running post-action listener: _resource_record_post_action 01:40:15 INFO - [mozharness: 2017-04-14 08:40:15.426128Z] Finished populate-webroot step (success) 01:40:15 INFO - [mozharness: 2017-04-14 08:40:15.426218Z] Running create-virtualenv step. 01:40:15 INFO - Running pre-action listener: _resource_record_pre_action 01:40:15 INFO - Running main action method: create_virtualenv 01:40:15 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:15 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 01:40:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:15 INFO - Using real prefix '/usr' 01:40:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:16 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:17 INFO - Installing pip.................done. 01:40:17 INFO - Return code: 0 01:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:40:18 INFO - Reading from file tmpfile_stdout 01:40:18 INFO - Output received: 01:40:18 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) 01:40:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:18 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:18 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 01:40:18 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 01:40:18 INFO - Using env: {'DISPLAY': ':0', 01:40:18 INFO - 'HOME': '/home/cltbld', 01:40:18 INFO - 'LANG': 'en_US.UTF-8', 01:40:18 INFO - 'LANGUAGE': 'en_US:en', 01:40:18 INFO - 'LOGNAME': 'cltbld', 01:40:18 INFO - 'MAIL': '/var/mail/cltbld', 01:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:18 INFO - 'MOZ_NO_REMOTE': '1', 01:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:18 INFO - 'NO_EM_RESTART': '1', 01:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:18 INFO - 'PWD': '/builds/slave/test', 01:40:18 INFO - 'SHELL': '/bin/bash', 01:40:18 INFO - 'SHLVL': '1', 01:40:18 INFO - 'TERM': 'linux', 01:40:18 INFO - 'TMOUT': '86400', 01:40:18 INFO - 'USER': 'cltbld', 01:40:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:18 INFO - '_': '/tools/buildbot/bin/python'} 01:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:18 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 01:40:18 INFO - Cleaning up... 01:40:18 INFO - Return code: 0 01:40:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:18 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:18 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 01:40:18 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 01:40:18 INFO - Using env: {'DISPLAY': ':0', 01:40:18 INFO - 'HOME': '/home/cltbld', 01:40:18 INFO - 'LANG': 'en_US.UTF-8', 01:40:18 INFO - 'LANGUAGE': 'en_US:en', 01:40:18 INFO - 'LOGNAME': 'cltbld', 01:40:18 INFO - 'MAIL': '/var/mail/cltbld', 01:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:18 INFO - 'MOZ_NO_REMOTE': '1', 01:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:18 INFO - 'NO_EM_RESTART': '1', 01:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:18 INFO - 'PWD': '/builds/slave/test', 01:40:18 INFO - 'SHELL': '/bin/bash', 01:40:18 INFO - 'SHLVL': '1', 01:40:18 INFO - 'TERM': 'linux', 01:40:18 INFO - 'TMOUT': '86400', 01:40:18 INFO - 'USER': 'cltbld', 01:40:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:18 INFO - '_': '/tools/buildbot/bin/python'} 01:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:18 INFO - Downloading/unpacking psutil>=3.1.1 01:40:18 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 01:40:18 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 01:40:18 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 01:40:18 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 01:40:18 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 01:40:18 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 01:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:20 INFO - Installing collected packages: psutil 01:40:20 INFO - Running setup.py install for psutil 01:40:20 INFO - building 'psutil._psutil_linux' extension 01:40:20 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 01:40:21 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 01:40:21 INFO - building 'psutil._psutil_posix' extension 01:40:21 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 01:40:21 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 01:40:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:21 INFO - Successfully installed psutil 01:40:21 INFO - Cleaning up... 01:40:21 INFO - Return code: 0 01:40:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:40:21 INFO - Using env: {'DISPLAY': ':0', 01:40:21 INFO - 'HOME': '/home/cltbld', 01:40:21 INFO - 'LANG': 'en_US.UTF-8', 01:40:21 INFO - 'LANGUAGE': 'en_US:en', 01:40:21 INFO - 'LOGNAME': 'cltbld', 01:40:21 INFO - 'MAIL': '/var/mail/cltbld', 01:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:21 INFO - 'MOZ_NO_REMOTE': '1', 01:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:21 INFO - 'NO_EM_RESTART': '1', 01:40:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:21 INFO - 'PWD': '/builds/slave/test', 01:40:21 INFO - 'SHELL': '/bin/bash', 01:40:21 INFO - 'SHLVL': '1', 01:40:21 INFO - 'TERM': 'linux', 01:40:21 INFO - 'TMOUT': '86400', 01:40:21 INFO - 'USER': 'cltbld', 01:40:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:21 INFO - '_': '/tools/buildbot/bin/python'} 01:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:40:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:40:23 INFO - Installing collected packages: mozsystemmonitor 01:40:23 INFO - Running setup.py install for mozsystemmonitor 01:40:23 INFO - Successfully installed mozsystemmonitor 01:40:23 INFO - Cleaning up... 01:40:23 INFO - Return code: 0 01:40:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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', '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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:40: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 jsonschema==2.5.1 01:40:23 INFO - Using env: {'DISPLAY': ':0', 01:40:23 INFO - 'HOME': '/home/cltbld', 01:40:23 INFO - 'LANG': 'en_US.UTF-8', 01:40:23 INFO - 'LANGUAGE': 'en_US:en', 01:40:23 INFO - 'LOGNAME': 'cltbld', 01:40:23 INFO - 'MAIL': '/var/mail/cltbld', 01:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:23 INFO - 'MOZ_NO_REMOTE': '1', 01:40:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:23 INFO - 'NO_EM_RESTART': '1', 01:40:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:23 INFO - 'PWD': '/builds/slave/test', 01:40:23 INFO - 'SHELL': '/bin/bash', 01:40:23 INFO - 'SHLVL': '1', 01:40:23 INFO - 'TERM': 'linux', 01:40:23 INFO - 'TMOUT': '86400', 01:40:23 INFO - 'USER': 'cltbld', 01:40:23 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:23 INFO - '_': '/tools/buildbot/bin/python'} 01:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:23 INFO - Downloading/unpacking jsonschema==2.5.1 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:40:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:26 INFO - Downloading functools32-3.2.3-2.tar.gz 01:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:40:26 INFO - warning: no files found matching '*.txt' 01:40:26 INFO - no previously-included directories found matching 'build' 01:40:26 INFO - no previously-included directories found matching 'dist' 01:40:26 INFO - no previously-included directories found matching '.git*' 01:40:26 INFO - Installing collected packages: jsonschema, functools32 01:40:26 INFO - Running setup.py install for functools32 01:40:26 INFO - warning: no files found matching '*.txt' 01:40:26 INFO - no previously-included directories found matching 'build' 01:40:26 INFO - no previously-included directories found matching 'dist' 01:40:26 INFO - no previously-included directories found matching '.git*' 01:40:26 INFO - Successfully installed jsonschema functools32 01:40:26 INFO - Cleaning up... 01:40:26 INFO - Return code: 0 01:40:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:40:26 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')]} 01:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:26 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')]} 01:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:26 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:26 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 01:40:26 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 01:40:26 INFO - Using env: {'DISPLAY': ':0', 01:40:26 INFO - 'HOME': '/home/cltbld', 01:40:26 INFO - 'LANG': 'en_US.UTF-8', 01:40:26 INFO - 'LANGUAGE': 'en_US:en', 01:40:26 INFO - 'LOGNAME': 'cltbld', 01:40:26 INFO - 'MAIL': '/var/mail/cltbld', 01:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:26 INFO - 'MOZ_NO_REMOTE': '1', 01:40:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:26 INFO - 'NO_EM_RESTART': '1', 01:40:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:26 INFO - 'PWD': '/builds/slave/test', 01:40:26 INFO - 'SHELL': '/bin/bash', 01:40:26 INFO - 'SHLVL': '1', 01:40:26 INFO - 'TERM': 'linux', 01:40:26 INFO - 'TMOUT': '86400', 01:40:26 INFO - 'USER': 'cltbld', 01:40:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:26 INFO - '_': '/tools/buildbot/bin/python'} 01:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:40:26 INFO - Cleaning up... 01:40:26 INFO - Return code: 0 01:40:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:40:26 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')]} 01:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:26 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')]} 01:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:26 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:26 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 01:40:26 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 01:40:26 INFO - Using env: {'DISPLAY': ':0', 01:40:26 INFO - 'HOME': '/home/cltbld', 01:40:26 INFO - 'LANG': 'en_US.UTF-8', 01:40:26 INFO - 'LANGUAGE': 'en_US:en', 01:40:26 INFO - 'LOGNAME': 'cltbld', 01:40:26 INFO - 'MAIL': '/var/mail/cltbld', 01:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:26 INFO - 'MOZ_NO_REMOTE': '1', 01:40:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:26 INFO - 'NO_EM_RESTART': '1', 01:40:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:26 INFO - 'PWD': '/builds/slave/test', 01:40:26 INFO - 'SHELL': '/bin/bash', 01:40:26 INFO - 'SHLVL': '1', 01:40:26 INFO - 'TERM': 'linux', 01:40:26 INFO - 'TMOUT': '86400', 01:40:26 INFO - 'USER': 'cltbld', 01:40:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:26 INFO - '_': '/tools/buildbot/bin/python'} 01:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:26 INFO - Downloading/unpacking blobuploader==1.2.4 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:28 INFO - Downloading blobuploader-1.2.4.tar.gz 01:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:40:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:40:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - Downloading docopt-0.6.1.tar.gz 01:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:40:29 INFO - Installing collected packages: blobuploader, requests, docopt 01:40:29 INFO - Running setup.py install for blobuploader 01:40:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:40:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:40:29 INFO - Running setup.py install for requests 01:40:30 INFO - Running setup.py install for docopt 01:40:30 INFO - Successfully installed blobuploader requests docopt 01:40:30 INFO - Cleaning up... 01:40:30 INFO - Return code: 0 01:40:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:30 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:30 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 01:40:30 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 01:40:30 INFO - Using env: {'DISPLAY': ':0', 01:40:30 INFO - 'HOME': '/home/cltbld', 01:40:30 INFO - 'LANG': 'en_US.UTF-8', 01:40:30 INFO - 'LANGUAGE': 'en_US:en', 01:40:30 INFO - 'LOGNAME': 'cltbld', 01:40:30 INFO - 'MAIL': '/var/mail/cltbld', 01:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:30 INFO - 'MOZ_NO_REMOTE': '1', 01:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:30 INFO - 'NO_EM_RESTART': '1', 01:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:30 INFO - 'PWD': '/builds/slave/test', 01:40:30 INFO - 'SHELL': '/bin/bash', 01:40:30 INFO - 'SHLVL': '1', 01:40:30 INFO - 'TERM': 'linux', 01:40:30 INFO - 'TMOUT': '86400', 01:40:30 INFO - 'USER': 'cltbld', 01:40:30 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:30 INFO - '_': '/tools/buildbot/bin/python'} 01:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:30 INFO - Running setup.py (path:/tmp/pip-7KfF2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:30 INFO - Running setup.py (path:/tmp/pip-p0JIaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:30 INFO - Running setup.py (path:/tmp/pip-CT2_7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:30 INFO - Running setup.py (path:/tmp/pip-kdpJbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:30 INFO - Running setup.py (path:/tmp/pip-s6SaGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:31 INFO - Running setup.py (path:/tmp/pip-cy85kN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:31 INFO - Running setup.py (path:/tmp/pip-7IZkQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:31 INFO - Running setup.py (path:/tmp/pip-OkozNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:31 INFO - Running setup.py (path:/tmp/pip-EDj6NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:31 INFO - Running setup.py (path:/tmp/pip-BCnp2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:31 INFO - Running setup.py (path:/tmp/pip-cvZy3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:31 INFO - Running setup.py (path:/tmp/pip-cQ0b96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:31 INFO - Running setup.py (path:/tmp/pip-c7ySp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:31 INFO - Running setup.py (path:/tmp/pip-t5s4z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:31 INFO - Running setup.py (path:/tmp/pip-VTwKpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:31 INFO - Running setup.py (path:/tmp/pip-2QeDV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:31 INFO - Running setup.py (path:/tmp/pip-JYjq_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:40:31 INFO - Running setup.py install for manifestparser 01:40:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Running setup.py install for mozcrash 01:40:32 INFO - Running setup.py install for mozdebug 01:40:32 INFO - Running setup.py install for mozdevice 01:40:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozfile 01:40:32 INFO - Running setup.py install for mozhttpd 01:40:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozinfo 01:40:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozInstall 01:40:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozleak 01:40:32 INFO - Running setup.py install for mozlog 01:40:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for moznetwork 01:40:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Running setup.py install for mozprocess 01:40:33 INFO - Running setup.py install for mozprofile 01:40:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Running setup.py install for mozrunner 01:40:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Running setup.py install for mozscreenshot 01:40:33 INFO - Running setup.py install for moztest 01:40:33 INFO - Running setup.py install for mozversion 01:40:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:40:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:40:33 INFO - Cleaning up... 01:40:33 INFO - Return code: 0 01:40:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:33 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')]} 01:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:33 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')]} 01:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:33 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:33 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 01:40:33 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 01:40:33 INFO - Using env: {'DISPLAY': ':0', 01:40:33 INFO - 'HOME': '/home/cltbld', 01:40:33 INFO - 'LANG': 'en_US.UTF-8', 01:40:33 INFO - 'LANGUAGE': 'en_US:en', 01:40:33 INFO - 'LOGNAME': 'cltbld', 01:40:33 INFO - 'MAIL': '/var/mail/cltbld', 01:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:33 INFO - 'MOZ_NO_REMOTE': '1', 01:40:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:33 INFO - 'NO_EM_RESTART': '1', 01:40:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:33 INFO - 'PWD': '/builds/slave/test', 01:40:33 INFO - 'SHELL': '/bin/bash', 01:40:33 INFO - 'SHLVL': '1', 01:40:33 INFO - 'TERM': 'linux', 01:40:33 INFO - 'TMOUT': '86400', 01:40:33 INFO - 'USER': 'cltbld', 01:40:33 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:33 INFO - '_': '/tools/buildbot/bin/python'} 01:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:33 INFO - Running setup.py (path:/tmp/pip-ECrEOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:33 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)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:33 INFO - Running setup.py (path:/tmp/pip-E4R1tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:34 INFO - Running setup.py (path:/tmp/pip-x867eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:34 INFO - Running setup.py (path:/tmp/pip-PzOleN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:34 INFO - Running setup.py (path:/tmp/pip-EjUUtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:34 INFO - Running setup.py (path:/tmp/pip-7kXP2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:34 INFO - Running setup.py (path:/tmp/pip-RJfhSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:34 INFO - Running setup.py (path:/tmp/pip-HMQWDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:34 INFO - Running setup.py (path:/tmp/pip-X9PWI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:34 INFO - Running setup.py (path:/tmp/pip-8f3GMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:34 INFO - Running setup.py (path:/tmp/pip-7KGmOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:34 INFO - Running setup.py (path:/tmp/pip-3lH7q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:34 INFO - Running setup.py (path:/tmp/pip-adMy8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:34 INFO - Running setup.py (path:/tmp/pip-RPosUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:34 INFO - Running setup.py (path:/tmp/pip-DXY9Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:34 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)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:34 INFO - Running setup.py (path:/tmp/pip-0L9Hhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:35 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)) 01:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:35 INFO - Running setup.py (path:/tmp/pip-IhhKd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:35 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)) 01:40:35 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)) 01:40:35 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)) 01:40:35 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)) 01:40:35 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)) 01:40:35 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)) 01:40:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40:35 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 01:40:35 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 01:40:35 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 01:40:35 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 01:40:35 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 01:40:35 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 01:40:37 INFO - Downloading blessings-1.6.tar.gz 01:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:40:37 INFO - Installing collected packages: blessings 01:40:37 INFO - Running setup.py install for blessings 01:40:37 INFO - Successfully installed blessings 01:40:37 INFO - Cleaning up... 01:40:37 INFO - Return code: 0 01:40:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:40:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:37 INFO - Reading from file tmpfile_stdout 01:40:37 INFO - Current package versions: 01:40:37 INFO - argparse == 1.2.1 01:40:37 INFO - blessings == 1.6 01:40:37 INFO - blobuploader == 1.2.4 01:40:37 INFO - docopt == 0.6.1 01:40:37 INFO - functools32 == 3.2.3-2 01:40:37 INFO - jsonschema == 2.5.1 01:40:37 INFO - manifestparser == 1.1 01:40:37 INFO - mozInstall == 1.12 01:40:37 INFO - mozcrash == 1.0 01:40:37 INFO - mozdebug == 0.1 01:40:37 INFO - mozdevice == 0.49 01:40:37 INFO - mozfile == 1.2 01:40:37 INFO - mozhttpd == 0.7 01:40:37 INFO - mozinfo == 0.9 01:40:37 INFO - mozleak == 0.1 01:40:37 INFO - mozlog == 3.4 01:40:37 INFO - moznetwork == 0.27 01:40:37 INFO - mozprocess == 0.25 01:40:37 INFO - mozprofile == 0.28 01:40:37 INFO - mozrunner == 6.13 01:40:37 INFO - mozscreenshot == 0.1 01:40:37 INFO - mozsystemmonitor == 0.3 01:40:37 INFO - moztest == 0.8 01:40:37 INFO - mozversion == 1.4 01:40:37 INFO - psutil == 3.1.1 01:40:37 INFO - requests == 1.2.3 01:40:37 INFO - wsgiref == 0.1.2 01:40:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:37 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')]} 01:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:37 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')]} 01:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:37 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:37 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 01:40:37 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 01:40:37 INFO - Using env: {'DISPLAY': ':0', 01:40:37 INFO - 'HOME': '/home/cltbld', 01:40:37 INFO - 'LANG': 'en_US.UTF-8', 01:40:37 INFO - 'LANGUAGE': 'en_US:en', 01:40:37 INFO - 'LOGNAME': 'cltbld', 01:40:37 INFO - 'MAIL': '/var/mail/cltbld', 01:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:37 INFO - 'MOZ_NO_REMOTE': '1', 01:40:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:37 INFO - 'NO_EM_RESTART': '1', 01:40:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:37 INFO - 'PWD': '/builds/slave/test', 01:40:37 INFO - 'SHELL': '/bin/bash', 01:40:37 INFO - 'SHLVL': '1', 01:40:37 INFO - 'TERM': 'linux', 01:40:37 INFO - 'TMOUT': '86400', 01:40:37 INFO - 'USER': 'cltbld', 01:40:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:37 INFO - '_': '/tools/buildbot/bin/python'} 01:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 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)) 01:40:37 INFO - Cleaning up... 01:40:37 INFO - Return code: 0 01:40:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:40:37 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')]} 01:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:37 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')]} 01:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:37 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 0x1e701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, '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': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', '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 01:40:37 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 01:40:37 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 01:40:37 INFO - Using env: {'DISPLAY': ':0', 01:40:37 INFO - 'HOME': '/home/cltbld', 01:40:37 INFO - 'LANG': 'en_US.UTF-8', 01:40:37 INFO - 'LANGUAGE': 'en_US:en', 01:40:37 INFO - 'LOGNAME': 'cltbld', 01:40:37 INFO - 'MAIL': '/var/mail/cltbld', 01:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:37 INFO - 'MOZ_NO_REMOTE': '1', 01:40:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:37 INFO - 'NO_EM_RESTART': '1', 01:40:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:37 INFO - 'PWD': '/builds/slave/test', 01:40:37 INFO - 'SHELL': '/bin/bash', 01:40:37 INFO - 'SHLVL': '1', 01:40:37 INFO - 'TERM': 'linux', 01:40:37 INFO - 'TMOUT': '86400', 01:40:37 INFO - 'USER': 'cltbld', 01:40:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:37 INFO - '_': '/tools/buildbot/bin/python'} 01:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:40:38 INFO - Cleaning up... 01:40:38 INFO - Return code: 0 01:40:38 INFO - Running post-action listener: _resource_record_post_action 01:40:38 INFO - Running post-action listener: _start_resource_monitoring 01:40:38 INFO - Starting resource monitoring. 01:40:38 INFO - [mozharness: 2017-04-14 08:40:38.089202Z] Finished create-virtualenv step (success) 01:40:38 INFO - [mozharness: 2017-04-14 08:40:38.089780Z] Running install step. 01:40:38 INFO - Running pre-action listener: _resource_record_pre_action 01:40:38 INFO - Running main action method: install 01:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:38 INFO - Reading from file tmpfile_stdout 01:40:38 INFO - Detecting whether we're running mozinstall >=1.0... 01:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:40:38 INFO - Reading from file tmpfile_stdout 01:40:38 INFO - Output received: 01:40:38 INFO - Usage: mozinstall [options] installer 01:40:38 INFO - Options: 01:40:38 INFO - -h, --help show this help message and exit 01:40:38 INFO - -d DEST, --destination=DEST 01:40:38 INFO - Directory to install application into. [default: 01:40:38 INFO - "/builds/slave/test"] 01:40:38 INFO - --app=APP Application being installed. [default: firefox] 01:40:38 INFO - mkdir: /builds/slave/test/build/application 01:40:38 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'] 01:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:40:54 INFO - Reading from file tmpfile_stdout 01:40:54 INFO - Output received: 01:40:54 INFO - /builds/slave/test/build/application/firefox/firefox 01:40:54 INFO - Running post-action listener: _resource_record_post_action 01:40:54 INFO - [mozharness: 2017-04-14 08:40:54.947267Z] Finished install step (success) 01:40:54 INFO - [mozharness: 2017-04-14 08:40:54.947540Z] Running run-tests step. 01:40:54 INFO - Running pre-action listener: _resource_record_pre_action 01:40:54 INFO - Running main action method: run_tests 01:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:40:54 INFO - Python 2.7.3 01:40:54 INFO - Return code: 0 01:40:54 INFO - grabbing minidump binary from tooltool 01:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:54 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 0x210b400>, '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 0x210c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x210c840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:40:54 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 01:40:54 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 01:40:54 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 01:40:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:40:55 INFO - Return code: 0 01:40:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:40:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:40:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:40:55 INFO - ENV: RUST_BACKTRACE is now 1 01:40:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:40:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:40:55 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/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 01:40:55 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/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 01:40:55 INFO - Using env: {'DISPLAY': ':0', 01:40:55 INFO - 'HOME': '/home/cltbld', 01:40:55 INFO - 'LANG': 'en_US.UTF-8', 01:40:55 INFO - 'LANGUAGE': 'en_US:en', 01:40:55 INFO - 'LOGNAME': 'cltbld', 01:40:55 INFO - 'MAIL': '/var/mail/cltbld', 01:40:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:40:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:55 INFO - 'MOZ_NO_REMOTE': '1', 01:40:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:55 INFO - 'NO_EM_RESTART': '1', 01:40:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:55 INFO - 'PWD': '/builds/slave/test', 01:40:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:40:55 INFO - 'RUST_BACKTRACE': '1', 01:40:55 INFO - 'SHELL': '/bin/bash', 01:40:55 INFO - 'SHLVL': '1', 01:40:55 INFO - 'TERM': 'linux', 01:40:55 INFO - 'TMOUT': '86400', 01:40:55 INFO - 'USER': 'cltbld', 01:40:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524', 01:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:55 INFO - '_': '/tools/buildbot/bin/python'} 01:40:55 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/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 01:40:55 INFO - mozversion application_buildid: 20170414074709 01:40:55 INFO - mozversion application_changeset: 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 01:40:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:40:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:40:55 INFO - mozversion application_name: Firefox 01:40:55 INFO - mozversion application_remotingname: firefox-dev 01:40:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:40:55 INFO - mozversion application_vendor: Mozilla 01:40:55 INFO - mozversion application_version: 54.0a2 01:40:55 INFO - mozversion platform_buildid: 20170414074709 01:40:55 INFO - mozversion platform_changeset: 189ed7b2306fcf0a7683df6959b78fdb94bb34e7 01:40:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:40:55 INFO - mozversion platform_version: 54.0a2 01:40:55 INFO - using testdate: 1492159255 01:40:55 INFO - actual date: 1492159255 01:40:55 INFO - starting webserver on 'localhost:33188' 01:40:55 INFO - SUITE-START | Running 2 tests 01:40:55 INFO - TEST-START | dromaeo_css 01:40:55 INFO - operating with platform_type : linux_ 01:40:55 INFO - Initialising browser for dromaeo_css test... 01:40:55 INFO - TEST-INFO | started process 18074 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO0ucqd/profile http://localhost:33188/getInfo.html) 01:40:59 INFO - PROCESS | 18074 | __metrics Screen width/height:1600/1200 01:40:59 INFO - PROCESS | 18074 | colorDepth:24 01:40:59 INFO - PROCESS | 18074 | Browser inner width/height: 1024/697 01:40:59 INFO - PROCESS | 18074 | __metrics 01:41:00 INFO - PROCESS | 18074 | 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" 01:41:00 INFO - PROCESS | 18074 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:01 INFO - TEST-INFO | 18074: exit 0 01:41:01 INFO - Browser initialized. 01:41:01 INFO - Running cycle 1/1 for dromaeo_css test... 01:41:01 INFO - TEST-INFO | started process 18168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO0ucqd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:13 INFO - PROCESS | 18168 | RSS: Main: 139722752 01:41:13 INFO - PROCESS | 18168 | 01:44:14 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33188/tests/dromaeo/cssquery-ext.html) 01:44:15 INFO - PROCESS | 18168 | RSS: Main: 230567936 01:44:15 INFO - PROCESS | 18168 | 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | A coding exception was thrown and uncaught in a Task. 01:46:03 INFO - PROCESS | 18168 | 01:46:03 ERROR - PROCESS | 18168 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:03 INFO - PROCESS | 18168 | Full stack: 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | A coding exception was thrown and uncaught in a Task. 01:46:03 INFO - PROCESS | 18168 | 01:46:03 ERROR - PROCESS | 18168 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:03 INFO - PROCESS | 18168 | Full stack: 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | A coding exception was thrown and uncaught in a Task. 01:46:03 INFO - PROCESS | 18168 | 01:46:03 ERROR - PROCESS | 18168 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:03 INFO - PROCESS | 18168 | Full stack: 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | ************************* 01:46:03 INFO - PROCESS | 18168 | A coding exception was thrown and uncaught in a Task. 01:46:03 INFO - PROCESS | 18168 | 01:46:03 ERROR - PROCESS | 18168 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:03 INFO - PROCESS | 18168 | Full stack: 01:46:03 INFO - PROCESS | 18168 | ************************* 01:47:18 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-ext.html (next: http://localhost:33188/tests/dromaeo/cssquery-jquery.html) 01:47:18 INFO - PROCESS | 18168 | RSS: Main: 222842880 01:47:18 INFO - PROCESS | 18168 | 01:50:20 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33188/tests/dromaeo/cssquery-mootools.html) 01:50:21 INFO - PROCESS | 18168 | RSS: Main: 170430464 01:50:21 INFO - PROCESS | 18168 | 01:53:23 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33188/tests/dromaeo/cssquery-prototype.html) 01:53:23 INFO - PROCESS | 18168 | RSS: Main: 201633792 01:53:23 INFO - PROCESS | 18168 | 01:56:26 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33188/tests/dromaeo/cssquery-yui.html) 01:56:27 INFO - PROCESS | 18168 | RSS: Main: 178266112 01:56:27 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | Cycle 1(1): loaded http://localhost:33188/tests/dromaeo/cssquery-yui.html (next: http://localhost:33188/tests/dromaeo/cssquery-dojo.html) 01:59:29 INFO - PROCESS | 18168 | RSS: Main: 269156352 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | __start_tp_report 01:59:29 INFO - PROCESS | 18168 | _x_x_mozilla_page_load 01:59:29 INFO - PROCESS | 18168 | _x_x_mozilla_page_load_details 01:59:29 INFO - PROCESS | 18168 | |i|pagename|runs| 01:59:29 INFO - PROCESS | 18168 | |0;dojo.html;3382.2728113455605;3404.8127352995575;3416.9829150854234;3426.8629254829807;3463.8614455421784;5729.484346408823;5802.709864506774;5811.622244554101;5817.040188368914;5829.591928564992;5795.507381872535;5856.443637854408;5859;5860.150278209657;5862.267216597926;5170.793168273264;5190.922136167942;5192.792288308463;5253.4221235664045;5270.393904700951;5013.473585273548;5033.219850923113;5069.366329208849;5269.604779641523;5276.683398996053;5178.559822415081;5192.40287366952;5197.7920883164625;5215.035218484592;5228;5184.766685499161;5195.532402083812;5198.532132108128;5215.904660021362;5227.738613069369;4891.070696567641;4963.081829861487;5153;5160.974195129038;5170.844874653766;5629.3526244482;5635.436456354373;5669.461401166883;5676.1769543416385;5692.601517893749;6131.50947924165;6136.662483563402;6138.95637741585;6172.87654246917;6181.6600086995195;5981.461668449861;6172.105044768528;6185.938140618581;6186.814395568095;6190.938090619081;5694.601377903548;5740.397258287903;5741;5747.5114615257335;5772.942270577324;6319.374934639987;6342.302346741854;6344.841378965562;6354.650494222862;6363.936360636427;6071.908921366183;6124.234470691164;6133.815985520441;6137.202163718688;6137.202163718777;5963.2844058713235;5999.160117583542;6103.969480152571;6114.144019837227;6131.908021379683;6127.102022122884;6136.938630613726;6147.754089836446;6161.168242287234;6164.815055548341;5958.136070269787;5978.55160862937;6007.699615019232;6013.488853447506;6061.818145455643;4683.437987441461;4704.670673052853;4710.105080034782;4723.197056500403;4727.125481785881;4120.896977575585;4131.07050913542;4142.068034692231;4185.853495127656;4224.894377640522;5971.044632858721;6003.489703375262;6009.639421634629;6010.248718910137;6032.366601506867;6234.064890266496;6274.874502509924;6277.52918531112;6280.434760871543;6284.717187726564;5729.169270455761;5768.077107662753;5900.793472228452;6003.219581454383;6030.095485677183;5261.579073674096;5280.023195708806;5284.815031473957;5287.41838397776;5304.469553044664;6384.457321127663;6449.419552240227;6452.9677351612945;6453.45145662624;6454.19322584677;5935.109733539831;6072.908906366496;6239.251289845248;6244.219472565929;6252.781152659635;6266.874662506815;6305.684715764101;6325.652089135142;6384.712687929147;6393.680315984275;6082.300535438481;6086.178365920544;6091.695415229132;6092.360302168208;6099.993501072273;5941.821745347646;6180.938190618216;6181.258249009968;6187.319394866471;6190;5954.07711804671;5966.105084237399;6017.097435384728;6081.331053584014;6110.480609148272;6022.09668549721;6057.061155520902;6061.484773794277;6063.8180854572665;6069.635821850703;5837.270341207349;5850.23946886919;6061.787837425669;6069.696515174312;6069.878602427839;5920.200772895604;5959.046552551571;6007.909881351696;6019.217501724747;6020.729067192075;5929.495992840484;5936.020556608284;5936.940630593639;5937.406259374028;5940.55445841555 01:59:29 INFO - PROCESS | 18168 | |1;ext.html;742.5841528743904;792.3344390711803;812.2283830361155;847.3306088190326;860.7632900952234;30803.537946930785;43128.568714312845;43553;43586.12827743443;43595.602241876724;45355.31967020489;45639.54360456394;45641;45644.54355456444;45727.314090288586;7557.395408367317;7708.075030996289;9015.819683606323;9138;9145.634174633007;10500.369977801356;10703.518341674604;10704.30422022566;10710.78578428431;10712.25014249003;10790.406527640976;10828.187885908548;10829.29609575375;10838.67483975482;10840.512176952056;10740.194485413585;10748.032677059102;10778.676639700821;10791.6222932197;10805.243632945698;10594.046535811814;10599.205059620483;10635.57457701691;10645.361278323247;10646.201534884915;40786.59213407858;40969.38545921814;40974.180516389504;40988.18023639511;41038.38442423366;4160.068097236521;4183.1215444756635;4246.384274280212;4332.133573285356;4338.175746608134;29748;29922.700772992208;30077.69922300771;30327.696723032706;31185.37629247425;4238.258304796648;4295.935560966588;4306.160298741746;4321.416608757809;4435.179491794029;229937;230916;231169;231200;231274.84362578247;54466.45533544693;54688;54780.72609636926;54843.725781370835;54950.72524637351;56331.43668563344;56517.152242716395;56520;56639.15041274384;56644;16517.33930642785;16855.325786968628;16927.746083808754;16973.405930792364;16994.660106797794;11064.336139831636;11223.71940701489;14509.274536273144;15042.323095460511;15052.774208386883;13897.652558686113;13931.512397066053;13972.371243294076;14004.789928150882;14086.647833804236;5709.42905709434;5768.9711551442815;5782.797602083991;5836.824895253149;5871.530277577784;1007.5314978535016;1014.0721240065405;1018.2871989607305;1025.2771795884032;1028.2647906746713;28530.572041419397;28636.14091577256;28657.28356791097;28704.71295287062;28715.2821179468;1075.3171735947574;1084.4035780320792;1093.2729734726477;1094.1356328500556;1108.451316598289;14411.207383593794;15282.159481228638;15320.923395383175;15478.148701821283;15483.071015739091;285270;285295;285874;286550;287102;55532;55589.722051389486;55672.44327556754;55713.442865570825;55733;1489.4637930345068;1510.4939845151998;1536.2395614171057;1541.2678977485605;1544.6833399153147;26218;27483.725162748917;27846.582301265902;27865.86067069652;27999.580006299515;41822.37266440945;41845.58154418417;41856;41886.58113418949;41900.161996759285;261009;262223;262604;262940;263209;26206.213813585622;26588.8670556646;35555.466667999484;37299.62700372961;38178.2364352717;40599.39100913548;40717.1856562873;43518.34722479102;44282.11435771331;44345.113097737216;30858;31335;33657.83171084129;33731.325373491905;33736.325273494884;31034;31200.531992019685;31476.213094672818;31479.84260078685;31941.520877187326;35747.82126089353;35805.82097089498;35813.46279805857;36237.63762362448;36475;30061;30519.694803051687;30535;30637.693623063486;31268.37463250768;23731;24834.130805421722;24881.50236995286;25153.28846192243;25555 01:59:29 INFO - PROCESS | 18168 | |2;jquery.html;426.44775016353816;435.50352598038234;439.887828603706;446.190164850796;464.6561544457102;19259.711104333448;19394;20954.37136885892;21128.683069753926;21179.152833886645;20788.688169677465;22167.113315467403;22264.42412582556;22305.44236394091;22496.32511024672;6505.447037001849;6770.898436523456;6798.9660051699675;6810.829729256759;6816.147981502312;6975.895361569581;7235.855282894339;7248.2389349118375;7256.4920455568135;7265.636718164097;6994.195667498227;7349.044624198847;7363.5581865088;7392.926070739304;7406.185319614838;7423.4061275097865;7460.36586890115;7509;7562.432817538706;7592.810179745496;7260.237675044097;7352.5588464691755;7357.705691772323;7365.042544469238;7366.1160660720525;24759;24785.380365490826;25120.120795772244;25598.360040998938;25817.870910645514;3000.189948713835;3042.7870049096573;3051.3287076843058;3104.8913288035037;3149.10250578584;16364.099974501398;16539.91730041354;16640.916795416066;16646.916765416216;17273.309067637278;2267.807236384909;2279.0541925101065;2289.1873384948435;2305.342977251475;2313.5025969416656;66760.66619666919;88383.55808220917;107878.46060769724;110330;111307.99999999919;17098.75123726513;18127.18427670758;18257.908710456362;18487.445376638723;18550.165242563846;31507.684923150475;31757.682423175935;31789.8410507946;31933.361332773213;31973.52039719406;10781.784364312669;10969.945150274198;10973.561057557608;11012.923837577342;11164.106871450265;9248.676296329597;9259.101884113741;9319.580618872167;9485.241180705527;9487.952560237154;8871.423357481743;9352.485613291334;9376.48429336393;9378.531073446436;9447.33868629203;3564.928701426009;3766.548014238309;3805;3824.732268741217;3836.1037416750733;1163.959261425846;1173.5364531010202;1178.6640807369859;1182.8757980412106;1198.4666823263565;19085.904570477058;19948.401547953585;20251.898740506498;20432.284870029478;20496.69254961177;1156.8206927926185;1157.4961060158892;1167.8598568171703;1173.6713720158368;1178.1253740872244;11105.444727763579;11106.222564420576;11116.555337786398;11255.155863310285;11336.093112550976;625163;627146;629303;630734;646581;101344;105387;108316;111626;112446;1325.7320704463543;1348.3864841497095;1382.8962827787755;1390;1405.746965546191;18855.528611784815;19078.427647170607;19157.8084219156;19275.61448771045;19323.323683671006;37846.43230351492;42825.78587107169;47873;48195.27707084467;50691.74654126706;66942.6652866752;67932;67933;68558.65720671364;69502.30497695161;12051;12288.877111228774;12316.199447035913;12334.013278937842;12454;13890;14040.64898377508;14099.788503172256;14134.50529231472;14216.715665686836;11756.966869991546;11828.112891533341;11908.702282443008;11965.222260553037;12478.18891772032;11343.943280283545;11426.314421134759;11645.126615504041;11671.35807530594;11769.764604708029;6624.575716221036;10889.782204356026;11868.643940681794;12089.879101208875;12274.75450491003;10057.597696092367;10194.745131371776;10373.740656483347;10388.264370565232;10463.162946964072;10836.783264334512;11541.134414918746;11683.766324673628;12075.57735479254;12376.62870113898 01:59:29 INFO - PROCESS | 18168 | |3;mootools.html;1545.7681347797832;1576.5822057154849;1582;1601.5755824706455;1607.6463178100814;10392.636257730981;11106.833397499044;11108.055815255702;11115.221934464571;11118.49966751497;10817.350958942468;10878.61924832631;11208.607698730546;11215.327080375184;11216.719582010453;2411.879406029696;2439.377958620549;2448.7918526925146;2449.742777008415;2454.9509009819794;2319.1651005637955;2395.281415575329;2433.2821817563863;2437.4150203951012;2439.4145405102736;2309.007126935417;2342.1451170322794;2348.3189874936247;2431.027588964411;2432.464857731305;2226.4656482444097;2303.9769602304013;2307.9192228272013;2314.271004633535;2387.3554140382;2277.829162812779;2293.6444851048113;2297.5060362022077;2300.861948283108;2304.43541332373;10963.945180274128;11151.721206969809;11186.384748838811;11201.439928003567;11205.271657342246;5619.7471113799975;5758.3090029196765;5759.971200143972;5760.654360738369;5763.6541807491685;3443.6039855416448;3592.515010473579;3622.7645203061716;3645.598984111772;3671.834767435472;1344.757943570157;1362.1146254934272;1381.6062422209623;1394.504950742482;1422.5590067079238;15955.521334359988;16547.503574892653;16584.17079146038;16749.832501674948;17012.234449449803;4604.884877878013;4674.322223277673;4766.4995175506765;4804.8798780030775;4813.614910807127;12419.813702794465;12425.502979880885;12449.128561000824;12455;12504.81242781359;5860.70696465175;5865.061590145641;5870.706464676749;5872.677002764804;5881.500072493886;5247.212918062245;5368.892622147613;5375.489328513785;5466.671999680032;5470.72646367688;3890.533136023695;4015.5783642717092;4046.736962097454;4095.1195492969155;4116.073883376216;1027.9023492768174;1029.9975571209839;1031.5254982708038;1041.8749750030045;1044.8537204791335;1068.2362111090608;1079.2175672637431;1085.7014321061772;1087.5106202209038;1098.6813823991113;6830.692618832164;6839.452843772487;6845.726170953206;6955.826104347432;6973.860522789617;1022.1822541966397;1022.504085518523;1035.5805898611075;1046.26238501856;1062.3572738493256;4086.8773936781945;4736.265878788793;4746.786394612251;4803;4862.149123903303;14038.438462461592;14498.275086245645;14545.41818327255;14691.265436728121;14825.332860021324;14683.11901285905;14683.19242441676;14699;14706.705865882623;14728.337224824909;8005.199480051948;8190.017197936286;10041.849372259421;10238.692839214835;10623.575056997788;7121.145462544528;7238.710451582088;7274.090738657668;7340.926590734024;7349;12814.359282035675;12935.611931642066;12984.026198035373;13019.283939383426;13073.346332683137;12870.099093063396;13368.131071480322;13423.865761342262;13469.39387727535;13480.056396052181;8846.734597962071;8976.28189744832;9013.369064165445;9036.864447033431;9051.864222036544;10240.846387304346;10477.633282835064;10547.261691681508;10707.785844283226;10771.08445782086;9062.093790620886;9242.768930776785;9253.352265341631;9256.72229833106;9344.112309330603;9136.223421009288;9227.35408521397;9333.57998890038;9374.109459601326;9488.240940724587;7857.2928436441;7932.405069619686;7961.8407631848195;7963.323117535075;7974.362051036019;5897.587168898136;5985.730642121202;6045.153678484928;6115.755369785159;6233.376662333731;6303.338149494237;6333.8733225336155;6357.682115894279;6468.579542329733;6497.122888410004 01:59:29 INFO - PROCESS | 18168 | |4;prototype.html;403.4835410674334;406.0944094668888;416.13444036404275;416.209202515221;424.598823881192;5211.609129315298;5309.283246761686;5313.840584782462;5346.27825243592;5382.2464854920345;5072.568831649314;5169.508896654821;5180.533751962323;5190.963286477406;5193.221016847475;4328.506665200501;4342.913141737164;4348.347747837818;4356.956430435703;4398.406215160944;4402.669799765031;4417.779111044451;4421.226285400042;4423.911521769563;4425.557444255564;4261.339492378686;4371.431713877208;4380.262336039975;4392.1874453226255;4399.538048504909;4344.695871289011;4352.606066663338;4352.91294174118;4356.695031347791;4366.847160349373;4085.734427262248;4103.199876024177;4107.95247211962;4125.876223713263;4156.501219853637;5093.821716239951;5143.382794064736;5148.9485105149215;5153.6085256980405;5174.97412512935;5074.416442109131;5084.347587034229;5138.563222126124;5143.871403214912;5163.302954101186;5521.4478552144865;5543.722813859291;5558.416366281523;5569.498745112919;5584.274044374205;4471.575200355961;4581.473130589991;4615.238485649864;4620.607248383892;4623.537646235383;5532.5573954083975;5596.412376700469;5706.343770466449;5802.694393761412;5809.535237181015;4657.906841863144;4700.764961751899;4712.104700106968;4713.457952335457;4715.575598196178;4600.42494688164;4687.320338550891;4693.929591056137;4778.163821331323;4809.326694262806;5098.1588037973315;5105.310783044241;5107.821226257063;5118.334616499831;5152.4847515248175;5545.33639908034;5564.441956252256;5573.888522229533;5578.748956296977;5598.496135347838;1599.2483532739593;1649.0105936438042;1655.7847479827785;1661.55968668303;1669.8747593930505;1270.28863836252;1271.6456973323382;1282.1537785061814;1290.1420555330787;1305.112523484021;5440.564754819605;5457.2359869618285;5482.753276102585;5546.639468434539;5550.5282051026115;5595.433278681976;5682.318121825408;5726.255586773693;5739.827805165852;5783.8554036149435;5321.281626980308;5433.26650902131;5452.182172674131;5455.890882182334;5531.474509921551;2992.384112579183;2994.7304742573274;3002.789804713693;3005.9849700751793;3016.502277124294;5663.137708450692;5695.316562012585;5897.351291357947;5916.349201587822;5935.52515798735;5695.715214239354;5717.485426311652;5730.684812335361;5763.596548241583;5793.449622285876;5796.82609521715;5815.621984570989;5859.579888603111;5866.943950088939;5878.412158784087;5500.257465242141;5605.243292155506;5670.631408958405;5677.432256774289;5678.943210568007;5357.571394288526;5494.835154945358;5540.307461567304;5638.661680299195;5662.178984047372;5875.911861322169;5887.704704965072;5896.3514013457625;5934.732937017931;5940.61386009908;4623.283391074388;4662;4706.247000479907;4726.669133160645;4758;5622.578306626938;5648.350439699323;5680.886382272249;5714.171445140514;5718.6267698904485;5464.699441530754;5527.1258945349;5548.0845660465575;5587.44125587438;5590.273264475592;5195.1425417235005;5499;5583.96185392337;5603;5609.635373700697;5537.390887002507;5543.805966791143;5562.638428502134;5568.49883510486;5616.550675945996;5284.233786100917;5460.781568737365;5578.609497335147;5626.736492834229;5660.716964151694;5348.411674715856;5360.812371566976;5400.2439658447065;5423.647462914898;5455.399906010415 01:59:29 INFO - PROCESS | 18168 | |5;yui.html;860.2043110123135;868.4311775786858;915.617521589845;918.9218916392107;934.3319526538523;17168.3132674693;17495.125243737824;18028.459146225636;18279;18316.90841545792;14626.853731462683;14829.110253384804;15423.228838558056;16695.248713807876;16833.989960602343;850.3494826457764;870.1124852650292;872.7774417523523;875.7591662292857;896.847535918892;893.4148132972913;895.4269267668698;898.3172788680585;898.6091050393068;899.4468401932829;942.2838642631581;943.4056544377033;943.42451104826;944.4663764972803;944.5230158769812;935.6117211357279;937.142514599141;937.9906200937971;938.4228699349885;938.6808485115059;689.4174422612884;689.9586024838526;690.32693124204;690.3924546399137;690.3959035843637;17575.912120439443;17962.5509362267;18002.54993625157;18042.729359059493;18241.17914693842;16635.750463743054;17273.22270497818;17302.91348543262;17523.474295771146;18201.63596728058;13565.389557469938;13998.30008499571;14020;14110.576682699433;14131.788023179557;4112.09533902541;4131.710780245384;4135.6898232632675;4199.223143718391;4236.04688944988;85354.57322713408;85875;86863.13136868677;88039;89814;13147.408366623526;13449;13602.795958060438;13617.182969021891;13660.385282662304;27216.727832721816;27347.45305093887;27598.310042249104;27677;27701.30746731348;8481.406301558955;8581.141885811368;8657.134286571292;8883.17922212426;9011.234045106108;4987.177115775857;5013.674111182761;5041.621878359138;5074.543291103819;5194.4805519447755;3457.083872773717;3527.8236088195486;3535.6994655454573;3561;3566.518519999767;622.2968046107869;623.5510432488601;630.9179806625201;631.1574048645018;639.8080575827233;684.3806355248508;693.8334799648048;710.5843081797103;714.653393104344;730.9926900731031;10559.524821383056;10607.363558186533;10819.945900270448;10870.456477176109;10939.179561532917;738.1216352540459;747.4805010517747;755.8223817402906;756.6027835386466;789.8025493626593;5074.876034700412;5148.845534633967;5158.226266060046;5205.947940520547;5216.504432078947;81343;83867;84145;85272.57363713141;86298.56850715706;70876;70929.64535177394;72663.63668181625;75038.24961750422;75496.62251688706;55365.99999999919;65426.67286663536;65712.7143642816;66047.33952660508;66729.33270667329;12003.1597788154;12657.240565566095;12702.428390722254;12752.234865908074;12923.870761292266;38822.029449263995;39776;40825.387619185145;42543.57456425396;42861.571384287;56320.436795633155;57002.42997570137;57190;58500.707496462244;59233;13409.798853017017;13797.310134493035;13982.790258146339;14026.509072182425;14148.504802331869;15836.208189590245;15986;16011.679766404839;16149;16302.429414970418;15440.768388473956;15653.843461565239;15854.682906341577;16012.759808602646;16110.275037623569;15137.243137843285;15363.61590960235;15512.457064002705;16403.261853216394;16432.342706291616;14449.277536123362;15120.924395378393;15490.380384784483;15994.840051599334;16055.67888642244;9963.651272205438;9970;10123.696289111338;10492.370457772558;10844;11905.642830715093;12214.816777748163;12307.323097229739;12434.875651243372;12540.87459125397 01:59:29 INFO - PROCESS | 18168 | __end_tp_report 01:59:29 INFO - PROCESS | 18168 | __start_cc_report 01:59:29 INFO - PROCESS | 18168 | _x_x_mozilla_cycle_collect,1329 01:59:29 INFO - PROCESS | 18168 | __end_cc_report 01:59:29 INFO - PROCESS | 18168 | __startTimestamp1492160369638__endTimestamp 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | ------- Summary: start ------- 01:59:29 INFO - PROCESS | 18168 | Number of tests: 6 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#0] dojo.html Cycles:165 Average:5718.43 Median:5966.11 stddev:656.14 (11.0%) stddev-sans-first:632.04 01:59:29 INFO - PROCESS | 18168 | Values: 3382.3 3404.8 3417.0 3426.9 3463.9 5729.5 5802.7 5811.6 5817.0 5829.6 5795.5 5856.4 5859.0 5860.2 5862.3 5170.8 5190.9 5192.8 5253.4 5270.4 5013.5 5033.2 5069.4 5269.6 5276.7 5178.6 5192.4 5197.8 5215.0 5228.0 5184.8 5195.5 5198.5 5215.9 5227.7 4891.1 4963.1 5153.0 5161.0 5170.8 5629.4 5635.4 5669.5 5676.2 5692.6 6131.5 6136.7 6139.0 6172.9 6181.7 5981.5 6172.1 6185.9 6186.8 6190.9 5694.6 5740.4 5741.0 5747.5 5772.9 6319.4 6342.3 6344.8 6354.7 6363.9 6071.9 6124.2 6133.8 6137.2 6137.2 5963.3 5999.2 6104.0 6114.1 6131.9 6127.1 6136.9 6147.8 6161.2 6164.8 5958.1 5978.6 6007.7 6013.5 6061.8 4683.4 4704.7 4710.1 4723.2 4727.1 4120.9 4131.1 4142.1 4185.9 4224.9 5971.0 6003.5 6009.6 6010.2 6032.4 6234.1 6274.9 6277.5 6280.4 6284.7 5729.2 5768.1 5900.8 6003.2 6030.1 5261.6 5280.0 5284.8 5287.4 5304.5 6384.5 6449.4 6453.0 6453.5 6454.2 5935.1 6072.9 6239.3 6244.2 6252.8 6266.9 6305.7 6325.7 6384.7 6393.7 6082.3 6086.2 6091.7 6092.4 6100.0 5941.8 6180.9 6181.3 6187.3 6190.0 5954.1 5966.1 6017.1 6081.3 6110.5 6022.1 6057.1 6061.5 6063.8 6069.6 5837.3 5850.2 6061.8 6069.7 6069.9 5920.2 5959.0 6007.9 6019.2 6020.7 5929.5 5936.0 5936.9 5937.4 5940.6 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#1] ext.html Cycles:180 Average:43405.24 Median:30202.70 stddev:67781.49 (224.4%) stddev-sans-first:67895.94 01:59:29 INFO - PROCESS | 18168 | Values: 742.6 792.3 812.2 847.3 860.8 30803.5 43128.6 43553.0 43586.1 43595.6 45355.3 45639.5 45641.0 45644.5 45727.3 7557.4 7708.1 9015.8 9138.0 9145.6 10500.4 10703.5 10704.3 10710.8 10712.3 10790.4 10828.2 10829.3 10838.7 10840.5 10740.2 10748.0 10778.7 10791.6 10805.2 10594.0 10599.2 10635.6 10645.4 10646.2 40786.6 40969.4 40974.2 40988.2 41038.4 4160.1 4183.1 4246.4 4332.1 4338.2 29748.0 29922.7 30077.7 30327.7 31185.4 4238.3 4295.9 4306.2 4321.4 4435.2 229937.0 230916.0 231169.0 231200.0 231274.8 54466.5 54688.0 54780.7 54843.7 54950.7 56331.4 56517.2 56520.0 56639.2 56644.0 16517.3 16855.3 16927.7 16973.4 16994.7 11064.3 11223.7 14509.3 15042.3 15052.8 13897.7 13931.5 13972.4 14004.8 14086.6 5709.4 5769.0 5782.8 5836.8 5871.5 1007.5 1014.1 1018.3 1025.3 1028.3 28530.6 28636.1 28657.3 28704.7 28715.3 1075.3 1084.4 1093.3 1094.1 1108.5 14411.2 15282.2 15320.9 15478.1 15483.1 285270.0 285295.0 285874.0 286550.0 287102.0 55532.0 55589.7 55672.4 55713.4 55733.0 1489.5 1510.5 1536.2 1541.3 1544.7 26218.0 27483.7 27846.6 27865.9 27999.6 41822.4 41845.6 41856.0 41886.6 41900.2 261009.0 262223.0 262604.0 262940.0 263209.0 26206.2 26588.9 35555.5 37299.6 38178.2 40599.4 40717.2 43518.3 44282.1 44345.1 30858.0 31335.0 33657.8 33731.3 33736.3 31034.0 31200.5 31476.2 31479.8 31941.5 35747.8 35805.8 35813.5 36237.6 36475.0 30061.0 30519.7 30535.0 30637.7 31268.4 23731.0 24834.1 24881.5 25153.3 25555.0 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#2] jquery.html Cycles:180 Average:36011.81 Median:21041.53 stddev:103867.45 (493.6%) stddev-sans-first:104124.46 01:59:29 INFO - PROCESS | 18168 | Values: 426.4 435.5 439.9 446.2 464.7 19259.7 19394.0 20954.4 21128.7 21179.2 20788.7 22167.1 22264.4 22305.4 22496.3 6505.4 6770.9 6799.0 6810.8 6816.1 6975.9 7235.9 7248.2 7256.5 7265.6 6994.2 7349.0 7363.6 7392.9 7406.2 7423.4 7460.4 7509.0 7562.4 7592.8 7260.2 7352.6 7357.7 7365.0 7366.1 24759.0 24785.4 25120.1 25598.4 25817.9 3000.2 3042.8 3051.3 3104.9 3149.1 16364.1 16539.9 16640.9 16646.9 17273.3 2267.8 2279.1 2289.2 2305.3 2313.5 66760.7 88383.6 107878.5 110330.0 111308.0 17098.8 18127.2 18257.9 18487.4 18550.2 31507.7 31757.7 31789.8 31933.4 31973.5 10781.8 10969.9 10973.6 11012.9 11164.1 9248.7 9259.1 9319.6 9485.2 9488.0 8871.4 9352.5 9376.5 9378.5 9447.3 3564.9 3766.5 3805.0 3824.7 3836.1 1164.0 1173.5 1178.7 1182.9 1198.5 19085.9 19948.4 20251.9 20432.3 20496.7 1156.8 1157.5 1167.9 1173.7 1178.1 11105.4 11106.2 11116.6 11255.2 11336.1 625163.0 627146.0 629303.0 630734.0 646581.0 101344.0 105387.0 108316.0 111626.0 112446.0 1325.7 1348.4 1382.9 1390.0 1405.7 18855.5 19078.4 19157.8 19275.6 19323.3 37846.4 42825.8 47873.0 48195.3 50691.7 66942.7 67932.0 67933.0 68558.7 69502.3 12051.0 12288.9 12316.2 12334.0 12454.0 13890.0 14040.6 14099.8 14134.5 14216.7 11757.0 11828.1 11908.7 11965.2 12478.2 11343.9 11426.3 11645.1 11671.4 11769.8 6624.6 10889.8 11868.6 12089.9 12274.8 10057.6 10194.7 10373.7 10388.3 10463.2 10836.8 11541.1 11683.8 12075.6 12376.6 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#3] mootools.html Cycles:180 Average:6991.13 Median:2367.84 stddev:4453.17 (188.1%) stddev-sans-first:4446.86 01:59:29 INFO - PROCESS | 18168 | Values: 1545.8 1576.6 1582.0 1601.6 1607.6 10392.6 11106.8 11108.1 11115.2 11118.5 10817.4 10878.6 11208.6 11215.3 11216.7 2411.9 2439.4 2448.8 2449.7 2455.0 2319.2 2395.3 2433.3 2437.4 2439.4 2309.0 2342.1 2348.3 2431.0 2432.5 2226.5 2304.0 2307.9 2314.3 2387.4 2277.8 2293.6 2297.5 2300.9 2304.4 10963.9 11151.7 11186.4 11201.4 11205.3 5619.7 5758.3 5760.0 5760.7 5763.7 3443.6 3592.5 3622.8 3645.6 3671.8 1344.8 1362.1 1381.6 1394.5 1422.6 15955.5 16547.5 16584.2 16749.8 17012.2 4604.9 4674.3 4766.5 4804.9 4813.6 12419.8 12425.5 12449.1 12455.0 12504.8 5860.7 5865.1 5870.7 5872.7 5881.5 5247.2 5368.9 5375.5 5466.7 5470.7 3890.5 4015.6 4046.7 4095.1 4116.1 1027.9 1030.0 1031.5 1041.9 1044.9 1068.2 1079.2 1085.7 1087.5 1098.7 6830.7 6839.5 6845.7 6955.8 6973.9 1022.2 1022.5 1035.6 1046.3 1062.4 4086.9 4736.3 4746.8 4803.0 4862.1 14038.4 14498.3 14545.4 14691.3 14825.3 14683.1 14683.2 14699.0 14706.7 14728.3 8005.2 8190.0 10041.8 10238.7 10623.6 7121.1 7238.7 7274.1 7340.9 7349.0 12814.4 12935.6 12984.0 13019.3 13073.3 12870.1 13368.1 13423.9 13469.4 13480.1 8846.7 8976.3 9013.4 9036.9 9051.9 10240.8 10477.6 10547.3 10707.8 10771.1 9062.1 9242.8 9253.4 9256.7 9344.1 9136.2 9227.4 9333.6 9374.1 9488.2 7857.3 7932.4 7961.8 7963.3 7974.4 5897.6 5985.7 6045.2 6115.8 6233.4 6303.3 6333.9 6357.7 6468.6 6497.1 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#4] prototype.html Cycles:180 Average:4823.23 Median:5333.78 stddev:1286.98 (24.1%) stddev-sans-first:1247.11 01:59:29 INFO - PROCESS | 18168 | Values: 403.5 406.1 416.1 416.2 424.6 5211.6 5309.3 5313.8 5346.3 5382.2 5072.6 5169.5 5180.5 5191.0 5193.2 4328.5 4342.9 4348.3 4357.0 4398.4 4402.7 4417.8 4421.2 4423.9 4425.6 4261.3 4371.4 4380.3 4392.2 4399.5 4344.7 4352.6 4352.9 4356.7 4366.8 4085.7 4103.2 4108.0 4125.9 4156.5 5093.8 5143.4 5148.9 5153.6 5175.0 5074.4 5084.3 5138.6 5143.9 5163.3 5521.4 5543.7 5558.4 5569.5 5584.3 4471.6 4581.5 4615.2 4620.6 4623.5 5532.6 5596.4 5706.3 5802.7 5809.5 4657.9 4700.8 4712.1 4713.5 4715.6 4600.4 4687.3 4693.9 4778.2 4809.3 5098.2 5105.3 5107.8 5118.3 5152.5 5545.3 5564.4 5573.9 5578.7 5598.5 1599.2 1649.0 1655.8 1661.6 1669.9 1270.3 1271.6 1282.2 1290.1 1305.1 5440.6 5457.2 5482.8 5546.6 5550.5 5595.4 5682.3 5726.3 5739.8 5783.9 5321.3 5433.3 5452.2 5455.9 5531.5 2992.4 2994.7 3002.8 3006.0 3016.5 5663.1 5695.3 5897.4 5916.3 5935.5 5695.7 5717.5 5730.7 5763.6 5793.4 5796.8 5815.6 5859.6 5866.9 5878.4 5500.3 5605.2 5670.6 5677.4 5678.9 5357.6 5494.8 5540.3 5638.7 5662.2 5875.9 5887.7 5896.4 5934.7 5940.6 4623.3 4662.0 4706.2 4726.7 4758.0 5622.6 5648.4 5680.9 5714.2 5718.6 5464.7 5527.1 5548.1 5587.4 5590.3 5195.1 5499.0 5584.0 5603.0 5609.6 5537.4 5543.8 5562.6 5568.5 5616.6 5284.2 5460.8 5578.6 5626.7 5660.7 5348.4 5360.8 5400.2 5423.6 5455.4 01:59:29 INFO - PROCESS | 18168 | 01:59:29 INFO - PROCESS | 18168 | [#5] yui.html Cycles:180 Average:19098.43 Median:23389.81 stddev:23757.44 (101.6%) stddev-sans-first:23784.61 01:59:29 INFO - PROCESS | 18168 | Values: 860.2 868.4 915.6 918.9 934.3 17168.3 17495.1 18028.5 18279.0 18316.9 14626.9 14829.1 15423.2 16695.2 16834.0 850.3 870.1 872.8 875.8 896.8 893.4 895.4 898.3 898.6 899.4 942.3 943.4 943.4 944.5 944.5 935.6 937.1 938.0 938.4 938.7 689.4 690.0 690.3 690.4 690.4 17575.9 17962.6 18002.5 18042.7 18241.2 16635.8 17273.2 17302.9 17523.5 18201.6 13565.4 13998.3 14020.0 14110.6 14131.8 4112.1 4131.7 4135.7 4199.2 4236.0 85354.6 85875.0 86863.1 88039.0 89814.0 13147.4 13449.0 13602.8 13617.2 13660.4 27216.7 27347.5 27598.3 27677.0 27701.3 8481.4 8581.1 8657.1 8883.2 9011.2 4987.2 5013.7 5041.6 5074.5 5194.5 3457.1 3527.8 3535.7 3561.0 3566.5 622.3 623.6 630.9 631.2 639.8 684.4 693.8 710.6 714.7 731.0 10559.5 10607.4 10819.9 10870.5 10939.2 738.1 747.5 755.8 756.6 789.8 5074.9 5148.8 5158.2 5205.9 5216.5 81343.0 83867.0 84145.0 85272.6 86298.6 70876.0 70929.6 72663.6 75038.2 75496.6 55366.0 65426.7 65712.7 66047.3 66729.3 12003.2 12657.2 12702.4 12752.2 12923.9 38822.0 39776.0 40825.4 42543.6 42861.6 56320.4 57002.4 57190.0 58500.7 59233.0 13409.8 13797.3 13982.8 14026.5 14148.5 15836.2 15986.0 16011.7 16149.0 16302.4 15440.8 15653.8 15854.7 16012.8 16110.3 15137.2 15363.6 15512.5 16403.3 16432.3 14449.3 15120.9 15490.4 15994.8 16055.7 9963.7 9970.0 10123.7 10492.4 10844.0 11905.6 12214.8 12307.3 12434.9 12540.9 01:59:29 INFO - PROCESS | 18168 | -------- Summary: end -------- 01:59:29 INFO - PROCESS | 18168 | 01:59:30 INFO - TEST-INFO | 18168: exit 0 01:59:30 INFO - TEST-OK | dromaeo_css | took 1115189ms 01:59:30 INFO - TEST-START | kraken 01:59:30 INFO - operating with platform_type : linux_ 01:59:30 INFO - Initialising browser for kraken test... 01:59:30 INFO - TEST-INFO | started process 18491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBhV06v/profile http://localhost:33188/getInfo.html) 01:59:34 INFO - PROCESS | 18491 | __metrics Screen width/height:1600/1200 01:59:34 INFO - PROCESS | 18491 | colorDepth:24 01:59:34 INFO - PROCESS | 18491 | Browser inner width/height: 1024/697 01:59:34 INFO - PROCESS | 18491 | __metrics 01:59:35 INFO - PROCESS | 18491 | 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" 01:59:35 INFO - PROCESS | 18491 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:59:36 INFO - TEST-INFO | 18491: exit 0 01:59:36 INFO - Browser initialized. 01:59:36 INFO - Running cycle 1/1 for kraken test... 01:59:36 INFO - TEST-INFO | started process 18583 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBhV06v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:48 INFO - PROCESS | 18583 | RSS: Main: 139878400 01:59:48 INFO - PROCESS | 18583 | 01:59:51 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33188/tests/kraken/driver.html?testName=audio-beat-detection) 01:59:51 INFO - PROCESS | 18583 | RSS: Main: 183365632 01:59:51 INFO - PROCESS | 18583 | 01:59:54 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33188/tests/kraken/driver.html?testName=audio-dft) 01:59:54 INFO - PROCESS | 18583 | RSS: Main: 174145536 01:59:54 INFO - PROCESS | 18583 | 01:59:57 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33188/tests/kraken/driver.html?testName=audio-fft) 01:59:58 INFO - PROCESS | 18583 | RSS: Main: 265695232 01:59:58 INFO - PROCESS | 18583 | 02:00:00 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33188/tests/kraken/driver.html?testName=audio-oscillator) 02:00:01 INFO - PROCESS | 18583 | RSS: Main: 171737088 02:00:01 INFO - PROCESS | 18583 | 02:00:03 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33188/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:00:03 INFO - PROCESS | 18583 | RSS: Main: 174137344 02:00:03 INFO - PROCESS | 18583 | 02:00:06 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33188/tests/kraken/driver.html?testName=imaging-darkroom) 02:00:07 INFO - PROCESS | 18583 | RSS: Main: 212213760 02:00:07 INFO - PROCESS | 18583 | 02:00:10 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33188/tests/kraken/driver.html?testName=imaging-desaturate) 02:00:11 INFO - PROCESS | 18583 | RSS: Main: 212176896 02:00:11 INFO - PROCESS | 18583 | 02:00:13 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33188/tests/kraken/driver.html?testName=json-parse-financial) 02:00:14 INFO - PROCESS | 18583 | RSS: Main: 210579456 02:00:14 INFO - PROCESS | 18583 | 02:00:16 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33188/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:00:16 INFO - PROCESS | 18583 | RSS: Main: 172470272 02:00:16 INFO - PROCESS | 18583 | 02:00:18 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:00:18 INFO - PROCESS | 18583 | RSS: Main: 179703808 02:00:18 INFO - PROCESS | 18583 | 02:00:21 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:00:21 INFO - PROCESS | 18583 | RSS: Main: 184324096 02:00:21 INFO - PROCESS | 18583 | 02:00:24 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:00:24 INFO - PROCESS | 18583 | RSS: Main: 179953664 02:00:24 INFO - PROCESS | 18583 | 02:00:27 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:00:28 INFO - PROCESS | 18583 | RSS: Main: 183787520 02:00:28 INFO - PROCESS | 18583 | 02:00:29 INFO - PROCESS | 18583 | Cycle 1(1): loaded http://localhost:33188/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33188/tests/kraken/driver.html?testName=ai-astar) 02:00:30 INFO - PROCESS | 18583 | RSS: Main: 236421120 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | __start_tp_report 02:00:30 INFO - PROCESS | 18583 | _x_x_mozilla_page_load 02:00:30 INFO - PROCESS | 18583 | _x_x_mozilla_page_load_details 02:00:30 INFO - PROCESS | 18583 | |i|pagename|runs| 02:00:30 INFO - PROCESS | 18583 | |0;ai-astar;105;111;113;111;114;113;112;113;111;112 02:00:30 INFO - PROCESS | 18583 | |1;audio-beat-detection;143;115;116;156;115;115;115;115;139;115 02:00:30 INFO - PROCESS | 18583 | |2;audio-dft;161;162;160;161;162;161;161;161;161;162 02:00:30 INFO - PROCESS | 18583 | |3;audio-fft;76;76;75;112;76;87;76;79;94;74 02:00:30 INFO - PROCESS | 18583 | |4;audio-oscillator;82;82;108;82;82;82;105;82;82;104 02:00:30 INFO - PROCESS | 18583 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;112;112 02:00:30 INFO - PROCESS | 18583 | |6;imaging-darkroom;147;148;146;146;146;145;145;147;146;145 02:00:30 INFO - PROCESS | 18583 | |7;imaging-desaturate;95;95;94;95;94;94;94;94;94;94 02:00:30 INFO - PROCESS | 18583 | |8;json-parse-financial;64;53;54;54;54;55;55;53;56;53 02:00:30 INFO - PROCESS | 18583 | |9;json-stringify-tinderbox;52;52;52;53;54;52;52;53;53;53 02:00:30 INFO - PROCESS | 18583 | |10;stanford-crypto-aes;84;74;80;85;79;84;79;85;84;103 02:00:30 INFO - PROCESS | 18583 | |11;stanford-crypto-ccm;121;128;130;122;137;127;119;118;148;120 02:00:30 INFO - PROCESS | 18583 | |12;stanford-crypto-pbkdf2;159;164;167;163;159;159;157;160;160;159 02:00:30 INFO - PROCESS | 18583 | |13;stanford-crypto-sha256-iterative;64;64;59;60;64;60;63;60;60;64 02:00:30 INFO - PROCESS | 18583 | __end_tp_report 02:00:30 INFO - PROCESS | 18583 | __start_cc_report 02:00:30 INFO - PROCESS | 18583 | _x_x_mozilla_cycle_collect,1667 02:00:30 INFO - PROCESS | 18583 | __end_cc_report 02:00:30 INFO - PROCESS | 18583 | __startTimestamp1492160430124__endTimestamp 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | ------- Summary: start ------- 02:00:30 INFO - PROCESS | 18583 | Number of tests: 14 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.50 stddev:2.51 (2.2%) stddev-sans-first:1.09 02:00:30 INFO - PROCESS | 18583 | Values: 105.0 111.0 113.0 111.0 114.0 113.0 112.0 113.0 111.0 112.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:115.50 stddev:15.49 (13.4%) stddev-sans-first:14.89 02:00:30 INFO - PROCESS | 18583 | Values: 143.0 115.0 116.0 156.0 115.0 115.0 115.0 115.0 139.0 115.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 02:00:30 INFO - PROCESS | 18583 | Values: 161.0 162.0 160.0 161.0 162.0 161.0 161.0 161.0 161.0 162.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:12.17 (16.0%) stddev-sans-first:12.68 02:00:30 INFO - PROCESS | 18583 | Values: 76.0 76.0 75.0 112.0 76.0 87.0 76.0 79.0 94.0 74.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:82.00 stddev:11.47 (14.0%) stddev-sans-first:11.88 02:00:30 INFO - PROCESS | 18583 | Values: 82.0 82.0 108.0 82.0 82.0 82.0 105.0 82.0 82.0 104.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:00:30 INFO - PROCESS | 18583 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 112.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 02:00:30 INFO - PROCESS | 18583 | Values: 147.0 148.0 146.0 146.0 146.0 145.0 145.0 147.0 146.0 145.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 02:00:30 INFO - PROCESS | 18583 | Values: 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:54.50 stddev:3.28 (6.0%) stddev-sans-first:1.05 02:00:30 INFO - PROCESS | 18583 | Values: 64.0 53.0 54.0 54.0 54.0 55.0 55.0 53.0 56.0 53.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#9] json-stringify-tinderbox Cycles:10 Average:52.60 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 02:00:30 INFO - PROCESS | 18583 | Values: 52.0 52.0 52.0 53.0 54.0 52.0 52.0 53.0 53.0 53.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:7.66 (9.1%) stddev-sans-first:8.12 02:00:30 INFO - PROCESS | 18583 | Values: 84.0 74.0 80.0 85.0 79.0 84.0 79.0 85.0 84.0 103.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:127.50 stddev:9.46 (7.4%) stddev-sans-first:9.79 02:00:30 INFO - PROCESS | 18583 | Values: 121.0 128.0 130.0 122.0 137.0 127.0 119.0 118.0 148.0 120.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:160.00 stddev:3.02 (1.9%) stddev-sans-first:3.14 02:00:30 INFO - PROCESS | 18583 | Values: 159.0 164.0 167.0 163.0 159.0 159.0 157.0 160.0 160.0 159.0 02:00:30 INFO - PROCESS | 18583 | 02:00:30 INFO - PROCESS | 18583 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:63.50 stddev:2.15 (3.4%) stddev-sans-first:2.13 02:00:30 INFO - PROCESS | 18583 | Values: 64.0 64.0 59.0 60.0 64.0 60.0 63.0 60.0 60.0 64.0 02:00:30 INFO - PROCESS | 18583 | -------- Summary: end -------- 02:00:30 INFO - PROCESS | 18583 | 02:00:31 INFO - TEST-INFO | 18583: exit 0 02:00:31 INFO - TEST-OK | kraken | took 60797ms 02:00:31 INFO - SUITE-END | took 1175s 02:00:31 INFO - Completed test suite (00:19:36) 02:00:31 INFO - javascript benchmark 02:00:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7821.9605998698735, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.2728113455605, 3404.8127352995575, 3416.9829150854234, 3426.8629254829807, 3463.8614455421784, 5729.484346408823, 5802.709864506774, 5811.622244554101, 5817.040188368914, 5829.591928564992, 5795.507381872535, 5856.443637854408, 5859.0, 5860.150278209657, 5862.267216597926, 5170.793168273264, 5190.922136167942, 5192.792288308463, 5253.4221235664045, 5270.393904700951, 5013.473585273548, 5033.219850923113, 5069.366329208849, 5269.604779641523, 5276.683398996053, 5178.559822415081, 5192.40287366952, 5197.7920883164625, 5215.035218484592, 5228.0, 5184.766685499161, 5195.532402083812, 5198.532132108128, 5215.904660021362, 5227.738613069369, 4891.070696567641, 4963.081829861487, 5153.0, 5160.974195129038, 5170.844874653766, 5629.3526244482, 5635.436456354373, 5669.461401166883, 5676.1769543416385, 5692.601517893749, 6131.50947924165, 6136.662483563402, 6138.95637741585, 6172.87654246917, 6181.6600086995195, 5981.461668449861, 6172.105044768528, 6185.938140618581, 6186.814395568095, 6190.938090619081, 5694.601377903548, 5740.397258287903, 5741.0, 5747.5114615257335, 5772.942270577324, 6319.374934639987, 6342.302346741854, 6344.841378965562, 6354.650494222862, 6363.936360636427, 6071.908921366183, 6124.234470691164, 6133.815985520441, 6137.202163718688, 6137.202163718777, 5963.2844058713235, 5999.160117583542, 6103.969480152571, 6114.144019837227, 6131.908021379683, 6127.102022122884, 6136.938630613726, 6147.754089836446, 6161.168242287234, 6164.815055548341, 5958.136070269787, 5978.55160862937, 6007.699615019232, 6013.488853447506, 6061.818145455643, 4683.437987441461, 4704.670673052853, 4710.105080034782, 4723.197056500403, 4727.125481785881, 4120.896977575585, 4131.07050913542, 4142.068034692231, 4185.853495127656, 4224.894377640522, 5971.044632858721, 6003.489703375262, 6009.639421634629, 6010.248718910137, 6032.366601506867, 6234.064890266496, 6274.874502509924, 6277.52918531112, 6280.434760871543, 6284.717187726564, 5729.169270455761, 5768.077107662753, 5900.793472228452, 6003.219581454383, 6030.095485677183, 5261.579073674096, 5280.023195708806, 5284.815031473957, 5287.41838397776, 5304.469553044664, 6384.457321127663, 6449.419552240227, 6452.9677351612945, 6453.45145662624, 6454.19322584677, 5935.109733539831, 6072.908906366496, 6239.251289845248, 6244.219472565929, 6252.781152659635, 6266.874662506815, 6305.684715764101, 6325.652089135142, 6384.712687929147, 6393.680315984275, 6082.300535438481, 6086.178365920544, 6091.695415229132, 6092.360302168208, 6099.993501072273, 5941.821745347646, 6180.938190618216, 6181.258249009968, 6187.319394866471, 6190.0, 5954.07711804671, 5966.105084237399, 6017.097435384728, 6081.331053584014, 6110.480609148272, 6022.09668549721, 6057.061155520902, 6061.484773794277, 6063.8180854572665, 6069.635821850703, 5837.270341207349, 5850.23946886919, 6061.787837425669, 6069.696515174312, 6069.878602427839, 5920.200772895604, 5959.046552551571, 6007.909881351696, 6019.217501724747, 6020.729067192075, 5929.495992840484, 5936.020556608284, 5936.940630593639, 5937.406259374028, 5940.55445841555], "value": 5674.388664312291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.5841528743904, 792.3344390711803, 812.2283830361155, 847.3306088190326, 860.7632900952234, 30803.537946930785, 43128.568714312845, 43553.0, 43586.12827743443, 43595.602241876724, 45355.31967020489, 45639.54360456394, 45641.0, 45644.54355456444, 45727.314090288586, 7557.395408367317, 7708.075030996289, 9015.819683606323, 9138.0, 9145.634174633007, 10500.369977801356, 10703.518341674604, 10704.30422022566, 10710.78578428431, 10712.25014249003, 10790.406527640976, 10828.187885908548, 10829.29609575375, 10838.67483975482, 10840.512176952056, 10740.194485413585, 10748.032677059102, 10778.676639700821, 10791.6222932197, 10805.243632945698, 10594.046535811814, 10599.205059620483, 10635.57457701691, 10645.361278323247, 10646.201534884915, 40786.59213407858, 40969.38545921814, 40974.180516389504, 40988.18023639511, 41038.38442423366, 4160.068097236521, 4183.1215444756635, 4246.384274280212, 4332.133573285356, 4338.175746608134, 29748.0, 29922.700772992208, 30077.69922300771, 30327.696723032706, 31185.37629247425, 4238.258304796648, 4295.935560966588, 4306.160298741746, 4321.416608757809, 4435.179491794029, 229937.0, 230916.0, 231169.0, 231200.0, 231274.84362578247, 54466.45533544693, 54688.0, 54780.72609636926, 54843.725781370835, 54950.72524637351, 56331.43668563344, 56517.152242716395, 56520.0, 56639.15041274384, 56644.0, 16517.33930642785, 16855.325786968628, 16927.746083808754, 16973.405930792364, 16994.660106797794, 11064.336139831636, 11223.71940701489, 14509.274536273144, 15042.323095460511, 15052.774208386883, 13897.652558686113, 13931.512397066053, 13972.371243294076, 14004.789928150882, 14086.647833804236, 5709.42905709434, 5768.9711551442815, 5782.797602083991, 5836.824895253149, 5871.530277577784, 1007.5314978535016, 1014.0721240065405, 1018.2871989607305, 1025.2771795884032, 1028.2647906746713, 28530.572041419397, 28636.14091577256, 28657.28356791097, 28704.71295287062, 28715.2821179468, 1075.3171735947574, 1084.4035780320792, 1093.2729734726477, 1094.1356328500556, 1108.451316598289, 14411.207383593794, 15282.159481228638, 15320.923395383175, 15478.148701821283, 15483.071015739091, 285270.0, 285295.0, 285874.0, 286550.0, 287102.0, 55532.0, 55589.722051389486, 55672.44327556754, 55713.442865570825, 55733.0, 1489.4637930345068, 1510.4939845151998, 1536.2395614171057, 1541.2678977485605, 1544.6833399153147, 26218.0, 27483.725162748917, 27846.582301265902, 27865.86067069652, 27999.580006299515, 41822.37266440945, 41845.58154418417, 41856.0, 41886.58113418949, 41900.161996759285, 261009.0, 262223.0, 262604.0, 262940.0, 263209.0, 26206.213813585622, 26588.8670556646, 35555.466667999484, 37299.62700372961, 38178.2364352717, 40599.39100913548, 40717.1856562873, 43518.34722479102, 44282.11435771331, 44345.113097737216, 30858.0, 31335.0, 33657.83171084129, 33731.325373491905, 33736.325273494884, 31034.0, 31200.531992019685, 31476.213094672818, 31479.84260078685, 31941.520877187326, 35747.82126089353, 35805.82097089498, 35813.46279805857, 36237.63762362448, 36475.0, 30061.0, 30519.694803051687, 30535.0, 30637.693623063486, 31268.37463250768, 23731.0, 24834.130805421722, 24881.50236995286, 25153.28846192243, 25555.0], "value": 18775.67241235909, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.44775016353816, 435.50352598038234, 439.887828603706, 446.190164850796, 464.6561544457102, 19259.711104333448, 19394.0, 20954.37136885892, 21128.683069753926, 21179.152833886645, 20788.688169677465, 22167.113315467403, 22264.42412582556, 22305.44236394091, 22496.32511024672, 6505.447037001849, 6770.898436523456, 6798.9660051699675, 6810.829729256759, 6816.147981502312, 6975.895361569581, 7235.855282894339, 7248.2389349118375, 7256.4920455568135, 7265.636718164097, 6994.195667498227, 7349.044624198847, 7363.5581865088, 7392.926070739304, 7406.185319614838, 7423.4061275097865, 7460.36586890115, 7509.0, 7562.432817538706, 7592.810179745496, 7260.237675044097, 7352.5588464691755, 7357.705691772323, 7365.042544469238, 7366.1160660720525, 24759.0, 24785.380365490826, 25120.120795772244, 25598.360040998938, 25817.870910645514, 3000.189948713835, 3042.7870049096573, 3051.3287076843058, 3104.8913288035037, 3149.10250578584, 16364.099974501398, 16539.91730041354, 16640.916795416066, 16646.916765416216, 17273.309067637278, 2267.807236384909, 2279.0541925101065, 2289.1873384948435, 2305.342977251475, 2313.5025969416656, 66760.66619666919, 88383.55808220917, 107878.46060769724, 110330.0, 111307.99999999919, 17098.75123726513, 18127.18427670758, 18257.908710456362, 18487.445376638723, 18550.165242563846, 31507.684923150475, 31757.682423175935, 31789.8410507946, 31933.361332773213, 31973.52039719406, 10781.784364312669, 10969.945150274198, 10973.561057557608, 11012.923837577342, 11164.106871450265, 9248.676296329597, 9259.101884113741, 9319.580618872167, 9485.241180705527, 9487.952560237154, 8871.423357481743, 9352.485613291334, 9376.48429336393, 9378.531073446436, 9447.33868629203, 3564.928701426009, 3766.548014238309, 3805.0, 3824.732268741217, 3836.1037416750733, 1163.959261425846, 1173.5364531010202, 1178.6640807369859, 1182.8757980412106, 1198.4666823263565, 19085.904570477058, 19948.401547953585, 20251.898740506498, 20432.284870029478, 20496.69254961177, 1156.8206927926185, 1157.4961060158892, 1167.8598568171703, 1173.6713720158368, 1178.1253740872244, 11105.444727763579, 11106.222564420576, 11116.555337786398, 11255.155863310285, 11336.093112550976, 625163.0, 627146.0, 629303.0, 630734.0, 646581.0, 101344.0, 105387.0, 108316.0, 111626.0, 112446.0, 1325.7320704463543, 1348.3864841497095, 1382.8962827787755, 1390.0, 1405.746965546191, 18855.528611784815, 19078.427647170607, 19157.8084219156, 19275.61448771045, 19323.323683671006, 37846.43230351492, 42825.78587107169, 47873.0, 48195.27707084467, 50691.74654126706, 66942.6652866752, 67932.0, 67933.0, 68558.65720671364, 69502.30497695161, 12051.0, 12288.877111228774, 12316.199447035913, 12334.013278937842, 12454.0, 13890.0, 14040.64898377508, 14099.788503172256, 14134.50529231472, 14216.715665686836, 11756.966869991546, 11828.112891533341, 11908.702282443008, 11965.222260553037, 12478.18891772032, 11343.943280283545, 11426.314421134759, 11645.126615504041, 11671.35807530594, 11769.764604708029, 6624.575716221036, 10889.782204356026, 11868.643940681794, 12089.879101208875, 12274.75450491003, 10057.597696092367, 10194.745131371776, 10373.740656483347, 10388.264370565232, 10463.162946964072, 10836.783264334512, 11541.134414918746, 11683.766324673628, 12075.57735479254, 12376.62870113898], "value": 11428.990844156417, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.7681347797832, 1576.5822057154849, 1582.0, 1601.5755824706455, 1607.6463178100814, 10392.636257730981, 11106.833397499044, 11108.055815255702, 11115.221934464571, 11118.49966751497, 10817.350958942468, 10878.61924832631, 11208.607698730546, 11215.327080375184, 11216.719582010453, 2411.879406029696, 2439.377958620549, 2448.7918526925146, 2449.742777008415, 2454.9509009819794, 2319.1651005637955, 2395.281415575329, 2433.2821817563863, 2437.4150203951012, 2439.4145405102736, 2309.007126935417, 2342.1451170322794, 2348.3189874936247, 2431.027588964411, 2432.464857731305, 2226.4656482444097, 2303.9769602304013, 2307.9192228272013, 2314.271004633535, 2387.3554140382, 2277.829162812779, 2293.6444851048113, 2297.5060362022077, 2300.861948283108, 2304.43541332373, 10963.945180274128, 11151.721206969809, 11186.384748838811, 11201.439928003567, 11205.271657342246, 5619.7471113799975, 5758.3090029196765, 5759.971200143972, 5760.654360738369, 5763.6541807491685, 3443.6039855416448, 3592.515010473579, 3622.7645203061716, 3645.598984111772, 3671.834767435472, 1344.757943570157, 1362.1146254934272, 1381.6062422209623, 1394.504950742482, 1422.5590067079238, 15955.521334359988, 16547.503574892653, 16584.17079146038, 16749.832501674948, 17012.234449449803, 4604.884877878013, 4674.322223277673, 4766.4995175506765, 4804.8798780030775, 4813.614910807127, 12419.813702794465, 12425.502979880885, 12449.128561000824, 12455.0, 12504.81242781359, 5860.70696465175, 5865.061590145641, 5870.706464676749, 5872.677002764804, 5881.500072493886, 5247.212918062245, 5368.892622147613, 5375.489328513785, 5466.671999680032, 5470.72646367688, 3890.533136023695, 4015.5783642717092, 4046.736962097454, 4095.1195492969155, 4116.073883376216, 1027.9023492768174, 1029.9975571209839, 1031.5254982708038, 1041.8749750030045, 1044.8537204791335, 1068.2362111090608, 1079.2175672637431, 1085.7014321061772, 1087.5106202209038, 1098.6813823991113, 6830.692618832164, 6839.452843772487, 6845.726170953206, 6955.826104347432, 6973.860522789617, 1022.1822541966397, 1022.504085518523, 1035.5805898611075, 1046.26238501856, 1062.3572738493256, 4086.8773936781945, 4736.265878788793, 4746.786394612251, 4803.0, 4862.149123903303, 14038.438462461592, 14498.275086245645, 14545.41818327255, 14691.265436728121, 14825.332860021324, 14683.11901285905, 14683.19242441676, 14699.0, 14706.705865882623, 14728.337224824909, 8005.199480051948, 8190.017197936286, 10041.849372259421, 10238.692839214835, 10623.575056997788, 7121.145462544528, 7238.710451582088, 7274.090738657668, 7340.926590734024, 7349.0, 12814.359282035675, 12935.611931642066, 12984.026198035373, 13019.283939383426, 13073.346332683137, 12870.099093063396, 13368.131071480322, 13423.865761342262, 13469.39387727535, 13480.056396052181, 8846.734597962071, 8976.28189744832, 9013.369064165445, 9036.864447033431, 9051.864222036544, 10240.846387304346, 10477.633282835064, 10547.261691681508, 10707.785844283226, 10771.08445782086, 9062.093790620886, 9242.768930776785, 9253.352265341631, 9256.72229833106, 9344.112309330603, 9136.223421009288, 9227.35408521397, 9333.57998890038, 9374.109459601326, 9488.240940724587, 7857.2928436441, 7932.405069619686, 7961.8407631848195, 7963.323117535075, 7974.362051036019, 5897.587168898136, 5985.730642121202, 6045.153678484928, 6115.755369785159, 6233.376662333731, 6303.338149494237, 6333.8733225336155, 6357.682115894279, 6468.579542329733, 6497.122888410004], "value": 5316.003042004724, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.4835410674334, 406.0944094668888, 416.13444036404275, 416.209202515221, 424.598823881192, 5211.609129315298, 5309.283246761686, 5313.840584782462, 5346.27825243592, 5382.2464854920345, 5072.568831649314, 5169.508896654821, 5180.533751962323, 5190.963286477406, 5193.221016847475, 4328.506665200501, 4342.913141737164, 4348.347747837818, 4356.956430435703, 4398.406215160944, 4402.669799765031, 4417.779111044451, 4421.226285400042, 4423.911521769563, 4425.557444255564, 4261.339492378686, 4371.431713877208, 4380.262336039975, 4392.1874453226255, 4399.538048504909, 4344.695871289011, 4352.606066663338, 4352.91294174118, 4356.695031347791, 4366.847160349373, 4085.734427262248, 4103.199876024177, 4107.95247211962, 4125.876223713263, 4156.501219853637, 5093.821716239951, 5143.382794064736, 5148.9485105149215, 5153.6085256980405, 5174.97412512935, 5074.416442109131, 5084.347587034229, 5138.563222126124, 5143.871403214912, 5163.302954101186, 5521.4478552144865, 5543.722813859291, 5558.416366281523, 5569.498745112919, 5584.274044374205, 4471.575200355961, 4581.473130589991, 4615.238485649864, 4620.607248383892, 4623.537646235383, 5532.5573954083975, 5596.412376700469, 5706.343770466449, 5802.694393761412, 5809.535237181015, 4657.906841863144, 4700.764961751899, 4712.104700106968, 4713.457952335457, 4715.575598196178, 4600.42494688164, 4687.320338550891, 4693.929591056137, 4778.163821331323, 4809.326694262806, 5098.1588037973315, 5105.310783044241, 5107.821226257063, 5118.334616499831, 5152.4847515248175, 5545.33639908034, 5564.441956252256, 5573.888522229533, 5578.748956296977, 5598.496135347838, 1599.2483532739593, 1649.0105936438042, 1655.7847479827785, 1661.55968668303, 1669.8747593930505, 1270.28863836252, 1271.6456973323382, 1282.1537785061814, 1290.1420555330787, 1305.112523484021, 5440.564754819605, 5457.2359869618285, 5482.753276102585, 5546.639468434539, 5550.5282051026115, 5595.433278681976, 5682.318121825408, 5726.255586773693, 5739.827805165852, 5783.8554036149435, 5321.281626980308, 5433.26650902131, 5452.182172674131, 5455.890882182334, 5531.474509921551, 2992.384112579183, 2994.7304742573274, 3002.789804713693, 3005.9849700751793, 3016.502277124294, 5663.137708450692, 5695.316562012585, 5897.351291357947, 5916.349201587822, 5935.52515798735, 5695.715214239354, 5717.485426311652, 5730.684812335361, 5763.596548241583, 5793.449622285876, 5796.82609521715, 5815.621984570989, 5859.579888603111, 5866.943950088939, 5878.412158784087, 5500.257465242141, 5605.243292155506, 5670.631408958405, 5677.432256774289, 5678.943210568007, 5357.571394288526, 5494.835154945358, 5540.307461567304, 5638.661680299195, 5662.178984047372, 5875.911861322169, 5887.704704965072, 5896.3514013457625, 5934.732937017931, 5940.61386009908, 4623.283391074388, 4662.0, 4706.247000479907, 4726.669133160645, 4758.0, 5622.578306626938, 5648.350439699323, 5680.886382272249, 5714.171445140514, 5718.6267698904485, 5464.699441530754, 5527.1258945349, 5548.0845660465575, 5587.44125587438, 5590.273264475592, 5195.1425417235005, 5499.0, 5583.96185392337, 5603.0, 5609.635373700697, 5537.390887002507, 5543.805966791143, 5562.638428502134, 5568.49883510486, 5616.550675945996, 5284.233786100917, 5460.781568737365, 5578.609497335147, 5626.736492834229, 5660.716964151694, 5348.411674715856, 5360.812371566976, 5400.2439658447065, 5423.647462914898, 5455.399906010415], "value": 4448.098472829847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.2043110123135, 868.4311775786858, 915.617521589845, 918.9218916392107, 934.3319526538523, 17168.3132674693, 17495.125243737824, 18028.459146225636, 18279.0, 18316.90841545792, 14626.853731462683, 14829.110253384804, 15423.228838558056, 16695.248713807876, 16833.989960602343, 850.3494826457764, 870.1124852650292, 872.7774417523523, 875.7591662292857, 896.847535918892, 893.4148132972913, 895.4269267668698, 898.3172788680585, 898.6091050393068, 899.4468401932829, 942.2838642631581, 943.4056544377033, 943.42451104826, 944.4663764972803, 944.5230158769812, 935.6117211357279, 937.142514599141, 937.9906200937971, 938.4228699349885, 938.6808485115059, 689.4174422612884, 689.9586024838526, 690.32693124204, 690.3924546399137, 690.3959035843637, 17575.912120439443, 17962.5509362267, 18002.54993625157, 18042.729359059493, 18241.17914693842, 16635.750463743054, 17273.22270497818, 17302.91348543262, 17523.474295771146, 18201.63596728058, 13565.389557469938, 13998.30008499571, 14020.0, 14110.576682699433, 14131.788023179557, 4112.09533902541, 4131.710780245384, 4135.6898232632675, 4199.223143718391, 4236.04688944988, 85354.57322713408, 85875.0, 86863.13136868677, 88039.0, 89814.0, 13147.408366623526, 13449.0, 13602.795958060438, 13617.182969021891, 13660.385282662304, 27216.727832721816, 27347.45305093887, 27598.310042249104, 27677.0, 27701.30746731348, 8481.406301558955, 8581.141885811368, 8657.134286571292, 8883.17922212426, 9011.234045106108, 4987.177115775857, 5013.674111182761, 5041.621878359138, 5074.543291103819, 5194.4805519447755, 3457.083872773717, 3527.8236088195486, 3535.6994655454573, 3561.0, 3566.518519999767, 622.2968046107869, 623.5510432488601, 630.9179806625201, 631.1574048645018, 639.8080575827233, 684.3806355248508, 693.8334799648048, 710.5843081797103, 714.653393104344, 730.9926900731031, 10559.524821383056, 10607.363558186533, 10819.945900270448, 10870.456477176109, 10939.179561532917, 738.1216352540459, 747.4805010517747, 755.8223817402906, 756.6027835386466, 789.8025493626593, 5074.876034700412, 5148.845534633967, 5158.226266060046, 5205.947940520547, 5216.504432078947, 81343.0, 83867.0, 84145.0, 85272.57363713141, 86298.56850715706, 70876.0, 70929.64535177394, 72663.63668181625, 75038.24961750422, 75496.62251688706, 55365.99999999919, 65426.67286663536, 65712.7143642816, 66047.33952660508, 66729.33270667329, 12003.1597788154, 12657.240565566095, 12702.428390722254, 12752.234865908074, 12923.870761292266, 38822.029449263995, 39776.0, 40825.387619185145, 42543.57456425396, 42861.571384287, 56320.436795633155, 57002.42997570137, 57190.0, 58500.707496462244, 59233.0, 13409.798853017017, 13797.310134493035, 13982.790258146339, 14026.509072182425, 14148.504802331869, 15836.208189590245, 15986.0, 16011.679766404839, 16149.0, 16302.429414970418, 15440.768388473956, 15653.843461565239, 15854.682906341577, 16012.759808602646, 16110.275037623569, 15137.243137843285, 15363.61590960235, 15512.457064002705, 16403.261853216394, 16432.342706291616, 14449.277536123362, 15120.924395378393, 15490.380384784483, 15994.840051599334, 16055.67888642244, 9963.651272205438, 9970.0, 10123.696289111338, 10492.370457772558, 10844.0, 11905.642830715093, 12214.816777748163, 12307.323097229739, 12434.875651243372, 12540.87459125397], "value": 7953.773354148624, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1462.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 113.0, 111.0, 114.0, 113.0, 112.0, 113.0, 111.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 116.0, 156.0, 115.0, 115.0, 115.0, 115.0, 139.0, 115.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 112.0, 76.0, 87.0, 76.0, 79.0, 94.0, 74.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 82.0, 82.0, 82.0, 105.0, 82.0, 82.0, 104.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 53.0, 54.0, 54.0, 54.0, 55.0, 55.0, 53.0, 56.0, 53.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 54.0, 52.0, 52.0, 53.0, 53.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 74.0, 80.0, 85.0, 79.0, 84.0, 79.0, 85.0, 84.0, 103.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 128.0, 130.0, 122.0, 137.0, 127.0, 119.0, 118.0, 148.0, 120.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 167.0, 163.0, 159.0, 159.0, 157.0, 160.0, 160.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 59.0, 60.0, 64.0, 60.0, 63.0, 60.0, 60.0, 64.0], "value": 61.8, "unit": "score"}]}]} 02:00:31 INFO - Return code: 0 02:00:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:00:31 ERROR - # TBPL SUCCESS # 02:00:31 INFO - Running post-action listener: _resource_record_post_action 02:00:31 INFO - [mozharness: 2017-04-14 09:00:31.434497Z] Finished run-tests step (success) 02:00:31 INFO - Running post-run listener: _resource_record_post_run 02:00:31 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 365043712; Read time: 16; Write time: 478392 02:00:31 INFO - TinderboxPrint: CPU usage
13.2% 02:00:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 02:00:31 INFO - TinderboxPrint: I/O write bytes / time
365,043,712 / 478,392 02:00:31 INFO - TinderboxPrint: CPU idle
8,265.5 (86.8%) 02:00:31 INFO - TinderboxPrint: CPU user
1,204.8 (12.7%) 02:00:31 INFO - TinderboxPrint: Swap in / out
0 / 0 02:00:31 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 109985792; Read time: 0; Write time: 180048 02:00:31 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254738432; Read time: 16; Write time: 298308 02:00:32 INFO - Running post-run listener: _upload_blobber_files 02:00:32 INFO - Blob upload gear active. 02:00:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:00:32 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 02:00:32 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'] 02:00:32 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 02:00:32 INFO - (blobuploader) - INFO - Open directory for files ... 02:00:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:00:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:34 INFO - (blobuploader) - INFO - Done attempting. 02:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:00:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:37 INFO - (blobuploader) - INFO - Done attempting. 02:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:00:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:37 INFO - (blobuploader) - INFO - Done attempting. 02:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:38 INFO - (blobuploader) - INFO - Done attempting. 02:00:38 INFO - (blobuploader) - INFO - Iteration through files over. 02:00:38 INFO - Return code: 0 02:00:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:00:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:00:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/71aaed9e2ac325fed4763d503cd87a5fe46b61c575a7e26346c05cca44a67a83f6ae21fe31519c5e2d4f9a17d6eb0be7f35eda9b8051c66cc715499f80525f39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/159334c621d9999f25463acf34db626dcdfc39571b7321f0a11d774826b59164961708aa9750ec5de232c75e65542ee8087ce9bf7cc31ce8417a287644b2865c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba7b752adc97d1ba8cf9ec290e4fed9f4bfbd9da1a4bfe1b0f2496d35796cf87c737266ad633c69e5a0750ccac33a8f85de6e1ee7d918ba28363e0c62debca5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/cf87150e682ea68bbe61be8c16c29301b38ab0114dde7cf8abf2e83c16a4ce93ef8378ad6bc32428744b0d38f0136bc4bfd8a58c088ce9bd1faf78cf708e01a7"} 02:00:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:00:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:00:38 INFO - Contents: 02:00:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/71aaed9e2ac325fed4763d503cd87a5fe46b61c575a7e26346c05cca44a67a83f6ae21fe31519c5e2d4f9a17d6eb0be7f35eda9b8051c66cc715499f80525f39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/159334c621d9999f25463acf34db626dcdfc39571b7321f0a11d774826b59164961708aa9750ec5de232c75e65542ee8087ce9bf7cc31ce8417a287644b2865c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba7b752adc97d1ba8cf9ec290e4fed9f4bfbd9da1a4bfe1b0f2496d35796cf87c737266ad633c69e5a0750ccac33a8f85de6e1ee7d918ba28363e0c62debca5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/cf87150e682ea68bbe61be8c16c29301b38ab0114dde7cf8abf2e83c16a4ce93ef8378ad6bc32428744b0d38f0136bc4bfd8a58c088ce9bd1faf78cf708e01a7"} 02:00:38 INFO - Running post-run listener: copy_logs_to_upload_dir 02:00:38 INFO - Copying logs to upload dir... 02:00:38 INFO - mkdir: /builds/slave/test/build/upload/logs 02:00:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.887975 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-14 02:00:38.744015) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 02:00:38.747428) ========= 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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 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/71aaed9e2ac325fed4763d503cd87a5fe46b61c575a7e26346c05cca44a67a83f6ae21fe31519c5e2d4f9a17d6eb0be7f35eda9b8051c66cc715499f80525f39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/159334c621d9999f25463acf34db626dcdfc39571b7321f0a11d774826b59164961708aa9750ec5de232c75e65542ee8087ce9bf7cc31ce8417a287644b2865c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba7b752adc97d1ba8cf9ec290e4fed9f4bfbd9da1a4bfe1b0f2496d35796cf87c737266ad633c69e5a0750ccac33a8f85de6e1ee7d918ba28363e0c62debca5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/cf87150e682ea68bbe61be8c16c29301b38ab0114dde7cf8abf2e83c16a4ce93ef8378ad6bc32428744b0d38f0136bc4bfd8a58c088ce9bd1faf78cf708e01a7"} build_url:https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/dP5Pp2IKTD2Lc8no8gZFcg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/71aaed9e2ac325fed4763d503cd87a5fe46b61c575a7e26346c05cca44a67a83f6ae21fe31519c5e2d4f9a17d6eb0be7f35eda9b8051c66cc715499f80525f39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/159334c621d9999f25463acf34db626dcdfc39571b7321f0a11d774826b59164961708aa9750ec5de232c75e65542ee8087ce9bf7cc31ce8417a287644b2865c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ba7b752adc97d1ba8cf9ec290e4fed9f4bfbd9da1a4bfe1b0f2496d35796cf87c737266ad633c69e5a0750ccac33a8f85de6e1ee7d918ba28363e0c62debca5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/cf87150e682ea68bbe61be8c16c29301b38ab0114dde7cf8abf2e83c16a4ce93ef8378ad6bc32428744b0d38f0136bc4bfd8a58c088ce9bd1faf78cf708e01a7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 02:00:38.775975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 02:00:38.776734) ========= 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=8783db0840cc980e80bb0bd400000236-1492159185.798271-44257524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 02:00:38.860077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 02:00:38.860383) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 02:00:38.860685) ========= ========= Total master_lag: 0.60 =========