builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1505124619.73 results: success (0) revision: 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.728053) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.728522) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.728796) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.746416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.746814) ========= 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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-11 03:10:19-- 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% 41.9M=0s 2017-09-11 03:10:19 (41.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.844906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.845230) ========= 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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019014 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:19.895289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 03:10:19.895631) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f --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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-11 03:10:19,951 truncating revision to first 12 chars 2017-09-11 03:10:19,951 Setting DEBUG logging. 2017-09-11 03:10:19,951 attempt 1/10 2017-09-11 03:10:19,951 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f1893590a1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-11 03:10:20,650 unpacking tar archive at: mozilla-central-1f1893590a1d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.246133 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 03:10:21.158608) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:21.158997) ========= script_repo_revision: 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:21.159444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:21.159739) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:10:21.173392) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-09-11 03:10:21.173829) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:10:21 INFO - MultiFileLogger online at 20170911 03:10:21 in /builds/slave/test 03:10:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:10:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:10:21 INFO - {'append_to_log': False, 03:10:21 INFO - 'base_work_dir': '/builds/slave/test', 03:10:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:10:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:10:21 INFO - 'branch': 'Firefox-Non-PGO', 03:10:21 INFO - 'buildbot_json_path': 'buildprops.json', 03:10:21 INFO - 'code_coverage': False, 03:10:21 INFO - 'config_files': ('talos/linux_config.py',), 03:10:21 INFO - 'default_actions': ('clobber', 03:10:21 INFO - 'read-buildbot-config', 03:10:21 INFO - 'download-and-extract', 03:10:21 INFO - 'populate-webroot', 03:10:21 INFO - 'create-virtualenv', 03:10:21 INFO - 'install', 03:10:21 INFO - 'setup-mitmproxy', 03:10:21 INFO - 'run-tests'), 03:10:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:10:21 INFO - 'disable_ccov_upload': False, 03:10:21 INFO - 'disable_stylo': False, 03:10:21 INFO - 'download_minidump_stackwalk': True, 03:10:21 INFO - 'download_symbols': 'ondemand', 03:10:21 INFO - 'enable_stylo': False, 03:10:21 INFO - 'enable_webrender': False, 03:10:21 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:10:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:10:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:10:21 INFO - 'gecko_profile': False, 03:10:21 INFO - 'gecko_profile_interval': 0, 03:10:21 INFO - 'installer_path': 'installer.exe', 03:10:21 INFO - 'log_level': 'info', 03:10:21 INFO - 'log_name': 'talos', 03:10:21 INFO - 'log_to_console': True, 03:10:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:10:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:10:21 INFO - 'opt_config_files': (), 03:10:21 INFO - 'pip_index': False, 03:10:21 INFO - 'suite': 'dromaeojs-e10s', 03:10:21 INFO - 'system_bits': '32', 03:10:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:10:21 INFO - 'title': 'talos-linux64-ix-056', 03:10:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:10:21 INFO - 'use_talos_json': True, 03:10:21 INFO - 'verify': 'False', 03:10:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:10:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:10:21 INFO - 'work_dir': 'build'} 03:10:21 INFO - [mozharness: 2017-09-11 10:10:21.375864Z] Running clobber step. 03:10:21 INFO - Running pre-action listener: _resource_record_pre_action 03:10:21 INFO - Running main action method: clobber 03:10:21 INFO - rmtree: /builds/slave/test/build 03:10:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:10:22 INFO - Running post-action listener: _resource_record_post_action 03:10:22 INFO - [mozharness: 2017-09-11 10:10:22.977901Z] Finished clobber step (success) 03:10:22 INFO - [mozharness: 2017-09-11 10:10:22.977997Z] Running read-buildbot-config step. 03:10:22 INFO - Running pre-action listener: _resource_record_pre_action 03:10:22 INFO - Running main action method: read_buildbot_config 03:10:22 INFO - Using buildbot properties: 03:10:22 INFO - { 03:10:22 INFO - "project": "", 03:10:22 INFO - "product": "firefox", 03:10:22 INFO - "who": "archaeopteryx@coole-files.de", 03:10:22 INFO - "installer_path": "public/build/target.tar.bz2", 03:10:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:10:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:10:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:10:22 INFO - "stage_platform": "linux64", 03:10:22 INFO - "basedir": "/builds/slave/test", 03:10:22 INFO - "buildnumber": 1134, 03:10:22 INFO - "platform": "ubuntu64_hw", 03:10:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:10:22 INFO - "slavebuilddir": "test", 03:10:22 INFO - "taskId": "NatfhkWjSVKtXv0NpxbCmQ", 03:10:22 INFO - "branch": "mozilla-central", 03:10:22 INFO - "script_repo_revision": "production", 03:10:22 INFO - "revision": "1f1893590a1df0ecf2d4f9e2dfdc3223991a942f", 03:10:22 INFO - "slavename": "talos-linux64-ix-056", 03:10:22 INFO - "repo_path": "mozilla-central" 03:10:22 INFO - } 03:10:22 INFO - Finding installer, test and symbols from parent task. 03:10:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NatfhkWjSVKtXv0NpxbCmQ'}, attempt #1 03:10:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NatfhkWjSVKtXv0NpxbCmQ'}, attempt #1 03:10:23 INFO - Task dependencies: AeHJJrkvT8GKfdWndNNNKg 03:10:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg'}, attempt #1 03:10:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 03:10:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.test_packages.json 03:10:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.crashreporter-symbols.zip 03:10:24 INFO - Running post-action listener: _resource_record_post_action 03:10:24 INFO - [mozharness: 2017-09-11 10:10:24.353286Z] Finished read-buildbot-config step (success) 03:10:24 INFO - [mozharness: 2017-09-11 10:10:24.353503Z] Running download-and-extract step. 03:10:24 INFO - Running pre-action listener: _resource_record_pre_action 03:10:24 INFO - Running main action method: download_and_extract 03:10:24 INFO - mkdir: /builds/slave/test/build/tests 03:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:24 INFO - trying https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.test_packages.json 03:10:24 INFO - Downloading https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:10:24 INFO - Downloaded 989 bytes. 03:10:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:10:24 INFO - Using the following test package requirements: 03:10:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:10:24 INFO - u'common': [u'target.common.tests.zip'], 03:10:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:10:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:10:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:10:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:10:24 INFO - u'mozbase': [u'target.common.tests.zip'], 03:10:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:10:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:10:24 INFO - u'web-platform': [u'target.common.tests.zip', 03:10:24 INFO - u'target.web-platform.tests.tar.gz'], 03:10:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:10:24 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:10:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.common.tests.zip 03:10:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:10:24 INFO - Fetch https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.common.tests.zip into memory 03:10:25 INFO - Content-Length response header: 55028441 03:10:25 INFO - Bytes received: 55028441 03:10:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.talos.tests.zip 03:10:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:10:37 INFO - Fetch https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.talos.tests.zip into memory 03:10:37 INFO - Content-Length response header: 14497963 03:10:37 INFO - Bytes received: 14497963 03:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:38 INFO - trying https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 03:10:38 INFO - Downloading https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:10:39 INFO - Downloaded 62930971 bytes. 03:10:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 03:10:39 INFO - mkdir: /builds/slave/test/properties 03:10:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:39 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:39 INFO - Contents: 03:10:39 INFO - build_url:https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 03:10:39 INFO - Running post-action listener: _resource_record_post_action 03:10:39 INFO - Running post-action listener: find_tests_for_verification 03:10:39 INFO - Running post-action listener: set_extra_try_arguments 03:10:39 INFO - [mozharness: 2017-09-11 10:10:39.707391Z] Finished download-and-extract step (success) 03:10:39 INFO - [mozharness: 2017-09-11 10:10:39.707492Z] Running populate-webroot step. 03:10:39 INFO - Running pre-action listener: _resource_record_pre_action 03:10:39 INFO - Running main action method: populate_webroot 03:10:39 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:39 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['tresize', 03:10:39 INFO - 'tcanvasmark']}, 03:10:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:39 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['dromaeo_css', 03:10:39 INFO - 'kraken']}, 03:10:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:39 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'tests': ['damp', 'tps']}, 03:10:39 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['damp', 'tps']}, 03:10:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:39 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['dromaeo_dom']}, 03:10:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:10:39 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['basic_compositor_video', 03:10:39 INFO - 'glvideo']}, 03:10:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:10:39 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['ts_paint_webext', 03:10:39 INFO - 'tp5o_webext']}, 03:10:39 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:39 INFO - 'ts_paint', 03:10:39 INFO - 'tpaint', 03:10:39 INFO - 'sessionrestore', 03:10:39 INFO - 'sessionrestore_many_windows', 03:10:39 INFO - 'sessionrestore_no_auto_restore', 03:10:39 INFO - 'tabpaint']}, 03:10:39 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['a11yr', 03:10:39 INFO - 'ts_paint', 03:10:39 INFO - 'tpaint', 03:10:39 INFO - 'sessionrestore', 03:10:39 INFO - 'sessionrestore_many_windows', 03:10:39 INFO - 'sessionrestore_no_auto_restore', 03:10:39 INFO - 'tabpaint']}, 03:10:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:10:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:10:39 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['bloom_basic_singleton']}, 03:10:39 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['bloom_basic']}, 03:10:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:39 INFO - 'tsvgr_opacity', 03:10:39 INFO - 'tart', 03:10:39 INFO - 'tscrollx', 03:10:39 INFO - 'tsvg_static']}, 03:10:39 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['tsvgx', 03:10:39 INFO - 'tsvgr_opacity', 03:10:39 INFO - 'tart', 03:10:39 INFO - 'tscrollx', 03:10:39 INFO - 'tsvg_static']}, 03:10:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:10:39 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo'], 03:10:39 INFO - 'tests': ['tp5o']}, 03:10:39 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:10:39 INFO - 'talos_options': ['--mitmproxy', 03:10:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:10:39 INFO - '--firstNonBlankPaint'], 03:10:39 INFO - 'tests': ['tp6_google', 03:10:39 INFO - 'tp6_youtube', 03:10:39 INFO - 'tp6_amazon', 03:10:39 INFO - 'tp6_facebook']}, 03:10:39 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo', 03:10:39 INFO - '--mitmproxy', 03:10:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:10:39 INFO - '--firstNonBlankPaint'], 03:10:39 INFO - 'tests': ['tp6_google', 03:10:39 INFO - 'tp6_youtube', 03:10:39 INFO - 'tp6_amazon', 03:10:39 INFO - 'tp6_facebook']}, 03:10:39 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:10:39 INFO - 'talos_options': ['--stylo-threads=1', 03:10:39 INFO - '--mitmproxy', 03:10:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:10:39 INFO - '--firstNonBlankPaint'], 03:10:39 INFO - 'tests': ['tp6_google', 03:10:39 INFO - 'tp6_youtube', 03:10:39 INFO - 'tp6_amazon', 03:10:39 INFO - 'tp6_facebook']}, 03:10:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--xperf_path', 03:10:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:39 INFO - 'tests': ['tp5n']}, 03:10:39 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:10:39 INFO - 'talos_options': ['--disable-stylo', 03:10:39 INFO - '--xperf_path', 03:10:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:39 INFO - 'tests': ['tp5n']}}} 03:10:39 INFO - Running post-action listener: _resource_record_post_action 03:10:39 INFO - [mozharness: 2017-09-11 10:10:39.722908Z] Finished populate-webroot step (success) 03:10:39 INFO - [mozharness: 2017-09-11 10:10:39.722996Z] Running create-virtualenv step. 03:10:39 INFO - Running pre-action listener: _resource_record_pre_action 03:10:39 INFO - Running main action method: create_virtualenv 03:10:39 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:39 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:10:39 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:10:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:10:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:41 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:41 INFO - Installing pip...............done. 03:10:41 INFO - Return code: 0 03:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:10:41 INFO - Reading from file tmpfile_stdout 03:10:41 INFO - Output received: 03:10:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:10:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:42 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:10:42 INFO - Using env: {'DISPLAY': ':0', 03:10:42 INFO - 'HOME': '/home/cltbld', 03:10:42 INFO - 'LANG': 'en_US.UTF-8', 03:10:42 INFO - 'LANGUAGE': 'en_US:en', 03:10:42 INFO - 'LOGNAME': 'cltbld', 03:10:42 INFO - 'MAIL': '/var/mail/cltbld', 03:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:42 INFO - 'MOZ_NO_REMOTE': '1', 03:10:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:42 INFO - 'NO_EM_RESTART': '1', 03:10:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:42 INFO - 'PWD': '/builds/slave/test', 03:10:42 INFO - 'SHELL': '/bin/bash', 03:10:42 INFO - 'SHLVL': '1', 03:10:42 INFO - 'TERM': 'linux', 03:10:42 INFO - 'TMOUT': '86400', 03:10:42 INFO - 'USER': 'cltbld', 03:10:42 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:42 INFO - '_': '/tools/buildbot/bin/python'} 03:10:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:10:43 INFO - Downloading/unpacking pip>=1.5 03:10:43 INFO - Running setup.py egg_info for package pip 03:10:43 INFO - warning: no files found matching 'pip/cacert.pem' 03:10:43 INFO - warning: no files found matching '*.html' under directory 'docs' 03:10:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:10:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:10:43 INFO - Installing collected packages: pip 03:10:43 INFO - Found existing installation: pip 1.1 03:10:43 INFO - Uninstalling pip: 03:10:43 INFO - Successfully uninstalled pip 03:10:43 INFO - Running setup.py install for pip 03:10:43 INFO - warning: no files found matching 'pip/cacert.pem' 03:10:43 INFO - warning: no files found matching '*.html' under directory 'docs' 03:10:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:10:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:10:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:10:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:10:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:10:43 INFO - Successfully installed pip 03:10:43 INFO - Cleaning up... 03:10:43 INFO - Return code: 0 03:10:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:43 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:10:43 INFO - Using env: {'DISPLAY': ':0', 03:10:43 INFO - 'HOME': '/home/cltbld', 03:10:43 INFO - 'LANG': 'en_US.UTF-8', 03:10:43 INFO - 'LANGUAGE': 'en_US:en', 03:10:43 INFO - 'LOGNAME': 'cltbld', 03:10:43 INFO - 'MAIL': '/var/mail/cltbld', 03:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:43 INFO - 'MOZ_NO_REMOTE': '1', 03:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:43 INFO - 'NO_EM_RESTART': '1', 03:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:43 INFO - 'PWD': '/builds/slave/test', 03:10:43 INFO - 'SHELL': '/bin/bash', 03:10:43 INFO - 'SHLVL': '1', 03:10:43 INFO - 'TERM': 'linux', 03:10:43 INFO - 'TMOUT': '86400', 03:10:43 INFO - 'USER': 'cltbld', 03:10:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:43 INFO - '_': '/tools/buildbot/bin/python'} 03:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:43 INFO - Downloading/unpacking psutil>=3.1.1 03:10:43 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 03:10:43 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 03:10:43 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 03:10:43 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 03:10:44 INFO - HTTP error 403 while getting http://pypi.pvt.build.mozilla.org/pub/psutil-3.1.1.tar.gz (from http://pypi.pvt.build.mozilla.org/pub/) 03:10:44 INFO - Cleaning up... 03:10:44 INFO - Exception: 03:10:44 ERROR - Traceback (most recent call last): 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/basecommand.py", line 122, in main 03:10:44 INFO - status = self.run(options, args) 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/commands/install.py", line 278, in run 03:10:44 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle) 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1197, in prepare_files 03:10:44 INFO - do_download, 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1375, in unpack_url 03:10:44 INFO - self.session, 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/download.py", line 547, in unpack_http_url 03:10:44 INFO - resp.raise_for_status() 03:10:44 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/_vendor/requests/models.py", line 773, in raise_for_status 03:10:44 INFO - raise HTTPError(http_error_msg, response=self) 03:10:44 INFO - HTTPError: 403 Client Error: Forbidden 03:10:44 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log 03:10:44 WARNING - Return code: 2 03:10:44 WARNING - Could not install python package: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 failed after 1 tries! 03:10:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:44 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:10:44 INFO - Using env: {'DISPLAY': ':0', 03:10:44 INFO - 'HOME': '/home/cltbld', 03:10:44 INFO - 'LANG': 'en_US.UTF-8', 03:10:44 INFO - 'LANGUAGE': 'en_US:en', 03:10:44 INFO - 'LOGNAME': 'cltbld', 03:10:44 INFO - 'MAIL': '/var/mail/cltbld', 03:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:44 INFO - 'MOZ_NO_REMOTE': '1', 03:10:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:44 INFO - 'NO_EM_RESTART': '1', 03:10:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:44 INFO - 'PWD': '/builds/slave/test', 03:10:44 INFO - 'SHELL': '/bin/bash', 03:10:44 INFO - 'SHLVL': '1', 03:10:44 INFO - 'TERM': 'linux', 03:10:44 INFO - 'TMOUT': '86400', 03:10:44 INFO - 'USER': 'cltbld', 03:10:44 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:44 INFO - '_': '/tools/buildbot/bin/python'} 03:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:10:44 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 03:10:44 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 03:10:44 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 03:10:44 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 03:10:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:10:45 INFO - Downloading/unpacking psutil>=3.1.1 (from mozsystemmonitor==0.3) 03:10:45 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 03:10:45 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 03:10:45 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 03:10:45 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 03:10:46 INFO - HTTP error 403 while getting http://pypi.pvt.build.mozilla.org/pub/psutil-3.1.1.tar.gz (from http://pypi.pvt.build.mozilla.org/pub/) 03:10:46 INFO - Cleaning up... 03:10:46 INFO - Exception: 03:10:46 ERROR - Traceback (most recent call last): 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/basecommand.py", line 122, in main 03:10:46 INFO - status = self.run(options, args) 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/commands/install.py", line 278, in run 03:10:46 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle) 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1197, in prepare_files 03:10:46 INFO - do_download, 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1375, in unpack_url 03:10:46 INFO - self.session, 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/download.py", line 547, in unpack_http_url 03:10:46 INFO - resp.raise_for_status() 03:10:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/_vendor/requests/models.py", line 773, in raise_for_status 03:10:46 INFO - raise HTTPError(http_error_msg, response=self) 03:10:46 INFO - HTTPError: 403 Client Error: Forbidden 03:10:46 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log 03:10:46 WARNING - Return code: 2 03:10:46 WARNING - Could not install python package: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 failed after 1 tries! 03:10:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:46 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:10:46 INFO - Using env: {'DISPLAY': ':0', 03:10:46 INFO - 'HOME': '/home/cltbld', 03:10:46 INFO - 'LANG': 'en_US.UTF-8', 03:10:46 INFO - 'LANGUAGE': 'en_US:en', 03:10:46 INFO - 'LOGNAME': 'cltbld', 03:10:46 INFO - 'MAIL': '/var/mail/cltbld', 03:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:46 INFO - 'MOZ_NO_REMOTE': '1', 03:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:46 INFO - 'NO_EM_RESTART': '1', 03:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:46 INFO - 'PWD': '/builds/slave/test', 03:10:46 INFO - 'SHELL': '/bin/bash', 03:10:46 INFO - 'SHLVL': '1', 03:10:46 INFO - 'TERM': 'linux', 03:10:46 INFO - 'TMOUT': '86400', 03:10:46 INFO - 'USER': 'cltbld', 03:10:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:46 INFO - '_': '/tools/buildbot/bin/python'} 03:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:10:46 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 03:10:46 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 03:10:46 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 03:10:46 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 03:10:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:10:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:10:47 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 03:10:47 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 03:10:47 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 03:10:47 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 03:10:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:10:48 INFO - warning: no files found matching '*.txt' 03:10:48 INFO - no previously-included directories found matching 'build' 03:10:48 INFO - no previously-included directories found matching 'dist' 03:10:48 INFO - no previously-included directories found matching '.git*' 03:10:48 INFO - Installing collected packages: jsonschema, functools32 03:10:49 INFO - Running setup.py install for functools32 03:10:49 INFO - warning: no files found matching '*.txt' 03:10:49 INFO - no previously-included directories found matching 'build' 03:10:49 INFO - no previously-included directories found matching 'dist' 03:10:49 INFO - no previously-included directories found matching '.git*' 03:10:49 INFO - Successfully installed jsonschema functools32 03:10:49 INFO - Cleaning up... 03:10:49 INFO - Return code: 0 03:10:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:49 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:10:49 INFO - Using env: {'DISPLAY': ':0', 03:10:49 INFO - 'HOME': '/home/cltbld', 03:10:49 INFO - 'LANG': 'en_US.UTF-8', 03:10:49 INFO - 'LANGUAGE': 'en_US:en', 03:10:49 INFO - 'LOGNAME': 'cltbld', 03:10:49 INFO - 'MAIL': '/var/mail/cltbld', 03:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:49 INFO - 'MOZ_NO_REMOTE': '1', 03:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:49 INFO - 'NO_EM_RESTART': '1', 03:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:49 INFO - 'PWD': '/builds/slave/test', 03:10:49 INFO - 'SHELL': '/bin/bash', 03:10:49 INFO - 'SHLVL': '1', 03:10:49 INFO - 'TERM': 'linux', 03:10:49 INFO - 'TMOUT': '86400', 03:10:49 INFO - 'USER': 'cltbld', 03:10:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:49 INFO - '_': '/tools/buildbot/bin/python'} 03:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:10:49 INFO - Cleaning up... 03:10:49 INFO - Return code: 0 03:10:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:49 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 03:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:10:49 INFO - Using env: {'DISPLAY': ':0', 03:10:49 INFO - 'HOME': '/home/cltbld', 03:10:49 INFO - 'LANG': 'en_US.UTF-8', 03:10:49 INFO - 'LANGUAGE': 'en_US:en', 03:10:49 INFO - 'LOGNAME': 'cltbld', 03:10:49 INFO - 'MAIL': '/var/mail/cltbld', 03:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:49 INFO - 'MOZ_NO_REMOTE': '1', 03:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:49 INFO - 'NO_EM_RESTART': '1', 03:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:49 INFO - 'PWD': '/builds/slave/test', 03:10:49 INFO - 'SHELL': '/bin/bash', 03:10:49 INFO - 'SHLVL': '1', 03:10:49 INFO - 'TERM': 'linux', 03:10:49 INFO - 'TMOUT': '86400', 03:10:49 INFO - 'USER': 'cltbld', 03:10:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:49 INFO - '_': '/tools/buildbot/bin/python'} 03:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:10:49 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 03:10:49 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 03:10:49 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 03:10:49 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 03:10:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:10:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:10:51 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 03:10:51 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 03:10:51 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 03:10:51 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 03:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:10:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:10:51 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 03:10:51 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 03:10:51 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 03:10:51 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 03:10:51 INFO - Downloading docopt-0.6.1.tar.gz 03:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:10:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:10:51 INFO - Running setup.py install for blobuploader 03:10:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:10:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:10:51 INFO - Running setup.py install for requests 03:10:52 INFO - Running setup.py install for docopt 03:10:52 INFO - Successfully installed blobuploader requests docopt 03:10:52 INFO - Cleaning up... 03:10:52 INFO - Return code: 0 03:10:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:52 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:52 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:10:52 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:52 INFO - Using env: {'DISPLAY': ':0', 03:10:52 INFO - 'HOME': '/home/cltbld', 03:10:52 INFO - 'LANG': 'en_US.UTF-8', 03:10:52 INFO - 'LANGUAGE': 'en_US:en', 03:10:52 INFO - 'LOGNAME': 'cltbld', 03:10:52 INFO - 'MAIL': '/var/mail/cltbld', 03:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:52 INFO - 'MOZ_NO_REMOTE': '1', 03:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:52 INFO - 'NO_EM_RESTART': '1', 03:10:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:52 INFO - 'PWD': '/builds/slave/test', 03:10:52 INFO - 'SHELL': '/bin/bash', 03:10:52 INFO - 'SHLVL': '1', 03:10:52 INFO - 'TERM': 'linux', 03:10:52 INFO - 'TMOUT': '86400', 03:10:52 INFO - 'USER': 'cltbld', 03:10:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:52 INFO - '_': '/tools/buildbot/bin/python'} 03:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:52 INFO - Running setup.py (path:/tmp/pip-ZgpsAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:52 INFO - Running setup.py (path:/tmp/pip-M0Q4_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:52 INFO - Running setup.py (path:/tmp/pip-hIqgGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:52 INFO - Running setup.py (path:/tmp/pip-SnhOPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:52 INFO - Running setup.py (path:/tmp/pip-t1aVow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:52 INFO - Running setup.py (path:/tmp/pip-Pjz_Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:52 INFO - Running setup.py (path:/tmp/pip-CnFHi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:52 INFO - Running setup.py (path:/tmp/pip-kIBnGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:52 INFO - Running setup.py (path:/tmp/pip-lKLpqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:52 INFO - Running setup.py (path:/tmp/pip-0nRA44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:53 INFO - Running setup.py (path:/tmp/pip-aaevtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:53 INFO - Running setup.py (path:/tmp/pip-eG2MZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:53 INFO - Running setup.py (path:/tmp/pip-mhJ1zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:53 INFO - Running setup.py (path:/tmp/pip-LQGENU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:53 INFO - Running setup.py (path:/tmp/pip-N5xDu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:53 INFO - Running setup.py (path:/tmp/pip-ZnDwHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:53 INFO - Running setup.py (path:/tmp/pip-xtpY3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:10:53 INFO - Running setup.py install for manifestparser 03:10:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:10:53 INFO - Running setup.py install for mozcrash 03:10:53 INFO - Running setup.py install for mozdebug 03:10:53 INFO - Running setup.py install for mozdevice 03:10:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:10:53 INFO - Running setup.py install for mozfile 03:10:54 INFO - Running setup.py install for mozhttpd 03:10:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for mozinfo 03:10:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for mozInstall 03:10:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for mozleak 03:10:54 INFO - Running setup.py install for mozlog 03:10:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for moznetwork 03:10:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for mozprocess 03:10:54 INFO - Running setup.py install for mozprofile 03:10:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:10:54 INFO - Running setup.py install for mozrunner 03:10:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:10:55 INFO - Running setup.py install for mozscreenshot 03:10:55 INFO - Running setup.py install for moztest 03:10:55 INFO - Running setup.py install for mozversion 03:10:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:10:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:10:55 INFO - Cleaning up... 03:10:55 INFO - Return code: 0 03:10:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:55 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:55 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:10:55 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:55 INFO - Using env: {'DISPLAY': ':0', 03:10:55 INFO - 'HOME': '/home/cltbld', 03:10:55 INFO - 'LANG': 'en_US.UTF-8', 03:10:55 INFO - 'LANGUAGE': 'en_US:en', 03:10:55 INFO - 'LOGNAME': 'cltbld', 03:10:55 INFO - 'MAIL': '/var/mail/cltbld', 03:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:55 INFO - 'MOZ_NO_REMOTE': '1', 03:10:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:55 INFO - 'NO_EM_RESTART': '1', 03:10:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:55 INFO - 'PWD': '/builds/slave/test', 03:10:55 INFO - 'SHELL': '/bin/bash', 03:10:55 INFO - 'SHLVL': '1', 03:10:55 INFO - 'TERM': 'linux', 03:10:55 INFO - 'TMOUT': '86400', 03:10:55 INFO - 'USER': 'cltbld', 03:10:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:55 INFO - '_': '/tools/buildbot/bin/python'} 03:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:55 INFO - Running setup.py (path:/tmp/pip-_KmFBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:55 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:55 INFO - Running setup.py (path:/tmp/pip-B9N7Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:55 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:55 INFO - Running setup.py (path:/tmp/pip-VSlNQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:55 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:55 INFO - Running setup.py (path:/tmp/pip-XNFz1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:55 INFO - Running setup.py (path:/tmp/pip-N7y4ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:55 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:55 INFO - Running setup.py (path:/tmp/pip-qHxnde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:56 INFO - Running setup.py (path:/tmp/pip-rLUwRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:56 INFO - Running setup.py (path:/tmp/pip-4ieFjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:56 INFO - Running setup.py (path:/tmp/pip-LrQcTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:56 INFO - Running setup.py (path:/tmp/pip-ecaLpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:56 INFO - Running setup.py (path:/tmp/pip-Cz_8K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:56 INFO - Running setup.py (path:/tmp/pip-ZNpJVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:56 INFO - Running setup.py (path:/tmp/pip-XgrCdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:56 INFO - Running setup.py (path:/tmp/pip-ecOMFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:56 INFO - Running setup.py (path:/tmp/pip-qPlmnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:56 INFO - Running setup.py (path:/tmp/pip-o2XuDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:56 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)) 03:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:56 INFO - Running setup.py (path:/tmp/pip-CyoXxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:56 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)) 03:10:56 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)) 03:10:56 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)) 03:10:56 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)) 03:10:56 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:10:56 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:10:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:57 INFO - Downloading blessings-1.6.tar.gz 03:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:10:58 INFO - Installing collected packages: blessings 03:10:58 INFO - Running setup.py install for blessings 03:10:58 INFO - Successfully installed blessings 03:10:58 INFO - Cleaning up... 03:10:58 INFO - Return code: 0 03:10:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:58 INFO - Reading from file tmpfile_stdout 03:10:58 INFO - Current package versions: 03:10:58 INFO - argparse == 1.2.1 03:10:58 INFO - blessings == 1.6 03:10:58 INFO - blobuploader == 1.2.4 03:10:58 INFO - docopt == 0.6.1 03:10:58 INFO - functools32 == 3.2.3-2 03:10:58 INFO - jsonschema == 2.5.1 03:10:58 INFO - manifestparser == 1.1 03:10:58 INFO - mozInstall == 1.13 03:10:58 INFO - mozcrash == 1.0 03:10:58 INFO - mozdebug == 0.1 03:10:58 INFO - mozdevice == 0.51 03:10:58 INFO - mozfile == 1.2 03:10:58 INFO - mozhttpd == 0.7 03:10:58 INFO - mozinfo == 0.10 03:10:58 INFO - mozleak == 0.1 03:10:58 INFO - mozlog == 3.5 03:10:58 INFO - moznetwork == 0.27 03:10:58 INFO - mozprocess == 0.25 03:10:58 INFO - mozprofile == 0.28 03:10:58 INFO - mozrunner == 6.13 03:10:58 INFO - mozscreenshot == 0.1 03:10:58 INFO - moztest == 0.8 03:10:58 INFO - mozversion == 1.4 03:10:58 INFO - requests == 1.2.3 03:10:58 INFO - wsgiref == 0.1.2 03:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:58 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:10:58 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:10:58 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:58 INFO - Using env: {'DISPLAY': ':0', 03:10:58 INFO - 'HOME': '/home/cltbld', 03:10:58 INFO - 'LANG': 'en_US.UTF-8', 03:10:58 INFO - 'LANGUAGE': 'en_US:en', 03:10:58 INFO - 'LOGNAME': 'cltbld', 03:10:58 INFO - 'MAIL': '/var/mail/cltbld', 03:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:58 INFO - 'MOZ_NO_REMOTE': '1', 03:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:58 INFO - 'NO_EM_RESTART': '1', 03:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:58 INFO - 'PWD': '/builds/slave/test', 03:10:58 INFO - 'SHELL': '/bin/bash', 03:10:58 INFO - 'SHLVL': '1', 03:10:58 INFO - 'TERM': 'linux', 03:10:58 INFO - 'TMOUT': '86400', 03:10:58 INFO - 'USER': 'cltbld', 03:10:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:58 INFO - '_': '/tools/buildbot/bin/python'} 03:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 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)) 03:10:58 INFO - Downloading/unpacking psutil>=3.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:10:58 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 03:10:58 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 03:10:58 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 03:10:58 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 03:10:59 INFO - HTTP error 403 while getting http://pypi.pvt.build.mozilla.org/pub/psutil-3.1.1.tar.gz (from http://pypi.pvt.build.mozilla.org/pub/) 03:10:59 INFO - Cleaning up... 03:10:59 INFO - Exception: 03:10:59 ERROR - Traceback (most recent call last): 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/basecommand.py", line 122, in main 03:10:59 INFO - status = self.run(options, args) 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/commands/install.py", line 278, in run 03:10:59 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle) 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1197, in prepare_files 03:10:59 INFO - do_download, 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1375, in unpack_url 03:10:59 INFO - self.session, 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/download.py", line 547, in unpack_http_url 03:10:59 INFO - resp.raise_for_status() 03:10:59 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/_vendor/requests/models.py", line 773, in raise_for_status 03:10:59 INFO - raise HTTPError(http_error_msg, response=self) 03:10:59 INFO - HTTPError: 403 Client Error: Forbidden 03:10:59 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log 03:10:59 WARNING - Return code: 2 03:10:59 INFO - retry: Failed, sleeping 60 seconds before retrying 03:11:59 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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 #2 03:11:59 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:11:59 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:11:59 INFO - Using env: {'DISPLAY': ':0', 03:11:59 INFO - 'HOME': '/home/cltbld', 03:11:59 INFO - 'LANG': 'en_US.UTF-8', 03:11:59 INFO - 'LANGUAGE': 'en_US:en', 03:11:59 INFO - 'LOGNAME': 'cltbld', 03:11:59 INFO - 'MAIL': '/var/mail/cltbld', 03:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:59 INFO - 'MOZ_NO_REMOTE': '1', 03:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:11:59 INFO - 'NO_EM_RESTART': '1', 03:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:59 INFO - 'PWD': '/builds/slave/test', 03:11:59 INFO - 'SHELL': '/bin/bash', 03:11:59 INFO - 'SHLVL': '1', 03:11:59 INFO - 'TERM': 'linux', 03:11:59 INFO - 'TMOUT': '86400', 03:11:59 INFO - 'USER': 'cltbld', 03:11:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:59 INFO - '_': '/tools/buildbot/bin/python'} 03:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 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)) 03:12:00 INFO - Downloading/unpacking psutil>=3.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:12:00 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 03:12:00 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 03:12:00 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 03:12:00 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 03:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:12:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:12:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:12:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:12:01 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)) 03:12:01 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)) 03:12:01 INFO - Installing collected packages: psutil, simplejson 03:12:01 INFO - Running setup.py install for psutil 03:12:01 INFO - building 'psutil._psutil_linux' extension 03:12:01 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 03:12:01 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 03:12:01 INFO - building 'psutil._psutil_posix' extension 03:12:01 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 03:12:01 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 03:12:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:12:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:12:01 INFO - Running setup.py install for simplejson 03:12:02 INFO - building 'simplejson._speedups' extension 03:12:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:12:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:12:02 INFO - Successfully installed psutil simplejson 03:12:02 INFO - Cleaning up... 03:12:03 INFO - Return code: 0 03:12:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12: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/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 0x29641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, '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': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', '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 03:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:12:03 INFO - Using env: {'DISPLAY': ':0', 03:12:03 INFO - 'HOME': '/home/cltbld', 03:12:03 INFO - 'LANG': 'en_US.UTF-8', 03:12:03 INFO - 'LANGUAGE': 'en_US:en', 03:12:03 INFO - 'LOGNAME': 'cltbld', 03:12:03 INFO - 'MAIL': '/var/mail/cltbld', 03:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:03 INFO - 'MOZ_NO_REMOTE': '1', 03:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:03 INFO - 'NO_EM_RESTART': '1', 03:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:03 INFO - 'PWD': '/builds/slave/test', 03:12:03 INFO - 'SHELL': '/bin/bash', 03:12:03 INFO - 'SHLVL': '1', 03:12:03 INFO - 'TERM': 'linux', 03:12:03 INFO - 'TMOUT': '86400', 03:12:03 INFO - 'USER': 'cltbld', 03:12:03 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:03 INFO - '_': '/tools/buildbot/bin/python'} 03:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:12:03 INFO - Cleaning up... 03:12:03 INFO - Return code: 0 03:12:03 INFO - Running post-action listener: _resource_record_post_action 03:12:03 INFO - Running post-action listener: _start_resource_monitoring 03:12:03 WARNING - Unable to start resource monitor: Traceback (most recent call last): 03:12:03 WARNING - File "/builds/slave/test/scripts/mozharness/base/python.py", line 584, in _start_resource_monitoring 03:12:03 WARNING - from mozsystemmonitor.resourcemonitor import SystemResourceMonitor 03:12:03 WARNING - ImportError: No module named mozsystemmonitor.resourcemonitor 03:12:03 INFO - [mozharness: 2017-09-11 10:12:03.276560Z] Finished create-virtualenv step (success) 03:12:03 INFO - [mozharness: 2017-09-11 10:12:03.276791Z] Running install step. 03:12:03 INFO - Running pre-action listener: _resource_record_pre_action 03:12:03 INFO - Running main action method: install 03:12:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:12:03 INFO - Reading from file tmpfile_stdout 03:12:03 INFO - Detecting whether we're running mozinstall >=1.0... 03:12:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:12:03 INFO - Reading from file tmpfile_stdout 03:12:03 INFO - Output received: 03:12:03 INFO - Usage: mozinstall [options] installer 03:12:03 INFO - Options: 03:12:03 INFO - -h, --help show this help message and exit 03:12:03 INFO - -d DEST, --destination=DEST 03:12:03 INFO - Directory to install application into. [default: 03:12:03 INFO - "/builds/slave/test"] 03:12:03 INFO - --app=APP Application being installed. [default: firefox] 03:12:03 INFO - mkdir: /builds/slave/test/build/application 03:12:03 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'] 03:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:12:20 INFO - Reading from file tmpfile_stdout 03:12:20 INFO - Output received: 03:12:20 INFO - /builds/slave/test/build/application/firefox/firefox 03:12:20 INFO - Running post-action listener: _resource_record_post_action 03:12:20 INFO - [mozharness: 2017-09-11 10:12:20.383686Z] Finished install step (success) 03:12:20 INFO - [mozharness: 2017-09-11 10:12:20.383923Z] Running setup-mitmproxy step. 03:12:20 INFO - Running pre-action listener: _resource_record_pre_action 03:12:20 INFO - Running main action method: setup_mitmproxy 03:12:20 INFO - Skipping: mitmproxy is not required 03:12:20 INFO - Running post-action listener: _resource_record_post_action 03:12:20 INFO - [mozharness: 2017-09-11 10:12:20.384980Z] Finished setup-mitmproxy step (success) 03:12:20 INFO - [mozharness: 2017-09-11 10:12:20.385189Z] Running run-tests step. 03:12:20 INFO - Running pre-action listener: _resource_record_pre_action 03:12:20 INFO - Running pre-action listener: _set_gcov_prefix 03:12:20 INFO - Running main action method: run_tests 03:12:20 WARNING - Try message not found. 03:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:12:20 INFO - Python 2.7.3 03:12:20 INFO - Return code: 0 03:12:20 INFO - grabbing minidump binary from tooltool 03:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 03:12:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x2cd4090>, '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 0x2cd4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ccef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:12:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:12:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:12:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:12:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:12:20 INFO - Return code: 0 03:12:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:12:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:12:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:12:20 INFO - ENV: RUST_BACKTRACE is now full 03:12:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:12:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:12:20 INFO - Using env: {'DISPLAY': ':0', 03:12:20 INFO - 'HOME': '/home/cltbld', 03:12:20 INFO - 'LANG': 'en_US.UTF-8', 03:12:20 INFO - 'LANGUAGE': 'en_US:en', 03:12:20 INFO - 'LOGNAME': 'cltbld', 03:12:20 INFO - 'MAIL': '/var/mail/cltbld', 03:12:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:12:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:20 INFO - 'MOZ_NO_REMOTE': '1', 03:12:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:20 INFO - 'NO_EM_RESTART': '1', 03:12:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:20 INFO - 'PWD': '/builds/slave/test', 03:12:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:12:20 INFO - 'RUST_BACKTRACE': 'full', 03:12:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:12:20 INFO - 'SHELL': '/bin/bash', 03:12:20 INFO - 'SHLVL': '1', 03:12:20 INFO - 'TERM': 'linux', 03:12:20 INFO - 'TMOUT': '86400', 03:12:20 INFO - 'USER': 'cltbld', 03:12:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:20 INFO - '_': '/tools/buildbot/bin/python'} 03:12:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:12:20 INFO - mozversion application_buildid: 20170911091655 03:12:20 INFO - mozversion application_changeset: 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f 03:12:20 INFO - mozversion application_display_name: Nightly 03:12:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:12:20 INFO - mozversion application_name: Firefox 03:12:20 INFO - mozversion application_remotingname: firefox 03:12:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:12:20 INFO - mozversion application_vendor: Mozilla 03:12:20 INFO - mozversion application_version: 57.0a1 03:12:20 INFO - mozversion platform_buildid: 20170911091655 03:12:20 INFO - mozversion platform_changeset: 1f1893590a1df0ecf2d4f9e2dfdc3223991a942f 03:12:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:12:20 INFO - mozversion platform_version: 57.0a1 03:12:20 INFO - using testdate: 1505124740 03:12:20 INFO - actual date: 1505124740 03:12:20 INFO - starting webserver on 'localhost:50913' 03:12:20 INFO - SUITE-START | Running 2 tests 03:12:20 INFO - TEST-START | dromaeo_css 03:12:20 INFO - Initialising browser for dromaeo_css test... 03:12:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50913/getInfo.html -profile /tmp/tmp_6XZNU/profile 03:12:20 INFO - TEST-INFO | started process 18374 (/builds/slave/test/build/application/firefox/firefox http://localhost:50913/getInfo.html) 03:12:27 INFO - TEST-INFO | 18374: exit 0 03:12:27 INFO - Browser initialized. 03:12:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:12:27 INFO - Using env: {'DISPLAY': ':0', 03:12:27 INFO - 'HOME': '/home/cltbld', 03:12:27 INFO - 'JSGC_DISABLE_POISONING': '1', 03:12:27 INFO - 'LANG': 'en_US.UTF-8', 03:12:27 INFO - 'LANGUAGE': 'en_US:en', 03:12:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:12:27 INFO - 'LOGNAME': 'cltbld', 03:12:27 INFO - 'MAIL': '/var/mail/cltbld', 03:12:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:12:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:12:27 INFO - 'MOZ_CRASHREPORTER': '1', 03:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:12:27 INFO - 'MOZ_NO_REMOTE': '1', 03:12:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:27 INFO - 'NO_EM_RESTART': '1', 03:12:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:27 INFO - 'PWD': '/builds/slave/test', 03:12:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:12:27 INFO - 'RUST_BACKTRACE': 'full', 03:12:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:12:27 INFO - 'SHELL': '/bin/bash', 03:12:27 INFO - 'SHLVL': '1', 03:12:27 INFO - 'TERM': 'linux', 03:12:27 INFO - 'TMOUT': '86400', 03:12:27 INFO - 'USER': 'cltbld', 03:12:27 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:27 INFO - '_': '/tools/buildbot/bin/python'} 03:12:27 INFO - TEST-INFO | started process 18572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_6XZNU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:28 INFO - PID 18572 | 03:12:28 INFO - PID 18572 | (/builds/slave/test/build/application/firefox/firefox:18624): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:12:28 INFO - PID 18572 | 03:12:38 INFO - PID 18572 | RSS: Main: 171421696 03:12:38 INFO - PID 18572 | 03:15:36 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50913/tests/dromaeo/cssquery-ext.html) 03:15:37 INFO - PID 18572 | RSS: Main: 174317568 03:15:37 INFO - PID 18572 | 03:18:39 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-ext.html (next: http://localhost:50913/tests/dromaeo/cssquery-jquery.html) 03:18:39 INFO - PID 18572 | RSS: Main: 170430464 03:18:39 INFO - PID 18572 | 03:21:41 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50913/tests/dromaeo/cssquery-mootools.html) 03:21:42 INFO - PID 18572 | RSS: Main: 173182976 03:21:42 INFO - PID 18572 | 03:24:43 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50913/tests/dromaeo/cssquery-prototype.html) 03:24:44 INFO - PID 18572 | RSS: Main: 171638784 03:24:44 INFO - PID 18572 | 03:27:47 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50913/tests/dromaeo/cssquery-yui.html) 03:27:48 INFO - PID 18572 | RSS: Main: 172933120 03:27:48 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | Cycle 1(1): loaded http://localhost:50913/tests/dromaeo/cssquery-yui.html (next: http://localhost:50913/tests/dromaeo/cssquery-dojo.html) 03:30:50 INFO - PID 18572 | RSS: Main: 175685632 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | __start_tp_report 03:30:50 INFO - PID 18572 | _x_x_mozilla_page_load 03:30:50 INFO - PID 18572 | _x_x_mozilla_page_load_details 03:30:50 INFO - PID 18572 | |i|pagename|runs| 03:30:50 INFO - PID 18572 | |0;dojo.html;3115.8130512169273;3142.6385965613954;3154.621445426548;3155.5897733294682;3189.1229911774276;6347.777827776029;6360.968195159024;6422.9357706422925;6487.805365839026;6503.317151699074;6243.625382477043;6278.874422511547;6384.585001974869;6386.744530218785;6416.45460135889;5627.774889004435;5749.971250143743;5786.045302525078;5801.883962320751;5847.181394604759;5635.295588051494;5805.854853628651;5878.147668588073;5908.822735317947;5909.940900590982;5415.891682166354;5835.891180675658;5862.50168735658;5898.469137777599;5911.911321330162;5800.53595712342;5889.646621202742;5927.555433342516;5958.016927207005;5973.283206015306;5734.856628584277;5754.74103665336;5794.188813566104;5796.942030579682;5816.476517113438;6301.212348456443;6325.841853953642;6325.936740632626;6331.810045698637;6454.612723236621;6745.156855393076;6803.523753337268;6868.965655171743;6884.208316043669;6886.8622627547165;6712.362325579111;6836;6840.657967101625;6844.452443804484;6847.281035491302;6228.314885362652;6236.03341482071;6237.157983672191;6240.438360547527;6246.500279977637;6883.65581720922;7009.684564194624;7042.92957070433;7113.64431778409;7118.644067796589;6714.764983225564;6731.091302674175;6769.898451523231;6815.2162501312;6825.385715285648;6698.732050718014;6857.417119544794;6868.828279292958;6959.547629404073;6968.268331825187;6707.1616047994;6777.423918966943;6825.5222134451105;6857.622830744257;6858.3141685832015;6591.176102987127;6766.357196066366;6767.424768894601;6785.626790526569;6797.286284940109;5306.204069389545;5316.813911513079;5383.542398896059;5405.21624364465;5407.783688652488;4673.205555055649;4703.106409782131;4711.623070154379;4731.124741922756;4732.834350797774;5861.283293700895;5873.20711703923;5946.791862284799;5946.851328716817;5981.222441082631;6709.899351509731;6817.5909445433435;6820.147481564804;6820.658967051628;6824.078749368871;5797.2173756542325;5798.623089499169;5829.183914252008;5842.795502157404;5936.495397891227;5854.121881717691;5893.882122357529;5941.346451890288;5950.702464876739;5961.910571341434;6564.081028655896;6642.070110184578;6733.293004234582;6734.56225345351;6757.864842703217;6720.227173875067;6839.076724642308;6860.48546359035;6937.895931561031;6960.825979350557;6550.672466376757;6551.80344589663;6553.606783593;6554.901676474952;6567.605943643397;6607.141071660653;6638.17022872141;6710;6725.831854203684;6739.93260067393;6883.552569082994;6960.4779641526075;6964.233934267125;6972.9302706972285;6990.475714321345;6570.8357291068105;6595.373439523238;6636.834079147867;6722;6728;6309.936900630935;6362.331955144643;6387.169667943138;6391.201099862517;6395.232572091379;6456.741730330734;6498.967505162444;6535.967320163369;6546.672666366758;6550.181227346582;6679.398854103154;6700.865982680417;6745.797626071226;6816.863662726817;6822.079019332326;6268.780592679417;6378.266499352634;6407.263164736115;6501.512386570931;6545.836354090995 03:30:50 INFO - PID 18572 | |1;ext.html;732.7948174511139;739.970401183952;755.2447552447552;774.1600363605489;779.6881247501002;40813.38779918304;41524.16951660969;42000.57999420005;42377.36433953489;42480.150396992045;42541.36187957183;42656.78671606637;42738;42803;42855.78572107135;7785.41609379296;7953.483023603476;7953.920460795376;7956.164602716718;7968.362530997506;7835.294823465887;7896.802579935519;7915.4063445241545;7926.048874135083;7949;7915.8416831663335;7946.364290856746;7946.960265198666;7953.323967462744;7959.960200198991;7807.336376408012;7843.803904902366;7845.097813751406;7851.293383595504;7898.6445609947405;7707.922920770833;7764.262395072459;7765.1070126935565;7781.805454863674;7842.254985776342;37846.621533784586;38020.42969355434;38245.808770956246;38424.423633645514;38429;3589.94615080774;3618.5295911531593;3625.818709064536;3627.818609069536;3634.036980200249;31978;31985.680143198737;32114;32137.357252855047;32247.83876080628;5147.382314122329;5213.087709650796;5336.67979921206;5342;5342.038433082043;180243;180859;181373;182050;182105;46058.76970615193;46283.76858115755;46357.30464043042;46651.76674116608;46705;48582.51417485851;48745;48991;49190.50809491931;49241;15028.09831410119;15224.238788060553;15255.160966146968;15259.084454932517;15279.236038198045;12454.377281135907;12469.812952805714;12509.687257818627;12598.81101783474;12613.369331533386;12954.870451295366;13234.272115033573;13286.402111904987;13418.127821691558;13447.125936814271;5293.894122117613;5299.31108955841;5391.032273220325;5429.348478182644;5472.753726082336;1006.6376104602338;1014.3052009373455;1018.6943916824923;1019.9643012494527;1027.8098551767946;22320.553588928455;22404.77595224027;22542.774572254068;22558.887205563868;22768;1118.5358076398204;1118.8098023336051;1125.9549618015187;1132.818749000156;1141.50915106505;11708.414579271002;11765.88234117665;11877.762444751057;12065.428444502102;12145.514179432723;207436;207782;209080;219418;223120;55499.16751248734;56116;56241;56366.436335636936;56387.15419268714;1421.7654087075723;1428.3715165327224;1438.5540482450474;1441.84139744628;1442.4590778458078;26833.597496037964;26949.59575606328;27191.59212611773;27510.724892750815;27575.448491029667;38034;38104.80947595244;38119.237615248094;38159;38358;197046;197809;198605;200130;200713;32840;33695.494567582;33812;34196;34245;42942.141157176055;43046.56953430551;43412.348814767174;43597.56402436062;43763.12473750571;31554.368912622078;31583;31715.207119821265;31737.5239371405;32228;31111;31726.206844829063;31790.682093178773;31880.8405957978;32113.67886321107;35439;35577;35640.465393019644;35728.82135589305;35771.28457430785;31034.224144395666;31321.37357252888;31384.843075784476;31734.841325794147;32235.516467253477;22843.885780571654;22886.885565572065;23042.654360184944;23193.652095218247;23630.527389452458 03:30:50 INFO - PID 18572 | |2;jquery.html;553.7093026161265;557.9609427340085;569.5358282999355;570.868700198954;583.7198144890452;22304;22968.65547016794;23202.30393088206;23296.534069318604;23315;22426.663600046013;22750.65874011891;23125.07499700014;24605.75394246057;24818.627720584205;6286.842828929279;6421.29365769765;6452.967735161317;6454.741810327581;6469;6305.085762564426;6307.463865571432;6418.967905160468;6423.871522569546;6426.80719578411;6433.8713225735455;6483.15718956535;6488.3187265337165;6494.772682956098;6499.967500162493;6458.547901646886;6462.709178086998;6481.513886458534;6482.7406903723795;6497.740090396378;5739.196512488255;5933.703314834241;5955.225820643332;5982.04287314032;5989.9101513477335;27656.86171569129;28200.576991345148;28246.15261542157;28255.57616635752;28268.717312826815;2019.162047750182;2041.4590133614604;2047.488127968008;2049.098396705445;2049.9590008199752;18897.33859314931;19062.237510499563;19374.030005173237;19457.610847783108;19560.80439195604;2460.6186041163646;2475.5915273979954;2506.385935445809;2509.297396728919;2510.259473455335;110547;116919;121780.39109804483;130711;135009;14404.532600118493;14460.710785784226;14489.565313040626;14512.637184070272;14610.26948652563;22340.664890026663;22390.440238994157;22414.21550245766;22422.215222467134;22426.88786556057;9465.716028519155;9469.289803264783;9598.904010959941;9635.855462168072;9703.369280996712;8555.101714320032;8671.383137534871;8698.434601750865;8759.2992560595;8838.160374764388;8798.47209167452;8819.735407937773;8850.690225842065;8872.822543549093;8975.910240897507;2675.7324267573085;2687.6640419947507;2704.7295270472796;2713.5658294672758;2831.4478676658264;944.5560586524323;946.4983558713893;955.3933252384786;959.7744530035436;973.8539219117048;18279.54301142482;18313.267469301347;18358.990255535806;18462.81537184638;18780.154893029845;988.9181533568468;1022.907938285543;1025.497506221946;1027.928045036855;1048.130052056791;10122.392656440637;10232.693019209435;10257.230707696952;10343;10412.010858968386;423238;434779;440394;440701;445175;101699.49150254202;111649.44175279072;112053.43973280245;113451;113663;1132.105636547135;1132.2979752553306;1151.9136064795173;1157.2767020612118;1160.2516376936828;16167.595810104842;16582.66834663276;16594.419195328104;16604;16750.246238919033;55058.44941550533;55542.444575555346;55757.721211393684;55835.72082139563;56805.71597141988;70233;70245.64877175582;70785;70896;71337;13803.861961380258;13930.512432064828;14071.648208794863;14082;14146.151230926178;21803.563928721018;22110.447238819157;22133.557328853654;22171.334859954226;22386;17006.319747209975;17102;17156.656866862842;17690.203940822437;17856.28574857043;15079;15495.767563486332;15587.142707151215;15765.975211611667;16553.337866485206;17496.125193740518;18026.639467210844;18188.545286367946;18247.54381140482;18356.632867342312;13681.589552313446;13742.65643358924;13811.102278351875;13813.654658633615;13919.23444210578;17319.393821216196;17376.13119344053;17650.382236621655;17657.646847063243;17675.116244187997 03:30:50 INFO - PID 18572 | |3;mootools.html;1300.7008388070744;1354.052163485559;1368.4594585138873;1374.670079180997;1376.3875075591366;14767.26163691815;15434.691306173883;15479.922600386997;15488;15491.302891369887;15187.392504299842;15219.315130819112;15270.465533706323;15314.617134571614;15369.69260614787;2695.272276485348;2779.652543432071;2810.423863108058;2813.1841765887866;2818.6053952446573;2573;2705.5671092625184;2728.2224565998695;2743.245607457945;2744.5608702607587;2788.2750484873977;2824.025711129657;2824.124521398363;2825.406664600436;2825.770789706474;2712.8372297662104;2728.6589176352954;2732.112063579325;2732.1120635793445;2733.3986522964915;2608.256646855637;2627.802914781399;2635.8023148263956;2647.232302632234;2649.443616840466;14605.269736513239;14877.33052012662;14891.851081489154;14911;14920.477783277533;7670.27132422419;7929.881051784228;7950.403719721044;7957.960210198969;7965.761027169194;5152.643915153523;5334.813281535127;5360.59795515334;5446.428125046853;5476.17857321401;1931.082735700545;1942.2619404626294;1944.5333120051228;1969.5174682202808;1994.112619884152;26253.343666408302;26594.468110637677;26677.199684009316;26779.598306025426;26856.32859178497;6199.628022318675;6233.937660623335;6417.422431981144;6600.60396376219;6737.6968036438475;16469.58826029335;16496.17519124039;16527.256273467483;16532;16538.83461165373;7324.26757324274;7464.216257293015;7482.6632801524065;7485.176630570633;7496.0255166827965;6562.245341785756;6862.279460656659;6904.620245886424;6928.688209030605;6982.406495447944;4876.536729010809;4997.90004199914;5005.949940500621;5047.747612619354;5060.873478163002;1258.1067442116016;1295.7861952777687;1313.7044165062784;1360.7550640884538;1381.2127087560195;878.5431575580662;885.0353115104567;895.54327293081;897.9135246351988;905.0587389115378;7712.691492340356;7745.148033716399;7801.882971755428;7814.843703125905;7861.095973962953;967.5645959318335;983.5770618634056;994.537540043883;1003.1523362758458;1007.0231875081159;4665.836695715634;4761.809527618926;4780.330753694486;4811.566958973709;4826.758662066883;20943.37169884906;21307.78692213089;22019.449513761963;22057.11771529153;22112.447188820086;21825.890870545547;22013;22194.556108877732;22230.777692223193;22244.221452249094;9792.657256995972;10824.296420732628;10923.453827308762;10951.671449856518;11001.394923279297;8156.265936065545;8290.129536398592;8297.543635099886;8372.748817535483;8382.706605268786;18394.356197533023;18524.907375463037;18752.15615297342;19035.71446428277;19386.2245510178;19007.42977710671;19412.902935485232;19488.8051119487;19743.80256197477;20032.399028029184;10829.837552436562;10867.673969780919;10942.23404361687;10944.835827462435;11005.229633925548;13161;13369.933150334185;13373.598792036255;13392.732145356844;13407.128536645087;11190.776184476103;11203.663890083311;11244.718882028015;11438.828417573577;11656.242344247597;10247.282690211912;10381.11760500366;10417.843732344172;10423.895761042293;10646;9324.487153206639;9343.832020997375;9535.284853635867;9620.086091821266;9628.18160456369;6913.585184888922;6960.617166055916;7001;7010.738067147962;7075.363217310467;7027.8945815811785;7028.753993610404;7036.507444478837;7092.680829362795;7127.10911136108 03:30:50 INFO - PID 18572 | |4;prototype.html;501.28275203246005;501.8746783640062;518.2381898609044;522.3209827224607;523.9214117882315;6114.449699527047;6168.211218746564;6184.474319682832;6190.3190649028575;6207.5344349173765;6036.275646922364;6043.6071655342375;6062.363451837548;6071.362506936764;6147.907781383283;5015.8212819987275;5017.473165317644;5044.243363495487;5058.848234552969;5080.923786143192;4656.697314674552;4868.975655121737;4923.679960802554;4949.28235405866;5030.220315851049;4803.543663351976;4815.903681926359;4832.420109586855;4845;4890.8043678252825;4792.568668819823;4824.5899098576665;4843.321934929113;4866.343043689092;4878.0731660984475;4246.596573325529;4308.138372325548;4319.4816622005565;4344.500382456026;4364;5836.708164591753;5930.940690593082;5962.493188079018;6041.154238406627;6064.272287325549;5422.267993820823;5473.8357849264185;5496.340439147288;5498.285222920995;5505.146702261156;6372.649504277263;6409.647469389181;6441.484681225491;6506.674666266667;6518.739250429977;4536.710884608666;4709.364235828154;4727.45634252062;4746.905061898743;4763.571278584944;6499.830030594491;6540.771073012422;6621.668916554201;6684.097646817666;6773.950037744059;4828.130936431406;4829.251466022702;4836.806527738851;4880.023995200903;4881;4860.975695121501;4884.780184891689;4890.608751299958;4919.532644398776;4950.925736113961;5511.173324001432;5534.806281780118;5556.833295001156;5560.805371811967;5569.526590239794;6539.313372095957;6555.180602424697;6560.114384558024;6571.0471981562405;6589.703463344161;1673.0714453478436;1697.3974239145173;1701.4045084220425;1710.4954038558656;1723.258998630576;1120.3054106453887;1162.2793867802018;1173.3253379306932;1195.665213740154;1204.6506513111099;5647.717614119278;5694.174344719992;5717.113847353667;5718.142278658234;5740.167675687086;6528.967355163289;6597.30728273534;6633.4693224541925;6635.5023373247195;6639.668016599151;5648.74580643872;5677;5692.943070569324;5714.228579141846;5733.971330143323;3501.649835016529;3504.1603158341563;3508.210652603195;3546.91132721684;3556.3598552260323;6371.39471750183;6419.871602568016;6425.3574642536305;6471.320511346335;6535.705893234815;6667.799796036663;6739.966300168468;6791.027249059257;6834.4874134440115;6845.931540684629;6356.523260755369;6397;6402.423781859655;6436.485081193493;6453.1610890583925;6628.900566491603;6664.933350666432;6697.497687673542;6703.698333574904;6730.394264516217;5864.592497800582;6503.804885853432;6517.427599402632;6590.176227971504;6604.9009264862025;6260.311365749855;6331.27190373113;6395.552311338161;6406.167198264196;6458.54790164684;4813.181759101031;4833.130036593447;4860.708357498562;4902.264660300841;4914.975425122851;6286.339934306832;6569.967150164219;6647.302033286435;6662.666866656745;6680.732770689118;6254.343293954069;6288.6855657217875;6433.871322573429;6440.549161558646;6451.2903580607035;6308.148399965946;6350.983842585164;6376.681165941777;6424.775132870514;6426.550141490051;6222.688865556795;6310.6213627182515;6323.652199128908;6342.651154186564;6373.426391624776;6443.387878151568;6475.676216189077;6479.857269224328;6527.086207930798;6555.6066636002;6249.437550620284;6260;6268.373162683695;6301.716422761078;6338.651374174465 03:30:50 INFO - PID 18572 | |5;yui.html;887.5872719185577;894.3828758156872;918.5040078357687;925.4354843545441;934.6635211323925;19242.23031078757;20207.69688454674;20299.391018269456;20308.796912030874;20353.796462035374;15935;16225.675486490263;16681.499555013368;16919.91540042301;17079.231434585443;965.946872921989;982.8280050991084;986.9309148359617;987.4272921705393;987.8370068938652;945.7162851144664;986.6842610364686;991.6231831903866;999.6651121874181;1002.0981116994726;1067.8451624514441;1070.2775626452153;1070.7430216747964;1071.383954226319;1072.3190773858616;1016.9796604067914;1054.4147997861185;1057.9365238085702;1059.7721489879675;1060.0353678152921;748.9063867016623;750.5834261984596;751.4739682222466;753.4688044928313;756.3759898084047;19821.40535783929;19962.900185499126;19976.200951961902;19980.60038799216;19992.200311987504;20539.691904621443;21922.342329730134;22176.667349989762;22195.66706499404;22198.88900555487;17057.330695601773;18000.549986250448;18033.27866885323;18039.188236529386;18074.096295185318;3789.109559253573;3795.5635101963353;3795.7912314822674;3805.124821291091;3832.0419895026243;136226;136474;137848;139233.3038334812;139611;15312.846871531143;15823.525294241192;15875.364985400685;15897.761533577006;15963.680726385408;31792;32428.675713242566;33276.00343979369;33354.49968250478;33408;8344.411086715165;8524.14758524155;8603.870941935877;8667.393282470166;8688.13118688126;4179.185058913544;4310.784460776948;4320.611144996966;4371.540988196193;4376.562343765598;4935.185694360391;5070.036693028313;5076.238564215323;5819.388964158787;6000.619857432727;1089.4661615808197;1105.1379923659558;1128.136975213962;1129.728865072393;1130.2709752209778;682.0860047536326;697.6093387702903;709.925457826931;714.5962531169872;715.3883429667663;11827.935485806318;12171.939140304241;12277.38613069343;12345.259284442962;12416.192947458387;574.7327492715824;580.7444724321285;580.9448102430263;588.808637192914;590.4154886662259;5944.702764861826;6043.8186854394435;6082.787102451393;6101.511879049665;6166.321704612579;112269.43865280622;114266;114975;119569.40215298868;120415.39792300982;102661.48669256756;102771;105934.47032764941;107383;108719;87990;88152.11847881567;88876.5556172215;89514;89967.55016225007;10890.836637450286;11085.27945683528;11220.270682405617;11237.60668376603;11298.548058077586;41099;41734.791326043174;42758.78620606877;43455;43886;54137;54620.180697290365;54959.45040549703;55095;56387;15169.24153792328;15370.23148842531;15525;15527.922360388126;15551.066935983878;17154.571135721708;17469.912650437174;17604.911975440555;17720.113994299976;17740.733888991934;15846.12846293465;15944.601384965468;15959.760603591189;16074.758878617064;16083.517494475185;15389.538313850602;15512.767308490156;15537.844621554092;15603.141827199614;15614.219289035276;14095.788563171356;14109;14307.856921430652;14353;14376.784348234994;10144.797104058025;10294.433806140734;10295.279330446796;10499.895001049892;10660.680179594625;11369;11511.021563166889;11597.6520704379;11619;11762.470688818852 03:30:50 INFO - PID 18572 | __end_tp_report 03:30:50 INFO - PID 18572 | __start_cc_report 03:30:50 INFO - PID 18572 | _x_x_mozilla_cycle_collect,359 03:30:50 INFO - PID 18572 | __end_cc_report 03:30:50 INFO - PID 18572 | __startTimestamp1505125850318__endTimestamp 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | ------- Summary: start ------- 03:30:50 INFO - PID 18572 | Number of tests: 6 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#0] dojo.html Cycles:165 Average:6256.22 Median:6487.81 stddev:760.41 (11.7%) stddev-sans-first:721.73 03:30:50 INFO - PID 18572 | Values: 3115.8 3142.6 3154.6 3155.6 3189.1 6347.8 6361.0 6422.9 6487.8 6503.3 6243.6 6278.9 6384.6 6386.7 6416.5 5627.8 5750.0 5786.0 5801.9 5847.2 5635.3 5805.9 5878.1 5908.8 5909.9 5415.9 5835.9 5862.5 5898.5 5911.9 5800.5 5889.6 5927.6 5958.0 5973.3 5734.9 5754.7 5794.2 5796.9 5816.5 6301.2 6325.8 6325.9 6331.8 6454.6 6745.2 6803.5 6869.0 6884.2 6886.9 6712.4 6836.0 6840.7 6844.5 6847.3 6228.3 6236.0 6237.2 6240.4 6246.5 6883.7 7009.7 7042.9 7113.6 7118.6 6714.8 6731.1 6769.9 6815.2 6825.4 6698.7 6857.4 6868.8 6959.5 6968.3 6707.2 6777.4 6825.5 6857.6 6858.3 6591.2 6766.4 6767.4 6785.6 6797.3 5306.2 5316.8 5383.5 5405.2 5407.8 4673.2 4703.1 4711.6 4731.1 4732.8 5861.3 5873.2 5946.8 5946.9 5981.2 6709.9 6817.6 6820.1 6820.7 6824.1 5797.2 5798.6 5829.2 5842.8 5936.5 5854.1 5893.9 5941.3 5950.7 5961.9 6564.1 6642.1 6733.3 6734.6 6757.9 6720.2 6839.1 6860.5 6937.9 6960.8 6550.7 6551.8 6553.6 6554.9 6567.6 6607.1 6638.2 6710.0 6725.8 6739.9 6883.6 6960.5 6964.2 6972.9 6990.5 6570.8 6595.4 6636.8 6722.0 6728.0 6309.9 6362.3 6387.2 6391.2 6395.2 6456.7 6499.0 6536.0 6546.7 6550.2 6679.4 6700.9 6745.8 6816.9 6822.1 6268.8 6378.3 6407.3 6501.5 6545.8 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#1] ext.html Cycles:180 Average:36932.78 Median:35508.00 stddev:51319.09 (144.5%) stddev-sans-first:51391.06 03:30:50 INFO - PID 18572 | Values: 732.8 740.0 755.2 774.2 779.7 40813.4 41524.2 42000.6 42377.4 42480.2 42541.4 42656.8 42738.0 42803.0 42855.8 7785.4 7953.5 7953.9 7956.2 7968.4 7835.3 7896.8 7915.4 7926.0 7949.0 7915.8 7946.4 7947.0 7953.3 7960.0 7807.3 7843.8 7845.1 7851.3 7898.6 7707.9 7764.3 7765.1 7781.8 7842.3 37846.6 38020.4 38245.8 38424.4 38429.0 3589.9 3618.5 3625.8 3627.8 3634.0 31978.0 31985.7 32114.0 32137.4 32247.8 5147.4 5213.1 5336.7 5342.0 5342.0 180243.0 180859.0 181373.0 182050.0 182105.0 46058.8 46283.8 46357.3 46651.8 46705.0 48582.5 48745.0 48991.0 49190.5 49241.0 15028.1 15224.2 15255.2 15259.1 15279.2 12454.4 12469.8 12509.7 12598.8 12613.4 12954.9 13234.3 13286.4 13418.1 13447.1 5293.9 5299.3 5391.0 5429.3 5472.8 1006.6 1014.3 1018.7 1020.0 1027.8 22320.6 22404.8 22542.8 22558.9 22768.0 1118.5 1118.8 1126.0 1132.8 1141.5 11708.4 11765.9 11877.8 12065.4 12145.5 207436.0 207782.0 209080.0 219418.0 223120.0 55499.2 56116.0 56241.0 56366.4 56387.2 1421.8 1428.4 1438.6 1441.8 1442.5 26833.6 26949.6 27191.6 27510.7 27575.4 38034.0 38104.8 38119.2 38159.0 38358.0 197046.0 197809.0 198605.0 200130.0 200713.0 32840.0 33695.5 33812.0 34196.0 34245.0 42942.1 43046.6 43412.3 43597.6 43763.1 31554.4 31583.0 31715.2 31737.5 32228.0 31111.0 31726.2 31790.7 31880.8 32113.7 35439.0 35577.0 35640.5 35728.8 35771.3 31034.2 31321.4 31384.8 31734.8 32235.5 22843.9 22886.9 23042.7 23193.7 23630.5 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#2] jquery.html Cycles:180 Average:32258.31 Median:22588.77 stddev:73840.26 (326.9%) stddev-sans-first:74009.03 03:30:50 INFO - PID 18572 | Values: 553.7 558.0 569.5 570.9 583.7 22304.0 22968.7 23202.3 23296.5 23315.0 22426.7 22750.7 23125.1 24605.8 24818.6 6286.8 6421.3 6453.0 6454.7 6469.0 6305.1 6307.5 6419.0 6423.9 6426.8 6433.9 6483.2 6488.3 6494.8 6500.0 6458.5 6462.7 6481.5 6482.7 6497.7 5739.2 5933.7 5955.2 5982.0 5989.9 27656.9 28200.6 28246.2 28255.6 28268.7 2019.2 2041.5 2047.5 2049.1 2050.0 18897.3 19062.2 19374.0 19457.6 19560.8 2460.6 2475.6 2506.4 2509.3 2510.3 110547.0 116919.0 121780.4 130711.0 135009.0 14404.5 14460.7 14489.6 14512.6 14610.3 22340.7 22390.4 22414.2 22422.2 22426.9 9465.7 9469.3 9598.9 9635.9 9703.4 8555.1 8671.4 8698.4 8759.3 8838.2 8798.5 8819.7 8850.7 8872.8 8975.9 2675.7 2687.7 2704.7 2713.6 2831.4 944.6 946.5 955.4 959.8 973.9 18279.5 18313.3 18359.0 18462.8 18780.2 988.9 1022.9 1025.5 1027.9 1048.1 10122.4 10232.7 10257.2 10343.0 10412.0 423238.0 434779.0 440394.0 440701.0 445175.0 101699.5 111649.4 112053.4 113451.0 113663.0 1132.1 1132.3 1151.9 1157.3 1160.3 16167.6 16582.7 16594.4 16604.0 16750.2 55058.4 55542.4 55757.7 55835.7 56805.7 70233.0 70245.6 70785.0 70896.0 71337.0 13803.9 13930.5 14071.6 14082.0 14146.2 21803.6 22110.4 22133.6 22171.3 22386.0 17006.3 17102.0 17156.7 17690.2 17856.3 15079.0 15495.8 15587.1 15766.0 16553.3 17496.1 18026.6 18188.5 18247.5 18356.6 13681.6 13742.7 13811.1 13813.7 13919.2 17319.4 17376.1 17650.4 17657.6 17675.1 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#3] mootools.html Cycles:180 Average:9195.18 Median:26817.96 stddev:6724.71 (25.1%) stddev-sans-first:6717.42 03:30:50 INFO - PID 18572 | Values: 1300.7 1354.1 1368.5 1374.7 1376.4 14767.3 15434.7 15479.9 15488.0 15491.3 15187.4 15219.3 15270.5 15314.6 15369.7 2695.3 2779.7 2810.4 2813.2 2818.6 2573.0 2705.6 2728.2 2743.2 2744.6 2788.3 2824.0 2824.1 2825.4 2825.8 2712.8 2728.7 2732.1 2732.1 2733.4 2608.3 2627.8 2635.8 2647.2 2649.4 14605.3 14877.3 14891.9 14911.0 14920.5 7670.3 7929.9 7950.4 7958.0 7965.8 5152.6 5334.8 5360.6 5446.4 5476.2 1931.1 1942.3 1944.5 1969.5 1994.1 26253.3 26594.5 26677.2 26779.6 26856.3 6199.6 6233.9 6417.4 6600.6 6737.7 16469.6 16496.2 16527.3 16532.0 16538.8 7324.3 7464.2 7482.7 7485.2 7496.0 6562.2 6862.3 6904.6 6928.7 6982.4 4876.5 4997.9 5005.9 5047.7 5060.9 1258.1 1295.8 1313.7 1360.8 1381.2 878.5 885.0 895.5 897.9 905.1 7712.7 7745.1 7801.9 7814.8 7861.1 967.6 983.6 994.5 1003.2 1007.0 4665.8 4761.8 4780.3 4811.6 4826.8 20943.4 21307.8 22019.4 22057.1 22112.4 21825.9 22013.0 22194.6 22230.8 22244.2 9792.7 10824.3 10923.5 10951.7 11001.4 8156.3 8290.1 8297.5 8372.7 8382.7 18394.4 18524.9 18752.2 19035.7 19386.2 19007.4 19412.9 19488.8 19743.8 20032.4 10829.8 10867.7 10942.2 10944.8 11005.2 13161.0 13369.9 13373.6 13392.7 13407.1 11190.8 11203.7 11244.7 11438.8 11656.2 10247.3 10381.1 10417.8 10423.9 10646.0 9324.5 9343.8 9535.3 9620.1 9628.2 6913.6 6960.6 7001.0 7010.7 7075.4 7027.9 7028.8 7036.5 7092.7 7127.1 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#4] prototype.html Cycles:180 Average:5403.98 Median:6042.38 stddev:1526.12 (25.3%) stddev-sans-first:1485.38 03:30:50 INFO - PID 18572 | Values: 501.3 501.9 518.2 522.3 523.9 6114.4 6168.2 6184.5 6190.3 6207.5 6036.3 6043.6 6062.4 6071.4 6147.9 5015.8 5017.5 5044.2 5058.8 5080.9 4656.7 4869.0 4923.7 4949.3 5030.2 4803.5 4815.9 4832.4 4845.0 4890.8 4792.6 4824.6 4843.3 4866.3 4878.1 4246.6 4308.1 4319.5 4344.5 4364.0 5836.7 5930.9 5962.5 6041.2 6064.3 5422.3 5473.8 5496.3 5498.3 5505.1 6372.6 6409.6 6441.5 6506.7 6518.7 4536.7 4709.4 4727.5 4746.9 4763.6 6499.8 6540.8 6621.7 6684.1 6774.0 4828.1 4829.3 4836.8 4880.0 4881.0 4861.0 4884.8 4890.6 4919.5 4950.9 5511.2 5534.8 5556.8 5560.8 5569.5 6539.3 6555.2 6560.1 6571.0 6589.7 1673.1 1697.4 1701.4 1710.5 1723.3 1120.3 1162.3 1173.3 1195.7 1204.7 5647.7 5694.2 5717.1 5718.1 5740.2 6529.0 6597.3 6633.5 6635.5 6639.7 5648.7 5677.0 5692.9 5714.2 5734.0 3501.6 3504.2 3508.2 3546.9 3556.4 6371.4 6419.9 6425.4 6471.3 6535.7 6667.8 6740.0 6791.0 6834.5 6845.9 6356.5 6397.0 6402.4 6436.5 6453.2 6628.9 6664.9 6697.5 6703.7 6730.4 5864.6 6503.8 6517.4 6590.2 6604.9 6260.3 6331.3 6395.6 6406.2 6458.5 4813.2 4833.1 4860.7 4902.3 4915.0 6286.3 6570.0 6647.3 6662.7 6680.7 6254.3 6288.7 6433.9 6440.5 6451.3 6308.1 6351.0 6376.7 6424.8 6426.6 6222.7 6310.6 6323.7 6342.7 6373.4 6443.4 6475.7 6479.9 6527.1 6555.6 6249.4 6260.0 6268.4 6301.7 6338.7 03:30:50 INFO - PID 18572 | 03:30:50 INFO - PID 18572 | [#5] yui.html Cycles:180 Average:23649.65 Median:18658.16 stddev:34020.85 (182.3%) stddev-sans-first:34073.35 03:30:50 INFO - PID 18572 | Values: 887.6 894.4 918.5 925.4 934.7 19242.2 20207.7 20299.4 20308.8 20353.8 15935.0 16225.7 16681.5 16919.9 17079.2 965.9 982.8 986.9 987.4 987.8 945.7 986.7 991.6 999.7 1002.1 1067.8 1070.3 1070.7 1071.4 1072.3 1017.0 1054.4 1057.9 1059.8 1060.0 748.9 750.6 751.5 753.5 756.4 19821.4 19962.9 19976.2 19980.6 19992.2 20539.7 21922.3 22176.7 22195.7 22198.9 17057.3 18000.5 18033.3 18039.2 18074.1 3789.1 3795.6 3795.8 3805.1 3832.0 136226.0 136474.0 137848.0 139233.3 139611.0 15312.8 15823.5 15875.4 15897.8 15963.7 31792.0 32428.7 33276.0 33354.5 33408.0 8344.4 8524.1 8603.9 8667.4 8688.1 4179.2 4310.8 4320.6 4371.5 4376.6 4935.2 5070.0 5076.2 5819.4 6000.6 1089.5 1105.1 1128.1 1129.7 1130.3 682.1 697.6 709.9 714.6 715.4 11827.9 12171.9 12277.4 12345.3 12416.2 574.7 580.7 580.9 588.8 590.4 5944.7 6043.8 6082.8 6101.5 6166.3 112269.4 114266.0 114975.0 119569.4 120415.4 102661.5 102771.0 105934.5 107383.0 108719.0 87990.0 88152.1 88876.6 89514.0 89967.6 10890.8 11085.3 11220.3 11237.6 11298.5 41099.0 41734.8 42758.8 43455.0 43886.0 54137.0 54620.2 54959.5 55095.0 56387.0 15169.2 15370.2 15525.0 15527.9 15551.1 17154.6 17469.9 17604.9 17720.1 17740.7 15846.1 15944.6 15959.8 16074.8 16083.5 15389.5 15512.8 15537.8 15603.1 15614.2 14095.8 14109.0 14307.9 14353.0 14376.8 10144.8 10294.4 10295.3 10499.9 10660.7 11369.0 11511.0 11597.7 11619.0 11762.5 03:30:50 INFO - PID 18572 | -------- Summary: end -------- 03:30:50 INFO - PID 18572 | 03:30:51 INFO - TEST-INFO | 18572: exit 0 03:30:51 INFO - TEST-OK | dromaeo_css | took 1110359ms 03:30:51 INFO - TEST-START | kraken 03:30:51 INFO - Initialising browser for kraken test... 03:30:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50913/getInfo.html -profile /tmp/tmp0Wmyfe/profile 03:30:51 INFO - TEST-INFO | started process 18962 (/builds/slave/test/build/application/firefox/firefox http://localhost:50913/getInfo.html) 03:30:57 INFO - TEST-INFO | 18962: exit 0 03:30:57 INFO - Browser initialized. 03:30:57 INFO - Running cycle 1/1 for kraken test... 03:30:57 INFO - Using env: {'DISPLAY': ':0', 03:30:57 INFO - 'HOME': '/home/cltbld', 03:30:57 INFO - 'JSGC_DISABLE_POISONING': '1', 03:30:57 INFO - 'LANG': 'en_US.UTF-8', 03:30:57 INFO - 'LANGUAGE': 'en_US:en', 03:30:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:30:57 INFO - 'LOGNAME': 'cltbld', 03:30:57 INFO - 'MAIL': '/var/mail/cltbld', 03:30:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:57 INFO - 'MOZ_CRASHREPORTER': '1', 03:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:30:57 INFO - 'MOZ_NO_REMOTE': '1', 03:30:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:57 INFO - 'NO_EM_RESTART': '1', 03:30:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:57 INFO - 'PWD': '/builds/slave/test', 03:30:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:57 INFO - 'RUST_BACKTRACE': 'full', 03:30:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:57 INFO - 'SHELL': '/bin/bash', 03:30:57 INFO - 'SHLVL': '1', 03:30:57 INFO - 'TERM': 'linux', 03:30:57 INFO - 'TMOUT': '86400', 03:30:57 INFO - 'USER': 'cltbld', 03:30:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605', 03:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:57 INFO - '_': '/tools/buildbot/bin/python'} 03:30:57 INFO - TEST-INFO | started process 19163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Wmyfe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:58 INFO - PID 19163 | 03:30:58 INFO - PID 19163 | (/builds/slave/test/build/application/firefox/firefox:19214): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:30:58 INFO - PID 19163 | 03:31:08 INFO - PID 19163 | RSS: Main: 170991616 03:31:08 INFO - PID 19163 | 03:31:11 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50913/tests/kraken/driver.html?testName=audio-beat-detection) 03:31:12 INFO - PID 19163 | RSS: Main: 176259072 03:31:12 INFO - PID 19163 | 03:31:15 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50913/tests/kraken/driver.html?testName=audio-dft) 03:31:15 INFO - PID 19163 | RSS: Main: 176238592 03:31:15 INFO - PID 19163 | 03:31:18 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50913/tests/kraken/driver.html?testName=audio-fft) 03:31:19 INFO - PID 19163 | RSS: Main: 171765760 03:31:19 INFO - PID 19163 | 03:31:21 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50913/tests/kraken/driver.html?testName=audio-oscillator) 03:31:22 INFO - PID 19163 | RSS: Main: 171884544 03:31:22 INFO - PID 19163 | 03:31:24 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50913/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:31:24 INFO - PID 19163 | RSS: Main: 172802048 03:31:24 INFO - PID 19163 | 03:31:28 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50913/tests/kraken/driver.html?testName=imaging-darkroom) 03:31:28 INFO - PID 19163 | RSS: Main: 175341568 03:31:28 INFO - PID 19163 | 03:31:32 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50913/tests/kraken/driver.html?testName=imaging-desaturate) 03:31:33 INFO - PID 19163 | RSS: Main: 174997504 03:31:33 INFO - PID 19163 | 03:31:36 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50913/tests/kraken/driver.html?testName=json-parse-financial) 03:31:36 INFO - PID 19163 | RSS: Main: 175288320 03:31:36 INFO - PID 19163 | 03:31:38 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50913/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:31:39 INFO - PID 19163 | RSS: Main: 175833088 03:31:39 INFO - PID 19163 | 03:31:40 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:31:41 INFO - PID 19163 | RSS: Main: 172802048 03:31:41 INFO - PID 19163 | 03:31:43 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:31:44 INFO - PID 19163 | RSS: Main: 172544000 03:31:44 INFO - PID 19163 | 03:31:46 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:31:47 INFO - PID 19163 | RSS: Main: 173453312 03:31:47 INFO - PID 19163 | 03:31:50 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:31:51 INFO - PID 19163 | RSS: Main: 173293568 03:31:51 INFO - PID 19163 | 03:31:52 INFO - PID 19163 | Cycle 1(1): loaded http://localhost:50913/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50913/tests/kraken/driver.html?testName=ai-astar) 03:31:53 INFO - PID 19163 | RSS: Main: 174854144 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | __start_tp_report 03:31:53 INFO - PID 19163 | _x_x_mozilla_page_load 03:31:53 INFO - PID 19163 | _x_x_mozilla_page_load_details 03:31:53 INFO - PID 19163 | |i|pagename|runs| 03:31:53 INFO - PID 19163 | |0;ai-astar;109;108;109;107;108;108;111;107;108;107 03:31:53 INFO - PID 19163 | |1;audio-beat-detection;121;116;118;118;143;115;121;122;117;119 03:31:53 INFO - PID 19163 | |2;audio-dft;164;163;165;162;162;166;181;173;165;164 03:31:53 INFO - PID 19163 | |3;audio-fft;77;77;81;74;75;74;75;75;75;74 03:31:53 INFO - PID 19163 | |4;audio-oscillator;80;80;79;80;87;79;82;81;81;79 03:31:53 INFO - PID 19163 | |5;imaging-gaussian-blur;114;116;115;115;115;118;114;112;114;119 03:31:53 INFO - PID 19163 | |6;imaging-darkroom;152;152;150;148;152;149;152;149;151;149 03:31:53 INFO - PID 19163 | |7;imaging-desaturate;94;95;95;99;99;99;95;94;96;93 03:31:53 INFO - PID 19163 | |8;json-parse-financial;54;53;54;54;52;51;51;51;52;53 03:31:53 INFO - PID 19163 | |9;json-stringify-tinderbox;39;39;43;40;39;38;38;39;38;38 03:31:53 INFO - PID 19163 | |10;stanford-crypto-aes;75;78;76;78;75;76;75;76;76;76 03:31:53 INFO - PID 19163 | |11;stanford-crypto-ccm;115;130;116;125;115;128;117;130;127;115 03:31:53 INFO - PID 19163 | |12;stanford-crypto-pbkdf2;171;171;171;174;176;181;174;174;185;170 03:31:53 INFO - PID 19163 | |13;stanford-crypto-sha256-iterative;66;62;65;63;67;65;64;66;66;66 03:31:53 INFO - PID 19163 | __end_tp_report 03:31:53 INFO - PID 19163 | __start_cc_report 03:31:53 INFO - PID 19163 | _x_x_mozilla_cycle_collect,932 03:31:53 INFO - PID 19163 | __end_cc_report 03:31:53 INFO - PID 19163 | __startTimestamp1505125913104__endTimestamp 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | ------- Summary: start ------- 03:31:53 INFO - PID 19163 | Number of tests: 14 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#0] ai-astar Cycles:10 Average:108.20 Median:108.00 stddev:1.23 (1.1%) stddev-sans-first:1.27 03:31:53 INFO - PID 19163 | Values: 109.0 108.0 109.0 107.0 108.0 108.0 111.0 107.0 108.0 107.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:120.00 stddev:8.06 (6.7%) stddev-sans-first:8.54 03:31:53 INFO - PID 19163 | Values: 121.0 116.0 118.0 118.0 143.0 115.0 121.0 122.0 117.0 119.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#2] audio-dft Cycles:10 Average:166.50 Median:165.00 stddev:5.99 (3.6%) stddev-sans-first:6.28 03:31:53 INFO - PID 19163 | Values: 164.0 163.0 165.0 162.0 162.0 166.0 181.0 173.0 165.0 164.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#3] audio-fft Cycles:10 Average:75.70 Median:75.00 stddev:2.16 (2.9%) stddev-sans-first:2.24 03:31:53 INFO - PID 19163 | Values: 77.0 77.0 81.0 74.0 75.0 74.0 75.0 75.0 75.0 74.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:80.50 stddev:2.39 (3.0%) stddev-sans-first:2.52 03:31:53 INFO - PID 19163 | Values: 80.0 80.0 79.0 80.0 87.0 79.0 82.0 81.0 81.0 79.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.00 stddev:2.04 (1.8%) stddev-sans-first:2.12 03:31:53 INFO - PID 19163 | Values: 114.0 116.0 115.0 115.0 115.0 118.0 114.0 112.0 114.0 119.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:151.50 stddev:1.58 (1.0%) stddev-sans-first:1.56 03:31:53 INFO - PID 19163 | Values: 152.0 152.0 150.0 148.0 152.0 149.0 152.0 149.0 151.0 149.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.50 stddev:2.28 (2.4%) stddev-sans-first:2.32 03:31:53 INFO - PID 19163 | Values: 94.0 95.0 95.0 99.0 99.0 99.0 95.0 94.0 96.0 93.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.27 (2.4%) stddev-sans-first:1.22 03:31:53 INFO - PID 19163 | Values: 54.0 53.0 54.0 54.0 52.0 51.0 51.0 51.0 52.0 53.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#9] json-stringify-tinderbox Cycles:10 Average:39.10 Median:39.00 stddev:1.52 (3.9%) stddev-sans-first:1.62 03:31:53 INFO - PID 19163 | Values: 39.0 39.0 43.0 40.0 39.0 38.0 38.0 39.0 38.0 38.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:1.10 (1.4%) stddev-sans-first:1.09 03:31:53 INFO - PID 19163 | Values: 75.0 78.0 76.0 78.0 75.0 76.0 75.0 76.0 76.0 76.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:126.00 stddev:6.71 (5.3%) stddev-sans-first:6.65 03:31:53 INFO - PID 19163 | Values: 115.0 130.0 116.0 125.0 115.0 128.0 117.0 130.0 127.0 115.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:174.00 stddev:4.85 (2.8%) stddev-sans-first:4.96 03:31:53 INFO - PID 19163 | Values: 171.0 171.0 171.0 174.0 176.0 181.0 174.0 174.0 185.0 170.0 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:66.00 stddev:1.56 (2.4%) stddev-sans-first:1.62 03:31:53 INFO - PID 19163 | Values: 66.0 62.0 65.0 63.0 67.0 65.0 64.0 66.0 66.0 66.0 03:31:53 INFO - PID 19163 | -------- Summary: end -------- 03:31:53 INFO - PID 19163 | 03:31:53 INFO - PID 19163 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:31:53 INFO - TEST-INFO | 19163: exit 0 03:31:53 INFO - TEST-OK | kraken | took 62792ms 03:31:53 INFO - SUITE-END | took 1173s 03:31:54 INFO - Completed test suite (00:19:33) 03:31:54 INFO - javascript benchmark 03:31:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8393.324741188428, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.8130512169273, 3142.6385965613954, 3154.621445426548, 3155.5897733294682, 3189.1229911774276, 6347.777827776029, 6360.968195159024, 6422.9357706422925, 6487.805365839026, 6503.317151699074, 6243.625382477043, 6278.874422511547, 6384.585001974869, 6386.744530218785, 6416.45460135889, 5627.774889004435, 5749.971250143743, 5786.045302525078, 5801.883962320751, 5847.181394604759, 5635.295588051494, 5805.854853628651, 5878.147668588073, 5908.822735317947, 5909.940900590982, 5415.891682166354, 5835.891180675658, 5862.50168735658, 5898.469137777599, 5911.911321330162, 5800.53595712342, 5889.646621202742, 5927.555433342516, 5958.016927207005, 5973.283206015306, 5734.856628584277, 5754.74103665336, 5794.188813566104, 5796.942030579682, 5816.476517113438, 6301.212348456443, 6325.841853953642, 6325.936740632626, 6331.810045698637, 6454.612723236621, 6745.156855393076, 6803.523753337268, 6868.965655171743, 6884.208316043669, 6886.8622627547165, 6712.362325579111, 6836.0, 6840.657967101625, 6844.452443804484, 6847.281035491302, 6228.314885362652, 6236.03341482071, 6237.157983672191, 6240.438360547527, 6246.500279977637, 6883.65581720922, 7009.684564194624, 7042.92957070433, 7113.64431778409, 7118.644067796589, 6714.764983225564, 6731.091302674175, 6769.898451523231, 6815.2162501312, 6825.385715285648, 6698.732050718014, 6857.417119544794, 6868.828279292958, 6959.547629404073, 6968.268331825187, 6707.1616047994, 6777.423918966943, 6825.5222134451105, 6857.622830744257, 6858.3141685832015, 6591.176102987127, 6766.357196066366, 6767.424768894601, 6785.626790526569, 6797.286284940109, 5306.204069389545, 5316.813911513079, 5383.542398896059, 5405.21624364465, 5407.783688652488, 4673.205555055649, 4703.106409782131, 4711.623070154379, 4731.124741922756, 4732.834350797774, 5861.283293700895, 5873.20711703923, 5946.791862284799, 5946.851328716817, 5981.222441082631, 6709.899351509731, 6817.5909445433435, 6820.147481564804, 6820.658967051628, 6824.078749368871, 5797.2173756542325, 5798.623089499169, 5829.183914252008, 5842.795502157404, 5936.495397891227, 5854.121881717691, 5893.882122357529, 5941.346451890288, 5950.702464876739, 5961.910571341434, 6564.081028655896, 6642.070110184578, 6733.293004234582, 6734.56225345351, 6757.864842703217, 6720.227173875067, 6839.076724642308, 6860.48546359035, 6937.895931561031, 6960.825979350557, 6550.672466376757, 6551.80344589663, 6553.606783593, 6554.901676474952, 6567.605943643397, 6607.141071660653, 6638.17022872141, 6710.0, 6725.831854203684, 6739.93260067393, 6883.552569082994, 6960.4779641526075, 6964.233934267125, 6972.9302706972285, 6990.475714321345, 6570.8357291068105, 6595.373439523238, 6636.834079147867, 6722.0, 6728.0, 6309.936900630935, 6362.331955144643, 6387.169667943138, 6391.201099862517, 6395.232572091379, 6456.741730330734, 6498.967505162444, 6535.967320163369, 6546.672666366758, 6550.181227346582, 6679.398854103154, 6700.865982680417, 6745.797626071226, 6816.863662726817, 6822.079019332326, 6268.780592679417, 6378.266499352634, 6407.263164736115, 6501.512386570931, 6545.836354090995], "value": 6197.45928828667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.7948174511139, 739.970401183952, 755.2447552447552, 774.1600363605489, 779.6881247501002, 40813.38779918304, 41524.16951660969, 42000.57999420005, 42377.36433953489, 42480.150396992045, 42541.36187957183, 42656.78671606637, 42738.0, 42803.0, 42855.78572107135, 7785.41609379296, 7953.483023603476, 7953.920460795376, 7956.164602716718, 7968.362530997506, 7835.294823465887, 7896.802579935519, 7915.4063445241545, 7926.048874135083, 7949.0, 7915.8416831663335, 7946.364290856746, 7946.960265198666, 7953.323967462744, 7959.960200198991, 7807.336376408012, 7843.803904902366, 7845.097813751406, 7851.293383595504, 7898.6445609947405, 7707.922920770833, 7764.262395072459, 7765.1070126935565, 7781.805454863674, 7842.254985776342, 37846.621533784586, 38020.42969355434, 38245.808770956246, 38424.423633645514, 38429.0, 3589.94615080774, 3618.5295911531593, 3625.818709064536, 3627.818609069536, 3634.036980200249, 31978.0, 31985.680143198737, 32114.0, 32137.357252855047, 32247.83876080628, 5147.382314122329, 5213.087709650796, 5336.67979921206, 5342.0, 5342.038433082043, 180243.0, 180859.0, 181373.0, 182050.0, 182105.0, 46058.76970615193, 46283.76858115755, 46357.30464043042, 46651.76674116608, 46705.0, 48582.51417485851, 48745.0, 48991.0, 49190.50809491931, 49241.0, 15028.09831410119, 15224.238788060553, 15255.160966146968, 15259.084454932517, 15279.236038198045, 12454.377281135907, 12469.812952805714, 12509.687257818627, 12598.81101783474, 12613.369331533386, 12954.870451295366, 13234.272115033573, 13286.402111904987, 13418.127821691558, 13447.125936814271, 5293.894122117613, 5299.31108955841, 5391.032273220325, 5429.348478182644, 5472.753726082336, 1006.6376104602338, 1014.3052009373455, 1018.6943916824923, 1019.9643012494527, 1027.8098551767946, 22320.553588928455, 22404.77595224027, 22542.774572254068, 22558.887205563868, 22768.0, 1118.5358076398204, 1118.8098023336051, 1125.9549618015187, 1132.818749000156, 1141.50915106505, 11708.414579271002, 11765.88234117665, 11877.762444751057, 12065.428444502102, 12145.514179432723, 207436.0, 207782.0, 209080.0, 219418.0, 223120.0, 55499.16751248734, 56116.0, 56241.0, 56366.436335636936, 56387.15419268714, 1421.7654087075723, 1428.3715165327224, 1438.5540482450474, 1441.84139744628, 1442.4590778458078, 26833.597496037964, 26949.59575606328, 27191.59212611773, 27510.724892750815, 27575.448491029667, 38034.0, 38104.80947595244, 38119.237615248094, 38159.0, 38358.0, 197046.0, 197809.0, 198605.0, 200130.0, 200713.0, 32840.0, 33695.494567582, 33812.0, 34196.0, 34245.0, 42942.141157176055, 43046.56953430551, 43412.348814767174, 43597.56402436062, 43763.12473750571, 31554.368912622078, 31583.0, 31715.207119821265, 31737.5239371405, 32228.0, 31111.0, 31726.206844829063, 31790.682093178773, 31880.8405957978, 32113.67886321107, 35439.0, 35577.0, 35640.465393019644, 35728.82135589305, 35771.28457430785, 31034.224144395666, 31321.37357252888, 31384.843075784476, 31734.841325794147, 32235.516467253477, 22843.885780571654, 22886.885565572065, 23042.654360184944, 23193.652095218247, 23630.527389452458], "value": 17062.895756555445, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.7093026161265, 557.9609427340085, 569.5358282999355, 570.868700198954, 583.7198144890452, 22304.0, 22968.65547016794, 23202.30393088206, 23296.534069318604, 23315.0, 22426.663600046013, 22750.65874011891, 23125.07499700014, 24605.75394246057, 24818.627720584205, 6286.842828929279, 6421.29365769765, 6452.967735161317, 6454.741810327581, 6469.0, 6305.085762564426, 6307.463865571432, 6418.967905160468, 6423.871522569546, 6426.80719578411, 6433.8713225735455, 6483.15718956535, 6488.3187265337165, 6494.772682956098, 6499.967500162493, 6458.547901646886, 6462.709178086998, 6481.513886458534, 6482.7406903723795, 6497.740090396378, 5739.196512488255, 5933.703314834241, 5955.225820643332, 5982.04287314032, 5989.9101513477335, 27656.86171569129, 28200.576991345148, 28246.15261542157, 28255.57616635752, 28268.717312826815, 2019.162047750182, 2041.4590133614604, 2047.488127968008, 2049.098396705445, 2049.9590008199752, 18897.33859314931, 19062.237510499563, 19374.030005173237, 19457.610847783108, 19560.80439195604, 2460.6186041163646, 2475.5915273979954, 2506.385935445809, 2509.297396728919, 2510.259473455335, 110547.0, 116919.0, 121780.39109804483, 130711.0, 135009.0, 14404.532600118493, 14460.710785784226, 14489.565313040626, 14512.637184070272, 14610.26948652563, 22340.664890026663, 22390.440238994157, 22414.21550245766, 22422.215222467134, 22426.88786556057, 9465.716028519155, 9469.289803264783, 9598.904010959941, 9635.855462168072, 9703.369280996712, 8555.101714320032, 8671.383137534871, 8698.434601750865, 8759.2992560595, 8838.160374764388, 8798.47209167452, 8819.735407937773, 8850.690225842065, 8872.822543549093, 8975.910240897507, 2675.7324267573085, 2687.6640419947507, 2704.7295270472796, 2713.5658294672758, 2831.4478676658264, 944.5560586524323, 946.4983558713893, 955.3933252384786, 959.7744530035436, 973.8539219117048, 18279.54301142482, 18313.267469301347, 18358.990255535806, 18462.81537184638, 18780.154893029845, 988.9181533568468, 1022.907938285543, 1025.497506221946, 1027.928045036855, 1048.130052056791, 10122.392656440637, 10232.693019209435, 10257.230707696952, 10343.0, 10412.010858968386, 423238.0, 434779.0, 440394.0, 440701.0, 445175.0, 101699.49150254202, 111649.44175279072, 112053.43973280245, 113451.0, 113663.0, 1132.105636547135, 1132.2979752553306, 1151.9136064795173, 1157.2767020612118, 1160.2516376936828, 16167.595810104842, 16582.66834663276, 16594.419195328104, 16604.0, 16750.246238919033, 55058.44941550533, 55542.444575555346, 55757.721211393684, 55835.72082139563, 56805.71597141988, 70233.0, 70245.64877175582, 70785.0, 70896.0, 71337.0, 13803.861961380258, 13930.512432064828, 14071.648208794863, 14082.0, 14146.151230926178, 21803.563928721018, 22110.447238819157, 22133.557328853654, 22171.334859954226, 22386.0, 17006.319747209975, 17102.0, 17156.656866862842, 17690.203940822437, 17856.28574857043, 15079.0, 15495.767563486332, 15587.142707151215, 15765.975211611667, 16553.337866485206, 17496.125193740518, 18026.639467210844, 18188.545286367946, 18247.54381140482, 18356.632867342312, 13681.589552313446, 13742.65643358924, 13811.102278351875, 13813.654658633615, 13919.23444210578, 17319.393821216196, 17376.13119344053, 17650.382236621655, 17657.646847063243, 17675.116244187997], "value": 11536.500123976672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.7008388070744, 1354.052163485559, 1368.4594585138873, 1374.670079180997, 1376.3875075591366, 14767.26163691815, 15434.691306173883, 15479.922600386997, 15488.0, 15491.302891369887, 15187.392504299842, 15219.315130819112, 15270.465533706323, 15314.617134571614, 15369.69260614787, 2695.272276485348, 2779.652543432071, 2810.423863108058, 2813.1841765887866, 2818.6053952446573, 2573.0, 2705.5671092625184, 2728.2224565998695, 2743.245607457945, 2744.5608702607587, 2788.2750484873977, 2824.025711129657, 2824.124521398363, 2825.406664600436, 2825.770789706474, 2712.8372297662104, 2728.6589176352954, 2732.112063579325, 2732.1120635793445, 2733.3986522964915, 2608.256646855637, 2627.802914781399, 2635.8023148263956, 2647.232302632234, 2649.443616840466, 14605.269736513239, 14877.33052012662, 14891.851081489154, 14911.0, 14920.477783277533, 7670.27132422419, 7929.881051784228, 7950.403719721044, 7957.960210198969, 7965.761027169194, 5152.643915153523, 5334.813281535127, 5360.59795515334, 5446.428125046853, 5476.17857321401, 1931.082735700545, 1942.2619404626294, 1944.5333120051228, 1969.5174682202808, 1994.112619884152, 26253.343666408302, 26594.468110637677, 26677.199684009316, 26779.598306025426, 26856.32859178497, 6199.628022318675, 6233.937660623335, 6417.422431981144, 6600.60396376219, 6737.6968036438475, 16469.58826029335, 16496.17519124039, 16527.256273467483, 16532.0, 16538.83461165373, 7324.26757324274, 7464.216257293015, 7482.6632801524065, 7485.176630570633, 7496.0255166827965, 6562.245341785756, 6862.279460656659, 6904.620245886424, 6928.688209030605, 6982.406495447944, 4876.536729010809, 4997.90004199914, 5005.949940500621, 5047.747612619354, 5060.873478163002, 1258.1067442116016, 1295.7861952777687, 1313.7044165062784, 1360.7550640884538, 1381.2127087560195, 878.5431575580662, 885.0353115104567, 895.54327293081, 897.9135246351988, 905.0587389115378, 7712.691492340356, 7745.148033716399, 7801.882971755428, 7814.843703125905, 7861.095973962953, 967.5645959318335, 983.5770618634056, 994.537540043883, 1003.1523362758458, 1007.0231875081159, 4665.836695715634, 4761.809527618926, 4780.330753694486, 4811.566958973709, 4826.758662066883, 20943.37169884906, 21307.78692213089, 22019.449513761963, 22057.11771529153, 22112.447188820086, 21825.890870545547, 22013.0, 22194.556108877732, 22230.777692223193, 22244.221452249094, 9792.657256995972, 10824.296420732628, 10923.453827308762, 10951.671449856518, 11001.394923279297, 8156.265936065545, 8290.129536398592, 8297.543635099886, 8372.748817535483, 8382.706605268786, 18394.356197533023, 18524.907375463037, 18752.15615297342, 19035.71446428277, 19386.2245510178, 19007.42977710671, 19412.902935485232, 19488.8051119487, 19743.80256197477, 20032.399028029184, 10829.837552436562, 10867.673969780919, 10942.23404361687, 10944.835827462435, 11005.229633925548, 13161.0, 13369.933150334185, 13373.598792036255, 13392.732145356844, 13407.128536645087, 11190.776184476103, 11203.663890083311, 11244.718882028015, 11438.828417573577, 11656.242344247597, 10247.282690211912, 10381.11760500366, 10417.843732344172, 10423.895761042293, 10646.0, 9324.487153206639, 9343.832020997375, 9535.284853635867, 9620.086091821266, 9628.18160456369, 6913.585184888922, 6960.617166055916, 7001.0, 7010.738067147962, 7075.363217310467, 7027.8945815811785, 7028.753993610404, 7036.507444478837, 7092.680829362795, 7127.10911136108], "value": 6544.503174918602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.28275203246005, 501.8746783640062, 518.2381898609044, 522.3209827224607, 523.9214117882315, 6114.449699527047, 6168.211218746564, 6184.474319682832, 6190.3190649028575, 6207.5344349173765, 6036.275646922364, 6043.6071655342375, 6062.363451837548, 6071.362506936764, 6147.907781383283, 5015.8212819987275, 5017.473165317644, 5044.243363495487, 5058.848234552969, 5080.923786143192, 4656.697314674552, 4868.975655121737, 4923.679960802554, 4949.28235405866, 5030.220315851049, 4803.543663351976, 4815.903681926359, 4832.420109586855, 4845.0, 4890.8043678252825, 4792.568668819823, 4824.5899098576665, 4843.321934929113, 4866.343043689092, 4878.0731660984475, 4246.596573325529, 4308.138372325548, 4319.4816622005565, 4344.500382456026, 4364.0, 5836.708164591753, 5930.940690593082, 5962.493188079018, 6041.154238406627, 6064.272287325549, 5422.267993820823, 5473.8357849264185, 5496.340439147288, 5498.285222920995, 5505.146702261156, 6372.649504277263, 6409.647469389181, 6441.484681225491, 6506.674666266667, 6518.739250429977, 4536.710884608666, 4709.364235828154, 4727.45634252062, 4746.905061898743, 4763.571278584944, 6499.830030594491, 6540.771073012422, 6621.668916554201, 6684.097646817666, 6773.950037744059, 4828.130936431406, 4829.251466022702, 4836.806527738851, 4880.023995200903, 4881.0, 4860.975695121501, 4884.780184891689, 4890.608751299958, 4919.532644398776, 4950.925736113961, 5511.173324001432, 5534.806281780118, 5556.833295001156, 5560.805371811967, 5569.526590239794, 6539.313372095957, 6555.180602424697, 6560.114384558024, 6571.0471981562405, 6589.703463344161, 1673.0714453478436, 1697.3974239145173, 1701.4045084220425, 1710.4954038558656, 1723.258998630576, 1120.3054106453887, 1162.2793867802018, 1173.3253379306932, 1195.665213740154, 1204.6506513111099, 5647.717614119278, 5694.174344719992, 5717.113847353667, 5718.142278658234, 5740.167675687086, 6528.967355163289, 6597.30728273534, 6633.4693224541925, 6635.5023373247195, 6639.668016599151, 5648.74580643872, 5677.0, 5692.943070569324, 5714.228579141846, 5733.971330143323, 3501.649835016529, 3504.1603158341563, 3508.210652603195, 3546.91132721684, 3556.3598552260323, 6371.39471750183, 6419.871602568016, 6425.3574642536305, 6471.320511346335, 6535.705893234815, 6667.799796036663, 6739.966300168468, 6791.027249059257, 6834.4874134440115, 6845.931540684629, 6356.523260755369, 6397.0, 6402.423781859655, 6436.485081193493, 6453.1610890583925, 6628.900566491603, 6664.933350666432, 6697.497687673542, 6703.698333574904, 6730.394264516217, 5864.592497800582, 6503.804885853432, 6517.427599402632, 6590.176227971504, 6604.9009264862025, 6260.311365749855, 6331.27190373113, 6395.552311338161, 6406.167198264196, 6458.54790164684, 4813.181759101031, 4833.130036593447, 4860.708357498562, 4902.264660300841, 4914.975425122851, 6286.339934306832, 6569.967150164219, 6647.302033286435, 6662.666866656745, 6680.732770689118, 6254.343293954069, 6288.6855657217875, 6433.871322573429, 6440.549161558646, 6451.2903580607035, 6308.148399965946, 6350.983842585164, 6376.681165941777, 6424.775132870514, 6426.550141490051, 6222.688865556795, 6310.6213627182515, 6323.652199128908, 6342.651154186564, 6373.426391624776, 6443.387878151568, 6475.676216189077, 6479.857269224328, 6527.086207930798, 6555.6066636002, 6249.437550620284, 6260.0, 6268.373162683695, 6301.716422761078, 6338.651374174465], "value": 4955.168411020202, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [887.5872719185577, 894.3828758156872, 918.5040078357687, 925.4354843545441, 934.6635211323925, 19242.23031078757, 20207.69688454674, 20299.391018269456, 20308.796912030874, 20353.796462035374, 15935.0, 16225.675486490263, 16681.499555013368, 16919.91540042301, 17079.231434585443, 965.946872921989, 982.8280050991084, 986.9309148359617, 987.4272921705393, 987.8370068938652, 945.7162851144664, 986.6842610364686, 991.6231831903866, 999.6651121874181, 1002.0981116994726, 1067.8451624514441, 1070.2775626452153, 1070.7430216747964, 1071.383954226319, 1072.3190773858616, 1016.9796604067914, 1054.4147997861185, 1057.9365238085702, 1059.7721489879675, 1060.0353678152921, 748.9063867016623, 750.5834261984596, 751.4739682222466, 753.4688044928313, 756.3759898084047, 19821.40535783929, 19962.900185499126, 19976.200951961902, 19980.60038799216, 19992.200311987504, 20539.691904621443, 21922.342329730134, 22176.667349989762, 22195.66706499404, 22198.88900555487, 17057.330695601773, 18000.549986250448, 18033.27866885323, 18039.188236529386, 18074.096295185318, 3789.109559253573, 3795.5635101963353, 3795.7912314822674, 3805.124821291091, 3832.0419895026243, 136226.0, 136474.0, 137848.0, 139233.3038334812, 139611.0, 15312.846871531143, 15823.525294241192, 15875.364985400685, 15897.761533577006, 15963.680726385408, 31792.0, 32428.675713242566, 33276.00343979369, 33354.49968250478, 33408.0, 8344.411086715165, 8524.14758524155, 8603.870941935877, 8667.393282470166, 8688.13118688126, 4179.185058913544, 4310.784460776948, 4320.611144996966, 4371.540988196193, 4376.562343765598, 4935.185694360391, 5070.036693028313, 5076.238564215323, 5819.388964158787, 6000.619857432727, 1089.4661615808197, 1105.1379923659558, 1128.136975213962, 1129.728865072393, 1130.2709752209778, 682.0860047536326, 697.6093387702903, 709.925457826931, 714.5962531169872, 715.3883429667663, 11827.935485806318, 12171.939140304241, 12277.38613069343, 12345.259284442962, 12416.192947458387, 574.7327492715824, 580.7444724321285, 580.9448102430263, 588.808637192914, 590.4154886662259, 5944.702764861826, 6043.8186854394435, 6082.787102451393, 6101.511879049665, 6166.321704612579, 112269.43865280622, 114266.0, 114975.0, 119569.40215298868, 120415.39792300982, 102661.48669256756, 102771.0, 105934.47032764941, 107383.0, 108719.0, 87990.0, 88152.11847881567, 88876.5556172215, 89514.0, 89967.55016225007, 10890.836637450286, 11085.27945683528, 11220.270682405617, 11237.60668376603, 11298.548058077586, 41099.0, 41734.791326043174, 42758.78620606877, 43455.0, 43886.0, 54137.0, 54620.180697290365, 54959.45040549703, 55095.0, 56387.0, 15169.24153792328, 15370.23148842531, 15525.0, 15527.922360388126, 15551.066935983878, 17154.571135721708, 17469.912650437174, 17604.911975440555, 17720.113994299976, 17740.733888991934, 15846.12846293465, 15944.601384965468, 15959.760603591189, 16074.758878617064, 16083.517494475185, 15389.538313850602, 15512.767308490156, 15537.844621554092, 15603.141827199614, 15614.219289035276, 14095.788563171356, 14109.0, 14307.856921430652, 14353.0, 14376.784348234994, 10144.797104058025, 10294.433806140734, 10295.279330446796, 10499.895001049892, 10660.680179594625, 11369.0, 11511.021563166889, 11597.6520704379, 11619.0, 11762.470688818852], "value": 8836.95616281674, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 109.0, 107.0, 108.0, 108.0, 111.0, 107.0, 108.0, 107.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 116.0, 118.0, 118.0, 143.0, 115.0, 121.0, 122.0, 117.0, 119.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 165.0, 162.0, 162.0, 166.0, 181.0, 173.0, 165.0, 164.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 81.0, 74.0, 75.0, 74.0, 75.0, 75.0, 75.0, 74.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 87.0, 79.0, 82.0, 81.0, 81.0, 79.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 115.0, 115.0, 118.0, 114.0, 112.0, 114.0, 119.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 150.0, 148.0, 152.0, 149.0, 152.0, 149.0, 151.0, 149.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 99.0, 99.0, 99.0, 95.0, 94.0, 96.0, 93.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 52.0, 51.0, 51.0, 51.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 43.0, 40.0, 39.0, 38.0, 38.0, 39.0, 38.0, 38.0], "value": 39.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 78.0, 75.0, 76.0, 75.0, 76.0, 76.0, 76.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 130.0, 116.0, 125.0, 115.0, 128.0, 117.0, 130.0, 127.0, 115.0], "value": 121.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 171.0, 174.0, 176.0, 181.0, 174.0, 174.0, 185.0, 170.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 65.0, 63.0, 67.0, 65.0, 64.0, 66.0, 66.0, 66.0], "value": 65.0, "unit": "score"}]}]} 03:31:54 INFO - Return code: 0 03:31:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:31:54 INFO - # TBPL SUCCESS # 03:31:54 INFO - Running post-action listener: _package_coverage_data 03:31:54 INFO - Running post-action listener: _resource_record_post_action 03:31:54 INFO - [mozharness: 2017-09-11 10:31:54.387709Z] Finished run-tests step (success) 03:31:54 INFO - Running post-run listener: _resource_record_post_run 03:31:54 INFO - Running post-run listener: _upload_blobber_files 03:31:54 INFO - Blob upload gear active. 03:31:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:31:54 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 03:31:54 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:31:54 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:31:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:31:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:31:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:31:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:55 INFO - (blobuploader) - INFO - Done attempting. 03:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:57 INFO - (blobuploader) - INFO - Done attempting. 03:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:58 INFO - (blobuploader) - INFO - Done attempting. 03:31:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:31:58 INFO - Return code: 0 03:31:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:31:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:31:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d76071016f09cff9867849aeb120262b9ecdb554560ff56e28b1cc5182808cc0c0810ea1dde958dbeac1f100dd591849a48b9eca5a506c8a9d828694287940c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9830d54855af98cb6f5afdd2f8dfaccc4e0d6005171fb3efc9b9275cd7fcf6b98942b52ad259c6555dd6ebf97d3799744bfdae462b51220727c88c160944ef16", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d76189fcbf3e4d73906e889f04830084e3254efa1fa916512565f0b56f35df03d6fccd56db5658133a1de7e8d1153933f514bf8fafdfab80c947b04208da66f"} 03:31:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:31:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:31:58 INFO - Contents: 03:31:58 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d76071016f09cff9867849aeb120262b9ecdb554560ff56e28b1cc5182808cc0c0810ea1dde958dbeac1f100dd591849a48b9eca5a506c8a9d828694287940c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9830d54855af98cb6f5afdd2f8dfaccc4e0d6005171fb3efc9b9275cd7fcf6b98942b52ad259c6555dd6ebf97d3799744bfdae462b51220727c88c160944ef16", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d76189fcbf3e4d73906e889f04830084e3254efa1fa916512565f0b56f35df03d6fccd56db5658133a1de7e8d1153933f514bf8fafdfab80c947b04208da66f"} 03:31:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:31:58 INFO - Copying logs to upload dir... 03:31:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:31:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1297.003983 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-09-11 03:31:58.226528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 03:31:58.229872) ========= 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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d76071016f09cff9867849aeb120262b9ecdb554560ff56e28b1cc5182808cc0c0810ea1dde958dbeac1f100dd591849a48b9eca5a506c8a9d828694287940c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9830d54855af98cb6f5afdd2f8dfaccc4e0d6005171fb3efc9b9275cd7fcf6b98942b52ad259c6555dd6ebf97d3799744bfdae462b51220727c88c160944ef16", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d76189fcbf3e4d73906e889f04830084e3254efa1fa916512565f0b56f35df03d6fccd56db5658133a1de7e8d1153933f514bf8fafdfab80c947b04208da66f"} build_url:https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/AeHJJrkvT8GKfdWndNNNKg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d76071016f09cff9867849aeb120262b9ecdb554560ff56e28b1cc5182808cc0c0810ea1dde958dbeac1f100dd591849a48b9eca5a506c8a9d828694287940c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9830d54855af98cb6f5afdd2f8dfaccc4e0d6005171fb3efc9b9275cd7fcf6b98942b52ad259c6555dd6ebf97d3799744bfdae462b51220727c88c160944ef16", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d76189fcbf3e4d73906e889f04830084e3254efa1fa916512565f0b56f35df03d6fccd56db5658133a1de7e8d1153933f514bf8fafdfab80c947b04208da66f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 03:31:58.258719) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:31:58.259053) ========= 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=52faae308a556c440401f8e5000001e8-1505124618.863211-1841497605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004571 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:31:58.343567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 03:31:58.343882) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 03:31:58.344186) ========= ========= Total master_lag: 0.21 =========