builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-008 starttime: 1484231410.28 results: success (0) revision: 1289b60bd5ed221f6713497fcdc8167ae3896916 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.282025) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.282400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.282680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.298003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.298252) ========= 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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-12 06:30:10-- 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% 45.7M=0s 2017-01-12 06:30:10 (45.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107956 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.419996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.420322) ========= 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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019944 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.470607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:10.470955) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1289b60bd5ed221f6713497fcdc8167ae3896916 --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 1289b60bd5ed221f6713497fcdc8167ae3896916 --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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-12 06:30:10,699 truncating revision to first 12 chars 2017-01-12 06:30:10,699 Setting DEBUG logging. 2017-01-12 06:30:10,699 attempt 1/10 2017-01-12 06:30:10,699 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1289b60bd5ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 06:30:11,038 unpacking tar archive at: mozilla-central-1289b60bd5ed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:11.144600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:11.144933) ========= script_repo_revision: 1289b60bd5ed221f6713497fcdc8167ae3896916 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:11.145308) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:11.145594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 06:30:11.155583) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-12 06:30:11.155948) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:30:11 INFO - MultiFileLogger online at 20170112 06:30:11 in /builds/slave/test 06:30:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 06:30:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:30:11 INFO - {'append_to_log': False, 06:30:11 INFO - 'base_work_dir': '/builds/slave/test', 06:30:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:30:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:30:11 INFO - 'branch': 'Firefox-Non-PGO', 06:30:11 INFO - 'buildbot_json_path': 'buildprops.json', 06:30:11 INFO - 'config_files': ('talos/linux_config.py',), 06:30:11 INFO - 'default_actions': ('clobber', 06:30:11 INFO - 'read-buildbot-config', 06:30:11 INFO - 'download-and-extract', 06:30:11 INFO - 'populate-webroot', 06:30:11 INFO - 'create-virtualenv', 06:30:11 INFO - 'install', 06:30:11 INFO - 'run-tests'), 06:30:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:30:11 INFO - 'download_minidump_stackwalk': True, 06:30:11 INFO - 'download_symbols': 'ondemand', 06:30:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:30:11 INFO - 'tooltool.py': '/tools/tooltool.py', 06:30:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:30:11 INFO - '/tools/misc-python/virtualenv.py')}, 06:30:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:30:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:30:11 INFO - 'installer_path': 'installer.exe', 06:30:11 INFO - 'log_level': 'info', 06:30:11 INFO - 'log_name': 'talos', 06:30:11 INFO - 'log_to_console': True, 06:30:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:30:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:30:11 INFO - 'opt_config_files': (), 06:30:11 INFO - 'pip_index': False, 06:30:11 INFO - 'sps_profile': False, 06:30:11 INFO - 'sps_profile_interval': 0, 06:30:11 INFO - 'suite': 'dromaeojs', 06:30:11 INFO - 'system_bits': '32', 06:30:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:30:11 INFO - 'title': 'talos-linux64-ix-008', 06:30:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:30:11 INFO - 'use_talos_json': True, 06:30:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:30:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:30:11 INFO - 'work_dir': 'build'} 06:30:11 INFO - [mozharness: 2017-01-12 14:30:11.481930Z] Running clobber step. 06:30:11 INFO - Running pre-action listener: _resource_record_pre_action 06:30:11 INFO - Running main action method: clobber 06:30:11 INFO - rmtree: /builds/slave/test/build 06:30:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:30:11 INFO - Running post-action listener: _resource_record_post_action 06:30:11 INFO - [mozharness: 2017-01-12 14:30:11.929462Z] Finished clobber step (success) 06:30:11 INFO - [mozharness: 2017-01-12 14:30:11.929562Z] Running read-buildbot-config step. 06:30:11 INFO - Running pre-action listener: _resource_record_pre_action 06:30:11 INFO - Running main action method: read_buildbot_config 06:30:11 INFO - Using buildbot properties: 06:30:11 INFO - { 06:30:11 INFO - "project": "", 06:30:11 INFO - "product": "firefox", 06:30:11 INFO - "who": "Callek@gmail.com", 06:30:11 INFO - "installer_path": "public/build/target.tar.bz2", 06:30:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:30:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:30:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 06:30:11 INFO - "stage_platform": "linux64", 06:30:11 INFO - "basedir": "/builds/slave/test", 06:30:11 INFO - "buildnumber": 914, 06:30:11 INFO - "platform": "ubuntu64_hw", 06:30:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:30:11 INFO - "slavebuilddir": "test", 06:30:11 INFO - "taskId": "MQW4EqHAQ1WEEbw_oVpUdg", 06:30:11 INFO - "branch": "mozilla-central", 06:30:11 INFO - "script_repo_revision": "production", 06:30:11 INFO - "revision": "1289b60bd5ed221f6713497fcdc8167ae3896916", 06:30:11 INFO - "slavename": "talos-linux64-ix-008", 06:30:11 INFO - "repo_path": "mozilla-central" 06:30:11 INFO - } 06:30:11 INFO - Finding installer, test and symbols from parent task. 06:30:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQW4EqHAQ1WEEbw_oVpUdg'}, attempt #1 06:30:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MQW4EqHAQ1WEEbw_oVpUdg'}, attempt #1 06:30:12 INFO - Task dependencies: VpFsK0SmRE-QT0T59AqsWQ 06:30:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ'}, attempt #1 06:30:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 06:30:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/test_packages.json 06:30:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.crashreporter-symbols.zip 06:30:13 INFO - Running post-action listener: _resource_record_post_action 06:30:13 INFO - [mozharness: 2017-01-12 14:30:13.322313Z] Finished read-buildbot-config step (success) 06:30:13 INFO - [mozharness: 2017-01-12 14:30:13.322529Z] Running download-and-extract step. 06:30:13 INFO - Running pre-action listener: _resource_record_pre_action 06:30:13 INFO - Running main action method: download_and_extract 06:30:13 INFO - mkdir: /builds/slave/test/build/tests 06:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:13 INFO - trying https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.test_packages.json 06:30:13 INFO - Downloading https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:30:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:30:13 INFO - Downloaded 896 bytes. 06:30:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:30:13 INFO - Using the following test package requirements: 06:30:13 INFO - {u'common': [u'target.common.tests.zip'], 06:30:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:30:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:30:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:30:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:30:13 INFO - u'mozbase': [u'target.common.tests.zip'], 06:30:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:30:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:30:13 INFO - u'web-platform': [u'target.common.tests.zip', 06:30:13 INFO - u'target.web-platform.tests.zip'], 06:30:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:30:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:30:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip 06:30:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:30:13 INFO - Fetch https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip into memory 06:30:14 INFO - Expected file size: 20260791 06:30:14 INFO - Obtained file size: 20260791 06:30:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:30:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip 06:30:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:30:18 INFO - Fetch https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.common.tests.zip into memory 06:30:19 INFO - Expected file size: 20260791 06:30:19 INFO - Obtained file size: 20260791 06:30:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.talos.tests.zip 06:30:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:30:24 INFO - Fetch https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.talos.tests.zip into memory 06:30:25 INFO - Expected file size: 14403532 06:30:25 INFO - Obtained file size: 14403532 06:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:25 INFO - trying https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 06:30:25 INFO - Downloading https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:30:28 INFO - Downloaded 56141752 bytes. 06:30:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 06:30:28 INFO - mkdir: /builds/slave/test/properties 06:30:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:30:28 INFO - Writing to file /builds/slave/test/properties/build_url 06:30:28 INFO - Contents: 06:30:28 INFO - build_url:https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 06:30:28 INFO - Running post-action listener: _resource_record_post_action 06:30:28 INFO - Running post-action listener: set_extra_try_arguments 06:30:28 INFO - [mozharness: 2017-01-12 14:30:28.405350Z] Finished download-and-extract step (success) 06:30:28 INFO - [mozharness: 2017-01-12 14:30:28.405461Z] Running populate-webroot step. 06:30:28 INFO - Running pre-action listener: _resource_record_pre_action 06:30:28 INFO - Running main action method: populate_webroot 06:30:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:30:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:30:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:30:28 INFO - '--tppagecycles', 06:30:28 INFO - '7'], 06:30:28 INFO - 'tests': ['tsvgm']}}, 06:30:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:30:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:30:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:30:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:30:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:30:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:30:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['damp', 'tps']}, 06:30:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'tests': ['damp', 'tps']}, 06:30:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['dromaeo_dom']}, 06:30:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:30:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['basic_compositor_video']}, 06:30:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:30:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'other-e10s': {'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['a11yr', 06:30:28 INFO - 'ts_paint', 06:30:28 INFO - 'tpaint', 06:30:28 INFO - 'sessionrestore', 06:30:28 INFO - 'sessionrestore_no_auto_restore', 06:30:28 INFO - 'tabpaint']}, 06:30:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['tsvgx', 06:30:28 INFO - 'tsvgr_opacity', 06:30:28 INFO - 'tart', 06:30:28 INFO - 'tscrollx', 06:30:28 INFO - 'cart', 06:30:28 INFO - 'tsvg_static']}, 06:30:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:30:28 INFO - 'tsvgr_opacity', 06:30:28 INFO - 'tart', 06:30:28 INFO - 'tscrollx', 06:30:28 INFO - 'cart', 06:30:28 INFO - 'tsvg_static']}, 06:30:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'talos_options': ['--disable-e10s'], 06:30:28 INFO - 'tests': ['tp5o']}, 06:30:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:30:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'talos_options': ['--disable-e10s', 06:30:28 INFO - '--xperf_path', 06:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:30:28 INFO - 'tests': ['tp5n']}, 06:30:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:30:28 INFO - 'talos_options': ['--xperf_path', 06:30:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:30:28 INFO - 'tests': ['tp5n']}}, 06:30:28 INFO - 'talos.zip': {'path': '', 06:30:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:30:28 INFO - Running post-action listener: _resource_record_post_action 06:30:28 INFO - [mozharness: 2017-01-12 14:30:28.420780Z] Finished populate-webroot step (success) 06:30:28 INFO - [mozharness: 2017-01-12 14:30:28.420888Z] Running create-virtualenv step. 06:30:28 INFO - Running pre-action listener: _resource_record_pre_action 06:30:28 INFO - Running main action method: create_virtualenv 06:30:28 INFO - Creating virtualenv /builds/slave/test/build/venv 06:30:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:30:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:30:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:30:28 INFO - Using real prefix '/usr' 06:30:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 06:30:30 INFO - Installing pip.................done. 06:30:31 INFO - Return code: 0 06:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:30:31 INFO - Reading from file tmpfile_stdout 06:30:31 INFO - Output received: 06:30:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:30:31 INFO - Using env: {'DISPLAY': ':0', 06:30:31 INFO - 'HOME': '/home/cltbld', 06:30:31 INFO - 'LANG': 'en_US.UTF-8', 06:30:31 INFO - 'LANGUAGE': 'en_US:en', 06:30:31 INFO - 'LOGNAME': 'cltbld', 06:30:31 INFO - 'MAIL': '/var/mail/cltbld', 06:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:31 INFO - 'MOZ_NO_REMOTE': '1', 06:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:31 INFO - 'NO_EM_RESTART': '1', 06:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:31 INFO - 'PWD': '/builds/slave/test', 06:30:31 INFO - 'SHELL': '/bin/bash', 06:30:31 INFO - 'SHLVL': '1', 06:30:31 INFO - 'TERM': 'linux', 06:30:31 INFO - 'TMOUT': '86400', 06:30:31 INFO - 'USER': 'cltbld', 06:30:31 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:31 INFO - '_': '/tools/buildbot/bin/python'} 06:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:30:31 INFO - Cleaning up... 06:30:31 INFO - Return code: 0 06:30:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:30:31 INFO - Using env: {'DISPLAY': ':0', 06:30:31 INFO - 'HOME': '/home/cltbld', 06:30:31 INFO - 'LANG': 'en_US.UTF-8', 06:30:31 INFO - 'LANGUAGE': 'en_US:en', 06:30:31 INFO - 'LOGNAME': 'cltbld', 06:30:31 INFO - 'MAIL': '/var/mail/cltbld', 06:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:31 INFO - 'MOZ_NO_REMOTE': '1', 06:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:31 INFO - 'NO_EM_RESTART': '1', 06:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:31 INFO - 'PWD': '/builds/slave/test', 06:30:31 INFO - 'SHELL': '/bin/bash', 06:30:31 INFO - 'SHLVL': '1', 06:30:31 INFO - 'TERM': 'linux', 06:30:31 INFO - 'TMOUT': '86400', 06:30:31 INFO - 'USER': 'cltbld', 06:30:31 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:31 INFO - '_': '/tools/buildbot/bin/python'} 06:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:31 INFO - Downloading/unpacking psutil>=3.1.1 06:30:31 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 06:30:31 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 06:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:31 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 06:30:31 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 06:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:30:33 INFO - Installing collected packages: psutil 06:30:33 INFO - Running setup.py install for psutil 06:30:33 INFO - building 'psutil._psutil_linux' extension 06:30:33 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 06:30:34 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 06:30:34 INFO - building 'psutil._psutil_posix' extension 06:30:34 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 06:30:34 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 06:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:30:34 INFO - Successfully installed psutil 06:30:34 INFO - Cleaning up... 06:30:34 INFO - Return code: 0 06:30:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:30:34 INFO - Using env: {'DISPLAY': ':0', 06:30:34 INFO - 'HOME': '/home/cltbld', 06:30:34 INFO - 'LANG': 'en_US.UTF-8', 06:30:34 INFO - 'LANGUAGE': 'en_US:en', 06:30:34 INFO - 'LOGNAME': 'cltbld', 06:30:34 INFO - 'MAIL': '/var/mail/cltbld', 06:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:34 INFO - 'MOZ_NO_REMOTE': '1', 06:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:34 INFO - 'NO_EM_RESTART': '1', 06:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:34 INFO - 'PWD': '/builds/slave/test', 06:30:34 INFO - 'SHELL': '/bin/bash', 06:30:34 INFO - 'SHLVL': '1', 06:30:34 INFO - 'TERM': 'linux', 06:30:34 INFO - 'TMOUT': '86400', 06:30:34 INFO - 'USER': 'cltbld', 06:30:34 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:34 INFO - '_': '/tools/buildbot/bin/python'} 06:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:30:34 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 06:30:34 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 06:30:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:34 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 06:30:34 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 06:30:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:30:36 INFO - Installing collected packages: mozsystemmonitor 06:30:36 INFO - Running setup.py install for mozsystemmonitor 06:30:36 INFO - Successfully installed mozsystemmonitor 06:30:36 INFO - Cleaning up... 06:30:36 INFO - Return code: 0 06:30:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:30:36 INFO - Using env: {'DISPLAY': ':0', 06:30:36 INFO - 'HOME': '/home/cltbld', 06:30:36 INFO - 'LANG': 'en_US.UTF-8', 06:30:36 INFO - 'LANGUAGE': 'en_US:en', 06:30:36 INFO - 'LOGNAME': 'cltbld', 06:30:36 INFO - 'MAIL': '/var/mail/cltbld', 06:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:36 INFO - 'MOZ_NO_REMOTE': '1', 06:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:36 INFO - 'NO_EM_RESTART': '1', 06:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:36 INFO - 'PWD': '/builds/slave/test', 06:30:36 INFO - 'SHELL': '/bin/bash', 06:30:36 INFO - 'SHLVL': '1', 06:30:36 INFO - 'TERM': 'linux', 06:30:36 INFO - 'TMOUT': '86400', 06:30:36 INFO - 'USER': 'cltbld', 06:30:36 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:36 INFO - '_': '/tools/buildbot/bin/python'} 06:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:36 INFO - Downloading/unpacking jsonschema==2.5.1 06:30:36 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 06:30:36 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 06:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:36 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 06:30:36 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 06:30:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:30:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:30:38 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 06:30:38 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 06:30:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:38 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 06:30:38 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 06:30:39 INFO - Downloading functools32-3.2.3-2.tar.gz 06:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:30:39 INFO - warning: no files found matching '*.txt' 06:30:39 INFO - no previously-included directories found matching 'build' 06:30:39 INFO - no previously-included directories found matching 'dist' 06:30:39 INFO - no previously-included directories found matching '.git*' 06:30:39 INFO - Installing collected packages: jsonschema, functools32 06:30:39 INFO - Running setup.py install for functools32 06:30:39 INFO - warning: no files found matching '*.txt' 06:30:39 INFO - no previously-included directories found matching 'build' 06:30:39 INFO - no previously-included directories found matching 'dist' 06:30:39 INFO - no previously-included directories found matching '.git*' 06:30:39 INFO - Successfully installed jsonschema functools32 06:30:39 INFO - Cleaning up... 06:30:39 INFO - Return code: 0 06:30:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:30:39 INFO - Using env: {'DISPLAY': ':0', 06:30:39 INFO - 'HOME': '/home/cltbld', 06:30:39 INFO - 'LANG': 'en_US.UTF-8', 06:30:39 INFO - 'LANGUAGE': 'en_US:en', 06:30:39 INFO - 'LOGNAME': 'cltbld', 06:30:39 INFO - 'MAIL': '/var/mail/cltbld', 06:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:39 INFO - 'MOZ_NO_REMOTE': '1', 06:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:39 INFO - 'NO_EM_RESTART': '1', 06:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:39 INFO - 'PWD': '/builds/slave/test', 06:30:39 INFO - 'SHELL': '/bin/bash', 06:30:39 INFO - 'SHLVL': '1', 06:30:39 INFO - 'TERM': 'linux', 06:30:39 INFO - 'TMOUT': '86400', 06:30:39 INFO - 'USER': 'cltbld', 06:30:39 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:39 INFO - '_': '/tools/buildbot/bin/python'} 06:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:30:39 INFO - Cleaning up... 06:30:39 INFO - Return code: 0 06:30:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:30:39 INFO - Using env: {'DISPLAY': ':0', 06:30:39 INFO - 'HOME': '/home/cltbld', 06:30:39 INFO - 'LANG': 'en_US.UTF-8', 06:30:39 INFO - 'LANGUAGE': 'en_US:en', 06:30:39 INFO - 'LOGNAME': 'cltbld', 06:30:39 INFO - 'MAIL': '/var/mail/cltbld', 06:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:39 INFO - 'MOZ_NO_REMOTE': '1', 06:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:39 INFO - 'NO_EM_RESTART': '1', 06:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:39 INFO - 'PWD': '/builds/slave/test', 06:30:39 INFO - 'SHELL': '/bin/bash', 06:30:39 INFO - 'SHLVL': '1', 06:30:39 INFO - 'TERM': 'linux', 06:30:39 INFO - 'TMOUT': '86400', 06:30:39 INFO - 'USER': 'cltbld', 06:30:39 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:39 INFO - '_': '/tools/buildbot/bin/python'} 06:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:39 INFO - Downloading/unpacking blobuploader==1.2.4 06:30:39 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 06:30:39 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 06:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:39 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 06:30:39 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 06:30:41 INFO - Downloading blobuploader-1.2.4.tar.gz 06:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:30:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:30:41 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 06:30:41 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 06:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:41 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 06:30:41 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 06:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:30:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:30:42 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 06:30:42 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 06:30:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:42 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 06:30:42 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 06:30:42 INFO - Downloading docopt-0.6.1.tar.gz 06:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:30:42 INFO - Installing collected packages: blobuploader, requests, docopt 06:30:42 INFO - Running setup.py install for blobuploader 06:30:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:30:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:30:42 INFO - Running setup.py install for requests 06:30:43 INFO - Running setup.py install for docopt 06:30:43 INFO - Successfully installed blobuploader requests docopt 06:30:43 INFO - Cleaning up... 06:30:43 INFO - Return code: 0 06:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:30:43 INFO - Using env: {'DISPLAY': ':0', 06:30:43 INFO - 'HOME': '/home/cltbld', 06:30:43 INFO - 'LANG': 'en_US.UTF-8', 06:30:43 INFO - 'LANGUAGE': 'en_US:en', 06:30:43 INFO - 'LOGNAME': 'cltbld', 06:30:43 INFO - 'MAIL': '/var/mail/cltbld', 06:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:43 INFO - 'MOZ_NO_REMOTE': '1', 06:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:43 INFO - 'NO_EM_RESTART': '1', 06:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:43 INFO - 'PWD': '/builds/slave/test', 06:30:43 INFO - 'SHELL': '/bin/bash', 06:30:43 INFO - 'SHLVL': '1', 06:30:43 INFO - 'TERM': 'linux', 06:30:43 INFO - 'TMOUT': '86400', 06:30:43 INFO - 'USER': 'cltbld', 06:30:43 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:43 INFO - '_': '/tools/buildbot/bin/python'} 06:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:30:43 INFO - Running setup.py (path:/tmp/pip-l80L8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:30:43 INFO - Running setup.py (path:/tmp/pip-3kMd95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:30:43 INFO - Running setup.py (path:/tmp/pip-_DS74z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:30:43 INFO - Running setup.py (path:/tmp/pip-wkzEL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:30:43 INFO - Running setup.py (path:/tmp/pip-6fMLCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:30:43 INFO - Running setup.py (path:/tmp/pip-W0avVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:30:43 INFO - Running setup.py (path:/tmp/pip-EP_FDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:30:44 INFO - Running setup.py (path:/tmp/pip-s5LT4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:30:44 INFO - Running setup.py (path:/tmp/pip-Vzs_Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:30:44 INFO - Running setup.py (path:/tmp/pip-aey5Qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:30:44 INFO - Running setup.py (path:/tmp/pip-ypV_8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:30:44 INFO - Running setup.py (path:/tmp/pip-cb3AfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:30:44 INFO - Running setup.py (path:/tmp/pip-mG3OMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:30:44 INFO - Running setup.py (path:/tmp/pip-TImpoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:30:44 INFO - Running setup.py (path:/tmp/pip-9Cyvhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:30:44 INFO - Running setup.py (path:/tmp/pip-32rO3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:30:44 INFO - Running setup.py (path:/tmp/pip-38YY6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:30:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:30:44 INFO - Running setup.py install for manifestparser 06:30:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:30:44 INFO - Running setup.py install for mozcrash 06:30:44 INFO - Running setup.py install for mozdebug 06:30:45 INFO - Running setup.py install for mozdevice 06:30:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for mozfile 06:30:45 INFO - Running setup.py install for mozhttpd 06:30:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for mozinfo 06:30:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for mozInstall 06:30:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for mozleak 06:30:45 INFO - Running setup.py install for mozlog 06:30:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for moznetwork 06:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:30:45 INFO - Running setup.py install for mozprocess 06:30:45 INFO - Running setup.py install for mozprofile 06:30:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:30:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:30:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:30:46 INFO - Running setup.py install for mozrunner 06:30:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:30:46 INFO - Running setup.py install for mozscreenshot 06:30:46 INFO - Running setup.py install for moztest 06:30:46 INFO - Running setup.py install for mozversion 06:30:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:30:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:30:46 INFO - Cleaning up... 06:30:46 INFO - Return code: 0 06:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:30:46 INFO - Using env: {'DISPLAY': ':0', 06:30:46 INFO - 'HOME': '/home/cltbld', 06:30:46 INFO - 'LANG': 'en_US.UTF-8', 06:30:46 INFO - 'LANGUAGE': 'en_US:en', 06:30:46 INFO - 'LOGNAME': 'cltbld', 06:30:46 INFO - 'MAIL': '/var/mail/cltbld', 06:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:46 INFO - 'MOZ_NO_REMOTE': '1', 06:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:46 INFO - 'NO_EM_RESTART': '1', 06:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:46 INFO - 'PWD': '/builds/slave/test', 06:30:46 INFO - 'SHELL': '/bin/bash', 06:30:46 INFO - 'SHLVL': '1', 06:30:46 INFO - 'TERM': 'linux', 06:30:46 INFO - 'TMOUT': '86400', 06:30:46 INFO - 'USER': 'cltbld', 06:30:46 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:46 INFO - '_': '/tools/buildbot/bin/python'} 06:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:30:46 INFO - Running setup.py (path:/tmp/pip-QvEvYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:30:46 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)) 06:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:30:46 INFO - Running setup.py (path:/tmp/pip-MQN0ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:30:46 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)) 06:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:30:46 INFO - Running setup.py (path:/tmp/pip-f6pdGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:30:46 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)) 06:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:30:46 INFO - Running setup.py (path:/tmp/pip-ai5NmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:30:47 INFO - Running setup.py (path:/tmp/pip-onOu65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:30:47 INFO - Running setup.py (path:/tmp/pip-35G3A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:30:47 INFO - Running setup.py (path:/tmp/pip-EWNl8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:30:47 INFO - Running setup.py (path:/tmp/pip-Jh16lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:30:47 INFO - Running setup.py (path:/tmp/pip-GQT_Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:30:47 INFO - Running setup.py (path:/tmp/pip-6JvHt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:30:47 INFO - Running setup.py (path:/tmp/pip-xSVR7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:30:47 INFO - Running setup.py (path:/tmp/pip-cS5zjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:30:47 INFO - Running setup.py (path:/tmp/pip-NIdnIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:30:47 INFO - Running setup.py (path:/tmp/pip-2NrIv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:30:47 INFO - Running setup.py (path:/tmp/pip-s_TB2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:30:47 INFO - Running setup.py (path:/tmp/pip-XvWgz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:30:47 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)) 06:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:30:47 INFO - Running setup.py (path:/tmp/pip-iE_Sdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:30:47 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)) 06:30:47 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)) 06:30:47 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)) 06:30:47 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)) 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:30:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:30: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 06:30: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 06:30:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:30: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 06:30: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 06:30:49 INFO - Downloading blessings-1.6.tar.gz 06:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:30:50 INFO - Installing collected packages: blessings 06:30:50 INFO - Running setup.py install for blessings 06:30:50 INFO - Successfully installed blessings 06:30:50 INFO - Cleaning up... 06:30:50 INFO - Return code: 0 06:30:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:30:50 INFO - Reading from file tmpfile_stdout 06:30:50 INFO - Current package versions: 06:30:50 INFO - argparse == 1.2.1 06:30:50 INFO - blessings == 1.6 06:30:50 INFO - blobuploader == 1.2.4 06:30:50 INFO - docopt == 0.6.1 06:30:50 INFO - functools32 == 3.2.3-2 06:30:50 INFO - jsonschema == 2.5.1 06:30:50 INFO - manifestparser == 1.1 06:30:50 INFO - mozInstall == 1.12 06:30:50 INFO - mozcrash == 1.0 06:30:50 INFO - mozdebug == 0.1 06:30:50 INFO - mozdevice == 0.49 06:30:50 INFO - mozfile == 1.2 06:30:50 INFO - mozhttpd == 0.7 06:30:50 INFO - mozinfo == 0.9 06:30:50 INFO - mozleak == 0.1 06:30:50 INFO - mozlog == 3.4 06:30:50 INFO - moznetwork == 0.27 06:30:50 INFO - mozprocess == 0.24 06:30:50 INFO - mozprofile == 0.28 06:30:50 INFO - mozrunner == 6.13 06:30:50 INFO - mozscreenshot == 0.1 06:30:50 INFO - mozsystemmonitor == 0.3 06:30:50 INFO - moztest == 0.8 06:30:50 INFO - mozversion == 1.4 06:30:50 INFO - psutil == 3.1.1 06:30:50 INFO - requests == 1.2.3 06:30:50 INFO - wsgiref == 0.1.2 06:30:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:30:50 INFO - Using env: {'DISPLAY': ':0', 06:30:50 INFO - 'HOME': '/home/cltbld', 06:30:50 INFO - 'LANG': 'en_US.UTF-8', 06:30:50 INFO - 'LANGUAGE': 'en_US:en', 06:30:50 INFO - 'LOGNAME': 'cltbld', 06:30:50 INFO - 'MAIL': '/var/mail/cltbld', 06:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:50 INFO - 'MOZ_NO_REMOTE': '1', 06:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:50 INFO - 'NO_EM_RESTART': '1', 06:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:50 INFO - 'PWD': '/builds/slave/test', 06:30:50 INFO - 'SHELL': '/bin/bash', 06:30:50 INFO - 'SHLVL': '1', 06:30:50 INFO - 'TERM': 'linux', 06:30:50 INFO - 'TMOUT': '86400', 06:30:50 INFO - 'USER': 'cltbld', 06:30:50 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:50 INFO - '_': '/tools/buildbot/bin/python'} 06:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 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)) 06:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:30:50 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)) 06:30:50 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)) 06:30:50 INFO - Cleaning up... 06:30:50 INFO - Return code: 0 06:30:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf54630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, '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': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', '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 06:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:30:50 INFO - Using env: {'DISPLAY': ':0', 06:30:50 INFO - 'HOME': '/home/cltbld', 06:30:50 INFO - 'LANG': 'en_US.UTF-8', 06:30:50 INFO - 'LANGUAGE': 'en_US:en', 06:30:50 INFO - 'LOGNAME': 'cltbld', 06:30:50 INFO - 'MAIL': '/var/mail/cltbld', 06:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:30:50 INFO - 'MOZ_NO_REMOTE': '1', 06:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:30:50 INFO - 'NO_EM_RESTART': '1', 06:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:30:50 INFO - 'PWD': '/builds/slave/test', 06:30:50 INFO - 'SHELL': '/bin/bash', 06:30:50 INFO - 'SHLVL': '1', 06:30:50 INFO - 'TERM': 'linux', 06:30:50 INFO - 'TMOUT': '86400', 06:30:50 INFO - 'USER': 'cltbld', 06:30:50 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:30:50 INFO - '_': '/tools/buildbot/bin/python'} 06:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:30:50 INFO - Cleaning up... 06:30:50 INFO - Return code: 0 06:30:50 INFO - Running post-action listener: _resource_record_post_action 06:30:50 INFO - Running post-action listener: _start_resource_monitoring 06:30:50 INFO - Starting resource monitoring. 06:30:50 INFO - [mozharness: 2017-01-12 14:30:50.963937Z] Finished create-virtualenv step (success) 06:30:50 INFO - [mozharness: 2017-01-12 14:30:50.964450Z] Running install step. 06:30:50 INFO - Running pre-action listener: _resource_record_pre_action 06:30:50 INFO - Running main action method: install 06:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:30:51 INFO - Reading from file tmpfile_stdout 06:30:51 INFO - Detecting whether we're running mozinstall >=1.0... 06:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:30:51 INFO - Reading from file tmpfile_stdout 06:30:51 INFO - Output received: 06:30:51 INFO - Usage: mozinstall [options] installer 06:30:51 INFO - Options: 06:30:51 INFO - -h, --help show this help message and exit 06:30:51 INFO - -d DEST, --destination=DEST 06:30:51 INFO - Directory to install application into. [default: 06:30:51 INFO - "/builds/slave/test"] 06:30:51 INFO - --app=APP Application being installed. [default: firefox] 06:30:51 INFO - mkdir: /builds/slave/test/build/application 06:30:51 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'] 06:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:31:07 INFO - Reading from file tmpfile_stdout 06:31:07 INFO - Output received: 06:31:07 INFO - /builds/slave/test/build/application/firefox/firefox 06:31:07 INFO - Running post-action listener: _resource_record_post_action 06:31:07 INFO - [mozharness: 2017-01-12 14:31:07.413909Z] Finished install step (success) 06:31:07 INFO - [mozharness: 2017-01-12 14:31:07.414182Z] Running run-tests step. 06:31:07 INFO - Running pre-action listener: _resource_record_pre_action 06:31:07 INFO - Running main action method: run_tests 06:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:31:07 INFO - Python 2.7.3 06:31:07 INFO - Return code: 0 06:31:07 INFO - grabbing minidump binary from tooltool 06:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:31:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd850>, '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 0xf526d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf52b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:31:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:31:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:31:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:31:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:31:07 INFO - Return code: 0 06:31:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:31:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:31:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:31:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:31:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:31:07 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:31:07 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:31:07 INFO - Using env: {'DISPLAY': ':0', 06:31:07 INFO - 'HOME': '/home/cltbld', 06:31:07 INFO - 'LANG': 'en_US.UTF-8', 06:31:07 INFO - 'LANGUAGE': 'en_US:en', 06:31:07 INFO - 'LOGNAME': 'cltbld', 06:31:07 INFO - 'MAIL': '/var/mail/cltbld', 06:31:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:31:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:07 INFO - 'MOZ_NO_REMOTE': '1', 06:31:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:31:07 INFO - 'NO_EM_RESTART': '1', 06:31:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:07 INFO - 'PWD': '/builds/slave/test', 06:31:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:31:07 INFO - 'SHELL': '/bin/bash', 06:31:07 INFO - 'SHLVL': '1', 06:31:07 INFO - 'TERM': 'linux', 06:31:07 INFO - 'TMOUT': '86400', 06:31:07 INFO - 'USER': 'cltbld', 06:31:07 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895', 06:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:07 INFO - '_': '/tools/buildbot/bin/python'} 06:31:07 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:31:07 INFO - mozversion application_buildid: 20170112140142 06:31:07 INFO - mozversion application_changeset: 1289b60bd5ed221f6713497fcdc8167ae3896916 06:31:07 INFO - mozversion application_display_name: Nightly 06:31:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:31:07 INFO - mozversion application_name: Firefox 06:31:07 INFO - mozversion application_remotingname: firefox 06:31:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:31:07 INFO - mozversion application_vendor: Mozilla 06:31:07 INFO - mozversion application_version: 53.0a1 06:31:07 INFO - mozversion platform_buildid: 20170112140142 06:31:07 INFO - mozversion platform_changeset: 1289b60bd5ed221f6713497fcdc8167ae3896916 06:31:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:31:07 INFO - mozversion platform_version: 53.0a1 06:31:07 INFO - using testdate: 1484231467 06:31:07 INFO - actual date: 1484231467 06:31:07 INFO - starting webserver on 'localhost:34242' 06:31:07 INFO - SUITE-START | Running 2 tests 06:31:07 INFO - TEST-START | dromaeo_css 06:31:07 INFO - operating with platform_type : linux_ 06:31:07 INFO - Initialising browser for dromaeo_css test... 06:31:07 INFO - TEST-INFO | started process 4413 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNHZ5jZ/profile http://localhost:34242/getInfo.html) 06:31:11 INFO - PROCESS | 4413 | __metrics Screen width/height:1600/1200 06:31:11 INFO - PROCESS | 4413 | colorDepth:24 06:31:11 INFO - PROCESS | 4413 | Browser inner width/height: 1024/697 06:31:11 INFO - PROCESS | 4413 | __metrics 06:31:12 INFO - PROCESS | 4413 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:31:12 INFO - PROCESS | 4413 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:31:12 INFO - PROCESS | 4413 | console.error: PushService: 06:31:12 INFO - PROCESS | 4413 | stateChangeProcessEnqueue: Error transitioning state 06:31:12 INFO - PROCESS | 4413 | UnknownError 06:31:13 INFO - TEST-INFO | 4413: exit 0 06:31:13 INFO - Browser initialized. 06:31:13 INFO - Running cycle 1/1 for dromaeo_css test... 06:31:13 INFO - TEST-INFO | started process 4498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNHZ5jZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:31:25 INFO - PROCESS | 4498 | RSS: Main: 151912448 06:31:25 INFO - PROCESS | 4498 | 06:34:30 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34242/tests/dromaeo/cssquery-ext.html) 06:34:32 INFO - PROCESS | 4498 | RSS: Main: 288702464 06:34:32 INFO - PROCESS | 4498 | 06:36:13 INFO - PROCESS | 4498 | ************************* 06:36:13 INFO - PROCESS | 4498 | A coding exception was thrown and uncaught in a Task. 06:36:13 INFO - PROCESS | 4498 | 06:36:13 INFO - PROCESS | 4498 | Full message: ReferenceError: fetch is not defined 06:36:13 INFO - PROCESS | 4498 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 06:36:13 INFO - PROCESS | 4498 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 06:36:13 INFO - PROCESS | 4498 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 06:36:13 INFO - PROCESS | 4498 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:36:13 INFO - PROCESS | 4498 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:36:13 INFO - PROCESS | 4498 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:36:13 INFO - PROCESS | 4498 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 06:36:13 INFO - PROCESS | 4498 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:36:13 INFO - PROCESS | 4498 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:36:13 INFO - PROCESS | 4498 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:36:13 INFO - PROCESS | 4498 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 06:36:13 INFO - PROCESS | 4498 | 06:36:13 INFO - PROCESS | 4498 | ************************* 06:37:34 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-ext.html (next: http://localhost:34242/tests/dromaeo/cssquery-jquery.html) 06:37:35 INFO - PROCESS | 4498 | RSS: Main: 240427008 06:37:35 INFO - PROCESS | 4498 | 06:40:37 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34242/tests/dromaeo/cssquery-mootools.html) 06:40:38 INFO - PROCESS | 4498 | RSS: Main: 182669312 06:40:38 INFO - PROCESS | 4498 | 06:43:40 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34242/tests/dromaeo/cssquery-prototype.html) 06:43:41 INFO - PROCESS | 4498 | RSS: Main: 182853632 06:43:41 INFO - PROCESS | 4498 | 06:46:44 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34242/tests/dromaeo/cssquery-yui.html) 06:46:45 INFO - PROCESS | 4498 | RSS: Main: 185589760 06:46:45 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | Cycle 1(1): loaded http://localhost:34242/tests/dromaeo/cssquery-yui.html (next: http://localhost:34242/tests/dromaeo/cssquery-dojo.html) 06:49:48 INFO - PROCESS | 4498 | RSS: Main: 284430336 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | __start_tp_report 06:49:48 INFO - PROCESS | 4498 | _x_x_mozilla_page_load 06:49:48 INFO - PROCESS | 4498 | _x_x_mozilla_page_load_details 06:49:48 INFO - PROCESS | 4498 | |i|pagename|runs| 06:49:48 INFO - PROCESS | 4498 | |0;dojo.html;2583.3799888026874;2605.088219123306;2616.764491195792;2638.063487461951;2643.814932954694;4716.292556116584;4788.257820037891;4803.663743537953;4825.445073816517;4844.394450693663;4847.975760121194;4850.4179498460235;4867.075255701423;4872.317875497424;4883.829065982692;4434.889127771808;4440.444944381952;4457.509673935864;4467.5755803198645;4485.932711009337;4476.440444944382;4482.798274077658;4484.439445069366;4499.955000450002;4534.206513860077;4482.417285752847;4506.346579745935;4516.029053753442;4520.163769702599;4564;4440.134173836103;4518.2319005769095;4543.750093744843;4578;4591.035882464686;4453.821847126111;4485.9551404485865;4500.48244451889;4522.29904364824;4538.591526762574;4717.764111794431;4718;4755.405574303212;4755.524447555217;4762.78567464465;5124.333836601218;5140.768665410028;5158.871028224287;5160.716160611165;5167.948320516784;5116.411612664554;5182.766775495112;5189.3772747270195;5195.896082078375;5237.659552129099;4731.7634118293945;4774.069056533943;4839.951600483985;4843.878903027453;4851.393575803025;5264.868378290496;5306.8407947761625;5308.761105750251;5351.919721204185;5399.757010934517;5095.0828850806465;5117.2068329409;5123.257127716461;5123.7438128093445;5165.096108181128;5068.746562671851;5197.480251974772;5208.088584497697;5210.192420174879;5210.97394513025;4951.10880041596;5048.1418158913075;5100.923486147711;5117.641765076409;5210.895782084337;5131.384233891882;5132.871678208075;5138.177891537411;5139.948600514022;5171.224316352577;4238.703290769617;4239.639630631369;4258.127083947783;4264.360345948133;4316.69783115179;3710.5176327077306;3726.2920045191327;3752.3620984432714;3763.1344790697744;3775.6979441644603;4454.799534020977;4476.731396116244;4478.126765280739;4482.148391805546;4530.501644819067;5053.1157047516535;5053.9241911371355;5241.082810508222;5243.397009343974;5244.003639308519;4394.780260986938;4416.84541041062;4439.223135951195;4463.754493502824;4477.507474177838;4624.306354046853;4627.537246275413;4629.213033784197;4664.137134630037;4669.229577119805;4835.2505361669;4840.515948405131;4841.975790121097;4860.684055536379;4890.168671325812;5108.131617625087;5142.357205349332;5142.51146141116;5152.072626927189;5178.6374953752875;4861.295112208781;4899.681520701143;4904.5340692634145;4940.7035577865445;4945.1346014448045;5123.871903202301;5127.53852153308;5130.384353877558;5139.254808052886;5185.27406163145;5067.493250674903;5082.949170508396;5199.688018718889;5203.921941170961;5212.635115542027;5033.446320904771;5078.644494885322;5084.186530155157;5088.211327244283;5157.561607263275;5084.796608135781;5094.414142373675;5099.23511473282;5101.8724531887;5150.201718733602;4928.630352723489;5027.572656324253;5032.62255330859;5051.74741262928;5061.493850614909;5062.038212739568;5078.365204349457;5124.564412024871;5125.205593133071;5174.586033117266;4990.4011518618;5001.924971125363;5009.423916249678;5026.924596130988;5058.31712718793 06:49:48 INFO - PROCESS | 4498 | |1;ext.html;548.3063924135968;593.2554643921878;596.5466245653303;607.696151924038;610.8900397928373;29805.701942980562;40445.79777101114;42105.578944210545;42281;46186.307205391946;43108.13783724332;43487.78256108723;43501.7824910875;43566;43577;7743.690252389898;7907.96046019772;7914.762557123267;7919.247671471201;7927;7565.130010048832;7779.533228006338;9936.403815771077;10201;10203.336783109075;10024.398536087858;10083;10083.495825208747;10092.142167915736;10113.696589102301;10197.541110650038;10252.846207306897;10260.948695256477;10270.024347686995;10274;9970;10043.648472303434;10044.497775111215;10050.849237261447;10057.798844023078;40558.594414056075;40933;40939.59060409388;40941.1811763763;40966.38550421716;3772.1324095457926;3854.2484215577974;3858.247641709868;3865.2849222893856;3905.49228600283;28677.85661071681;28914.42171156565;28984.565231521545;29161.56257656137;30092.398152036836;2989.2975150839534;3012.9548056779167;3030.4696678081245;3039.285767844555;3044.862981165853;240183;240322;241123;242002;242067;55344.16983745247;55456.44543554513;55607.44392556023;55765;56015.719921400945;59957.40042599518;59965;60018.39981600128;60089.39910600925;60103.39896601065;14073.718525629634;14593.416263349347;14598.41606335756;14600.12399256048;14629.561113166623;11765.352905590104;11865.228760130563;11867.228630139014;11873.465694043789;11882;15199.696006079817;15351.462698805488;16883.155842208085;17175.484735457958;17429.389971351196;5912.793052243151;6082;6082.726277317532;6106.297775755845;6141.570090093651;827.4249396669377;842.5744998775579;845.7927807687033;847.4576271186446;854.833307505043;26693.19920402391;28242.85878570594;28564;28793.568096478568;28857;935.2798345274101;992.3103443107116;997.2321312589265;997.9650712225183;999.695092996643;15249.542513724606;15294.77057844111;15312.487504498371;15334.386624534895;15618.84381156174;320042;320386;320536;320613;321665;51883.74058129685;52296;52466;52487.737561312715;52513.73743131336;1208.7824191645589;1248.8563815161372;1268.0489632775418;1271.7647235261506;1275.043717212091;25001;26542.469150616493;27405.31486712848;27559.31101722393;27776.861115694293;44315.778421108975;44955.32567011563;44955.77522112369;45001;45013.77493112513;213938;216281;216401;216868;217166;33513.16217094592;36179.27641447104;36457.817710912335;36578.26843463063;36659.81670091739;33832.323353533284;43346.349804752324;43942;44166;48955.510444895095;36334.81832590926;37054.44418333781;37179.25641487209;37382.25235495329;37540.2491950154;36890;37281.44077838889;37401.43897841589;37731.43402849014;37776.43335349917;36134.819325903205;36644.45033324449;36836.81581592075;36990.260194796494;37171.44242836306;31510.68489315169;33087.50368744422;33201.66798331986;33234.66765332316;33565.32869342648;24985.625215621418;25178;25256.484610923402;25295.873520632278;25556.616650750624 06:49:48 INFO - PROCESS | 4498 | |2;jquery.html;379.8366702318004;383.62213219978327;390.43103196471526;392.3017029687157;408.38130232697466;13376.531821386254;13831.930840345784;16971.49085527433;18485.445436636906;18516.814831851676;15333.846661533382;18561.07194640266;18611.906940465313;18695.71956420655;18817.529561760963;5197.818076367328;5399.217113518537;5404;5404.189371594253;5404.270423492818;5084.3644544431945;5273.34083239595;5286.788528458857;5287.47125287472;5294.391145018334;5263.473652634744;5268.657537260085;5269.446708095652;5269.8946021079555;5275.103232450492;5085.644004919657;5245.842624721265;5268.262443257947;5275.60432967529;5276.314079169683;4757.26262429324;4759.785809638574;4762.690425122356;4763.714177149347;4765.499622539618;21289.574208515896;21775.782242177534;21863;21941.670874936888;22035.338939831672;2259.3109101723967;2290.1640901071155;2314.5139520700673;2328.8951997159998;2359.2686267257204;13295.268760218185;13513.527026554024;13805.171689698549;13838.30808459573;14360;1771.7165253559497;2055.2190167736167;2174.4765702891327;2190.266260802633;2197.2529340024466;69182.65408672974;89037;90440.54779726059;95656.9999999993;96469;16049.277782499816;17576.09825741324;17619.91190044067;18375.54061148482;18460.630787384176;32968.505472417935;33310.16724581905;33480;33485.33029339399;33926;9006.50464224474;9043.140901614335;9109.134632209929;9186.63253469867;9476.052394760607;7313.963430182815;7329.010583571257;7539.283768042027;7544.170141284454;7573.46985711006;9176.816463670822;9240.491772952551;9269.49723574631;9498.430094194371;9577.808443831085;3488.7906725596545;3503.964960350415;3748.231612519427;3860.82626281818;3867.961320386816;789.7946533901227;804.1435870797699;822.42841225349;823.7569916874536;830.7466222802103;16813.32746690143;16954.576135596708;17485.47543573695;17838.732419013726;18061.638767224584;786.5438045933345;799.8440304140637;802.9277365037175;812.6627449608465;824.5547404401676;9191.586378612978;9457.43255404678;9829.91870894201;9846.310758246997;9907.356021858555;464910;469751;473474;473831;485957;89313.55343223242;91884;92200;94129.5293523528;94155.52922235345;841.4320333774677;891.6344298837446;903.9141281578239;931.1154403316741;938.7891515481081;15700.293486792893;15814.9209253953;15950.8404915954;16298.066348057464;16538.338466461206;39994;40343.596564033985;40965;41269.38095928504;41459;54825.725871370385;55956;56532.71733641306;57863.421365785805;58945.115823261825;6921.6193109379465;7270.345668889825;7295.416366690546;7768.53388796674;8091.87862182056;11514.42427878586;12263.632091037283;12820.679483013;12929.741405172032;13115.803262950873;10270.6405275815;10379.948100259451;10702.73243168927;10725.088367488539;11287.782981850462;10365.326253793479;10450.738731531772;10874.401907894826;10891.31184666378;11187.608433705107;10776.784464310827;10898.291611045257;11265.436728163395;11267.323960562393;11532.827007594726;7644.15914249421;7649.464537482323;7800.102988156208;7810.6485208166905;7919.643616037408;9520.761980950532;10973.890261097287;10998.560057597606;11056.834147487954;11425.485853136463 06:49:48 INFO - PROCESS | 4498 | |3;mootools.html;1369.4590636698504;1370.2326697049652;1378.7104708011318;1380.986190138099;1388.8819450346723;6923.549969252002;7322.0847394075745;7326.597037162954;7329.230430804769;7331.340179383855;6336.271328797178;6518.934810651892;6929.896051559231;7151.356377925986;7252.3835473984645;2153.4616345913482;2199.5051113499494;2202.790734880192;2204.581129585382;2206.5476577301697;2198.6482162853945;2207.227470385368;2238.03764381316;2246.9101235950543;2263.2418139923147;1924.7978962208977;1988.741463609736;2095.559932414195;2182.9235976740815;2189.8905054747197;2168.7831216878267;2178.5098352870637;2178.6405243134864;2179.2263746369968;2179.56408718257;2135.765065842756;2148.4803188839064;2178.8039076483033;2180.694702741617;2189.7372315322104;7310.049693539859;7332.633368331562;7341.559506429631;7347.669354879044;7348.154962179365;4409;4549.431321084864;4573.931391029138;4582.839600613963;4586.059857729158;2903.6951120132294;3025.077351407842;3091.119031076155;3102.6742192069732;3122.9219269518217;1106.6569363497342;1146.5757669662278;1183.5325046606542;1185.9703507412298;1213.4175595714019;9520.571574279174;9576.329656924017;9581.61673533058;9588;9597.040295970348;4062.2687916175373;4245.61789438952;4343.4136391586735;4380.408644832971;4401.471823381215;8417.494950302878;8421.831563368698;8427.789305267295;8437.029741579798;8445.873311900326;4691.577758001728;4694.201985662446;4696.131215725101;4697.671163018625;4699.942512934631;3777.6222377762006;3779.338615742234;4197.433346498245;4221.134667393177;4318.201132790444;3571.74997750155;3577.821108944542;3682.797446140434;3684.778913265159;3694.612065733113;1242.7763002659428;1245.1532957588927;1254.4793910527212;1260.1871792693662;1263.1284413754663;872.2106493623281;876.3120950054176;877.6489404238356;887.1394747095308;901.2879824938225;4898.167311557044;4984.6261530384645;5038.622103342264;5106.106431374569;5145.716985565829;572.747990884018;577.1573502686125;579.9043157878903;580.7996241296746;589.0516268807216;3381.7970921744773;3615.837287322077;3624.5288112545727;3640.581333146669;3648.927021459556;8572.914270857336;8810.911890881009;8890.59992300335;8893.110688931056;8907.51008694515;8829.33779966505;8876.423032502867;8880.289576833839;8885.955570222108;8892.288616910631;6545.854475466775;6870.381665650116;6904.930950690429;6985.126859142607;6994.33553812387;4941.876453088702;4975.452700202903;5051.166557517969;5108.208227724708;5122.436531981404;8073.35413166957;8101.959490202511;8123.75628731139;8262.710805121791;8322.126176751595;8151.103378628465;8328.916710832815;8340.249377556049;8368.497890126611;8377.246047855722;5537.5569954402745;5643.971780141073;5795.623284486664;5903.468687818117;5917.733701983507;6831.795046148623;6841.555298905555;7041.964790176016;7043.823904402226;7048.154221493453;6292.842678933063;6365.140706004815;6377.681115944277;6412.903806442814;6523.804285871432;6185.8144255672405;6199.473044791062;6249.0626406039455;6257.7184026718;6308.08532762756;5779.422057794187;5870.0607902735355;5873.207117039145;5873.882522349614;5876.382979787232;4485.215087359634;4498.167838949805;4557.475890272661;4588.541145885385;4619.676622636383;4513.887152821075;4516.774161291988;4663.790129444251;4698.976505117453;4763.642726795435 06:49:48 INFO - PROCESS | 4498 | |4;prototype.html;364.93066317399695;368.62216228365924;368.95756987946396;371.89401020709096;376.64595280436373;4621.768911554417;4761.357216775734;4778.989236780832;4792.442456201736;4827.396575428072;4611.446626404828;4722.929156062662;4764.97617511912;4800.183968725316;4805.90388192236;3754.962450375502;3835.8082095895093;3853.537575490945;3937.566867644571;3950.486436763224;3910.8240129194114;3914.158455931974;3922.7646341219474;3936.0553467167815;3939.606039396052;3872.7676339419586;3894.357431023878;3894.6494815466604;3910.9413358799643;3914.9821046527823;3894.7273690841653;3901.653929394383;3905.5703872573995;3908.456159816885;3926.3914093315298;3141.607299087614;3254.886078987224;3294.8682052717863;3295.917602059944;3304.801711897294;4618.9076218475775;4636.142313671981;4638.240247053031;4638.420197475316;4670.5754744802825;4293.806778694966;4314.633256173198;4320.351947207912;4326.134773045404;4330.033926760179;4834.371531700892;5030.119729047402;5035.496450354972;5049.899002019939;5055.140626093536;3619.1675914539805;3619.819009049537;3623.6738693517445;3627.8548858045647;3635.818209089535;5248.343957005374;5267.236250743621;5273.050850846846;5289.920651190235;5305;3631.94552081719;3722.199727058641;3725.832337544817;3729.1609387887506;3729.384651532521;3733.4026555750947;3736.850525978985;3738.5887552369218;3765.4163604641326;3782.6217378261954;4222.134462435193;4233.153369326147;4238.4490016298305;4244.0663054128045;4255.553111941955;4805.9759701201965;4907.68100073494;4910.165271903785;5031.899362012739;5040.697558146523;1249.8312727781815;1254.2913254011455;1254.7352029586684;1257.459292504232;1257.698152443407;834.5952213176605;858.8067684771001;864;867.9627844725619;875.4528419737663;4302.161078589645;4306.533240741171;4308.827646894089;4310.288802347577;4315.65536709053;5008.994603238028;5011.523905228997;5025.969676216367;5026.723530205801;5034.029922345833;4307.547707490731;4317.481902171759;4335.6965012449455;4338.392625032498;4339.435873336446;2521.9369515761887;2655.4423571050297;2662.4808162408144;2662.9467410651678;2679.7990150738383;4737.028909073631;4741.739204343725;4756.6432517561325;4759.214729569651;4764.6426518011285;4896.804127834918;5138.4604616514725;5153.458886816905;5165.199394093921;5182.274481572583;4745.83389581363;4747.928781068286;4775.585456398691;4789.449213340441;4790.976045119752;5063.974140558339;5070.41690205631;5073.868975030422;5096.980603879313;5103.285540024325;4814.398200224972;4954.281629163679;4984.551390374883;5000.899982000413;5007.57435617962;4847.878803029953;4894.314795928502;4896.2410826321975;4901.166801643801;4915.778789954389;3668.779873207616;3677.4116141417244;3695.5934847167327;3698.204885949534;3705.5368078990127;4964.230544265644;5045.905949031111;5197.92203116946;5204.453532379197;5205.687658740488;4622.682535477373;5001.224810154432;5017.774200161075;5019.297298378157;5093.92359114621;5021.472745361684;5028.396592408934;5056.6713163644245;5080.517350851663;5099.92350114756;4809.927851082307;4939.851804445872;4979.975100124621;4981.6014718823135;5036.899262014813;5060.266261392151;5079.16193828029;5082.542571168613;5088.6946783193125;5092.668976516515;4952.207646776498;4979;4993.251012348177;5010.398752149766;5017.84946451607 06:49:48 INFO - PROCESS | 4498 | |5;yui.html;670.8658268346326;682.9248782633906;728.2426276672261;737.715979347951;752.2740555364072;13114.60656180315;14593.489227877026;14602.853971460283;14637.048591841523;14650.34073466694;12869.742605147914;12960.675983100404;12995.2202867828;13006.284654344006;13089.149205301648;653.875763604916;665.7403612591094;789.65649942275;793.1196372027046;802.0976401548259;812.2283830361151;815.6737305077957;818.10008990111;819.045811629451;820.9876851847227;854.9700760473385;855.3627547477138;855.7261676263598;855.8373908957309;856.028407757197;684.5003147702205;692.5394612582627;698.5145323999823;699.40899939551;853.8591132463137;618.7277597856929;619.5601123202532;620.0544170140527;629.3140476880177;630.9589876658002;13123;13141.080124391296;13190.802137967938;13217.537386191438;13220.537281195113;11939.88060119405;11943.641690749291;11991.220570662967;12367.567135150226;12594.24434533931;12130.696732581757;12374;12419.565315214015;12518.561850335194;12609.117361784678;4111.362738775495;4124.979375103106;4127.938080928789;4169.666426685858;4342.739435633872;55567.16649250264;62529.68735156341;64852;66052.66973665149;66102.3389766101;10041.246906482043;10094.192464602815;10111.39331640104;10193.541290641791;10213.182945364353;18437.53906152357;18513.90743046276;18540.16569254387;18592.81407185938;18723;6725.327467253235;6770.1537307836525;6822.965885170543;6930.203026651899;7143.535670181422;3403.5064915587595;3542.2561262134723;3545.8758943437374;3551.5383000210322;3588.748787584896;3024.682408347136;3059.7246247837356;3085.0590569875953;3105.7515398768046;3128.9687103128676;503.4361515103108;509.05062059259217;515.4407467897373;518.1062666899568;519.0967716173906;611.2664802237333;612.6109920200615;621.7046902721171;624.7188765055743;629.0815409502087;8773.824523509495;8839.867401988975;8841.778955526162;8878.822423551623;8901.554922253861;613.871087071711;623.1058431151313;627.209715758148;627.8461776864698;630.8643641617074;4257.808398622069;4310.482742070972;4350.956490435118;4367.956320436755;4434.246178149722;66407;66807.33192668011;67629;67865.66067169732;69472.30527694659;50903.74548127236;56468.717656411456;57033.14450283249;57301;59732.402675972684;49383.25925111126;51293.7435312821;51635.22547161795;51832.48167518352;51886.74056629768;8242.917570824215;8409.915900841159;8519.74440766778;8543.871841922251;8668.913310867063;35293.294134117685;35722.8213858929;35943;36549;37360.065998350256;41267.174656507304;41325;42668;45581.54418455773;46422;10611;10913.727156821144;10941.398223097805;10971.945140274247;11024.85015421985;11037.39294338787;11177.161712871404;11289.661310160709;11617.6514704559;12033.398330083635;8924;11677.416129193676;11724.531018759155;11768.94115529417;11771.941140294586;11693;11777.175597708077;12216.877831221575;12851.16467429614;13581.049326547047;11561.42192890369;11647.242929209575;12338.50645974151;12394.690132746753;12497.500099995897;9997.957974111108;10020.112088712975;10349.275550711378;10502.579896804042;10512.211584131068;9651.469169195761;9668.661596844076;9689.12797848197;9744.805103897741;9760.853587196056 06:49:48 INFO - PROCESS | 4498 | __end_tp_report 06:49:48 INFO - PROCESS | 4498 | __start_cc_report 06:49:48 INFO - PROCESS | 4498 | _x_x_mozilla_cycle_collect,1502 06:49:48 INFO - PROCESS | 4498 | __end_cc_report 06:49:48 INFO - PROCESS | 4498 | __startTimestamp1484232588351__endTimestamp 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | ------- Summary: start ------- 06:49:48 INFO - PROCESS | 4498 | Number of tests: 6 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#0] dojo.html Cycles:165 Average:4782.33 Median:4904.53 stddev:517.76 (10.6%) stddev-sans-first:489.77 06:49:48 INFO - PROCESS | 4498 | Values: 2583.4 2605.1 2616.8 2638.1 2643.8 4716.3 4788.3 4803.7 4825.4 4844.4 4848.0 4850.4 4867.1 4872.3 4883.8 4434.9 4440.4 4457.5 4467.6 4485.9 4476.4 4482.8 4484.4 4500.0 4534.2 4482.4 4506.3 4516.0 4520.2 4564.0 4440.1 4518.2 4543.8 4578.0 4591.0 4453.8 4486.0 4500.5 4522.3 4538.6 4717.8 4718.0 4755.4 4755.5 4762.8 5124.3 5140.8 5158.9 5160.7 5167.9 5116.4 5182.8 5189.4 5195.9 5237.7 4731.8 4774.1 4840.0 4843.9 4851.4 5264.9 5306.8 5308.8 5351.9 5399.8 5095.1 5117.2 5123.3 5123.7 5165.1 5068.7 5197.5 5208.1 5210.2 5211.0 4951.1 5048.1 5100.9 5117.6 5210.9 5131.4 5132.9 5138.2 5139.9 5171.2 4238.7 4239.6 4258.1 4264.4 4316.7 3710.5 3726.3 3752.4 3763.1 3775.7 4454.8 4476.7 4478.1 4482.1 4530.5 5053.1 5053.9 5241.1 5243.4 5244.0 4394.8 4416.8 4439.2 4463.8 4477.5 4624.3 4627.5 4629.2 4664.1 4669.2 4835.3 4840.5 4842.0 4860.7 4890.2 5108.1 5142.4 5142.5 5152.1 5178.6 4861.3 4899.7 4904.5 4940.7 4945.1 5123.9 5127.5 5130.4 5139.3 5185.3 5067.5 5082.9 5199.7 5203.9 5212.6 5033.4 5078.6 5084.2 5088.2 5157.6 5084.8 5094.4 5099.2 5101.9 5150.2 4928.6 5027.6 5032.6 5051.7 5061.5 5062.0 5078.4 5124.6 5125.2 5174.6 4990.4 5001.9 5009.4 5026.9 5058.3 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#1] ext.html Cycles:180 Average:43605.05 Median:36257.05 stddev:68630.52 (189.3%) stddev-sans-first:68746.90 06:49:48 INFO - PROCESS | 4498 | Values: 548.3 593.3 596.5 607.7 610.9 29805.7 40445.8 42105.6 42281.0 46186.3 43108.1 43487.8 43501.8 43566.0 43577.0 7743.7 7908.0 7914.8 7919.2 7927.0 7565.1 7779.5 9936.4 10201.0 10203.3 10024.4 10083.0 10083.5 10092.1 10113.7 10197.5 10252.8 10260.9 10270.0 10274.0 9970.0 10043.6 10044.5 10050.8 10057.8 40558.6 40933.0 40939.6 40941.2 40966.4 3772.1 3854.2 3858.2 3865.3 3905.5 28677.9 28914.4 28984.6 29161.6 30092.4 2989.3 3013.0 3030.5 3039.3 3044.9 240183.0 240322.0 241123.0 242002.0 242067.0 55344.2 55456.4 55607.4 55765.0 56015.7 59957.4 59965.0 60018.4 60089.4 60103.4 14073.7 14593.4 14598.4 14600.1 14629.6 11765.4 11865.2 11867.2 11873.5 11882.0 15199.7 15351.5 16883.2 17175.5 17429.4 5912.8 6082.0 6082.7 6106.3 6141.6 827.4 842.6 845.8 847.5 854.8 26693.2 28242.9 28564.0 28793.6 28857.0 935.3 992.3 997.2 998.0 999.7 15249.5 15294.8 15312.5 15334.4 15618.8 320042.0 320386.0 320536.0 320613.0 321665.0 51883.7 52296.0 52466.0 52487.7 52513.7 1208.8 1248.9 1268.0 1271.8 1275.0 25001.0 26542.5 27405.3 27559.3 27776.9 44315.8 44955.3 44955.8 45001.0 45013.8 213938.0 216281.0 216401.0 216868.0 217166.0 33513.2 36179.3 36457.8 36578.3 36659.8 33832.3 43346.3 43942.0 44166.0 48955.5 36334.8 37054.4 37179.3 37382.3 37540.2 36890.0 37281.4 37401.4 37731.4 37776.4 36134.8 36644.5 36836.8 36990.3 37171.4 31510.7 33087.5 33201.7 33234.7 33565.3 24985.6 25178.0 25256.5 25295.9 25556.6 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#2] jquery.html Cycles:180 Average:29157.06 Median:471612.50 stddev:78301.85 (16.6%) stddev-sans-first:78491.70 06:49:48 INFO - PROCESS | 4498 | Values: 379.8 383.6 390.4 392.3 408.4 13376.5 13831.9 16971.5 18485.4 18516.8 15333.8 18561.1 18611.9 18695.7 18817.5 5197.8 5399.2 5404.0 5404.2 5404.3 5084.4 5273.3 5286.8 5287.5 5294.4 5263.5 5268.7 5269.4 5269.9 5275.1 5085.6 5245.8 5268.3 5275.6 5276.3 4757.3 4759.8 4762.7 4763.7 4765.5 21289.6 21775.8 21863.0 21941.7 22035.3 2259.3 2290.2 2314.5 2328.9 2359.3 13295.3 13513.5 13805.2 13838.3 14360.0 1771.7 2055.2 2174.5 2190.3 2197.3 69182.7 89037.0 90440.5 95657.0 96469.0 16049.3 17576.1 17619.9 18375.5 18460.6 32968.5 33310.2 33480.0 33485.3 33926.0 9006.5 9043.1 9109.1 9186.6 9476.1 7314.0 7329.0 7539.3 7544.2 7573.5 9176.8 9240.5 9269.5 9498.4 9577.8 3488.8 3504.0 3748.2 3860.8 3868.0 789.8 804.1 822.4 823.8 830.7 16813.3 16954.6 17485.5 17838.7 18061.6 786.5 799.8 802.9 812.7 824.6 9191.6 9457.4 9829.9 9846.3 9907.4 464910.0 469751.0 473474.0 473831.0 485957.0 89313.6 91884.0 92200.0 94129.5 94155.5 841.4 891.6 903.9 931.1 938.8 15700.3 15814.9 15950.8 16298.1 16538.3 39994.0 40343.6 40965.0 41269.4 41459.0 54825.7 55956.0 56532.7 57863.4 58945.1 6921.6 7270.3 7295.4 7768.5 8091.9 11514.4 12263.6 12820.7 12929.7 13115.8 10270.6 10379.9 10702.7 10725.1 11287.8 10365.3 10450.7 10874.4 10891.3 11187.6 10776.8 10898.3 11265.4 11267.3 11532.8 7644.2 7649.5 7800.1 7810.6 7919.6 9520.8 10973.9 10998.6 11056.8 11425.5 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#3] mootools.html Cycles:180 Average:4857.98 Median:5078.64 stddev:2573.31 (50.7%) stddev-sans-first:2567.17 06:49:48 INFO - PROCESS | 4498 | Values: 1369.5 1370.2 1378.7 1381.0 1388.9 6923.5 7322.1 7326.6 7329.2 7331.3 6336.3 6518.9 6929.9 7151.4 7252.4 2153.5 2199.5 2202.8 2204.6 2206.5 2198.6 2207.2 2238.0 2246.9 2263.2 1924.8 1988.7 2095.6 2182.9 2189.9 2168.8 2178.5 2178.6 2179.2 2179.6 2135.8 2148.5 2178.8 2180.7 2189.7 7310.0 7332.6 7341.6 7347.7 7348.2 4409.0 4549.4 4573.9 4582.8 4586.1 2903.7 3025.1 3091.1 3102.7 3122.9 1106.7 1146.6 1183.5 1186.0 1213.4 9520.6 9576.3 9581.6 9588.0 9597.0 4062.3 4245.6 4343.4 4380.4 4401.5 8417.5 8421.8 8427.8 8437.0 8445.9 4691.6 4694.2 4696.1 4697.7 4699.9 3777.6 3779.3 4197.4 4221.1 4318.2 3571.7 3577.8 3682.8 3684.8 3694.6 1242.8 1245.2 1254.5 1260.2 1263.1 872.2 876.3 877.6 887.1 901.3 4898.2 4984.6 5038.6 5106.1 5145.7 572.7 577.2 579.9 580.8 589.1 3381.8 3615.8 3624.5 3640.6 3648.9 8572.9 8810.9 8890.6 8893.1 8907.5 8829.3 8876.4 8880.3 8886.0 8892.3 6545.9 6870.4 6904.9 6985.1 6994.3 4941.9 4975.5 5051.2 5108.2 5122.4 8073.4 8102.0 8123.8 8262.7 8322.1 8151.1 8328.9 8340.2 8368.5 8377.2 5537.6 5644.0 5795.6 5903.5 5917.7 6831.8 6841.6 7042.0 7043.8 7048.2 6292.8 6365.1 6377.7 6412.9 6523.8 6185.8 6199.5 6249.1 6257.7 6308.1 5779.4 5870.1 5873.2 5873.9 5876.4 4485.2 4498.2 4557.5 4588.5 4619.7 4513.9 4516.8 4663.8 4699.0 4763.6 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#4] prototype.html Cycles:180 Average:4167.36 Median:4743.79 stddev:1188.19 (25.0%) stddev-sans-first:1156.73 06:49:48 INFO - PROCESS | 4498 | Values: 364.9 368.6 369.0 371.9 376.6 4621.8 4761.4 4779.0 4792.4 4827.4 4611.4 4722.9 4765.0 4800.2 4805.9 3755.0 3835.8 3853.5 3937.6 3950.5 3910.8 3914.2 3922.8 3936.1 3939.6 3872.8 3894.4 3894.6 3910.9 3915.0 3894.7 3901.7 3905.6 3908.5 3926.4 3141.6 3254.9 3294.9 3295.9 3304.8 4618.9 4636.1 4638.2 4638.4 4670.6 4293.8 4314.6 4320.4 4326.1 4330.0 4834.4 5030.1 5035.5 5049.9 5055.1 3619.2 3619.8 3623.7 3627.9 3635.8 5248.3 5267.2 5273.1 5289.9 5305.0 3631.9 3722.2 3725.8 3729.2 3729.4 3733.4 3736.9 3738.6 3765.4 3782.6 4222.1 4233.2 4238.4 4244.1 4255.6 4806.0 4907.7 4910.2 5031.9 5040.7 1249.8 1254.3 1254.7 1257.5 1257.7 834.6 858.8 864.0 868.0 875.5 4302.2 4306.5 4308.8 4310.3 4315.7 5009.0 5011.5 5026.0 5026.7 5034.0 4307.5 4317.5 4335.7 4338.4 4339.4 2521.9 2655.4 2662.5 2662.9 2679.8 4737.0 4741.7 4756.6 4759.2 4764.6 4896.8 5138.5 5153.5 5165.2 5182.3 4745.8 4747.9 4775.6 4789.4 4791.0 5064.0 5070.4 5073.9 5097.0 5103.3 4814.4 4954.3 4984.6 5000.9 5007.6 4847.9 4894.3 4896.2 4901.2 4915.8 3668.8 3677.4 3695.6 3698.2 3705.5 4964.2 5045.9 5197.9 5204.5 5205.7 4622.7 5001.2 5017.8 5019.3 5093.9 5021.5 5028.4 5056.7 5080.5 5099.9 4809.9 4939.9 4980.0 4981.6 5036.9 5060.3 5079.2 5082.5 5088.7 5092.7 4952.2 4979.0 4993.3 5010.4 5017.8 06:49:48 INFO - PROCESS | 4498 | 06:49:48 INFO - PROCESS | 4498 | [#5] yui.html Cycles:180 Average:14863.41 Median:25007.90 stddev:18342.74 (73.3%) stddev-sans-first:18363.24 06:49:48 INFO - PROCESS | 4498 | Values: 670.9 682.9 728.2 737.7 752.3 13114.6 14593.5 14602.9 14637.0 14650.3 12869.7 12960.7 12995.2 13006.3 13089.1 653.9 665.7 789.7 793.1 802.1 812.2 815.7 818.1 819.0 821.0 855.0 855.4 855.7 855.8 856.0 684.5 692.5 698.5 699.4 853.9 618.7 619.6 620.1 629.3 631.0 13123.0 13141.1 13190.8 13217.5 13220.5 11939.9 11943.6 11991.2 12367.6 12594.2 12130.7 12374.0 12419.6 12518.6 12609.1 4111.4 4125.0 4127.9 4169.7 4342.7 55567.2 62529.7 64852.0 66052.7 66102.3 10041.2 10094.2 10111.4 10193.5 10213.2 18437.5 18513.9 18540.2 18592.8 18723.0 6725.3 6770.2 6823.0 6930.2 7143.5 3403.5 3542.3 3545.9 3551.5 3588.7 3024.7 3059.7 3085.1 3105.8 3129.0 503.4 509.1 515.4 518.1 519.1 611.3 612.6 621.7 624.7 629.1 8773.8 8839.9 8841.8 8878.8 8901.6 613.9 623.1 627.2 627.8 630.9 4257.8 4310.5 4351.0 4368.0 4434.2 66407.0 66807.3 67629.0 67865.7 69472.3 50903.7 56468.7 57033.1 57301.0 59732.4 49383.3 51293.7 51635.2 51832.5 51886.7 8242.9 8409.9 8519.7 8543.9 8668.9 35293.3 35722.8 35943.0 36549.0 37360.1 41267.2 41325.0 42668.0 45581.5 46422.0 10611.0 10913.7 10941.4 10971.9 11024.9 11037.4 11177.2 11289.7 11617.7 12033.4 8924.0 11677.4 11724.5 11768.9 11771.9 11693.0 11777.2 12216.9 12851.2 13581.0 11561.4 11647.2 12338.5 12394.7 12497.5 9998.0 10020.1 10349.3 10502.6 10512.2 9651.5 9668.7 9689.1 9744.8 9760.9 06:49:48 INFO - PROCESS | 4498 | -------- Summary: end -------- 06:49:48 INFO - PROCESS | 4498 | 06:49:49 INFO - TEST-INFO | 4498: exit 0 06:49:49 INFO - TEST-OK | dromaeo_css | took 1121542ms 06:49:49 INFO - TEST-START | kraken 06:49:49 INFO - operating with platform_type : linux_ 06:49:49 INFO - Initialising browser for kraken test... 06:49:49 INFO - TEST-INFO | started process 4796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpahwLXS/profile http://localhost:34242/getInfo.html) 06:49:52 INFO - PROCESS | 4796 | __metrics Screen width/height:1600/1200 06:49:52 INFO - PROCESS | 4796 | colorDepth:24 06:49:52 INFO - PROCESS | 4796 | Browser inner width/height: 1024/697 06:49:52 INFO - PROCESS | 4796 | __metrics 06:49:53 INFO - PROCESS | 4796 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:49:53 INFO - PROCESS | 4796 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:49:54 INFO - PROCESS | 4796 | console.error: PushService: 06:49:54 INFO - PROCESS | 4796 | stateChangeProcessEnqueue: Error transitioning state 06:49:54 INFO - PROCESS | 4796 | UnknownError 06:49:54 INFO - TEST-INFO | 4796: exit 0 06:49:54 INFO - Browser initialized. 06:49:54 INFO - Running cycle 1/1 for kraken test... 06:49:54 INFO - TEST-INFO | started process 4882 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpahwLXS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:50:06 INFO - PROCESS | 4882 | RSS: Main: 152526848 06:50:06 INFO - PROCESS | 4882 | 06:50:09 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34242/tests/kraken/driver.html?testName=audio-beat-detection) 06:50:10 INFO - PROCESS | 4882 | RSS: Main: 206233600 06:50:10 INFO - PROCESS | 4882 | 06:50:13 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34242/tests/kraken/driver.html?testName=audio-dft) 06:50:13 INFO - PROCESS | 4882 | RSS: Main: 186003456 06:50:13 INFO - PROCESS | 4882 | 06:50:16 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34242/tests/kraken/driver.html?testName=audio-fft) 06:50:17 INFO - PROCESS | 4882 | RSS: Main: 268656640 06:50:17 INFO - PROCESS | 4882 | 06:50:19 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34242/tests/kraken/driver.html?testName=audio-oscillator) 06:50:20 INFO - PROCESS | 4882 | RSS: Main: 179662848 06:50:20 INFO - PROCESS | 4882 | 06:50:22 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:50:23 INFO - PROCESS | 4882 | RSS: Main: 183005184 06:50:23 INFO - PROCESS | 4882 | 06:50:26 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34242/tests/kraken/driver.html?testName=imaging-darkroom) 06:50:26 INFO - PROCESS | 4882 | RSS: Main: 222609408 06:50:26 INFO - PROCESS | 4882 | 06:50:30 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34242/tests/kraken/driver.html?testName=imaging-desaturate) 06:50:30 INFO - PROCESS | 4882 | RSS: Main: 216383488 06:50:30 INFO - PROCESS | 4882 | 06:50:33 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34242/tests/kraken/driver.html?testName=json-parse-financial) 06:50:34 INFO - PROCESS | 4882 | RSS: Main: 218013696 06:50:34 INFO - PROCESS | 4882 | 06:50:36 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:50:36 INFO - PROCESS | 4882 | RSS: Main: 181624832 06:50:36 INFO - PROCESS | 4882 | 06:50:38 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:50:39 INFO - PROCESS | 4882 | RSS: Main: 189079552 06:50:39 INFO - PROCESS | 4882 | 06:50:41 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:50:42 INFO - PROCESS | 4882 | RSS: Main: 197062656 06:50:42 INFO - PROCESS | 4882 | 06:50:44 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:50:45 INFO - PROCESS | 4882 | RSS: Main: 207007744 06:50:45 INFO - PROCESS | 4882 | 06:50:48 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:50:49 INFO - PROCESS | 4882 | RSS: Main: 203657216 06:50:49 INFO - PROCESS | 4882 | 06:50:50 INFO - PROCESS | 4882 | Cycle 1(1): loaded http://localhost:34242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34242/tests/kraken/driver.html?testName=ai-astar) 06:50:51 INFO - PROCESS | 4882 | RSS: Main: 253976576 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | __start_tp_report 06:50:51 INFO - PROCESS | 4882 | _x_x_mozilla_page_load 06:50:51 INFO - PROCESS | 4882 | _x_x_mozilla_page_load_details 06:50:51 INFO - PROCESS | 4882 | |i|pagename|runs| 06:50:51 INFO - PROCESS | 4882 | |0;ai-astar;111;113;114;115;114;112;113;113;115;113 06:50:51 INFO - PROCESS | 4882 | |1;audio-beat-detection;152;125;123;169;131;131;127;124;144;126 06:50:51 INFO - PROCESS | 4882 | |2;audio-dft;161;161;162;160;161;161;161;161;160;160 06:50:51 INFO - PROCESS | 4882 | |3;audio-fft;75;75;77;109;76;77;76;75;92;76 06:50:51 INFO - PROCESS | 4882 | |4;audio-oscillator;85;85;108;86;84;84;103;84;85;112 06:50:51 INFO - PROCESS | 4882 | |5;imaging-gaussian-blur;117;116;117;117;117;117;126;117;118;117 06:50:51 INFO - PROCESS | 4882 | |6;imaging-darkroom;149;149;149;148;150;148;149;148;149;151 06:50:51 INFO - PROCESS | 4882 | |7;imaging-desaturate;96;95;96;95;95;95;95;95;95;95 06:50:51 INFO - PROCESS | 4882 | |8;json-parse-financial;60;59;58;57;60;60;61;60;59;65 06:50:51 INFO - PROCESS | 4882 | |9;json-stringify-tinderbox;66;67;67;67;66;66;68;67;68;67 06:50:51 INFO - PROCESS | 4882 | |10;stanford-crypto-aes;86;84;91;84;96;86;85;84;93;84 06:50:51 INFO - PROCESS | 4882 | |11;stanford-crypto-ccm;160;133;139;135;133;138;129;130;180;130 06:50:51 INFO - PROCESS | 4882 | |12;stanford-crypto-pbkdf2;175;171;170;177;175;172;177;172;170;170 06:50:51 INFO - PROCESS | 4882 | |13;stanford-crypto-sha256-iterative;69;67;68;67;68;68;67;67;67;66 06:50:51 INFO - PROCESS | 4882 | __end_tp_report 06:50:51 INFO - PROCESS | 4882 | __start_cc_report 06:50:51 INFO - PROCESS | 4882 | _x_x_mozilla_cycle_collect,1813 06:50:51 INFO - PROCESS | 4882 | __end_cc_report 06:50:51 INFO - PROCESS | 4882 | __startTimestamp1484232651189__endTimestamp 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | ------- Summary: start ------- 06:50:51 INFO - PROCESS | 4882 | Number of tests: 14 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.50 stddev:1.25 (1.1%) stddev-sans-first:1.01 06:50:51 INFO - PROCESS | 4882 | Values: 111.0 113.0 114.0 115.0 114.0 112.0 113.0 113.0 115.0 113.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:131.00 stddev:15.16 (11.6%) stddev-sans-first:14.81 06:50:51 INFO - PROCESS | 4882 | Values: 152.0 125.0 123.0 169.0 131.0 131.0 127.0 124.0 144.0 126.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 06:50:51 INFO - PROCESS | 4882 | Values: 161.0 161.0 162.0 160.0 161.0 161.0 161.0 161.0 160.0 160.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:11.15 (14.7%) stddev-sans-first:11.63 06:50:51 INFO - PROCESS | 4882 | Values: 75.0 75.0 77.0 109.0 76.0 77.0 76.0 75.0 92.0 76.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:84.50 stddev:11.31 (13.4%) stddev-sans-first:11.74 06:50:51 INFO - PROCESS | 4882 | Values: 85.0 85.0 108.0 86.0 84.0 84.0 103.0 84.0 85.0 112.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:117.00 stddev:2.88 (2.5%) stddev-sans-first:3.04 06:50:51 INFO - PROCESS | 4882 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 126.0 117.0 118.0 117.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 06:50:51 INFO - PROCESS | 4882 | Values: 149.0 149.0 149.0 148.0 150.0 148.0 149.0 148.0 149.0 151.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 06:50:51 INFO - PROCESS | 4882 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:60.00 stddev:2.13 (3.6%) stddev-sans-first:2.26 06:50:51 INFO - PROCESS | 4882 | Values: 60.0 59.0 58.0 57.0 60.0 60.0 61.0 60.0 59.0 65.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 06:50:51 INFO - PROCESS | 4882 | Values: 66.0 67.0 67.0 67.0 66.0 66.0 68.0 67.0 68.0 67.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.00 stddev:4.40 (5.1%) stddev-sans-first:4.64 06:50:51 INFO - PROCESS | 4882 | Values: 86.0 84.0 91.0 84.0 96.0 86.0 85.0 84.0 93.0 84.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:136.50 stddev:16.48 (12.1%) stddev-sans-first:15.93 06:50:51 INFO - PROCESS | 4882 | Values: 160.0 133.0 139.0 135.0 133.0 138.0 129.0 130.0 180.0 130.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.50 stddev:2.85 (1.6%) stddev-sans-first:2.92 06:50:51 INFO - PROCESS | 4882 | Values: 175.0 171.0 170.0 177.0 175.0 172.0 177.0 172.0 170.0 170.0 06:50:51 INFO - PROCESS | 4882 | 06:50:51 INFO - PROCESS | 4882 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.67 06:50:51 INFO - PROCESS | 4882 | Values: 69.0 67.0 68.0 67.0 68.0 68.0 67.0 67.0 67.0 66.0 06:50:51 INFO - PROCESS | 4882 | -------- Summary: end -------- 06:50:51 INFO - PROCESS | 4882 | 06:50:52 INFO - TEST-INFO | 4882: exit 0 06:50:52 INFO - TEST-OK | kraken | took 63142ms 06:50:52 INFO - SUITE-END | took 1184s 06:50:52 INFO - Completed test suite (00:19:45) 06:50:52 INFO - javascript benchmark 06:50:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2583.3799888026874, 2605.088219123306, 2616.764491195792, 2638.063487461951, 2643.814932954694, 4716.292556116584, 4788.257820037891, 4803.663743537953, 4825.445073816517, 4844.394450693663, 4847.975760121194, 4850.4179498460235, 4867.075255701423, 4872.317875497424, 4883.829065982692, 4434.889127771808, 4440.444944381952, 4457.509673935864, 4467.5755803198645, 4485.932711009337, 4476.440444944382, 4482.798274077658, 4484.439445069366, 4499.955000450002, 4534.206513860077, 4482.417285752847, 4506.346579745935, 4516.029053753442, 4520.163769702599, 4564.0, 4440.134173836103, 4518.2319005769095, 4543.750093744843, 4578.0, 4591.035882464686, 4453.821847126111, 4485.9551404485865, 4500.48244451889, 4522.29904364824, 4538.591526762574, 4717.764111794431, 4718.0, 4755.405574303212, 4755.524447555217, 4762.78567464465, 5124.333836601218, 5140.768665410028, 5158.871028224287, 5160.716160611165, 5167.948320516784, 5116.411612664554, 5182.766775495112, 5189.3772747270195, 5195.896082078375, 5237.659552129099, 4731.7634118293945, 4774.069056533943, 4839.951600483985, 4843.878903027453, 4851.393575803025, 5264.868378290496, 5306.8407947761625, 5308.761105750251, 5351.919721204185, 5399.757010934517, 5095.0828850806465, 5117.2068329409, 5123.257127716461, 5123.7438128093445, 5165.096108181128, 5068.746562671851, 5197.480251974772, 5208.088584497697, 5210.192420174879, 5210.97394513025, 4951.10880041596, 5048.1418158913075, 5100.923486147711, 5117.641765076409, 5210.895782084337, 5131.384233891882, 5132.871678208075, 5138.177891537411, 5139.948600514022, 5171.224316352577, 4238.703290769617, 4239.639630631369, 4258.127083947783, 4264.360345948133, 4316.69783115179, 3710.5176327077306, 3726.2920045191327, 3752.3620984432714, 3763.1344790697744, 3775.6979441644603, 4454.799534020977, 4476.731396116244, 4478.126765280739, 4482.148391805546, 4530.501644819067, 5053.1157047516535, 5053.9241911371355, 5241.082810508222, 5243.397009343974, 5244.003639308519, 4394.780260986938, 4416.84541041062, 4439.223135951195, 4463.754493502824, 4477.507474177838, 4624.306354046853, 4627.537246275413, 4629.213033784197, 4664.137134630037, 4669.229577119805, 4835.2505361669, 4840.515948405131, 4841.975790121097, 4860.684055536379, 4890.168671325812, 5108.131617625087, 5142.357205349332, 5142.51146141116, 5152.072626927189, 5178.6374953752875, 4861.295112208781, 4899.681520701143, 4904.5340692634145, 4940.7035577865445, 4945.1346014448045, 5123.871903202301, 5127.53852153308, 5130.384353877558, 5139.254808052886, 5185.27406163145, 5067.493250674903, 5082.949170508396, 5199.688018718889, 5203.921941170961, 5212.635115542027, 5033.446320904771, 5078.644494885322, 5084.186530155157, 5088.211327244283, 5157.561607263275, 5084.796608135781, 5094.414142373675, 5099.23511473282, 5101.8724531887, 5150.201718733602, 4928.630352723489, 5027.572656324253, 5032.62255330859, 5051.74741262928, 5061.493850614909, 5062.038212739568, 5078.365204349457, 5124.564412024871, 5125.205593133071, 5174.586033117266, 4990.4011518618, 5001.924971125363, 5009.423916249678, 5026.924596130988, 5058.31712718793], "value": 4747.459480925153, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.3063924135968, 593.2554643921878, 596.5466245653303, 607.696151924038, 610.8900397928373, 29805.701942980562, 40445.79777101114, 42105.578944210545, 42281.0, 46186.307205391946, 43108.13783724332, 43487.78256108723, 43501.7824910875, 43566.0, 43577.0, 7743.690252389898, 7907.96046019772, 7914.762557123267, 7919.247671471201, 7927.0, 7565.130010048832, 7779.533228006338, 9936.403815771077, 10201.0, 10203.336783109075, 10024.398536087858, 10083.0, 10083.495825208747, 10092.142167915736, 10113.696589102301, 10197.541110650038, 10252.846207306897, 10260.948695256477, 10270.024347686995, 10274.0, 9970.0, 10043.648472303434, 10044.497775111215, 10050.849237261447, 10057.798844023078, 40558.594414056075, 40933.0, 40939.59060409388, 40941.1811763763, 40966.38550421716, 3772.1324095457926, 3854.2484215577974, 3858.247641709868, 3865.2849222893856, 3905.49228600283, 28677.85661071681, 28914.42171156565, 28984.565231521545, 29161.56257656137, 30092.398152036836, 2989.2975150839534, 3012.9548056779167, 3030.4696678081245, 3039.285767844555, 3044.862981165853, 240183.0, 240322.0, 241123.0, 242002.0, 242067.0, 55344.16983745247, 55456.44543554513, 55607.44392556023, 55765.0, 56015.719921400945, 59957.40042599518, 59965.0, 60018.39981600128, 60089.39910600925, 60103.39896601065, 14073.718525629634, 14593.416263349347, 14598.41606335756, 14600.12399256048, 14629.561113166623, 11765.352905590104, 11865.228760130563, 11867.228630139014, 11873.465694043789, 11882.0, 15199.696006079817, 15351.462698805488, 16883.155842208085, 17175.484735457958, 17429.389971351196, 5912.793052243151, 6082.0, 6082.726277317532, 6106.297775755845, 6141.570090093651, 827.4249396669377, 842.5744998775579, 845.7927807687033, 847.4576271186446, 854.833307505043, 26693.19920402391, 28242.85878570594, 28564.0, 28793.568096478568, 28857.0, 935.2798345274101, 992.3103443107116, 997.2321312589265, 997.9650712225183, 999.695092996643, 15249.542513724606, 15294.77057844111, 15312.487504498371, 15334.386624534895, 15618.84381156174, 320042.0, 320386.0, 320536.0, 320613.0, 321665.0, 51883.74058129685, 52296.0, 52466.0, 52487.737561312715, 52513.73743131336, 1208.7824191645589, 1248.8563815161372, 1268.0489632775418, 1271.7647235261506, 1275.043717212091, 25001.0, 26542.469150616493, 27405.31486712848, 27559.31101722393, 27776.861115694293, 44315.778421108975, 44955.32567011563, 44955.77522112369, 45001.0, 45013.77493112513, 213938.0, 216281.0, 216401.0, 216868.0, 217166.0, 33513.16217094592, 36179.27641447104, 36457.817710912335, 36578.26843463063, 36659.81670091739, 33832.323353533284, 43346.349804752324, 43942.0, 44166.0, 48955.510444895095, 36334.81832590926, 37054.44418333781, 37179.25641487209, 37382.25235495329, 37540.2491950154, 36890.0, 37281.44077838889, 37401.43897841589, 37731.43402849014, 37776.43335349917, 36134.819325903205, 36644.45033324449, 36836.81581592075, 36990.260194796494, 37171.44242836306, 31510.68489315169, 33087.50368744422, 33201.66798331986, 33234.66765332316, 33565.32869342648, 24985.625215621418, 25178.0, 25256.484610923402, 25295.873520632278, 25556.616650750624], "value": 18103.783707254308, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.8366702318004, 383.62213219978327, 390.43103196471526, 392.3017029687157, 408.38130232697466, 13376.531821386254, 13831.930840345784, 16971.49085527433, 18485.445436636906, 18516.814831851676, 15333.846661533382, 18561.07194640266, 18611.906940465313, 18695.71956420655, 18817.529561760963, 5197.818076367328, 5399.217113518537, 5404.0, 5404.189371594253, 5404.270423492818, 5084.3644544431945, 5273.34083239595, 5286.788528458857, 5287.47125287472, 5294.391145018334, 5263.473652634744, 5268.657537260085, 5269.446708095652, 5269.8946021079555, 5275.103232450492, 5085.644004919657, 5245.842624721265, 5268.262443257947, 5275.60432967529, 5276.314079169683, 4757.26262429324, 4759.785809638574, 4762.690425122356, 4763.714177149347, 4765.499622539618, 21289.574208515896, 21775.782242177534, 21863.0, 21941.670874936888, 22035.338939831672, 2259.3109101723967, 2290.1640901071155, 2314.5139520700673, 2328.8951997159998, 2359.2686267257204, 13295.268760218185, 13513.527026554024, 13805.171689698549, 13838.30808459573, 14360.0, 1771.7165253559497, 2055.2190167736167, 2174.4765702891327, 2190.266260802633, 2197.2529340024466, 69182.65408672974, 89037.0, 90440.54779726059, 95656.9999999993, 96469.0, 16049.277782499816, 17576.09825741324, 17619.91190044067, 18375.54061148482, 18460.630787384176, 32968.505472417935, 33310.16724581905, 33480.0, 33485.33029339399, 33926.0, 9006.50464224474, 9043.140901614335, 9109.134632209929, 9186.63253469867, 9476.052394760607, 7313.963430182815, 7329.010583571257, 7539.283768042027, 7544.170141284454, 7573.46985711006, 9176.816463670822, 9240.491772952551, 9269.49723574631, 9498.430094194371, 9577.808443831085, 3488.7906725596545, 3503.964960350415, 3748.231612519427, 3860.82626281818, 3867.961320386816, 789.7946533901227, 804.1435870797699, 822.42841225349, 823.7569916874536, 830.7466222802103, 16813.32746690143, 16954.576135596708, 17485.47543573695, 17838.732419013726, 18061.638767224584, 786.5438045933345, 799.8440304140637, 802.9277365037175, 812.6627449608465, 824.5547404401676, 9191.586378612978, 9457.43255404678, 9829.91870894201, 9846.310758246997, 9907.356021858555, 464910.0, 469751.0, 473474.0, 473831.0, 485957.0, 89313.55343223242, 91884.0, 92200.0, 94129.5293523528, 94155.52922235345, 841.4320333774677, 891.6344298837446, 903.9141281578239, 931.1154403316741, 938.7891515481081, 15700.293486792893, 15814.9209253953, 15950.8404915954, 16298.066348057464, 16538.338466461206, 39994.0, 40343.596564033985, 40965.0, 41269.38095928504, 41459.0, 54825.725871370385, 55956.0, 56532.71733641306, 57863.421365785805, 58945.115823261825, 6921.6193109379465, 7270.345668889825, 7295.416366690546, 7768.53388796674, 8091.87862182056, 11514.42427878586, 12263.632091037283, 12820.679483013, 12929.741405172032, 13115.803262950873, 10270.6405275815, 10379.948100259451, 10702.73243168927, 10725.088367488539, 11287.782981850462, 10365.326253793479, 10450.738731531772, 10874.401907894826, 10891.31184666378, 11187.608433705107, 10776.784464310827, 10898.291611045257, 11265.436728163395, 11267.323960562393, 11532.827007594726, 7644.15914249421, 7649.464537482323, 7800.102988156208, 7810.6485208166905, 7919.643616037408, 9520.761980950532, 10973.890261097287, 10998.560057597606, 11056.834147487954, 11425.485853136463], "value": 9431.635287120545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.4590636698504, 1370.2326697049652, 1378.7104708011318, 1380.986190138099, 1388.8819450346723, 6923.549969252002, 7322.0847394075745, 7326.597037162954, 7329.230430804769, 7331.340179383855, 6336.271328797178, 6518.934810651892, 6929.896051559231, 7151.356377925986, 7252.3835473984645, 2153.4616345913482, 2199.5051113499494, 2202.790734880192, 2204.581129585382, 2206.5476577301697, 2198.6482162853945, 2207.227470385368, 2238.03764381316, 2246.9101235950543, 2263.2418139923147, 1924.7978962208977, 1988.741463609736, 2095.559932414195, 2182.9235976740815, 2189.8905054747197, 2168.7831216878267, 2178.5098352870637, 2178.6405243134864, 2179.2263746369968, 2179.56408718257, 2135.765065842756, 2148.4803188839064, 2178.8039076483033, 2180.694702741617, 2189.7372315322104, 7310.049693539859, 7332.633368331562, 7341.559506429631, 7347.669354879044, 7348.154962179365, 4409.0, 4549.431321084864, 4573.931391029138, 4582.839600613963, 4586.059857729158, 2903.6951120132294, 3025.077351407842, 3091.119031076155, 3102.6742192069732, 3122.9219269518217, 1106.6569363497342, 1146.5757669662278, 1183.5325046606542, 1185.9703507412298, 1213.4175595714019, 9520.571574279174, 9576.329656924017, 9581.61673533058, 9588.0, 9597.040295970348, 4062.2687916175373, 4245.61789438952, 4343.4136391586735, 4380.408644832971, 4401.471823381215, 8417.494950302878, 8421.831563368698, 8427.789305267295, 8437.029741579798, 8445.873311900326, 4691.577758001728, 4694.201985662446, 4696.131215725101, 4697.671163018625, 4699.942512934631, 3777.6222377762006, 3779.338615742234, 4197.433346498245, 4221.134667393177, 4318.201132790444, 3571.74997750155, 3577.821108944542, 3682.797446140434, 3684.778913265159, 3694.612065733113, 1242.7763002659428, 1245.1532957588927, 1254.4793910527212, 1260.1871792693662, 1263.1284413754663, 872.2106493623281, 876.3120950054176, 877.6489404238356, 887.1394747095308, 901.2879824938225, 4898.167311557044, 4984.6261530384645, 5038.622103342264, 5106.106431374569, 5145.716985565829, 572.747990884018, 577.1573502686125, 579.9043157878903, 580.7996241296746, 589.0516268807216, 3381.7970921744773, 3615.837287322077, 3624.5288112545727, 3640.581333146669, 3648.927021459556, 8572.914270857336, 8810.911890881009, 8890.59992300335, 8893.110688931056, 8907.51008694515, 8829.33779966505, 8876.423032502867, 8880.289576833839, 8885.955570222108, 8892.288616910631, 6545.854475466775, 6870.381665650116, 6904.930950690429, 6985.126859142607, 6994.33553812387, 4941.876453088702, 4975.452700202903, 5051.166557517969, 5108.208227724708, 5122.436531981404, 8073.35413166957, 8101.959490202511, 8123.75628731139, 8262.710805121791, 8322.126176751595, 8151.103378628465, 8328.916710832815, 8340.249377556049, 8368.497890126611, 8377.246047855722, 5537.5569954402745, 5643.971780141073, 5795.623284486664, 5903.468687818117, 5917.733701983507, 6831.795046148623, 6841.555298905555, 7041.964790176016, 7043.823904402226, 7048.154221493453, 6292.842678933063, 6365.140706004815, 6377.681115944277, 6412.903806442814, 6523.804285871432, 6185.8144255672405, 6199.473044791062, 6249.0626406039455, 6257.7184026718, 6308.08532762756, 5779.422057794187, 5870.0607902735355, 5873.207117039145, 5873.882522349614, 5876.382979787232, 4485.215087359634, 4498.167838949805, 4557.475890272661, 4588.541145885385, 4619.676622636383, 4513.887152821075, 4516.774161291988, 4663.790129444251, 4698.976505117453, 4763.642726795435], "value": 3979.430415524305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.93066317399695, 368.62216228365924, 368.95756987946396, 371.89401020709096, 376.64595280436373, 4621.768911554417, 4761.357216775734, 4778.989236780832, 4792.442456201736, 4827.396575428072, 4611.446626404828, 4722.929156062662, 4764.97617511912, 4800.183968725316, 4805.90388192236, 3754.962450375502, 3835.8082095895093, 3853.537575490945, 3937.566867644571, 3950.486436763224, 3910.8240129194114, 3914.158455931974, 3922.7646341219474, 3936.0553467167815, 3939.606039396052, 3872.7676339419586, 3894.357431023878, 3894.6494815466604, 3910.9413358799643, 3914.9821046527823, 3894.7273690841653, 3901.653929394383, 3905.5703872573995, 3908.456159816885, 3926.3914093315298, 3141.607299087614, 3254.886078987224, 3294.8682052717863, 3295.917602059944, 3304.801711897294, 4618.9076218475775, 4636.142313671981, 4638.240247053031, 4638.420197475316, 4670.5754744802825, 4293.806778694966, 4314.633256173198, 4320.351947207912, 4326.134773045404, 4330.033926760179, 4834.371531700892, 5030.119729047402, 5035.496450354972, 5049.899002019939, 5055.140626093536, 3619.1675914539805, 3619.819009049537, 3623.6738693517445, 3627.8548858045647, 3635.818209089535, 5248.343957005374, 5267.236250743621, 5273.050850846846, 5289.920651190235, 5305.0, 3631.94552081719, 3722.199727058641, 3725.832337544817, 3729.1609387887506, 3729.384651532521, 3733.4026555750947, 3736.850525978985, 3738.5887552369218, 3765.4163604641326, 3782.6217378261954, 4222.134462435193, 4233.153369326147, 4238.4490016298305, 4244.0663054128045, 4255.553111941955, 4805.9759701201965, 4907.68100073494, 4910.165271903785, 5031.899362012739, 5040.697558146523, 1249.8312727781815, 1254.2913254011455, 1254.7352029586684, 1257.459292504232, 1257.698152443407, 834.5952213176605, 858.8067684771001, 864.0, 867.9627844725619, 875.4528419737663, 4302.161078589645, 4306.533240741171, 4308.827646894089, 4310.288802347577, 4315.65536709053, 5008.994603238028, 5011.523905228997, 5025.969676216367, 5026.723530205801, 5034.029922345833, 4307.547707490731, 4317.481902171759, 4335.6965012449455, 4338.392625032498, 4339.435873336446, 2521.9369515761887, 2655.4423571050297, 2662.4808162408144, 2662.9467410651678, 2679.7990150738383, 4737.028909073631, 4741.739204343725, 4756.6432517561325, 4759.214729569651, 4764.6426518011285, 4896.804127834918, 5138.4604616514725, 5153.458886816905, 5165.199394093921, 5182.274481572583, 4745.83389581363, 4747.928781068286, 4775.585456398691, 4789.449213340441, 4790.976045119752, 5063.974140558339, 5070.41690205631, 5073.868975030422, 5096.980603879313, 5103.285540024325, 4814.398200224972, 4954.281629163679, 4984.551390374883, 5000.899982000413, 5007.57435617962, 4847.878803029953, 4894.314795928502, 4896.2410826321975, 4901.166801643801, 4915.778789954389, 3668.779873207616, 3677.4116141417244, 3695.5934847167327, 3698.204885949534, 3705.5368078990127, 4964.230544265644, 5045.905949031111, 5197.92203116946, 5204.453532379197, 5205.687658740488, 4622.682535477373, 5001.224810154432, 5017.774200161075, 5019.297298378157, 5093.92359114621, 5021.472745361684, 5028.396592408934, 5056.6713163644245, 5080.517350851663, 5099.92350114756, 4809.927851082307, 4939.851804445872, 4979.975100124621, 4981.6014718823135, 5036.899262014813, 5060.266261392151, 5079.16193828029, 5082.542571168613, 5088.6946783193125, 5092.668976516515, 4952.207646776498, 4979.0, 4993.251012348177, 5010.398752149766, 5017.84946451607], "value": 3806.6305992067264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.8658268346326, 682.9248782633906, 728.2426276672261, 737.715979347951, 752.2740555364072, 13114.60656180315, 14593.489227877026, 14602.853971460283, 14637.048591841523, 14650.34073466694, 12869.742605147914, 12960.675983100404, 12995.2202867828, 13006.284654344006, 13089.149205301648, 653.875763604916, 665.7403612591094, 789.65649942275, 793.1196372027046, 802.0976401548259, 812.2283830361151, 815.6737305077957, 818.10008990111, 819.045811629451, 820.9876851847227, 854.9700760473385, 855.3627547477138, 855.7261676263598, 855.8373908957309, 856.028407757197, 684.5003147702205, 692.5394612582627, 698.5145323999823, 699.40899939551, 853.8591132463137, 618.7277597856929, 619.5601123202532, 620.0544170140527, 629.3140476880177, 630.9589876658002, 13123.0, 13141.080124391296, 13190.802137967938, 13217.537386191438, 13220.537281195113, 11939.88060119405, 11943.641690749291, 11991.220570662967, 12367.567135150226, 12594.24434533931, 12130.696732581757, 12374.0, 12419.565315214015, 12518.561850335194, 12609.117361784678, 4111.362738775495, 4124.979375103106, 4127.938080928789, 4169.666426685858, 4342.739435633872, 55567.16649250264, 62529.68735156341, 64852.0, 66052.66973665149, 66102.3389766101, 10041.246906482043, 10094.192464602815, 10111.39331640104, 10193.541290641791, 10213.182945364353, 18437.53906152357, 18513.90743046276, 18540.16569254387, 18592.81407185938, 18723.0, 6725.327467253235, 6770.1537307836525, 6822.965885170543, 6930.203026651899, 7143.535670181422, 3403.5064915587595, 3542.2561262134723, 3545.8758943437374, 3551.5383000210322, 3588.748787584896, 3024.682408347136, 3059.7246247837356, 3085.0590569875953, 3105.7515398768046, 3128.9687103128676, 503.4361515103108, 509.05062059259217, 515.4407467897373, 518.1062666899568, 519.0967716173906, 611.2664802237333, 612.6109920200615, 621.7046902721171, 624.7188765055743, 629.0815409502087, 8773.824523509495, 8839.867401988975, 8841.778955526162, 8878.822423551623, 8901.554922253861, 613.871087071711, 623.1058431151313, 627.209715758148, 627.8461776864698, 630.8643641617074, 4257.808398622069, 4310.482742070972, 4350.956490435118, 4367.956320436755, 4434.246178149722, 66407.0, 66807.33192668011, 67629.0, 67865.66067169732, 69472.30527694659, 50903.74548127236, 56468.717656411456, 57033.14450283249, 57301.0, 59732.402675972684, 49383.25925111126, 51293.7435312821, 51635.22547161795, 51832.48167518352, 51886.74056629768, 8242.917570824215, 8409.915900841159, 8519.74440766778, 8543.871841922251, 8668.913310867063, 35293.294134117685, 35722.8213858929, 35943.0, 36549.0, 37360.065998350256, 41267.174656507304, 41325.0, 42668.0, 45581.54418455773, 46422.0, 10611.0, 10913.727156821144, 10941.398223097805, 10971.945140274247, 11024.85015421985, 11037.39294338787, 11177.161712871404, 11289.661310160709, 11617.6514704559, 12033.398330083635, 8924.0, 11677.416129193676, 11724.531018759155, 11768.94115529417, 11771.941140294586, 11693.0, 11777.175597708077, 12216.877831221575, 12851.16467429614, 13581.049326547047, 11561.42192890369, 11647.242929209575, 12338.50645974151, 12394.690132746753, 12497.500099995897, 9997.957974111108, 10020.112088712975, 10349.275550711378, 10502.579896804042, 10512.211584131068, 9651.469169195761, 9668.661596844076, 9689.12797848197, 9744.805103897741, 9760.853587196056], "value": 6382.95742304418, "unit": "score"}], "name": "dromaeo_css", "value": 6541.980978287483, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 115.0, 114.0, 112.0, 113.0, 113.0, 115.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 123.0, 169.0, 131.0, 131.0, 127.0, 124.0, 144.0, 126.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 109.0, 76.0, 77.0, 76.0, 75.0, 92.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 108.0, 86.0, 84.0, 84.0, 103.0, 84.0, 85.0, 112.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 126.0, 117.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 148.0, 150.0, 148.0, 149.0, 148.0, 149.0, 151.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 57.0, 60.0, 60.0, 61.0, 60.0, 59.0, 65.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 68.0, 67.0, 68.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 91.0, 84.0, 96.0, 86.0, 85.0, 84.0, 93.0, 84.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 133.0, 139.0, 135.0, 133.0, 138.0, 129.0, 130.0, 180.0, 130.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 170.0, 177.0, 175.0, 172.0, 177.0, 172.0, 170.0, 170.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1538.9000000000003, "alertThreshold": 2.0}]} 06:50:52 INFO - Return code: 0 06:50:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:50:53 INFO - # TBPL SUCCESS # 06:50:53 INFO - Running post-action listener: _resource_record_post_action 06:50:53 INFO - [mozharness: 2017-01-12 14:50:53.001700Z] Finished run-tests step (success) 06:50:53 INFO - Running post-run listener: _resource_record_post_run 06:50:53 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 382984192; Read time: 20; Write time: 293760 06:50:53 INFO - TinderboxPrint: CPU usage
13.1% 06:50:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 20 06:50:53 INFO - TinderboxPrint: I/O write bytes / time
382,984,192 / 293,760 06:50:53 INFO - TinderboxPrint: CPU idle
8,329.1 (86.9%) 06:50:53 INFO - TinderboxPrint: CPU user
1,211.7 (12.6%) 06:50:53 INFO - TinderboxPrint: Swap in / out
0 / 0 06:50:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100274176; Read time: 0; Write time: 92236 06:50:53 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 282710016; Read time: 20; Write time: 201524 06:50:53 INFO - Running post-run listener: _upload_blobber_files 06:50:53 INFO - Blob upload gear active. 06:50:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:50:53 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 06:50:53 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'] 06:50:53 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 06:50:54 INFO - (blobuploader) - INFO - Open directory for files ... 06:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:50:57 INFO - (blobuploader) - INFO - Done attempting. 06:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:50:58 INFO - (blobuploader) - INFO - Done attempting. 06:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:50:58 INFO - (blobuploader) - INFO - Done attempting. 06:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:50:59 INFO - (blobuploader) - INFO - Done attempting. 06:50:59 INFO - (blobuploader) - INFO - Iteration through files over. 06:50:59 INFO - Return code: 0 06:50:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:50:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:50:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6e0e10f5d7b5b23ff4670b3a760f9172f649fc8e069ee67966d9f13b27af51236f88c0e44c4479fcb0cfef53ec0c719f585224f0390372feba45cee00d692b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1459d7fffa22d66e4a9b4de93c18e179a10a8537aac2032064f2cbc23689d99c9b29008f1fd32506ccdcbb789bbbdfc5ab64232a6abe4b9575afa454557802e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff7d93a04b980a0186fd7ea39a95735e8a95e28cb3ca00c200057fed0cfb4473bb2681da09a330c376059cc76e5952de8e04a77b6532bfb2a6a856b6295c7b0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c17b2e805eb08033f6d353425b4bc1a55f72bc717387d00753e8446c19e6c65ae53d12c1f20f236608850769571266f3a183d5be70f61163b42bdba50675d9"} 06:50:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:50:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:50:59 INFO - Contents: 06:50:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6e0e10f5d7b5b23ff4670b3a760f9172f649fc8e069ee67966d9f13b27af51236f88c0e44c4479fcb0cfef53ec0c719f585224f0390372feba45cee00d692b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1459d7fffa22d66e4a9b4de93c18e179a10a8537aac2032064f2cbc23689d99c9b29008f1fd32506ccdcbb789bbbdfc5ab64232a6abe4b9575afa454557802e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff7d93a04b980a0186fd7ea39a95735e8a95e28cb3ca00c200057fed0cfb4473bb2681da09a330c376059cc76e5952de8e04a77b6532bfb2a6a856b6295c7b0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c17b2e805eb08033f6d353425b4bc1a55f72bc717387d00753e8446c19e6c65ae53d12c1f20f236608850769571266f3a183d5be70f61163b42bdba50675d9"} 06:50:59 INFO - Running post-run listener: copy_logs_to_upload_dir 06:50:59 INFO - Copying logs to upload dir... 06:50:59 INFO - mkdir: /builds/slave/test/build/upload/logs 06:50:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.486275 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-12 06:50:59.651051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 06:50:59.655133) ========= 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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6e0e10f5d7b5b23ff4670b3a760f9172f649fc8e069ee67966d9f13b27af51236f88c0e44c4479fcb0cfef53ec0c719f585224f0390372feba45cee00d692b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1459d7fffa22d66e4a9b4de93c18e179a10a8537aac2032064f2cbc23689d99c9b29008f1fd32506ccdcbb789bbbdfc5ab64232a6abe4b9575afa454557802e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff7d93a04b980a0186fd7ea39a95735e8a95e28cb3ca00c200057fed0cfb4473bb2681da09a330c376059cc76e5952de8e04a77b6532bfb2a6a856b6295c7b0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c17b2e805eb08033f6d353425b4bc1a55f72bc717387d00753e8446c19e6c65ae53d12c1f20f236608850769571266f3a183d5be70f61163b42bdba50675d9"} build_url:https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/VpFsK0SmRE-QT0T59AqsWQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6e0e10f5d7b5b23ff4670b3a760f9172f649fc8e069ee67966d9f13b27af51236f88c0e44c4479fcb0cfef53ec0c719f585224f0390372feba45cee00d692b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1459d7fffa22d66e4a9b4de93c18e179a10a8537aac2032064f2cbc23689d99c9b29008f1fd32506ccdcbb789bbbdfc5ab64232a6abe4b9575afa454557802e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff7d93a04b980a0186fd7ea39a95735e8a95e28cb3ca00c200057fed0cfb4473bb2681da09a330c376059cc76e5952de8e04a77b6532bfb2a6a856b6295c7b0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c17b2e805eb08033f6d353425b4bc1a55f72bc717387d00753e8446c19e6c65ae53d12c1f20f236608850769571266f3a183d5be70f61163b42bdba50675d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 06:50:59.681533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:50:59.681854) ========= 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=32c5991c409366f7fec8a718000001d2-1484230529.120532-1393168895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 06:50:59.764417) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 06:50:59.764737) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 06:50:59.765019) ========= ========= Total master_lag: 0.17 =========