builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1491522869.75 results: success (0) revision: 3f67a4d375a17ece4f0a3cb108703ab3f39e060b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.754821) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.755271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.755552) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.770236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.770559) ========= 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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 16:54:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2017-04-06 16:54:29 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139596 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.924610) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.924936) ========= 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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022942 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.974809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:29.975153) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f67a4d375a17ece4f0a3cb108703ab3f39e060b --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 3f67a4d375a17ece4f0a3cb108703ab3f39e060b --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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 16:54:29,974 truncating revision to first 12 chars 2017-04-06 16:54:29,974 Setting DEBUG logging. 2017-04-06 16:54:29,974 attempt 1/10 2017-04-06 16:54:29,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3f67a4d375a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 16:54:30,237 unpacking tar archive at: mozilla-aurora-3f67a4d375a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.598059 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:30.586501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:30.586833) ========= script_repo_revision: 3f67a4d375a17ece4f0a3cb108703ab3f39e060b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:30.587190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:30.587453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 16:54:30.598099) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-06 16:54:30.598362) ========= /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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:54:30 INFO - MultiFileLogger online at 20170406 16:54:30 in /builds/slave/test 16:54:30 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 16:54:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:54:30 INFO - {'append_to_log': False, 16:54:30 INFO - 'base_work_dir': '/builds/slave/test', 16:54:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 16:54:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:54:30 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 16:54:30 INFO - 'buildbot_json_path': 'buildprops.json', 16:54:30 INFO - 'config_files': ('talos/linux_config.py',), 16:54:30 INFO - 'default_actions': ('clobber', 16:54:30 INFO - 'read-buildbot-config', 16:54:30 INFO - 'download-and-extract', 16:54:30 INFO - 'populate-webroot', 16:54:30 INFO - 'create-virtualenv', 16:54:30 INFO - 'install', 16:54:30 INFO - 'run-tests'), 16:54:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:54:30 INFO - 'download_minidump_stackwalk': True, 16:54:30 INFO - 'download_symbols': 'ondemand', 16:54:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:54:30 INFO - 'tooltool.py': '/tools/tooltool.py', 16:54:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:54:30 INFO - '/tools/misc-python/virtualenv.py')}, 16:54:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:54:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:54:30 INFO - 'gecko_profile': False, 16:54:30 INFO - 'gecko_profile_interval': 0, 16:54:30 INFO - 'installer_path': 'installer.exe', 16:54:30 INFO - 'log_level': 'info', 16:54:30 INFO - 'log_name': 'talos', 16:54:30 INFO - 'log_to_console': True, 16:54:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:54:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:54:30 INFO - 'opt_config_files': (), 16:54:30 INFO - 'pip_index': False, 16:54:30 INFO - 'suite': 'dromaeojs', 16:54:30 INFO - 'system_bits': '32', 16:54:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:54:30 INFO - 'title': 'talos-linux64-ix-032', 16:54:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:54:30 INFO - 'use_talos_json': True, 16:54:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:54:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:54:30 INFO - 'work_dir': 'build'} 16:54:30 INFO - [mozharness: 2017-04-06 23:54:30.697911Z] Running clobber step. 16:54:30 INFO - Running pre-action listener: _resource_record_pre_action 16:54:30 INFO - Running main action method: clobber 16:54:30 INFO - rmtree: /builds/slave/test/build 16:54:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:54:31 INFO - Running post-action listener: _resource_record_post_action 16:54:31 INFO - [mozharness: 2017-04-06 23:54:31.621608Z] Finished clobber step (success) 16:54:31 INFO - [mozharness: 2017-04-06 23:54:31.621709Z] Running read-buildbot-config step. 16:54:31 INFO - Running pre-action listener: _resource_record_pre_action 16:54:31 INFO - Running main action method: read_buildbot_config 16:54:31 INFO - Using buildbot properties: 16:54:31 INFO - { 16:54:31 INFO - "project": "", 16:54:31 INFO - "product": "firefox", 16:54:31 INFO - "who": "ryanvm@gmail.com", 16:54:31 INFO - "installer_path": "public/build/target.tar.bz2", 16:54:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:54:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 16:54:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 16:54:31 INFO - "stage_platform": "linux64", 16:54:31 INFO - "basedir": "/builds/slave/test", 16:54:31 INFO - "buildnumber": 120, 16:54:31 INFO - "platform": "ubuntu64_hw", 16:54:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:54:31 INFO - "slavebuilddir": "test", 16:54:31 INFO - "taskId": "Hgxow-G8Tb2QMni6K366Ww", 16:54:31 INFO - "branch": "mozilla-aurora", 16:54:31 INFO - "script_repo_revision": "production", 16:54:31 INFO - "revision": "3f67a4d375a17ece4f0a3cb108703ab3f39e060b", 16:54:31 INFO - "slavename": "talos-linux64-ix-032", 16:54:31 INFO - "repo_path": "releases/mozilla-aurora" 16:54:31 INFO - } 16:54:31 INFO - Finding installer, test and symbols from parent task. 16:54:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hgxow-G8Tb2QMni6K366Ww'}, attempt #1 16:54:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hgxow-G8Tb2QMni6K366Ww'}, attempt #1 16:54:32 INFO - Task dependencies: Hmz_xXPRSfiIW2lfeBlpLA 16:54:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA'}, attempt #1 16:54:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 16:54:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/test_packages.json 16:54:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.crashreporter-symbols.zip 16:54:33 INFO - Running post-action listener: _resource_record_post_action 16:54:33 INFO - [mozharness: 2017-04-06 23:54:33.164808Z] Finished read-buildbot-config step (success) 16:54:33 INFO - [mozharness: 2017-04-06 23:54:33.165029Z] Running download-and-extract step. 16:54:33 INFO - Running pre-action listener: _resource_record_pre_action 16:54:33 INFO - Running main action method: download_and_extract 16:54:33 INFO - mkdir: /builds/slave/test/build/tests 16:54: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')]} 16:54:33 INFO - trying https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.test_packages.json 16:54:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:54:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:54:33 INFO - Downloaded 896 bytes. 16:54:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:54:33 INFO - Using the following test package requirements: 16:54:33 INFO - {u'common': [u'target.common.tests.zip'], 16:54:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:54:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:54:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:54:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:54:33 INFO - u'mozbase': [u'target.common.tests.zip'], 16:54:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:54:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:54:33 INFO - u'web-platform': [u'target.common.tests.zip', 16:54:33 INFO - u'target.web-platform.tests.zip'], 16:54:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:54:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:54:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip 16:54:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:54:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip into memory 16:54:34 INFO - Expected file size: 59542241 16:54:34 INFO - Obtained file size: 59542241 16:54:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:54:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip 16:54:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:54:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.common.tests.zip into memory 16:54:45 INFO - Expected file size: 59542241 16:54:45 INFO - Obtained file size: 59542241 16:54:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.talos.tests.zip 16:54:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:54:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.talos.tests.zip into memory 16:54:59 INFO - Expected file size: 14404424 16:54:59 INFO - Obtained file size: 14404424 16:54:59 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')]} 16:54:59 INFO - trying https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 16:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:55:00 INFO - Downloaded 66759307 bytes. 16:55:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 16:55:00 INFO - mkdir: /builds/slave/test/properties 16:55:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:55:00 INFO - Writing to file /builds/slave/test/properties/build_url 16:55:00 INFO - Contents: 16:55:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 16:55:00 INFO - Running post-action listener: _resource_record_post_action 16:55:00 INFO - Running post-action listener: set_extra_try_arguments 16:55:00 INFO - [mozharness: 2017-04-06 23:55:00.413186Z] Finished download-and-extract step (success) 16:55:00 INFO - [mozharness: 2017-04-06 23:55:00.413282Z] Running populate-webroot step. 16:55:00 INFO - Running pre-action listener: _resource_record_pre_action 16:55:00 INFO - Running main action method: populate_webroot 16:55:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:55:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:55:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:55:00 INFO - '--tppagecycles', 16:55:00 INFO - '7'], 16:55:00 INFO - 'tests': ['tsvgm']}}, 16:55:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:55:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:55:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:55:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:55:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['damp', 'tps']}, 16:55:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'tests': ['damp', 'tps']}, 16:55:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['dromaeo_dom']}, 16:55:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:55:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['basic_compositor_video']}, 16:55:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:55:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['a11yr', 16:55:00 INFO - 'ts_paint', 16:55:00 INFO - 'tpaint', 16:55:00 INFO - 'sessionrestore', 16:55:00 INFO - 'sessionrestore_no_auto_restore', 16:55:00 INFO - 'tabpaint']}, 16:55:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tsvgx', 16:55:00 INFO - 'tsvgr_opacity', 16:55:00 INFO - 'tart', 16:55:00 INFO - 'tscrollx', 16:55:00 INFO - 'cart', 16:55:00 INFO - 'tsvg_static']}, 16:55:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:55:00 INFO - 'tsvgr_opacity', 16:55:00 INFO - 'tart', 16:55:00 INFO - 'tscrollx', 16:55:00 INFO - 'cart', 16:55:00 INFO - 'tsvg_static']}, 16:55:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s'], 16:55:00 INFO - 'tests': ['tp5o']}, 16:55:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:55:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'talos_options': ['--disable-e10s', 16:55:00 INFO - '--xperf_path', 16:55:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:00 INFO - 'tests': ['tp5n']}, 16:55:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:55:00 INFO - 'talos_options': ['--xperf_path', 16:55:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:00 INFO - 'tests': ['tp5n']}}, 16:55:00 INFO - 'talos.zip': {'path': '', 16:55:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:55:00 INFO - Running post-action listener: _resource_record_post_action 16:55:00 INFO - [mozharness: 2017-04-06 23:55:00.426544Z] Finished populate-webroot step (success) 16:55:00 INFO - [mozharness: 2017-04-06 23:55:00.426634Z] Running create-virtualenv step. 16:55:00 INFO - Running pre-action listener: _resource_record_pre_action 16:55:00 INFO - Running main action method: create_virtualenv 16:55:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:55:00 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 16:55:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:55:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:55:00 INFO - Using real prefix '/usr' 16:55:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:55:01 INFO - Installing distribute.............................................................................................................................................................................................done. 16:55:02 INFO - Installing pip.................done. 16:55:03 INFO - Return code: 0 16:55:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:55:03 INFO - Reading from file tmpfile_stdout 16:55:03 INFO - Output received: 16:55:03 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) 16:55:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:03 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 16:55:03 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 16:55:03 INFO - Using env: {'DISPLAY': ':0', 16:55:03 INFO - 'HOME': '/home/cltbld', 16:55:03 INFO - 'LANG': 'en_US.UTF-8', 16:55:03 INFO - 'LANGUAGE': 'en_US:en', 16:55:03 INFO - 'LOGNAME': 'cltbld', 16:55:03 INFO - 'MAIL': '/var/mail/cltbld', 16:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:03 INFO - 'MOZ_NO_REMOTE': '1', 16:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:03 INFO - 'NO_EM_RESTART': '1', 16:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:03 INFO - 'PWD': '/builds/slave/test', 16:55:03 INFO - 'SHELL': '/bin/bash', 16:55:03 INFO - 'SHLVL': '1', 16:55:03 INFO - 'TERM': 'linux', 16:55:03 INFO - 'TMOUT': '86400', 16:55:03 INFO - 'USER': 'cltbld', 16:55:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:03 INFO - '_': '/tools/buildbot/bin/python'} 16:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:03 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 16:55:03 INFO - Cleaning up... 16:55:03 INFO - Return code: 0 16:55:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:03 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:03 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 16:55:03 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 16:55:03 INFO - Using env: {'DISPLAY': ':0', 16:55:03 INFO - 'HOME': '/home/cltbld', 16:55:03 INFO - 'LANG': 'en_US.UTF-8', 16:55:03 INFO - 'LANGUAGE': 'en_US:en', 16:55:03 INFO - 'LOGNAME': 'cltbld', 16:55:03 INFO - 'MAIL': '/var/mail/cltbld', 16:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:03 INFO - 'MOZ_NO_REMOTE': '1', 16:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:03 INFO - 'NO_EM_RESTART': '1', 16:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:03 INFO - 'PWD': '/builds/slave/test', 16:55:03 INFO - 'SHELL': '/bin/bash', 16:55:03 INFO - 'SHLVL': '1', 16:55:03 INFO - 'TERM': 'linux', 16:55:03 INFO - 'TMOUT': '86400', 16:55:03 INFO - 'USER': 'cltbld', 16:55:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:03 INFO - '_': '/tools/buildbot/bin/python'} 16:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:03 INFO - Downloading/unpacking psutil>=3.1.1 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:05 INFO - Installing collected packages: psutil 16:55:05 INFO - Running setup.py install for psutil 16:55:05 INFO - building 'psutil._psutil_linux' extension 16:55:05 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 16:55:05 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 16:55:06 INFO - building 'psutil._psutil_posix' extension 16:55:06 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 16:55:06 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 16:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:06 INFO - Successfully installed psutil 16:55:06 INFO - Cleaning up... 16:55:06 INFO - Return code: 0 16:55:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:55:06 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')]} 16:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:06 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')]} 16:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:06 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:06 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 16:55:06 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 16:55:06 INFO - Using env: {'DISPLAY': ':0', 16:55:06 INFO - 'HOME': '/home/cltbld', 16:55:06 INFO - 'LANG': 'en_US.UTF-8', 16:55:06 INFO - 'LANGUAGE': 'en_US:en', 16:55:06 INFO - 'LOGNAME': 'cltbld', 16:55:06 INFO - 'MAIL': '/var/mail/cltbld', 16:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:06 INFO - 'MOZ_NO_REMOTE': '1', 16:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:06 INFO - 'NO_EM_RESTART': '1', 16:55:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:06 INFO - 'PWD': '/builds/slave/test', 16:55:06 INFO - 'SHELL': '/bin/bash', 16:55:06 INFO - 'SHLVL': '1', 16:55:06 INFO - 'TERM': 'linux', 16:55:06 INFO - 'TMOUT': '86400', 16:55:06 INFO - 'USER': 'cltbld', 16:55:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:06 INFO - '_': '/tools/buildbot/bin/python'} 16:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:55:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:55:08 INFO - Installing collected packages: mozsystemmonitor 16:55:08 INFO - Running setup.py install for mozsystemmonitor 16:55:08 INFO - Successfully installed mozsystemmonitor 16:55:08 INFO - Cleaning up... 16:55:08 INFO - Return code: 0 16:55:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:55:08 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')]} 16:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:08 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')]} 16:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:08 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:08 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 16:55:08 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 16:55:08 INFO - Using env: {'DISPLAY': ':0', 16:55:08 INFO - 'HOME': '/home/cltbld', 16:55:08 INFO - 'LANG': 'en_US.UTF-8', 16:55:08 INFO - 'LANGUAGE': 'en_US:en', 16:55:08 INFO - 'LOGNAME': 'cltbld', 16:55:08 INFO - 'MAIL': '/var/mail/cltbld', 16:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:08 INFO - 'MOZ_NO_REMOTE': '1', 16:55:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:08 INFO - 'NO_EM_RESTART': '1', 16:55:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:08 INFO - 'PWD': '/builds/slave/test', 16:55:08 INFO - 'SHELL': '/bin/bash', 16:55:08 INFO - 'SHLVL': '1', 16:55:08 INFO - 'TERM': 'linux', 16:55:08 INFO - 'TMOUT': '86400', 16:55:08 INFO - 'USER': 'cltbld', 16:55:08 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:08 INFO - '_': '/tools/buildbot/bin/python'} 16:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:08 INFO - Downloading/unpacking jsonschema==2.5.1 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:08 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 16:55:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:55:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:55:11 INFO - warning: no files found matching '*.txt' 16:55:11 INFO - no previously-included directories found matching 'build' 16:55:11 INFO - no previously-included directories found matching 'dist' 16:55:11 INFO - no previously-included directories found matching '.git*' 16:55:11 INFO - Installing collected packages: jsonschema, functools32 16:55:11 INFO - Running setup.py install for functools32 16:55:11 INFO - warning: no files found matching '*.txt' 16:55:11 INFO - no previously-included directories found matching 'build' 16:55:11 INFO - no previously-included directories found matching 'dist' 16:55:11 INFO - no previously-included directories found matching '.git*' 16:55:11 INFO - Successfully installed jsonschema functools32 16:55:11 INFO - Cleaning up... 16:55:11 INFO - Return code: 0 16:55:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:11 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 16:55:11 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 16:55:11 INFO - Using env: {'DISPLAY': ':0', 16:55:11 INFO - 'HOME': '/home/cltbld', 16:55:11 INFO - 'LANG': 'en_US.UTF-8', 16:55:11 INFO - 'LANGUAGE': 'en_US:en', 16:55:11 INFO - 'LOGNAME': 'cltbld', 16:55:11 INFO - 'MAIL': '/var/mail/cltbld', 16:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:11 INFO - 'MOZ_NO_REMOTE': '1', 16:55:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:11 INFO - 'NO_EM_RESTART': '1', 16:55:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:11 INFO - 'PWD': '/builds/slave/test', 16:55:11 INFO - 'SHELL': '/bin/bash', 16:55:11 INFO - 'SHLVL': '1', 16:55:11 INFO - 'TERM': 'linux', 16:55:11 INFO - 'TMOUT': '86400', 16:55:11 INFO - 'USER': 'cltbld', 16:55:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:11 INFO - '_': '/tools/buildbot/bin/python'} 16:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:55:11 INFO - Cleaning up... 16:55:11 INFO - Return code: 0 16:55:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:11 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:11 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 16:55:11 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 16:55:11 INFO - Using env: {'DISPLAY': ':0', 16:55:11 INFO - 'HOME': '/home/cltbld', 16:55:11 INFO - 'LANG': 'en_US.UTF-8', 16:55:11 INFO - 'LANGUAGE': 'en_US:en', 16:55:11 INFO - 'LOGNAME': 'cltbld', 16:55:11 INFO - 'MAIL': '/var/mail/cltbld', 16:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:11 INFO - 'MOZ_NO_REMOTE': '1', 16:55:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:11 INFO - 'NO_EM_RESTART': '1', 16:55:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:11 INFO - 'PWD': '/builds/slave/test', 16:55:11 INFO - 'SHELL': '/bin/bash', 16:55:11 INFO - 'SHLVL': '1', 16:55:11 INFO - 'TERM': 'linux', 16:55:11 INFO - 'TMOUT': '86400', 16:55:11 INFO - 'USER': 'cltbld', 16:55:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:11 INFO - '_': '/tools/buildbot/bin/python'} 16:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:11 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 16:55:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:55:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:55:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:14 INFO - Downloading docopt-0.6.1.tar.gz 16:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:55:14 INFO - Installing collected packages: blobuploader, requests, docopt 16:55:14 INFO - Running setup.py install for blobuploader 16:55:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:55:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:55:14 INFO - Running setup.py install for requests 16:55:15 INFO - Running setup.py install for docopt 16:55:15 INFO - Successfully installed blobuploader requests docopt 16:55:15 INFO - Cleaning up... 16:55:15 INFO - Return code: 0 16:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:15 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:15 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 16:55:15 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 16:55:15 INFO - Using env: {'DISPLAY': ':0', 16:55:15 INFO - 'HOME': '/home/cltbld', 16:55:15 INFO - 'LANG': 'en_US.UTF-8', 16:55:15 INFO - 'LANGUAGE': 'en_US:en', 16:55:15 INFO - 'LOGNAME': 'cltbld', 16:55:15 INFO - 'MAIL': '/var/mail/cltbld', 16:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:15 INFO - 'MOZ_NO_REMOTE': '1', 16:55:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:15 INFO - 'NO_EM_RESTART': '1', 16:55:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:15 INFO - 'PWD': '/builds/slave/test', 16:55:15 INFO - 'SHELL': '/bin/bash', 16:55:15 INFO - 'SHLVL': '1', 16:55:15 INFO - 'TERM': 'linux', 16:55:15 INFO - 'TMOUT': '86400', 16:55:15 INFO - 'USER': 'cltbld', 16:55:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:15 INFO - '_': '/tools/buildbot/bin/python'} 16:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:15 INFO - Running setup.py (path:/tmp/pip-gaVFU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:15 INFO - Running setup.py (path:/tmp/pip-MLfrgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:15 INFO - Running setup.py (path:/tmp/pip-6bSJrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:15 INFO - Running setup.py (path:/tmp/pip-P2NrSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:15 INFO - Running setup.py (path:/tmp/pip-lMi9jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:15 INFO - Running setup.py (path:/tmp/pip-UwfgOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:15 INFO - Running setup.py (path:/tmp/pip-hBBQlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:16 INFO - Running setup.py (path:/tmp/pip-iixrPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:16 INFO - Running setup.py (path:/tmp/pip-anhYJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:16 INFO - Running setup.py (path:/tmp/pip-MVefjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:16 INFO - Running setup.py (path:/tmp/pip-2mTTF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:16 INFO - Running setup.py (path:/tmp/pip-CAdVii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:16 INFO - Running setup.py (path:/tmp/pip-7oRCIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:16 INFO - Running setup.py (path:/tmp/pip-umy1Ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:16 INFO - Running setup.py (path:/tmp/pip-LbOkTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:16 INFO - Running setup.py (path:/tmp/pip-L1o0Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:16 INFO - Running setup.py (path:/tmp/pip-xd8ANG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:55:16 INFO - Running setup.py install for manifestparser 16:55:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:55:16 INFO - Running setup.py install for mozcrash 16:55:16 INFO - Running setup.py install for mozdebug 16:55:17 INFO - Running setup.py install for mozdevice 16:55:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozfile 16:55:17 INFO - Running setup.py install for mozhttpd 16:55:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozinfo 16:55:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozInstall 16:55:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozleak 16:55:17 INFO - Running setup.py install for mozlog 16:55:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for moznetwork 16:55:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:55:17 INFO - Running setup.py install for mozprocess 16:55:17 INFO - Running setup.py install for mozprofile 16:55:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Running setup.py install for mozrunner 16:55:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Running setup.py install for mozscreenshot 16:55:18 INFO - Running setup.py install for moztest 16:55:18 INFO - Running setup.py install for mozversion 16:55:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:55:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:55:18 INFO - Cleaning up... 16:55:18 INFO - Return code: 0 16:55:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55: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')]} 16:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55: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')]} 16:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:18 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:18 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 16:55:18 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 16:55:18 INFO - Using env: {'DISPLAY': ':0', 16:55:18 INFO - 'HOME': '/home/cltbld', 16:55:18 INFO - 'LANG': 'en_US.UTF-8', 16:55:18 INFO - 'LANGUAGE': 'en_US:en', 16:55:18 INFO - 'LOGNAME': 'cltbld', 16:55:18 INFO - 'MAIL': '/var/mail/cltbld', 16:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:18 INFO - 'MOZ_NO_REMOTE': '1', 16:55:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:18 INFO - 'NO_EM_RESTART': '1', 16:55:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:18 INFO - 'PWD': '/builds/slave/test', 16:55:18 INFO - 'SHELL': '/bin/bash', 16:55:18 INFO - 'SHLVL': '1', 16:55:18 INFO - 'TERM': 'linux', 16:55:18 INFO - 'TMOUT': '86400', 16:55:18 INFO - 'USER': 'cltbld', 16:55:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:18 INFO - '_': '/tools/buildbot/bin/python'} 16:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:18 INFO - Running setup.py (path:/tmp/pip-3h_Ekk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:18 INFO - Running setup.py (path:/tmp/pip-9Q7R5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:18 INFO - Running setup.py (path:/tmp/pip-kXCgoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:18 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)) 16:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:18 INFO - Running setup.py (path:/tmp/pip-3Z1iQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:19 INFO - Running setup.py (path:/tmp/pip-09cBbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:19 INFO - Running setup.py (path:/tmp/pip-I2gqB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:19 INFO - Running setup.py (path:/tmp/pip-XsnwWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:19 INFO - Running setup.py (path:/tmp/pip-82imuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:19 INFO - Running setup.py (path:/tmp/pip-C0fDJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:19 INFO - Running setup.py (path:/tmp/pip-NxKk7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:19 INFO - Running setup.py (path:/tmp/pip-rEiR8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:19 INFO - Running setup.py (path:/tmp/pip-_xGov0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:19 INFO - Running setup.py (path:/tmp/pip-hw4MBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:19 INFO - Running setup.py (path:/tmp/pip-D8kNDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:19 INFO - Running setup.py (path:/tmp/pip-0Szu0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:19 INFO - Running setup.py (path:/tmp/pip-VBOfE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:19 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)) 16:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:19 INFO - Running setup.py (path:/tmp/pip-gCBvFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 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)) 16:55:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:22 INFO - Downloading blessings-1.6.tar.gz 16:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:55:22 INFO - Installing collected packages: blessings 16:55:22 INFO - Running setup.py install for blessings 16:55:22 INFO - Successfully installed blessings 16:55:22 INFO - Cleaning up... 16:55:22 INFO - Return code: 0 16:55:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:22 INFO - Reading from file tmpfile_stdout 16:55:22 INFO - Current package versions: 16:55:22 INFO - argparse == 1.2.1 16:55:22 INFO - blessings == 1.6 16:55:22 INFO - blobuploader == 1.2.4 16:55:22 INFO - docopt == 0.6.1 16:55:22 INFO - functools32 == 3.2.3-2 16:55:22 INFO - jsonschema == 2.5.1 16:55:22 INFO - manifestparser == 1.1 16:55:22 INFO - mozInstall == 1.12 16:55:22 INFO - mozcrash == 1.0 16:55:22 INFO - mozdebug == 0.1 16:55:22 INFO - mozdevice == 0.49 16:55:22 INFO - mozfile == 1.2 16:55:22 INFO - mozhttpd == 0.7 16:55:22 INFO - mozinfo == 0.9 16:55:22 INFO - mozleak == 0.1 16:55:22 INFO - mozlog == 3.4 16:55:22 INFO - moznetwork == 0.27 16:55:22 INFO - mozprocess == 0.25 16:55:22 INFO - mozprofile == 0.28 16:55:22 INFO - mozrunner == 6.13 16:55:22 INFO - mozscreenshot == 0.1 16:55:22 INFO - mozsystemmonitor == 0.3 16:55:22 INFO - moztest == 0.8 16:55:22 INFO - mozversion == 1.4 16:55:22 INFO - psutil == 3.1.1 16:55:22 INFO - requests == 1.2.3 16:55:22 INFO - wsgiref == 0.1.2 16:55:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:22 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')]} 16:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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')]} 16:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:22 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 16:55:22 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 16:55:22 INFO - Using env: {'DISPLAY': ':0', 16:55:22 INFO - 'HOME': '/home/cltbld', 16:55:22 INFO - 'LANG': 'en_US.UTF-8', 16:55:22 INFO - 'LANGUAGE': 'en_US:en', 16:55:22 INFO - 'LOGNAME': 'cltbld', 16:55:22 INFO - 'MAIL': '/var/mail/cltbld', 16:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:22 INFO - 'MOZ_NO_REMOTE': '1', 16:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:22 INFO - 'NO_EM_RESTART': '1', 16:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:22 INFO - 'PWD': '/builds/slave/test', 16:55:22 INFO - 'SHELL': '/bin/bash', 16:55:22 INFO - 'SHLVL': '1', 16:55:22 INFO - 'TERM': 'linux', 16:55:22 INFO - 'TMOUT': '86400', 16:55:22 INFO - 'USER': 'cltbld', 16:55:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:22 INFO - '_': '/tools/buildbot/bin/python'} 16:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 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)) 16:55:22 INFO - Cleaning up... 16:55:22 INFO - Return code: 0 16:55:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:55:22 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')]} 16:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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')]} 16:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:22 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 0x22b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2557310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, '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': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', '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 16:55:22 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 16:55:22 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 16:55:22 INFO - Using env: {'DISPLAY': ':0', 16:55:22 INFO - 'HOME': '/home/cltbld', 16:55:22 INFO - 'LANG': 'en_US.UTF-8', 16:55:22 INFO - 'LANGUAGE': 'en_US:en', 16:55:22 INFO - 'LOGNAME': 'cltbld', 16:55:22 INFO - 'MAIL': '/var/mail/cltbld', 16:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:22 INFO - 'MOZ_NO_REMOTE': '1', 16:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:22 INFO - 'NO_EM_RESTART': '1', 16:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:22 INFO - 'PWD': '/builds/slave/test', 16:55:22 INFO - 'SHELL': '/bin/bash', 16:55:22 INFO - 'SHLVL': '1', 16:55:22 INFO - 'TERM': 'linux', 16:55:22 INFO - 'TMOUT': '86400', 16:55:22 INFO - 'USER': 'cltbld', 16:55:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:22 INFO - '_': '/tools/buildbot/bin/python'} 16:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:55:23 INFO - Cleaning up... 16:55:23 INFO - Return code: 0 16:55:23 INFO - Running post-action listener: _resource_record_post_action 16:55:23 INFO - Running post-action listener: _start_resource_monitoring 16:55:23 INFO - Starting resource monitoring. 16:55:23 INFO - [mozharness: 2017-04-06 23:55:23.208727Z] Finished create-virtualenv step (success) 16:55:23 INFO - [mozharness: 2017-04-06 23:55:23.209192Z] Running install step. 16:55:23 INFO - Running pre-action listener: _resource_record_pre_action 16:55:23 INFO - Running main action method: install 16:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:23 INFO - Reading from file tmpfile_stdout 16:55:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:55:23 INFO - Reading from file tmpfile_stdout 16:55:23 INFO - Output received: 16:55:23 INFO - Usage: mozinstall [options] installer 16:55:23 INFO - Options: 16:55:23 INFO - -h, --help show this help message and exit 16:55:23 INFO - -d DEST, --destination=DEST 16:55:23 INFO - Directory to install application into. [default: 16:55:23 INFO - "/builds/slave/test"] 16:55:23 INFO - --app=APP Application being installed. [default: firefox] 16:55:23 INFO - mkdir: /builds/slave/test/build/application 16:55:23 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'] 16:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:55:40 INFO - Reading from file tmpfile_stdout 16:55:40 INFO - Output received: 16:55:40 INFO - /builds/slave/test/build/application/firefox/firefox 16:55:40 INFO - Running post-action listener: _resource_record_post_action 16:55:40 INFO - [mozharness: 2017-04-06 23:55:40.035967Z] Finished install step (success) 16:55:40 INFO - [mozharness: 2017-04-06 23:55:40.036240Z] Running run-tests step. 16:55:40 INFO - Running pre-action listener: _resource_record_pre_action 16:55:40 INFO - Running main action method: run_tests 16:55:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:55:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:55:40 INFO - Python 2.7.3 16:55:40 INFO - Return code: 0 16:55:40 INFO - grabbing minidump binary from tooltool 16:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:40 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 0x2554400>, '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 0x25553b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2555840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:55:40 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 16:55:40 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 16:55:40 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 16:55:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:55:40 INFO - Return code: 0 16:55:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:55:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:55:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:55:40 INFO - ENV: RUST_BACKTRACE is now 1 16:55:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:55:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:55:40 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/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:55:40 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/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 16:55:40 INFO - Using env: {'DISPLAY': ':0', 16:55:40 INFO - 'HOME': '/home/cltbld', 16:55:40 INFO - 'LANG': 'en_US.UTF-8', 16:55:40 INFO - 'LANGUAGE': 'en_US:en', 16:55:40 INFO - 'LOGNAME': 'cltbld', 16:55:40 INFO - 'MAIL': '/var/mail/cltbld', 16:55:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:55:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:40 INFO - 'MOZ_NO_REMOTE': '1', 16:55:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:55:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:40 INFO - 'NO_EM_RESTART': '1', 16:55:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:40 INFO - 'PWD': '/builds/slave/test', 16:55:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:55:40 INFO - 'RUST_BACKTRACE': '1', 16:55:40 INFO - 'SHELL': '/bin/bash', 16:55:40 INFO - 'SHLVL': '1', 16:55:40 INFO - 'TERM': 'linux', 16:55:40 INFO - 'TMOUT': '86400', 16:55:40 INFO - 'USER': 'cltbld', 16:55:40 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1491522869.10035-305780684', 16:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:40 INFO - '_': '/tools/buildbot/bin/python'} 16:55:40 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/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:55:40 INFO - mozversion application_buildid: 20170406224927 16:55:40 INFO - mozversion application_changeset: 3f67a4d375a17ece4f0a3cb108703ab3f39e060b 16:55:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:55:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:55:40 INFO - mozversion application_name: Firefox 16:55:40 INFO - mozversion application_remotingname: firefox-dev 16:55:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:55:40 INFO - mozversion application_vendor: Mozilla 16:55:40 INFO - mozversion application_version: 54.0a2 16:55:40 INFO - mozversion platform_buildid: 20170406224927 16:55:40 INFO - mozversion platform_changeset: 3f67a4d375a17ece4f0a3cb108703ab3f39e060b 16:55:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:55:40 INFO - mozversion platform_version: 54.0a2 16:55:40 INFO - using testdate: 1491522940 16:55:40 INFO - actual date: 1491522940 16:55:40 INFO - starting webserver on 'localhost:53241' 16:55:40 INFO - SUITE-START | Running 2 tests 16:55:40 INFO - TEST-START | dromaeo_css 16:55:40 INFO - operating with platform_type : linux_ 16:55:40 INFO - Initialising browser for dromaeo_css test... 16:55:40 INFO - TEST-INFO | started process 5974 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0DqMH/profile http://localhost:53241/getInfo.html) 16:55:44 INFO - PROCESS | 5974 | __metrics Screen width/height:1600/1200 16:55:44 INFO - PROCESS | 5974 | colorDepth:24 16:55:44 INFO - PROCESS | 5974 | Browser inner width/height: 1024/697 16:55:44 INFO - PROCESS | 5974 | __metrics 16:55:45 INFO - PROCESS | 5974 | 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" 16:55:45 INFO - PROCESS | 5974 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:55:46 INFO - TEST-INFO | 5974: exit 0 16:55:46 INFO - Browser initialized. 16:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 16:55:46 INFO - TEST-INFO | started process 6067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe0DqMH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:55:58 INFO - PROCESS | 6067 | RSS: Main: 139829248 16:55:58 INFO - PROCESS | 6067 | 16:59:01 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53241/tests/dromaeo/cssquery-ext.html) 16:59:02 INFO - PROCESS | 6067 | RSS: Main: 223875072 16:59:02 INFO - PROCESS | 6067 | 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | A coding exception was thrown and uncaught in a Task. 17:00:48 INFO - PROCESS | 6067 | 17:00:48 ERROR - PROCESS | 6067 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:00:48 INFO - PROCESS | 6067 | Full stack: 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | A coding exception was thrown and uncaught in a Task. 17:00:48 INFO - PROCESS | 6067 | 17:00:48 ERROR - PROCESS | 6067 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:00:48 INFO - PROCESS | 6067 | Full stack: 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | A coding exception was thrown and uncaught in a Task. 17:00:48 INFO - PROCESS | 6067 | 17:00:48 ERROR - PROCESS | 6067 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:00:48 INFO - PROCESS | 6067 | Full stack: 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | ************************* 17:00:48 INFO - PROCESS | 6067 | A coding exception was thrown and uncaught in a Task. 17:00:48 INFO - PROCESS | 6067 | 17:00:48 ERROR - PROCESS | 6067 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:00:48 INFO - PROCESS | 6067 | Full stack: 17:00:48 INFO - PROCESS | 6067 | ************************* 17:02:04 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-ext.html (next: http://localhost:53241/tests/dromaeo/cssquery-jquery.html) 17:02:05 INFO - PROCESS | 6067 | RSS: Main: 220565504 17:02:05 INFO - PROCESS | 6067 | 17:05:07 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53241/tests/dromaeo/cssquery-mootools.html) 17:05:07 INFO - PROCESS | 6067 | RSS: Main: 170651648 17:05:07 INFO - PROCESS | 6067 | 17:08:09 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53241/tests/dromaeo/cssquery-prototype.html) 17:08:10 INFO - PROCESS | 6067 | RSS: Main: 170852352 17:08:10 INFO - PROCESS | 6067 | 17:11:13 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53241/tests/dromaeo/cssquery-yui.html) 17:11:13 INFO - PROCESS | 6067 | RSS: Main: 176721920 17:11:13 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | Cycle 1(1): loaded http://localhost:53241/tests/dromaeo/cssquery-yui.html (next: http://localhost:53241/tests/dromaeo/cssquery-dojo.html) 17:14:16 INFO - PROCESS | 6067 | RSS: Main: 264192000 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | __start_tp_report 17:14:16 INFO - PROCESS | 6067 | _x_x_mozilla_page_load 17:14:16 INFO - PROCESS | 6067 | _x_x_mozilla_page_load_details 17:14:16 INFO - PROCESS | 6067 | |i|pagename|runs| 17:14:16 INFO - PROCESS | 6067 | |0;dojo.html;3326.2515933914856;3332.1003329101136;3354.2788300515385;3361.966380336199;3398.6771256730603;5491.478309560591;5539.390667026624;5549.334079910406;5564.693941833202;5572.164175373696;5495.505404513593;5545.417731138233;5556.277683901088;5564.276644036279;5583.35791383991;5016.924746128801;5041.420236672775;5074.720890351029;5094.79620815167;5129.563987061104;4988.002399520111;5013.172826483644;5057.898842023157;5106.514881086291;5107.055194788957;5063.316452278932;5093;5160.096983027974;5174.560162386202;5174.689518628876;5171.9224211636665;5175.844724658266;5178.741062946838;5182.896342073137;5207.791688332462;4858.344123543312;5098.107831129574;5104.591632669378;5156.226566015091;5167.121589329823;5485.424030476823;5514.751836167382;5564.304461942257;5573.386927437979;5575.972120139414;5996.340402555716;6041.063635136561;6046.213992181032;6048.637081775108;6052.4552790248645;6070.57505974582;6084.908726369108;6096.268447786249;6106.694665266763;6108.053251745942;5474.042042642562;5496.477834605706;5579.8884022319735;5610.887782244414;5615.101583746663;6171.074338849119;6172.290186628596;6174.351693072253;6174.845628859314;6180.536459765535;5896.911546326808;5951.970240148771;5960.791372302036;5963.5527335450015;5983.581149319593;5789.363170051291;5819.941800582024;5953.613015154001;5981.192539007266;6027.8794424111275;5927.436893495111;5986.7904623338845;5988.341282458926;6008.579399441997;6009.038553831366;5785;5840;5847.239858818326;5907.497862681635;5951.077582974646;4489.236829738952;4504.009117994036;4509.616682582017;4520.254158063948;4532.909341813145;4006.4390985262085;4017.5580686124504;4026.074002979273;4078.327076032481;4107.712460127822;5853.707314634251;5869.119632055226;5885.558583106199;5896.64620122794;5912.526997840162;5998.640081595118;5999.910001349983;6003.849903752353;6015.789447369321;6021.638701677914;5720.856978575485;5746.195532625436;5843.503302219359;5873.794417195378;5874.853128671817;5086.18621020635;5110.488951104934;5139.280500729901;5164.948350516522;5183.2743415921805;6279.403456671609;6290.842728931813;6337.841553961188;6339;6339.334369891188;5780.364159942487;6029.306629737636;6032.788852390146;6042.305134909541;6043.244594425697;6182.876342473035;6225.782097626561;6228.346023667631;6229.626222426669;6238.594491358047;5793.073108302651;5893.823185304448;5948.107783832286;5957.3744756799915;5967.671778052249;6041.728122234422;6043.516518678407;6045.818625441244;6052.848678783065;6062.545309101747;5709.800156994652;5782.739776709974;5880.588358814842;5892.764289428546;5905.881882362414;5829.59192856496;5855.38518455556;5887.970560147171;5954.523638108854;5959.22530071088;5692.5445964321925;5890.204822349099;5896.675682837314;5898.144769008555;5904.852378690567;5780.421957804354;5812.73842677089;5951.1370851225465;5954.493868021266;5990.9700451497465;5834.912476312944;5837.357890632112;5839.532837373085;5840.182374467493;5868.853278667897 17:14:16 INFO - PROCESS | 6067 | |1;ext.html;786.2766255045358;823;843.0852525010365;895.0467751844285;902.7878448564591;31063.06810795677;42920.141597168076;43678.563214367845;43816.342754858706;43842.12315753683;45295.77352113243;45552.544474555245;45574;45622;45649.08701825961;7686.346660533862;7835.373170146403;9092.181703646671;9238.907610923872;9243.352965292432;10543.314684545518;10738.872418396035;10744.462776861164;10746.086582640484;10750.139988800876;10852.240343175981;10898;10898;10901.345919244832;10906.291091079054;10665.520051597698;10804.72988175294;10825;10830.350178989285;10860.13118950486;10372;10409;10410.42742649154;10414.062734353869;10414.531346089445;42039.15921681549;42610.78694606507;42652.78673606643;42691;42694;4018.8794336169963;4035.2131334389514;4066.207089617526;4152.584741525853;4180.979095104505;29597.260068498246;29821.254468638457;30246.84876575625;30253.092407227818;30421.847890760626;3645.598984111772;3706.110533471974;3752.81235938202;3761.9059523511855;3837.961620383788;222886.88556557277;223894;224190;224437;225225;52821.471785282425;53038;53106;53183.73408133012;53308;54994.450055498935;55054.44945550493;55058.72470637621;55067;55195.448045519835;16309.51071467858;16475.505734827973;16480.42318518846;16489;16492.505224843273;12942.935285323512;13073.934630326978;14228.928855355864;14611.196384198758;14611.634709132357;12885.871141288655;13104.475820967054;13104.606861794162;13180.879625231772;13186.340682965812;5520.696361700145;5557;5563.888722225533;5597.244372009726;5706.3722990471015;1218.5560111268176;1265.0828149591657;1277.4060062070996;1282.3716378974236;1302.6873550348039;27546.724532754815;27590;27874;27877;28059.71940280612;1171.84765980422;1315.6449954581192;1344.475654494748;1389.5414513210617;1395.162902258657;13928.233947133005;14058.859411405754;14190.361433735301;14262;14427;330537;331946;332729;333705;333905;47371.289430658566;47470.5252947473;48019.27971080437;48022.5197748018;48066.75966620145;1460.6348412896775;1492.5820770184148;1522.9695406091816;1530.1813529761523;1533.302347431916;24745.752542474343;25666.486670266862;26258.606120908586;26445.867770661025;26518;35329.47005794864;35447.64552354546;35461.1134721634;35473.99999999897;35475.290494190485;289453;291559;291921;292571;292787;25256.242312730647;25490.23529294124;25709.871450643375;27569.862150689118;28864.855675721486;38433.23133537258;38494.80752596219;38999.22001559896;42342.67115308688;42714.5728542723;30923.2269193263;31453;31587.526187106752;31614.051578452683;31702.524462133548;30578.235544110685;30684;30815.383692326475;30958.226044348172;31072.378552428374;35150.12124696801;35461.29077418386;35479.11302217465;35859.46210806788;36327.636723633485;29563.704362956094;30371.2407189822;30628;30647.54028689616;31016.53475197919;23616.88191559031;24594.754052459248;24803.62794558047;25128.874355628835;25159.371015724755 17:14:16 INFO - PROCESS | 6067 | |2;jquery.html;450.111030714339;461.8106576303716;465.5042379865443;469.42507886042347;481.9879503012425;15584.844151558482;16198.514044578682;19579.5105122372;21156.788432115674;21992.560148797034;21044.263450779225;21693.132274708994;21852.12591496338;22735.20426785063;23016.88491557544;6626.635535045582;6920.411764999981;6921.8961715574305;6929.029935808991;6933.5493192942295;6101.9389806102035;6773.593584384953;6800;6835.28229535899;6883.242843287235;6682.599044057372;6871.931280687179;6876.587404755731;6879.449644028491;6879.6216208108535;6714.597124172541;6765.255821859591;6821.1814582250445;6822.7953161404985;6828.931710682879;6498.5126115541525;6580.407763301278;6631.071649969008;6696.229933557605;6696.8995465068065;25711.228663140137;25936.61095083575;25973.610395844076;26032.8698356507;26066.86966565174;3104.4877595196767;3195.7123858852583;3251.105945864906;3273.4435146024994;3299.7030267275823;15133.319000644997;16369.017858928502;16624.916875415547;16724.58188545284;16749.497515074567;2216.645336746129;2248.1457046322294;2266.7506574276817;2268.761780013108;2312.664663623765;86738.97639941142;97372;109538.45230773796;119814;122281.38859305647;17390.391336303423;18106.909465452587;18125.728114078298;18138.727919081226;18386;31968.520472192937;31984.520232196537;32040;32132.19669508281;32395.838020809744;10797.136229101652;10861.131109511378;10953.740319863318;11077.335359878272;11216.66350009501;9144.81710365789;9243.537823108818;9274.165325120772;9318.90681093194;9505.5722492488;9005.14451127142;9264;9297.652844975803;9316.462783640758;9444.102810233016;3764.1743754279155;3843.6355093941806;3856.6721828644318;3861.6524512793985;3910.159315747128;927.9675211367705;957.1720461800631;958.1328897347772;963.7590602349412;973.9707808765748;19401.611967760567;20574.58850822975;20581.176752929714;20726.215470853112;20849.89575052115;955.4744890310302;966.5650457294246;967.8161149381594;980.2696990741942;987.5161171026145;10639.25525213243;11306.208565400508;11344.546218151347;11417.60038398652;11517.712057198469;670440;715587;715640;732699;744225;86279.56860215659;101116.49441752744;103097.39159129394;108492;119721;1245.651217659057;1258.6853286678331;1286.4854058376725;1288.9291088990196;1302.7134030513273;18317.81682183161;18758.71861922045;18947.526311842314;18992.24031038743;19131.23475060982;51141.74429127831;51286.743566283425;51439.742801285756;51589.742051291;51664.74167629138;64507.35492645201;64720.029199561104;65820.34179658334;65924;67225.3277467219;11021.669349919515;11366.318020918772;11682;11890.583829565923;12047;12885.613431597067;13608.25154616466;13639.727205455636;13862.168269903837;13877.930610347288;11699;11701.356425396685;11705.707357316136;11829.17195796286;12147.392630368622;10505.474726263808;10565.366078035342;10642;11124.888751112385;11408.315501069963;11191.496382662963;11294.94352528232;11323.094152467618;12080.335581543099;12269.693257668272;11464.598739044093;11543.538258469567;11779.172998480475;11832.171747977904;11963.162578619413;10835.079018283534;10941.12471002302;10953.890461095605;11187.496562654538;11379.487923043649 17:14:16 INFO - PROCESS | 6067 | |3;mootools.html;1562.9062256264633;1573.229117732311;1595.7925469688942;1602.7435610302346;1605.1893793634213;10385.117265032472;10961.451927403628;10962;10964.451777411117;10971.945140274298;10939.343639381643;11070.948259915316;11172.162087843404;11228.157888158401;11552.191346605741;2338.4621537046532;2373.537160253751;2373.6439534069855;2373.9643905341345;2376.4177776444794;2226.7884550967715;2249.8200143988443;2382.2376839411395;2382.7855493005627;2384.701912260967;2226.4545186429345;2267.013848975693;2274.590573696734;2313.3985163857433;2322.6167682332393;2230.7323121225395;2279.179495381661;2282.4978504728933;2287.782660647236;2289.095807156172;2308.3998160478227;2317.8609283443047;2321.0019691532634;2325.4186453386656;2327.93016209514;11576.073914086854;11671.883281167164;11673.124515661275;11677.182597218198;11679.29924204542;5743.052396354638;5887.175795388649;5888.116782482619;5889.116632505158;5890.941090589082;3233.7736358454918;3280.7375409967385;3397.11674964511;3432.6910578048096;3448.3965306071586;1359.2863746533149;1362.9795553066713;1374.8006539051783;1395.4487977249032;1473.086686254529;14619.561413157622;14971.401143954228;16094.356225750957;16696.415625453174;17027.063511506985;4679.298105284234;4740.881477963009;4767.833125840648;4874.561289483963;4876.219804831281;12886.29125398112;12891.935540322425;12906.483740650268;12923.159994600508;12927.353632318347;4891.388576427947;5042.672226305279;5091.159958606789;5610.915836262459;5752.511036561939;5269.78920843162;5307.708076055854;5361.758720857571;5372.57019438444;5373.838784836461;3992.041909941593;4005.9799701001307;4046.6155715207597;4093.488313960755;4096.057906681421;1009.6213919780082;1024.1704219582161;1026.1585499890168;1026.6714651311509;1035.0736091198335;1038.672818062308;1051.090806452413;1051.1958351860833;1056.1128651932413;1065.4246706778254;6659.400653941072;6841.863162736718;6851.588904665736;6979.022936788853;7051.29487051301;1023.6263763726187;1059.3273271472517;1065.63235683689;1072.9892701073045;1079.7852416031965;3977.3039718049226;4028.5367182774357;4073.1446396256524;4123.299039163349;4175.874723758231;14215.786763198561;14752.55742327732;14770.26148692561;14777.630559236106;14781.482648107265;14146;14327.06874053204;14544.636384090483;14582.270886455635;14786.482473113389;8930.642774289086;10551.26141170126;10610.469476526143;10635.415052172204;10702.678919632424;6082.482988945989;7065.22282548909;7159.355657990805;7210;7267.491275610657;12444.875551244371;12446.564370246997;12459.252444853339;12543;12651.430685618985;12539;12662.113652044269;12690.984721222465;12694.936525317315;12785.232886026868;8989.595468203815;9105.089491050841;9180.816383672423;9180.908190918006;9198.95400522993;10781.460926953778;10833.349999000086;10840.132789376674;10956.56173753041;10962.506687198937;9178.26573874075;9184.219341355792;9241.168294853496;9346.15884570392;9587;8995.370324077252;9015.2787776979;9021.50381728985;9071.818563628558;9266.53667316618;8593.398462107592;8629.8705519416;8633.95683021581;8676.739697809076;8691;6065.090236464565;6103.542234332532;6226.906596400967;6227.937720622917;6306.621602703853;5891.49922256613;5904.527637789052;6065.7573697053385;6120.479759220516;6142.877142457215 17:14:16 INFO - PROCESS | 6067 | |4;prototype.html;412.7214130461938;418.22419411990757;424.5902703890745;426.54785926917486;432.885320300227;5157.020166168425;5226.895462090761;5282.445343238957;5284.497972692588;5299.788008479656;5141.177411614142;5190.754218987444;5215.105616382906;5238.79044838206;5252.185911183763;4428.6014258716705;4538.183127037132;4549.363089167519;4571.725696458223;4593.196190666628;4549.226631472641;4553.544645535437;4561.56665116815;4562.904902823315;4563.931541026871;4550.817967281321;4560.612347950411;4608.953910460886;4622.37597924281;4624.005838744669;4401.955980440202;4516.41286632739;4536.319552067183;4558.179527685015;4618.930716039262;4329.437173167499;4345.478542574911;4354.956450435487;4357.825686972517;4416.7191514460765;5037.319961805146;5042.445331013586;5128.333316668846;5139.665921715076;5144.279800827887;5150.420457781843;5204.765785539622;5282.603804714662;5308.699368654704;5327.17428798533;5484.396716361237;5498.80754173606;5509.338879334466;5518.420565840609;5535.308086489189;4823.614110871122;4848.903021939542;4851.320815085891;4871.196252618313;4883.291922671228;5378.515933565998;5522.806701765419;5572.6037803744375;5687.289088863892;5721.313442386899;4912.533309335608;5006.349174607278;5083.364579427572;5092.898142037138;5094.61790365724;5143.562797162208;5174.9650069986155;5176.282451712881;5176.5858731301405;5202.08963431398;4998.175301075282;5213.348331458567;5221.895562088737;5273.499017593323;5294.602904782157;5489.835304940858;5492.862678433071;5501.807436739695;5511.58663100269;5518.172274158908;1771.0967406622458;1778.8843725157824;1780.7952085510333;1790.6329202513714;1800.666876627828;1439.3450979804163;1446.182906657735;1467.889908256885;1478.741220286467;1494.798202242705;5474.835754927359;5492.176173573996;5510.960944450579;5517.61376703635;5534.667919924817;5384.703841288689;5619.286117734048;5715.228444159985;5720.914186287209;5729.341125770589;5424.698072462659;5442.673439593637;5451.509364157245;5458.344998600114;5495.505404513613;2934.413117376533;2951.5130003549602;2957.497225471675;2959.7336239738097;2985.4178435204926;5605.355384130796;5786.4828799471525;5803.8549036274435;5916.763329466859;5955.970220148872;5654.858628534237;5670.461306175989;5695.202671625976;5700.315962084576;5733.369329373765;5821.592488525847;5907.527397808251;5909.8227053188475;5921;5923.348431672513;5355.679478723217;5608.663480191201;5655.293088363955;5657.030059218943;5657.349404818498;5424.484673955968;5578.888422231614;5594.580406469449;5597.244372009726;5613.382527922007;5766.509846662912;5815.273090863642;5901.675407852609;5904.202932604044;5921.075650413719;4685.039566888711;4689.788959496899;4692.319613656069;4709.529047095263;4759.785809638505;5427.267318912054;5546.72266386671;5609.80365687199;5693.430656934273;5712.857178570569;5444.101723215784;5479.972600136974;5487.451254874481;5487.780488780404;5489.698066606215;5411.729413529387;5476.426079742515;5484;5507.9449205507435;5562.527185189304;5341.946580534145;5428.782848686008;5451.7001564912725;5475.835724928259;5506.779728810803;5500.339959204921;5514.862128446821;5520.889582208253;5561.777528898961;5592.468715472024;5314.52169304749;5350.705711185922;5499.065158923073;5511.476409741068;5539.196816461671 17:14:16 INFO - PROCESS | 6067 | |5;yui.html;737.2111840330848;767.827238871254;789.3290702902533;802.7952872017642;828.7720876758889;16488.91755541222;17770;17911.462656120337;17936.910315448422;17942;15919.522414327559;15984;16086.195690215502;16138.83861161388;16706.24821883015;874.0429229993148;888.0985799413601;892.3307519360479;905.7780843693305;917.7200953709116;905.2486436257923;908.8364094462994;911.3074063711593;911.5077857956695;912.2565109435822;953.1993125774349;953.50894289441;953.5470651440579;954.0173621170205;954.8519979403169;948.1798244518511;948.5636607160715;949.1125797379437;949.2074118111385;951.4481600671628;690.9032735417046;691.0394551573318;691.1636919327619;691.239636399961;691.9065926100013;16898.74651880223;17007.149642517823;17085;17871.821281787146;18029.368972085915;15879.285432155466;15883.841161588352;16015.35938562456;16152.83847161525;16461.423850165305;12537.686557836036;12875.034372422013;12930.353482325847;13045.543405980745;13098.934505327508;3886.378179491268;3960.960390396088;4054.5945405459515;4078.8164532596106;4102.261592913274;41649.79175104105;55216.44783552153;70828.6458567709;71436;89501;13968.650783730489;13987.160770353608;14089.225092620005;14109.647258818406;14313.355898984571;22422.887885560467;22781.202657906895;22790.202342917917;22859.771402286096;22906.885465572566;8545.273651739548;8578.399512034222;8670.95664521686;8883.555822208864;9432.575534100983;4867.294242334912;5004.824831130893;5086.287919691246;5107.438181799999;5358.812441564526;3617.8914632561064;3651.1602331463914;3680.4111342185656;3693.8891833245048;3712.2575484903127;606.5784279925409;608.8538750699801;610.4993904997948;612.4182027074297;622.8567429491243;629.7988530087283;670.718298314709;673.5217995223348;675.93240675933;681.2846511163358;10388.324758890649;10926.672199834018;11013.017656821688;11200.383978881076;11316.434178291052;575.077001412735;591.0749676755863;597.2116805816281;600.2646757721756;632.5635311634957;4970.801167953314;4993.7503124843615;5009.073321435473;5010.874728131826;5020.899582008339;84005.57997210097;85044.57477712572;85904;86075.5696221515;88668;72689;73272;75213;76382;78336.21663783403;53682.463175367746;65723.34276657268;66284.66857665681;66306.33693663098;67147.32852671508;10727.785444291227;11639.47622357013;11666.183367164216;11830.349330786888;11888.167828251939;41506;42126.36810447907;42295.7885210572;42421.78789106035;43712;42495.362569560864;42584.57415425806;45419.318710218715;47220.527794722984;47230.76384618055;11635.76728465409;11767.293962362286;11783.29300241954;11972.880271197177;12240.326782027074;14221.857781422466;15195.924020379827;15285;15314.004589701633;15715.685686285982;14160.787588186391;14891;15313.733658672169;16755.246013929158;17224.82775172232;14007.859921400655;14349.856501434851;15597.220138993232;15978;16015.679686405974;14142;14603.342849571582;15815.446459373867;15887.841121588635;16653.25060372262;11191.104711623213;11493;11766.705832354259;12572.05709571804;12767.872321276669;10804.94014138898;12955;13012.28432436225;13186.604401867959;13390.062695611603 17:14:16 INFO - PROCESS | 6067 | __end_tp_report 17:14:16 INFO - PROCESS | 6067 | __start_cc_report 17:14:16 INFO - PROCESS | 6067 | _x_x_mozilla_cycle_collect,981 17:14:16 INFO - PROCESS | 6067 | __end_cc_report 17:14:16 INFO - PROCESS | 6067 | __startTimestamp1491524056380__endTimestamp 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | ------- Summary: start ------- 17:14:16 INFO - PROCESS | 6067 | Number of tests: 6 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#0] dojo.html Cycles:165 Average:5584.83 Median:5840.18 stddev:638.86 (10.9%) stddev-sans-first:615.76 17:14:16 INFO - PROCESS | 6067 | Values: 3326.3 3332.1 3354.3 3362.0 3398.7 5491.5 5539.4 5549.3 5564.7 5572.2 5495.5 5545.4 5556.3 5564.3 5583.4 5016.9 5041.4 5074.7 5094.8 5129.6 4988.0 5013.2 5057.9 5106.5 5107.1 5063.3 5093.0 5160.1 5174.6 5174.7 5171.9 5175.8 5178.7 5182.9 5207.8 4858.3 5098.1 5104.6 5156.2 5167.1 5485.4 5514.8 5564.3 5573.4 5576.0 5996.3 6041.1 6046.2 6048.6 6052.5 6070.6 6084.9 6096.3 6106.7 6108.1 5474.0 5496.5 5579.9 5610.9 5615.1 6171.1 6172.3 6174.4 6174.8 6180.5 5896.9 5952.0 5960.8 5963.6 5983.6 5789.4 5819.9 5953.6 5981.2 6027.9 5927.4 5986.8 5988.3 6008.6 6009.0 5785.0 5840.0 5847.2 5907.5 5951.1 4489.2 4504.0 4509.6 4520.3 4532.9 4006.4 4017.6 4026.1 4078.3 4107.7 5853.7 5869.1 5885.6 5896.6 5912.5 5998.6 5999.9 6003.8 6015.8 6021.6 5720.9 5746.2 5843.5 5873.8 5874.9 5086.2 5110.5 5139.3 5164.9 5183.3 6279.4 6290.8 6337.8 6339.0 6339.3 5780.4 6029.3 6032.8 6042.3 6043.2 6182.9 6225.8 6228.3 6229.6 6238.6 5793.1 5893.8 5948.1 5957.4 5967.7 6041.7 6043.5 6045.8 6052.8 6062.5 5709.8 5782.7 5880.6 5892.8 5905.9 5829.6 5855.4 5888.0 5954.5 5959.2 5692.5 5890.2 5896.7 5898.1 5904.9 5780.4 5812.7 5951.1 5954.5 5991.0 5834.9 5837.4 5839.5 5840.2 5868.9 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#1] ext.html Cycles:180 Average:44441.05 Median:30396.54 stddev:74864.36 (246.3%) stddev-sans-first:75002.62 17:14:16 INFO - PROCESS | 6067 | Values: 786.3 823.0 843.1 895.0 902.8 31063.1 42920.1 43678.6 43816.3 43842.1 45295.8 45552.5 45574.0 45622.0 45649.1 7686.3 7835.4 9092.2 9238.9 9243.4 10543.3 10738.9 10744.5 10746.1 10750.1 10852.2 10898.0 10898.0 10901.3 10906.3 10665.5 10804.7 10825.0 10830.4 10860.1 10372.0 10409.0 10410.4 10414.1 10414.5 42039.2 42610.8 42652.8 42691.0 42694.0 4018.9 4035.2 4066.2 4152.6 4181.0 29597.3 29821.3 30246.8 30253.1 30421.8 3645.6 3706.1 3752.8 3761.9 3838.0 222886.9 223894.0 224190.0 224437.0 225225.0 52821.5 53038.0 53106.0 53183.7 53308.0 54994.5 55054.4 55058.7 55067.0 55195.4 16309.5 16475.5 16480.4 16489.0 16492.5 12942.9 13073.9 14228.9 14611.2 14611.6 12885.9 13104.5 13104.6 13180.9 13186.3 5520.7 5557.0 5563.9 5597.2 5706.4 1218.6 1265.1 1277.4 1282.4 1302.7 27546.7 27590.0 27874.0 27877.0 28059.7 1171.8 1315.6 1344.5 1389.5 1395.2 13928.2 14058.9 14190.4 14262.0 14427.0 330537.0 331946.0 332729.0 333705.0 333905.0 47371.3 47470.5 48019.3 48022.5 48066.8 1460.6 1492.6 1523.0 1530.2 1533.3 24745.8 25666.5 26258.6 26445.9 26518.0 35329.5 35447.6 35461.1 35474.0 35475.3 289453.0 291559.0 291921.0 292571.0 292787.0 25256.2 25490.2 25709.9 27569.9 28864.9 38433.2 38494.8 38999.2 42342.7 42714.6 30923.2 31453.0 31587.5 31614.1 31702.5 30578.2 30684.0 30815.4 30958.2 31072.4 35150.1 35461.3 35479.1 35859.5 36327.6 29563.7 30371.2 30628.0 30647.5 31016.5 23616.9 24594.8 24803.6 25128.9 25159.4 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#2] jquery.html Cycles:180 Average:38503.77 Median:12664.77 stddev:117537.73 (928.1%) stddev-sans-first:117832.72 17:14:16 INFO - PROCESS | 6067 | Values: 450.1 461.8 465.5 469.4 482.0 15584.8 16198.5 19579.5 21156.8 21992.6 21044.3 21693.1 21852.1 22735.2 23016.9 6626.6 6920.4 6921.9 6929.0 6933.5 6101.9 6773.6 6800.0 6835.3 6883.2 6682.6 6871.9 6876.6 6879.4 6879.6 6714.6 6765.3 6821.2 6822.8 6828.9 6498.5 6580.4 6631.1 6696.2 6696.9 25711.2 25936.6 25973.6 26032.9 26066.9 3104.5 3195.7 3251.1 3273.4 3299.7 15133.3 16369.0 16624.9 16724.6 16749.5 2216.6 2248.1 2266.8 2268.8 2312.7 86739.0 97372.0 109538.5 119814.0 122281.4 17390.4 18106.9 18125.7 18138.7 18386.0 31968.5 31984.5 32040.0 32132.2 32395.8 10797.1 10861.1 10953.7 11077.3 11216.7 9144.8 9243.5 9274.2 9318.9 9505.6 9005.1 9264.0 9297.7 9316.5 9444.1 3764.2 3843.6 3856.7 3861.7 3910.2 928.0 957.2 958.1 963.8 974.0 19401.6 20574.6 20581.2 20726.2 20849.9 955.5 966.6 967.8 980.3 987.5 10639.3 11306.2 11344.5 11417.6 11517.7 670440.0 715587.0 715640.0 732699.0 744225.0 86279.6 101116.5 103097.4 108492.0 119721.0 1245.7 1258.7 1286.5 1288.9 1302.7 18317.8 18758.7 18947.5 18992.2 19131.2 51141.7 51286.7 51439.7 51589.7 51664.7 64507.4 64720.0 65820.3 65924.0 67225.3 11021.7 11366.3 11682.0 11890.6 12047.0 12885.6 13608.3 13639.7 13862.2 13877.9 11699.0 11701.4 11705.7 11829.2 12147.4 10505.5 10565.4 10642.0 11124.9 11408.3 11191.5 11294.9 11323.1 12080.3 12269.7 11464.6 11543.5 11779.2 11832.2 11963.2 10835.1 10941.1 10953.9 11187.5 11379.5 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#3] mootools.html Cycles:180 Average:6961.06 Median:2315.63 stddev:4455.22 (192.4%) stddev-sans-first:4449.25 17:14:16 INFO - PROCESS | 6067 | Values: 1562.9 1573.2 1595.8 1602.7 1605.2 10385.1 10961.5 10962.0 10964.5 10971.9 10939.3 11070.9 11172.2 11228.2 11552.2 2338.5 2373.5 2373.6 2374.0 2376.4 2226.8 2249.8 2382.2 2382.8 2384.7 2226.5 2267.0 2274.6 2313.4 2322.6 2230.7 2279.2 2282.5 2287.8 2289.1 2308.4 2317.9 2321.0 2325.4 2327.9 11576.1 11671.9 11673.1 11677.2 11679.3 5743.1 5887.2 5888.1 5889.1 5890.9 3233.8 3280.7 3397.1 3432.7 3448.4 1359.3 1363.0 1374.8 1395.4 1473.1 14619.6 14971.4 16094.4 16696.4 17027.1 4679.3 4740.9 4767.8 4874.6 4876.2 12886.3 12891.9 12906.5 12923.2 12927.4 4891.4 5042.7 5091.2 5610.9 5752.5 5269.8 5307.7 5361.8 5372.6 5373.8 3992.0 4006.0 4046.6 4093.5 4096.1 1009.6 1024.2 1026.2 1026.7 1035.1 1038.7 1051.1 1051.2 1056.1 1065.4 6659.4 6841.9 6851.6 6979.0 7051.3 1023.6 1059.3 1065.6 1073.0 1079.8 3977.3 4028.5 4073.1 4123.3 4175.9 14215.8 14752.6 14770.3 14777.6 14781.5 14146.0 14327.1 14544.6 14582.3 14786.5 8930.6 10551.3 10610.5 10635.4 10702.7 6082.5 7065.2 7159.4 7210.0 7267.5 12444.9 12446.6 12459.3 12543.0 12651.4 12539.0 12662.1 12691.0 12694.9 12785.2 8989.6 9105.1 9180.8 9180.9 9199.0 10781.5 10833.3 10840.1 10956.6 10962.5 9178.3 9184.2 9241.2 9346.2 9587.0 8995.4 9015.3 9021.5 9071.8 9266.5 8593.4 8629.9 8634.0 8676.7 8691.0 6065.1 6103.5 6226.9 6227.9 6306.6 5891.5 5904.5 6065.8 6120.5 6142.9 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#4] prototype.html Cycles:180 Average:4877.04 Median:5334.56 stddev:1247.06 (23.4%) stddev-sans-first:1204.70 17:14:16 INFO - PROCESS | 6067 | Values: 412.7 418.2 424.6 426.5 432.9 5157.0 5226.9 5282.4 5284.5 5299.8 5141.2 5190.8 5215.1 5238.8 5252.2 4428.6 4538.2 4549.4 4571.7 4593.2 4549.2 4553.5 4561.6 4562.9 4563.9 4550.8 4560.6 4609.0 4622.4 4624.0 4402.0 4516.4 4536.3 4558.2 4618.9 4329.4 4345.5 4355.0 4357.8 4416.7 5037.3 5042.4 5128.3 5139.7 5144.3 5150.4 5204.8 5282.6 5308.7 5327.2 5484.4 5498.8 5509.3 5518.4 5535.3 4823.6 4848.9 4851.3 4871.2 4883.3 5378.5 5522.8 5572.6 5687.3 5721.3 4912.5 5006.3 5083.4 5092.9 5094.6 5143.6 5175.0 5176.3 5176.6 5202.1 4998.2 5213.3 5221.9 5273.5 5294.6 5489.8 5492.9 5501.8 5511.6 5518.2 1771.1 1778.9 1780.8 1790.6 1800.7 1439.3 1446.2 1467.9 1478.7 1494.8 5474.8 5492.2 5511.0 5517.6 5534.7 5384.7 5619.3 5715.2 5720.9 5729.3 5424.7 5442.7 5451.5 5458.3 5495.5 2934.4 2951.5 2957.5 2959.7 2985.4 5605.4 5786.5 5803.9 5916.8 5956.0 5654.9 5670.5 5695.2 5700.3 5733.4 5821.6 5907.5 5909.8 5921.0 5923.3 5355.7 5608.7 5655.3 5657.0 5657.3 5424.5 5578.9 5594.6 5597.2 5613.4 5766.5 5815.3 5901.7 5904.2 5921.1 4685.0 4689.8 4692.3 4709.5 4759.8 5427.3 5546.7 5609.8 5693.4 5712.9 5444.1 5480.0 5487.5 5487.8 5489.7 5411.7 5476.4 5484.0 5507.9 5562.5 5341.9 5428.8 5451.7 5475.8 5506.8 5500.3 5514.9 5520.9 5561.8 5592.5 5314.5 5350.7 5499.1 5511.5 5539.2 17:14:16 INFO - PROCESS | 6067 | 17:14:16 INFO - PROCESS | 6067 | [#5] yui.html Cycles:180 Average:18032.34 Median:41577.90 stddev:22219.64 (53.4%) stddev-sans-first:22244.02 17:14:16 INFO - PROCESS | 6067 | Values: 737.2 767.8 789.3 802.8 828.8 16488.9 17770.0 17911.5 17936.9 17942.0 15919.5 15984.0 16086.2 16138.8 16706.2 874.0 888.1 892.3 905.8 917.7 905.2 908.8 911.3 911.5 912.3 953.2 953.5 953.5 954.0 954.9 948.2 948.6 949.1 949.2 951.4 690.9 691.0 691.2 691.2 691.9 16898.7 17007.1 17085.0 17871.8 18029.4 15879.3 15883.8 16015.4 16152.8 16461.4 12537.7 12875.0 12930.4 13045.5 13098.9 3886.4 3961.0 4054.6 4078.8 4102.3 41649.8 55216.4 70828.6 71436.0 89501.0 13968.7 13987.2 14089.2 14109.6 14313.4 22422.9 22781.2 22790.2 22859.8 22906.9 8545.3 8578.4 8671.0 8883.6 9432.6 4867.3 5004.8 5086.3 5107.4 5358.8 3617.9 3651.2 3680.4 3693.9 3712.3 606.6 608.9 610.5 612.4 622.9 629.8 670.7 673.5 675.9 681.3 10388.3 10926.7 11013.0 11200.4 11316.4 575.1 591.1 597.2 600.3 632.6 4970.8 4993.8 5009.1 5010.9 5020.9 84005.6 85044.6 85904.0 86075.6 88668.0 72689.0 73272.0 75213.0 76382.0 78336.2 53682.5 65723.3 66284.7 66306.3 67147.3 10727.8 11639.5 11666.2 11830.3 11888.2 41506.0 42126.4 42295.8 42421.8 43712.0 42495.4 42584.6 45419.3 47220.5 47230.8 11635.8 11767.3 11783.3 11972.9 12240.3 14221.9 15195.9 15285.0 15314.0 15715.7 14160.8 14891.0 15313.7 16755.2 17224.8 14007.9 14349.9 15597.2 15978.0 16015.7 14142.0 14603.3 15815.4 15887.8 16653.3 11191.1 11493.0 11766.7 12572.1 12767.9 10804.9 12955.0 13012.3 13186.6 13390.1 17:14:16 INFO - PROCESS | 6067 | -------- Summary: end -------- 17:14:16 INFO - PROCESS | 6067 | 17:14:17 INFO - TEST-INFO | 6067: exit 0 17:14:17 INFO - TEST-OK | dromaeo_css | took 1116869ms 17:14:17 INFO - TEST-START | kraken 17:14:17 INFO - operating with platform_type : linux_ 17:14:17 INFO - Initialising browser for kraken test... 17:14:17 INFO - TEST-INFO | started process 6391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_tvwNO/profile http://localhost:53241/getInfo.html) 17:14:21 INFO - PROCESS | 6391 | __metrics Screen width/height:1600/1200 17:14:21 INFO - PROCESS | 6391 | colorDepth:24 17:14:21 INFO - PROCESS | 6391 | Browser inner width/height: 1024/697 17:14:21 INFO - PROCESS | 6391 | __metrics 17:14:22 INFO - PROCESS | 6391 | 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" 17:14:22 INFO - PROCESS | 6391 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:14:22 INFO - PROCESS | 6391 | console.error: PushService: 17:14:22 INFO - PROCESS | 6391 | stateChangeProcessEnqueue: Error transitioning state 17:14:22 INFO - PROCESS | 6391 | UnknownError 17:14:23 INFO - TEST-INFO | 6391: exit 0 17:14:23 INFO - Browser initialized. 17:14:23 INFO - Running cycle 1/1 for kraken test... 17:14:23 INFO - TEST-INFO | started process 6488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_tvwNO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:35 INFO - PROCESS | 6488 | RSS: Main: 139935744 17:14:35 INFO - PROCESS | 6488 | 17:14:37 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53241/tests/kraken/driver.html?testName=audio-beat-detection) 17:14:38 INFO - PROCESS | 6488 | RSS: Main: 183013376 17:14:38 INFO - PROCESS | 6488 | 17:14:41 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53241/tests/kraken/driver.html?testName=audio-dft) 17:14:41 INFO - PROCESS | 6488 | RSS: Main: 173662208 17:14:41 INFO - PROCESS | 6488 | 17:14:44 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53241/tests/kraken/driver.html?testName=audio-fft) 17:14:45 INFO - PROCESS | 6488 | RSS: Main: 266264576 17:14:45 INFO - PROCESS | 6488 | 17:14:47 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53241/tests/kraken/driver.html?testName=audio-oscillator) 17:14:47 INFO - PROCESS | 6488 | RSS: Main: 172109824 17:14:47 INFO - PROCESS | 6488 | 17:14:49 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:14:50 INFO - PROCESS | 6488 | RSS: Main: 174501888 17:14:50 INFO - PROCESS | 6488 | 17:14:53 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53241/tests/kraken/driver.html?testName=imaging-darkroom) 17:14:53 INFO - PROCESS | 6488 | RSS: Main: 213651456 17:14:53 INFO - PROCESS | 6488 | 17:14:57 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53241/tests/kraken/driver.html?testName=imaging-desaturate) 17:14:57 INFO - PROCESS | 6488 | RSS: Main: 212054016 17:14:57 INFO - PROCESS | 6488 | 17:15:00 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53241/tests/kraken/driver.html?testName=json-parse-financial) 17:15:01 INFO - PROCESS | 6488 | RSS: Main: 211144704 17:15:01 INFO - PROCESS | 6488 | 17:15:02 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:15:03 INFO - PROCESS | 6488 | RSS: Main: 173187072 17:15:03 INFO - PROCESS | 6488 | 17:15:05 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:15:05 INFO - PROCESS | 6488 | RSS: Main: 179683328 17:15:05 INFO - PROCESS | 6488 | 17:15:07 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:15:08 INFO - PROCESS | 6488 | RSS: Main: 189173760 17:15:08 INFO - PROCESS | 6488 | 17:15:10 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:15:11 INFO - PROCESS | 6488 | RSS: Main: 199434240 17:15:11 INFO - PROCESS | 6488 | 17:15:14 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:15:14 INFO - PROCESS | 6488 | RSS: Main: 195452928 17:15:14 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | Cycle 1(1): loaded http://localhost:53241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53241/tests/kraken/driver.html?testName=ai-astar) 17:15:16 INFO - PROCESS | 6488 | RSS: Main: 247341056 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | __start_tp_report 17:15:16 INFO - PROCESS | 6488 | _x_x_mozilla_page_load 17:15:16 INFO - PROCESS | 6488 | _x_x_mozilla_page_load_details 17:15:16 INFO - PROCESS | 6488 | |i|pagename|runs| 17:15:16 INFO - PROCESS | 6488 | |0;ai-astar;103;115;111;111;114;112;113;111;112;112 17:15:16 INFO - PROCESS | 6488 | |1;audio-beat-detection;147;114;115;155;120;116;115;116;136;116 17:15:16 INFO - PROCESS | 6488 | |2;audio-dft;161;160;160;159;161;160;163;163;158;159 17:15:16 INFO - PROCESS | 6488 | |3;audio-fft;75;76;76;116;75;76;77;76;89;75 17:15:16 INFO - PROCESS | 6488 | |4;audio-oscillator;82;82;104;83;83;82;104;81;82;100 17:15:16 INFO - PROCESS | 6488 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;114 17:15:16 INFO - PROCESS | 6488 | |6;imaging-darkroom;146;146;146;147;146;147;146;146;146;146 17:15:16 INFO - PROCESS | 6488 | |7;imaging-desaturate;94;94;94;94;94;93;93;94;94;94 17:15:16 INFO - PROCESS | 6488 | |8;json-parse-financial;54;53;54;54;53;53;54;54;53;54 17:15:16 INFO - PROCESS | 6488 | |9;json-stringify-tinderbox;53;55;53;53;54;53;53;54;53;53 17:15:16 INFO - PROCESS | 6488 | |10;stanford-crypto-aes;79;82;81;76;76;83;76;74;74;80 17:15:16 INFO - PROCESS | 6488 | |11;stanford-crypto-ccm;121;121;123;120;129;128;122;122;157;134 17:15:16 INFO - PROCESS | 6488 | |12;stanford-crypto-pbkdf2;163;160;158;159;162;167;161;159;158;157 17:15:16 INFO - PROCESS | 6488 | |13;stanford-crypto-sha256-iterative;59;59;59;60;60;67;59;59;67;59 17:15:16 INFO - PROCESS | 6488 | __end_tp_report 17:15:16 INFO - PROCESS | 6488 | __start_cc_report 17:15:16 INFO - PROCESS | 6488 | _x_x_mozilla_cycle_collect,1694 17:15:16 INFO - PROCESS | 6488 | __end_cc_report 17:15:16 INFO - PROCESS | 6488 | __startTimestamp1491524116709__endTimestamp 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | ------- Summary: start ------- 17:15:16 INFO - PROCESS | 6488 | Number of tests: 14 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:3.24 (2.9%) stddev-sans-first:1.41 17:15:16 INFO - PROCESS | 6488 | Values: 103.0 115.0 111.0 111.0 114.0 112.0 113.0 111.0 112.0 112.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:118.00 stddev:15.25 (12.9%) stddev-sans-first:13.95 17:15:16 INFO - PROCESS | 6488 | Values: 147.0 114.0 115.0 155.0 120.0 116.0 115.0 116.0 136.0 116.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.65 (1.0%) stddev-sans-first:1.73 17:15:16 INFO - PROCESS | 6488 | Values: 161.0 160.0 160.0 159.0 161.0 160.0 163.0 163.0 158.0 159.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:12.97 (17.1%) stddev-sans-first:13.56 17:15:16 INFO - PROCESS | 6488 | Values: 75.0 76.0 76.0 116.0 75.0 76.0 77.0 76.0 89.0 75.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:82.00 stddev:9.99 (12.2%) stddev-sans-first:10.33 17:15:16 INFO - PROCESS | 6488 | Values: 82.0 82.0 104.0 83.0 83.0 82.0 104.0 81.0 82.0 100.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:15:16 INFO - PROCESS | 6488 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 17:15:16 INFO - PROCESS | 6488 | Values: 146.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:15:16 INFO - PROCESS | 6488 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.52 (1.0%) stddev-sans-first:0.53 17:15:16 INFO - PROCESS | 6488 | Values: 54.0 53.0 54.0 54.0 53.0 53.0 54.0 54.0 53.0 54.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 17:15:16 INFO - PROCESS | 6488 | Values: 53.0 55.0 53.0 53.0 54.0 53.0 53.0 54.0 53.0 53.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:79.50 stddev:3.31 (4.2%) stddev-sans-first:3.50 17:15:16 INFO - PROCESS | 6488 | Values: 79.0 82.0 81.0 76.0 76.0 83.0 76.0 74.0 74.0 80.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:125.50 stddev:11.24 (9.0%) stddev-sans-first:11.65 17:15:16 INFO - PROCESS | 6488 | Values: 121.0 121.0 123.0 120.0 129.0 128.0 122.0 122.0 157.0 134.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.40 Median:160.50 stddev:2.99 (1.9%) stddev-sans-first:3.02 17:15:16 INFO - PROCESS | 6488 | Values: 163.0 160.0 158.0 159.0 162.0 167.0 161.0 159.0 158.0 157.0 17:15:16 INFO - PROCESS | 6488 | 17:15:16 INFO - PROCESS | 6488 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:59.50 stddev:3.29 (5.5%) stddev-sans-first:3.43 17:15:16 INFO - PROCESS | 6488 | Values: 59.0 59.0 59.0 60.0 60.0 67.0 59.0 59.0 67.0 59.0 17:15:16 INFO - PROCESS | 6488 | -------- Summary: end -------- 17:15:16 INFO - PROCESS | 6488 | 17:15:17 INFO - TEST-INFO | 6488: exit 0 17:15:17 INFO - TEST-OK | kraken | took 60559ms 17:15:17 INFO - SUITE-END | took 1177s 17:15:17 INFO - Completed test suite (00:19:37) 17:15:17 INFO - javascript benchmark 17:15:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7720.202206839941, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3326.2515933914856, 3332.1003329101136, 3354.2788300515385, 3361.966380336199, 3398.6771256730603, 5491.478309560591, 5539.390667026624, 5549.334079910406, 5564.693941833202, 5572.164175373696, 5495.505404513593, 5545.417731138233, 5556.277683901088, 5564.276644036279, 5583.35791383991, 5016.924746128801, 5041.420236672775, 5074.720890351029, 5094.79620815167, 5129.563987061104, 4988.002399520111, 5013.172826483644, 5057.898842023157, 5106.514881086291, 5107.055194788957, 5063.316452278932, 5093.0, 5160.096983027974, 5174.560162386202, 5174.689518628876, 5171.9224211636665, 5175.844724658266, 5178.741062946838, 5182.896342073137, 5207.791688332462, 4858.344123543312, 5098.107831129574, 5104.591632669378, 5156.226566015091, 5167.121589329823, 5485.424030476823, 5514.751836167382, 5564.304461942257, 5573.386927437979, 5575.972120139414, 5996.340402555716, 6041.063635136561, 6046.213992181032, 6048.637081775108, 6052.4552790248645, 6070.57505974582, 6084.908726369108, 6096.268447786249, 6106.694665266763, 6108.053251745942, 5474.042042642562, 5496.477834605706, 5579.8884022319735, 5610.887782244414, 5615.101583746663, 6171.074338849119, 6172.290186628596, 6174.351693072253, 6174.845628859314, 6180.536459765535, 5896.911546326808, 5951.970240148771, 5960.791372302036, 5963.5527335450015, 5983.581149319593, 5789.363170051291, 5819.941800582024, 5953.613015154001, 5981.192539007266, 6027.8794424111275, 5927.436893495111, 5986.7904623338845, 5988.341282458926, 6008.579399441997, 6009.038553831366, 5785.0, 5840.0, 5847.239858818326, 5907.497862681635, 5951.077582974646, 4489.236829738952, 4504.009117994036, 4509.616682582017, 4520.254158063948, 4532.909341813145, 4006.4390985262085, 4017.5580686124504, 4026.074002979273, 4078.327076032481, 4107.712460127822, 5853.707314634251, 5869.119632055226, 5885.558583106199, 5896.64620122794, 5912.526997840162, 5998.640081595118, 5999.910001349983, 6003.849903752353, 6015.789447369321, 6021.638701677914, 5720.856978575485, 5746.195532625436, 5843.503302219359, 5873.794417195378, 5874.853128671817, 5086.18621020635, 5110.488951104934, 5139.280500729901, 5164.948350516522, 5183.2743415921805, 6279.403456671609, 6290.842728931813, 6337.841553961188, 6339.0, 6339.334369891188, 5780.364159942487, 6029.306629737636, 6032.788852390146, 6042.305134909541, 6043.244594425697, 6182.876342473035, 6225.782097626561, 6228.346023667631, 6229.626222426669, 6238.594491358047, 5793.073108302651, 5893.823185304448, 5948.107783832286, 5957.3744756799915, 5967.671778052249, 6041.728122234422, 6043.516518678407, 6045.818625441244, 6052.848678783065, 6062.545309101747, 5709.800156994652, 5782.739776709974, 5880.588358814842, 5892.764289428546, 5905.881882362414, 5829.59192856496, 5855.38518455556, 5887.970560147171, 5954.523638108854, 5959.22530071088, 5692.5445964321925, 5890.204822349099, 5896.675682837314, 5898.144769008555, 5904.852378690567, 5780.421957804354, 5812.73842677089, 5951.1370851225465, 5954.493868021266, 5990.9700451497465, 5834.912476312944, 5837.357890632112, 5839.532837373085, 5840.182374467493, 5868.853278667897], "value": 5542.054569197625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.2766255045358, 823.0, 843.0852525010365, 895.0467751844285, 902.7878448564591, 31063.06810795677, 42920.141597168076, 43678.563214367845, 43816.342754858706, 43842.12315753683, 45295.77352113243, 45552.544474555245, 45574.0, 45622.0, 45649.08701825961, 7686.346660533862, 7835.373170146403, 9092.181703646671, 9238.907610923872, 9243.352965292432, 10543.314684545518, 10738.872418396035, 10744.462776861164, 10746.086582640484, 10750.139988800876, 10852.240343175981, 10898.0, 10898.0, 10901.345919244832, 10906.291091079054, 10665.520051597698, 10804.72988175294, 10825.0, 10830.350178989285, 10860.13118950486, 10372.0, 10409.0, 10410.42742649154, 10414.062734353869, 10414.531346089445, 42039.15921681549, 42610.78694606507, 42652.78673606643, 42691.0, 42694.0, 4018.8794336169963, 4035.2131334389514, 4066.207089617526, 4152.584741525853, 4180.979095104505, 29597.260068498246, 29821.254468638457, 30246.84876575625, 30253.092407227818, 30421.847890760626, 3645.598984111772, 3706.110533471974, 3752.81235938202, 3761.9059523511855, 3837.961620383788, 222886.88556557277, 223894.0, 224190.0, 224437.0, 225225.0, 52821.471785282425, 53038.0, 53106.0, 53183.73408133012, 53308.0, 54994.450055498935, 55054.44945550493, 55058.72470637621, 55067.0, 55195.448045519835, 16309.51071467858, 16475.505734827973, 16480.42318518846, 16489.0, 16492.505224843273, 12942.935285323512, 13073.934630326978, 14228.928855355864, 14611.196384198758, 14611.634709132357, 12885.871141288655, 13104.475820967054, 13104.606861794162, 13180.879625231772, 13186.340682965812, 5520.696361700145, 5557.0, 5563.888722225533, 5597.244372009726, 5706.3722990471015, 1218.5560111268176, 1265.0828149591657, 1277.4060062070996, 1282.3716378974236, 1302.6873550348039, 27546.724532754815, 27590.0, 27874.0, 27877.0, 28059.71940280612, 1171.84765980422, 1315.6449954581192, 1344.475654494748, 1389.5414513210617, 1395.162902258657, 13928.233947133005, 14058.859411405754, 14190.361433735301, 14262.0, 14427.0, 330537.0, 331946.0, 332729.0, 333705.0, 333905.0, 47371.289430658566, 47470.5252947473, 48019.27971080437, 48022.5197748018, 48066.75966620145, 1460.6348412896775, 1492.5820770184148, 1522.9695406091816, 1530.1813529761523, 1533.302347431916, 24745.752542474343, 25666.486670266862, 26258.606120908586, 26445.867770661025, 26518.0, 35329.47005794864, 35447.64552354546, 35461.1134721634, 35473.99999999897, 35475.290494190485, 289453.0, 291559.0, 291921.0, 292571.0, 292787.0, 25256.242312730647, 25490.23529294124, 25709.871450643375, 27569.862150689118, 28864.855675721486, 38433.23133537258, 38494.80752596219, 38999.22001559896, 42342.67115308688, 42714.5728542723, 30923.2269193263, 31453.0, 31587.526187106752, 31614.051578452683, 31702.524462133548, 30578.235544110685, 30684.0, 30815.383692326475, 30958.226044348172, 31072.378552428374, 35150.12124696801, 35461.29077418386, 35479.11302217465, 35859.46210806788, 36327.636723633485, 29563.704362956094, 30371.2407189822, 30628.0, 30647.54028689616, 31016.53475197919, 23616.88191559031, 24594.754052459248, 24803.62794558047, 25128.874355628835, 25159.371015724755], "value": 18547.180490889874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.111030714339, 461.8106576303716, 465.5042379865443, 469.42507886042347, 481.9879503012425, 15584.844151558482, 16198.514044578682, 19579.5105122372, 21156.788432115674, 21992.560148797034, 21044.263450779225, 21693.132274708994, 21852.12591496338, 22735.20426785063, 23016.88491557544, 6626.635535045582, 6920.411764999981, 6921.8961715574305, 6929.029935808991, 6933.5493192942295, 6101.9389806102035, 6773.593584384953, 6800.0, 6835.28229535899, 6883.242843287235, 6682.599044057372, 6871.931280687179, 6876.587404755731, 6879.449644028491, 6879.6216208108535, 6714.597124172541, 6765.255821859591, 6821.1814582250445, 6822.7953161404985, 6828.931710682879, 6498.5126115541525, 6580.407763301278, 6631.071649969008, 6696.229933557605, 6696.8995465068065, 25711.228663140137, 25936.61095083575, 25973.610395844076, 26032.8698356507, 26066.86966565174, 3104.4877595196767, 3195.7123858852583, 3251.105945864906, 3273.4435146024994, 3299.7030267275823, 15133.319000644997, 16369.017858928502, 16624.916875415547, 16724.58188545284, 16749.497515074567, 2216.645336746129, 2248.1457046322294, 2266.7506574276817, 2268.761780013108, 2312.664663623765, 86738.97639941142, 97372.0, 109538.45230773796, 119814.0, 122281.38859305647, 17390.391336303423, 18106.909465452587, 18125.728114078298, 18138.727919081226, 18386.0, 31968.520472192937, 31984.520232196537, 32040.0, 32132.19669508281, 32395.838020809744, 10797.136229101652, 10861.131109511378, 10953.740319863318, 11077.335359878272, 11216.66350009501, 9144.81710365789, 9243.537823108818, 9274.165325120772, 9318.90681093194, 9505.5722492488, 9005.14451127142, 9264.0, 9297.652844975803, 9316.462783640758, 9444.102810233016, 3764.1743754279155, 3843.6355093941806, 3856.6721828644318, 3861.6524512793985, 3910.159315747128, 927.9675211367705, 957.1720461800631, 958.1328897347772, 963.7590602349412, 973.9707808765748, 19401.611967760567, 20574.58850822975, 20581.176752929714, 20726.215470853112, 20849.89575052115, 955.4744890310302, 966.5650457294246, 967.8161149381594, 980.2696990741942, 987.5161171026145, 10639.25525213243, 11306.208565400508, 11344.546218151347, 11417.60038398652, 11517.712057198469, 670440.0, 715587.0, 715640.0, 732699.0, 744225.0, 86279.56860215659, 101116.49441752744, 103097.39159129394, 108492.0, 119721.0, 1245.651217659057, 1258.6853286678331, 1286.4854058376725, 1288.9291088990196, 1302.7134030513273, 18317.81682183161, 18758.71861922045, 18947.526311842314, 18992.24031038743, 19131.23475060982, 51141.74429127831, 51286.743566283425, 51439.742801285756, 51589.742051291, 51664.74167629138, 64507.35492645201, 64720.029199561104, 65820.34179658334, 65924.0, 67225.3277467219, 11021.669349919515, 11366.318020918772, 11682.0, 11890.583829565923, 12047.0, 12885.613431597067, 13608.25154616466, 13639.727205455636, 13862.168269903837, 13877.930610347288, 11699.0, 11701.356425396685, 11705.707357316136, 11829.17195796286, 12147.392630368622, 10505.474726263808, 10565.366078035342, 10642.0, 11124.888751112385, 11408.315501069963, 11191.496382662963, 11294.94352528232, 11323.094152467618, 12080.335581543099, 12269.693257668272, 11464.598739044093, 11543.538258469567, 11779.172998480475, 11832.171747977904, 11963.162578619413, 10835.079018283534, 10941.12471002302, 10953.890461095605, 11187.496562654538, 11379.487923043649], "value": 11274.574874819822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1562.9062256264633, 1573.229117732311, 1595.7925469688942, 1602.7435610302346, 1605.1893793634213, 10385.117265032472, 10961.451927403628, 10962.0, 10964.451777411117, 10971.945140274298, 10939.343639381643, 11070.948259915316, 11172.162087843404, 11228.157888158401, 11552.191346605741, 2338.4621537046532, 2373.537160253751, 2373.6439534069855, 2373.9643905341345, 2376.4177776444794, 2226.7884550967715, 2249.8200143988443, 2382.2376839411395, 2382.7855493005627, 2384.701912260967, 2226.4545186429345, 2267.013848975693, 2274.590573696734, 2313.3985163857433, 2322.6167682332393, 2230.7323121225395, 2279.179495381661, 2282.4978504728933, 2287.782660647236, 2289.095807156172, 2308.3998160478227, 2317.8609283443047, 2321.0019691532634, 2325.4186453386656, 2327.93016209514, 11576.073914086854, 11671.883281167164, 11673.124515661275, 11677.182597218198, 11679.29924204542, 5743.052396354638, 5887.175795388649, 5888.116782482619, 5889.116632505158, 5890.941090589082, 3233.7736358454918, 3280.7375409967385, 3397.11674964511, 3432.6910578048096, 3448.3965306071586, 1359.2863746533149, 1362.9795553066713, 1374.8006539051783, 1395.4487977249032, 1473.086686254529, 14619.561413157622, 14971.401143954228, 16094.356225750957, 16696.415625453174, 17027.063511506985, 4679.298105284234, 4740.881477963009, 4767.833125840648, 4874.561289483963, 4876.219804831281, 12886.29125398112, 12891.935540322425, 12906.483740650268, 12923.159994600508, 12927.353632318347, 4891.388576427947, 5042.672226305279, 5091.159958606789, 5610.915836262459, 5752.511036561939, 5269.78920843162, 5307.708076055854, 5361.758720857571, 5372.57019438444, 5373.838784836461, 3992.041909941593, 4005.9799701001307, 4046.6155715207597, 4093.488313960755, 4096.057906681421, 1009.6213919780082, 1024.1704219582161, 1026.1585499890168, 1026.6714651311509, 1035.0736091198335, 1038.672818062308, 1051.090806452413, 1051.1958351860833, 1056.1128651932413, 1065.4246706778254, 6659.400653941072, 6841.863162736718, 6851.588904665736, 6979.022936788853, 7051.29487051301, 1023.6263763726187, 1059.3273271472517, 1065.63235683689, 1072.9892701073045, 1079.7852416031965, 3977.3039718049226, 4028.5367182774357, 4073.1446396256524, 4123.299039163349, 4175.874723758231, 14215.786763198561, 14752.55742327732, 14770.26148692561, 14777.630559236106, 14781.482648107265, 14146.0, 14327.06874053204, 14544.636384090483, 14582.270886455635, 14786.482473113389, 8930.642774289086, 10551.26141170126, 10610.469476526143, 10635.415052172204, 10702.678919632424, 6082.482988945989, 7065.22282548909, 7159.355657990805, 7210.0, 7267.491275610657, 12444.875551244371, 12446.564370246997, 12459.252444853339, 12543.0, 12651.430685618985, 12539.0, 12662.113652044269, 12690.984721222465, 12694.936525317315, 12785.232886026868, 8989.595468203815, 9105.089491050841, 9180.816383672423, 9180.908190918006, 9198.95400522993, 10781.460926953778, 10833.349999000086, 10840.132789376674, 10956.56173753041, 10962.506687198937, 9178.26573874075, 9184.219341355792, 9241.168294853496, 9346.15884570392, 9587.0, 8995.370324077252, 9015.2787776979, 9021.50381728985, 9071.818563628558, 9266.53667316618, 8593.398462107592, 8629.8705519416, 8633.95683021581, 8676.739697809076, 8691.0, 6065.090236464565, 6103.542234332532, 6226.906596400967, 6227.937720622917, 6306.621602703853, 5891.49922256613, 5904.527637789052, 6065.7573697053385, 6120.479759220516, 6142.877142457215], "value": 5268.192575133682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.7214130461938, 418.22419411990757, 424.5902703890745, 426.54785926917486, 432.885320300227, 5157.020166168425, 5226.895462090761, 5282.445343238957, 5284.497972692588, 5299.788008479656, 5141.177411614142, 5190.754218987444, 5215.105616382906, 5238.79044838206, 5252.185911183763, 4428.6014258716705, 4538.183127037132, 4549.363089167519, 4571.725696458223, 4593.196190666628, 4549.226631472641, 4553.544645535437, 4561.56665116815, 4562.904902823315, 4563.931541026871, 4550.817967281321, 4560.612347950411, 4608.953910460886, 4622.37597924281, 4624.005838744669, 4401.955980440202, 4516.41286632739, 4536.319552067183, 4558.179527685015, 4618.930716039262, 4329.437173167499, 4345.478542574911, 4354.956450435487, 4357.825686972517, 4416.7191514460765, 5037.319961805146, 5042.445331013586, 5128.333316668846, 5139.665921715076, 5144.279800827887, 5150.420457781843, 5204.765785539622, 5282.603804714662, 5308.699368654704, 5327.17428798533, 5484.396716361237, 5498.80754173606, 5509.338879334466, 5518.420565840609, 5535.308086489189, 4823.614110871122, 4848.903021939542, 4851.320815085891, 4871.196252618313, 4883.291922671228, 5378.515933565998, 5522.806701765419, 5572.6037803744375, 5687.289088863892, 5721.313442386899, 4912.533309335608, 5006.349174607278, 5083.364579427572, 5092.898142037138, 5094.61790365724, 5143.562797162208, 5174.9650069986155, 5176.282451712881, 5176.5858731301405, 5202.08963431398, 4998.175301075282, 5213.348331458567, 5221.895562088737, 5273.499017593323, 5294.602904782157, 5489.835304940858, 5492.862678433071, 5501.807436739695, 5511.58663100269, 5518.172274158908, 1771.0967406622458, 1778.8843725157824, 1780.7952085510333, 1790.6329202513714, 1800.666876627828, 1439.3450979804163, 1446.182906657735, 1467.889908256885, 1478.741220286467, 1494.798202242705, 5474.835754927359, 5492.176173573996, 5510.960944450579, 5517.61376703635, 5534.667919924817, 5384.703841288689, 5619.286117734048, 5715.228444159985, 5720.914186287209, 5729.341125770589, 5424.698072462659, 5442.673439593637, 5451.509364157245, 5458.344998600114, 5495.505404513613, 2934.413117376533, 2951.5130003549602, 2957.497225471675, 2959.7336239738097, 2985.4178435204926, 5605.355384130796, 5786.4828799471525, 5803.8549036274435, 5916.763329466859, 5955.970220148872, 5654.858628534237, 5670.461306175989, 5695.202671625976, 5700.315962084576, 5733.369329373765, 5821.592488525847, 5907.527397808251, 5909.8227053188475, 5921.0, 5923.348431672513, 5355.679478723217, 5608.663480191201, 5655.293088363955, 5657.030059218943, 5657.349404818498, 5424.484673955968, 5578.888422231614, 5594.580406469449, 5597.244372009726, 5613.382527922007, 5766.509846662912, 5815.273090863642, 5901.675407852609, 5904.202932604044, 5921.075650413719, 4685.039566888711, 4689.788959496899, 4692.319613656069, 4709.529047095263, 4759.785809638505, 5427.267318912054, 5546.72266386671, 5609.80365687199, 5693.430656934273, 5712.857178570569, 5444.101723215784, 5479.972600136974, 5487.451254874481, 5487.780488780404, 5489.698066606215, 5411.729413529387, 5476.426079742515, 5484.0, 5507.9449205507435, 5562.527185189304, 5341.946580534145, 5428.782848686008, 5451.7001564912725, 5475.835724928259, 5506.779728810803, 5500.339959204921, 5514.862128446821, 5520.889582208253, 5561.777528898961, 5592.468715472024, 5314.52169304749, 5350.705711185922, 5499.065158923073, 5511.476409741068, 5539.196816461671], "value": 4522.209549857521, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.2111840330848, 767.827238871254, 789.3290702902533, 802.7952872017642, 828.7720876758889, 16488.91755541222, 17770.0, 17911.462656120337, 17936.910315448422, 17942.0, 15919.522414327559, 15984.0, 16086.195690215502, 16138.83861161388, 16706.24821883015, 874.0429229993148, 888.0985799413601, 892.3307519360479, 905.7780843693305, 917.7200953709116, 905.2486436257923, 908.8364094462994, 911.3074063711593, 911.5077857956695, 912.2565109435822, 953.1993125774349, 953.50894289441, 953.5470651440579, 954.0173621170205, 954.8519979403169, 948.1798244518511, 948.5636607160715, 949.1125797379437, 949.2074118111385, 951.4481600671628, 690.9032735417046, 691.0394551573318, 691.1636919327619, 691.239636399961, 691.9065926100013, 16898.74651880223, 17007.149642517823, 17085.0, 17871.821281787146, 18029.368972085915, 15879.285432155466, 15883.841161588352, 16015.35938562456, 16152.83847161525, 16461.423850165305, 12537.686557836036, 12875.034372422013, 12930.353482325847, 13045.543405980745, 13098.934505327508, 3886.378179491268, 3960.960390396088, 4054.5945405459515, 4078.8164532596106, 4102.261592913274, 41649.79175104105, 55216.44783552153, 70828.6458567709, 71436.0, 89501.0, 13968.650783730489, 13987.160770353608, 14089.225092620005, 14109.647258818406, 14313.355898984571, 22422.887885560467, 22781.202657906895, 22790.202342917917, 22859.771402286096, 22906.885465572566, 8545.273651739548, 8578.399512034222, 8670.95664521686, 8883.555822208864, 9432.575534100983, 4867.294242334912, 5004.824831130893, 5086.287919691246, 5107.438181799999, 5358.812441564526, 3617.8914632561064, 3651.1602331463914, 3680.4111342185656, 3693.8891833245048, 3712.2575484903127, 606.5784279925409, 608.8538750699801, 610.4993904997948, 612.4182027074297, 622.8567429491243, 629.7988530087283, 670.718298314709, 673.5217995223348, 675.93240675933, 681.2846511163358, 10388.324758890649, 10926.672199834018, 11013.017656821688, 11200.383978881076, 11316.434178291052, 575.077001412735, 591.0749676755863, 597.2116805816281, 600.2646757721756, 632.5635311634957, 4970.801167953314, 4993.7503124843615, 5009.073321435473, 5010.874728131826, 5020.899582008339, 84005.57997210097, 85044.57477712572, 85904.0, 86075.5696221515, 88668.0, 72689.0, 73272.0, 75213.0, 76382.0, 78336.21663783403, 53682.463175367746, 65723.34276657268, 66284.66857665681, 66306.33693663098, 67147.32852671508, 10727.785444291227, 11639.47622357013, 11666.183367164216, 11830.349330786888, 11888.167828251939, 41506.0, 42126.36810447907, 42295.7885210572, 42421.78789106035, 43712.0, 42495.362569560864, 42584.57415425806, 45419.318710218715, 47220.527794722984, 47230.76384618055, 11635.76728465409, 11767.293962362286, 11783.29300241954, 11972.880271197177, 12240.326782027074, 14221.857781422466, 15195.924020379827, 15285.0, 15314.004589701633, 15715.685686285982, 14160.787588186391, 14891.0, 15313.733658672169, 16755.246013929158, 17224.82775172232, 14007.859921400655, 14349.856501434851, 15597.220138993232, 15978.0, 16015.679686405974, 14142.0, 14603.342849571582, 15815.446459373867, 15887.841121588635, 16653.25060372262, 11191.104711623213, 11493.0, 11766.705832354259, 12572.05709571804, 12767.872321276669, 10804.94014138898, 12955.0, 13012.28432436225, 13186.604401867959, 13390.062695611603], "value": 7667.838940828924, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 111.0, 111.0, 114.0, 112.0, 113.0, 111.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 115.0, 155.0, 120.0, 116.0, 115.0, 116.0, 136.0, 116.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 161.0, 160.0, 163.0, 163.0, 158.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 116.0, 75.0, 76.0, 77.0, 76.0, 89.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 104.0, 83.0, 83.0, 82.0, 104.0, 81.0, 82.0, 100.0], "value": 88.3, "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, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 54.0, 53.0, 53.0, 54.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 81.0, 76.0, 76.0, 83.0, 76.0, 74.0, 74.0, 80.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 123.0, 120.0, 129.0, 128.0, 122.0, 122.0, 157.0, 134.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 158.0, 159.0, 162.0, 167.0, 161.0, 159.0, 158.0, 157.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 67.0, 59.0, 59.0, 67.0, 59.0], "value": 60.8, "unit": "score"}]}]} 17:15:17 INFO - Return code: 0 17:15:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:15:18 ERROR - # TBPL SUCCESS # 17:15:18 INFO - Running post-action listener: _resource_record_post_action 17:15:18 INFO - [mozharness: 2017-04-07 00:15:18.042873Z] Finished run-tests step (success) 17:15:18 INFO - Running post-run listener: _resource_record_post_run 17:15:18 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 3862528; Write bytes: 374861824; Read time: 732; Write time: 330676 17:15:18 INFO - TinderboxPrint: CPU usage
13.1% 17:15:18 INFO - TinderboxPrint: I/O read bytes / time
3,862,528 / 732 17:15:18 INFO - TinderboxPrint: I/O write bytes / time
374,861,824 / 330,676 17:15:18 INFO - TinderboxPrint: CPU idle
8,276.8 (86.8%) 17:15:18 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 17:15:18 INFO - TinderboxPrint: Swap in / out
0 / 0 17:15:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 121364480; Read time: 36; Write time: 156936 17:15:18 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 3858432; Write bytes: 253095936; Read time: 696; Write time: 173700 17:15:18 INFO - Running post-run listener: _upload_blobber_files 17:15:18 INFO - Blob upload gear active. 17:15:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:15:18 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 17:15:18 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'] 17:15:18 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 17:15:19 INFO - (blobuploader) - INFO - Open directory for files ... 17:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:19 INFO - (blobuploader) - INFO - Done attempting. 17:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:15:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:22 INFO - (blobuploader) - INFO - Done attempting. 17:15:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:15:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:23 INFO - (blobuploader) - INFO - Done attempting. 17:15:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:15:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:15:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:24 INFO - (blobuploader) - INFO - Done attempting. 17:15:24 INFO - (blobuploader) - INFO - Iteration through files over. 17:15:24 INFO - Return code: 0 17:15:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:15:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:15:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e64e8bff8c9deaf6762416b29311814a0e0774fb9a67f7bbcd34fc93acff56c47b555f9022347bdff22b829aec799a36b3edd70118061d3161747fcc7a39f8fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98ce276a7ca4ab47a056fed52d91e29c3fc0f797463378a432b6f0368c932795a5da1e6cc5f1a0f24e8998a216d18554d2470c635a10b5b037d67a70328e4b01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f406a36ac66a2c3bc9c67f6c488574586d52f57fa30c8b5f0f4f06c7ecde748ce9f852ccb400c113a62e4f0490fb0a3760ab9428f6a212a93863614d495bc022", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/596e75c39f6badd30bde58c488622950efb02c83839b77139232e5d1dbc9be9bc67ab934dedf5569e4e14e1a2efccb30128f0e4d11627598fea28d30276f885a"} 17:15:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:15:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:15:24 INFO - Contents: 17:15:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e64e8bff8c9deaf6762416b29311814a0e0774fb9a67f7bbcd34fc93acff56c47b555f9022347bdff22b829aec799a36b3edd70118061d3161747fcc7a39f8fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98ce276a7ca4ab47a056fed52d91e29c3fc0f797463378a432b6f0368c932795a5da1e6cc5f1a0f24e8998a216d18554d2470c635a10b5b037d67a70328e4b01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f406a36ac66a2c3bc9c67f6c488574586d52f57fa30c8b5f0f4f06c7ecde748ce9f852ccb400c113a62e4f0490fb0a3760ab9428f6a212a93863614d495bc022", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/596e75c39f6badd30bde58c488622950efb02c83839b77139232e5d1dbc9be9bc67ab934dedf5569e4e14e1a2efccb30128f0e4d11627598fea28d30276f885a"} 17:15:24 INFO - Running post-run listener: copy_logs_to_upload_dir 17:15:24 INFO - Copying logs to upload dir... 17:15:24 INFO - mkdir: /builds/slave/test/build/upload/logs 17:15:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.838772 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-06 17:15:24.459841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 17:15:24.464248) ========= 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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e64e8bff8c9deaf6762416b29311814a0e0774fb9a67f7bbcd34fc93acff56c47b555f9022347bdff22b829aec799a36b3edd70118061d3161747fcc7a39f8fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98ce276a7ca4ab47a056fed52d91e29c3fc0f797463378a432b6f0368c932795a5da1e6cc5f1a0f24e8998a216d18554d2470c635a10b5b037d67a70328e4b01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f406a36ac66a2c3bc9c67f6c488574586d52f57fa30c8b5f0f4f06c7ecde748ce9f852ccb400c113a62e4f0490fb0a3760ab9428f6a212a93863614d495bc022", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/596e75c39f6badd30bde58c488622950efb02c83839b77139232e5d1dbc9be9bc67ab934dedf5569e4e14e1a2efccb30128f0e4d11627598fea28d30276f885a"} build_url:https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011421 build_url: 'https://queue.taskcluster.net/v1/task/Hmz_xXPRSfiIW2lfeBlpLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e64e8bff8c9deaf6762416b29311814a0e0774fb9a67f7bbcd34fc93acff56c47b555f9022347bdff22b829aec799a36b3edd70118061d3161747fcc7a39f8fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98ce276a7ca4ab47a056fed52d91e29c3fc0f797463378a432b6f0368c932795a5da1e6cc5f1a0f24e8998a216d18554d2470c635a10b5b037d67a70328e4b01", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f406a36ac66a2c3bc9c67f6c488574586d52f57fa30c8b5f0f4f06c7ecde748ce9f852ccb400c113a62e4f0490fb0a3760ab9428f6a212a93863614d495bc022", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/596e75c39f6badd30bde58c488622950efb02c83839b77139232e5d1dbc9be9bc67ab934dedf5569e4e14e1a2efccb30128f0e4d11627598fea28d30276f885a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 17:15:24.494508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 17:15:24.494901) ========= 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=745a4373a2a16df560f216e000000264-1491522869.10035-305780684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 17:15:24.575305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 17:15:24.575641) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 17:15:24.575989) ========= ========= Total master_lag: 0.17 =========