builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1484048589.59 results: success (0) revision: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.587764) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.588225) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.588532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.602951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.603307) ========= 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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-10 03:43:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2017-01-10 03:43:09 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146708 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.763885) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.764231) ========= 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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016346 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:09.814208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 03:43:09.814544) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 --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 acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 --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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-10 03:43:09,906 truncating revision to first 12 chars 2017-01-10 03:43:09,906 Setting DEBUG logging. 2017-01-10 03:43:09,906 attempt 1/10 2017-01-10 03:43:09,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/acd4177b28d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 03:43:11,167 unpacking tar archive at: mozilla-central-acd4177b28d7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.543028 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 03:43:11.370734) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:11.371066) ========= script_repo_revision: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:11.371437) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:11.371698) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 03:43:11.381424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-10 03:43:11.381756) ========= /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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:43:11 INFO - MultiFileLogger online at 20170110 03:43:11 in /builds/slave/test 03:43: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 03:43:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:11 INFO - {'append_to_log': False, 03:43:11 INFO - 'base_work_dir': '/builds/slave/test', 03:43:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:43:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:11 INFO - 'branch': 'Firefox-Non-PGO', 03:43:11 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:11 INFO - 'config_files': ('talos/linux_config.py',), 03:43:11 INFO - 'default_actions': ('clobber', 03:43:11 INFO - 'read-buildbot-config', 03:43:11 INFO - 'download-and-extract', 03:43:11 INFO - 'populate-webroot', 03:43:11 INFO - 'create-virtualenv', 03:43:11 INFO - 'install', 03:43:11 INFO - 'run-tests'), 03:43:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:11 INFO - 'download_minidump_stackwalk': True, 03:43:11 INFO - 'download_symbols': 'ondemand', 03:43:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:43:11 INFO - 'tooltool.py': '/tools/tooltool.py', 03:43:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:43:11 INFO - '/tools/misc-python/virtualenv.py')}, 03:43:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:11 INFO - 'installer_path': 'installer.exe', 03:43:11 INFO - 'log_level': 'info', 03:43:11 INFO - 'log_name': 'talos', 03:43:11 INFO - 'log_to_console': True, 03:43:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:43:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:43:11 INFO - 'opt_config_files': (), 03:43:11 INFO - 'pip_index': False, 03:43:11 INFO - 'sps_profile': False, 03:43:11 INFO - 'sps_profile_interval': 0, 03:43:11 INFO - 'suite': 'dromaeojs', 03:43:11 INFO - 'system_bits': '32', 03:43:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:11 INFO - 'title': 'talos-linux64-ix-015', 03:43:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:11 INFO - 'use_talos_json': True, 03:43:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:11 INFO - 'work_dir': 'build'} 03:43:11 INFO - [mozharness: 2017-01-10 11:43:11.577488Z] Running clobber step. 03:43:11 INFO - Running pre-action listener: _resource_record_pre_action 03:43:11 INFO - Running main action method: clobber 03:43:11 INFO - rmtree: /builds/slave/test/build 03:43:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:12 INFO - Running post-action listener: _resource_record_post_action 03:43:12 INFO - [mozharness: 2017-01-10 11:43:12.591171Z] Finished clobber step (success) 03:43:12 INFO - [mozharness: 2017-01-10 11:43:12.591271Z] Running read-buildbot-config step. 03:43:12 INFO - Running pre-action listener: _resource_record_pre_action 03:43:12 INFO - Running main action method: read_buildbot_config 03:43:12 INFO - Using buildbot properties: 03:43:12 INFO - { 03:43:12 INFO - "project": "", 03:43:12 INFO - "product": "firefox", 03:43:12 INFO - "who": "cbook@mozilla.com", 03:43:12 INFO - "installer_path": "public/build/target.tar.bz2", 03:43:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 03:43:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:43:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 03:43:12 INFO - "stage_platform": "linux64", 03:43:12 INFO - "basedir": "/builds/slave/test", 03:43:12 INFO - "buildnumber": 770, 03:43:12 INFO - "platform": "ubuntu64_hw", 03:43:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:43:12 INFO - "slavebuilddir": "test", 03:43:12 INFO - "taskId": "B66YpZWnQpWKCozK2FmSPA", 03:43:12 INFO - "branch": "mozilla-central", 03:43:12 INFO - "script_repo_revision": "production", 03:43:12 INFO - "revision": "acd4177b28d7862528f63edb40e3d5a5f0b7c8b3", 03:43:12 INFO - "slavename": "talos-linux64-ix-015", 03:43:12 INFO - "repo_path": "mozilla-central" 03:43:12 INFO - } 03:43:12 INFO - Finding installer, test and symbols from parent task. 03:43:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B66YpZWnQpWKCozK2FmSPA'}, attempt #1 03:43:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B66YpZWnQpWKCozK2FmSPA'}, attempt #1 03:43:13 INFO - Task dependencies: aZ7EFsWuSLy6BkxBTfeDHw 03:43:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw'}, attempt #1 03:43:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 03:43:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/test_packages.json 03:43:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.crashreporter-symbols.zip 03:43:13 INFO - Running post-action listener: _resource_record_post_action 03:43:13 INFO - [mozharness: 2017-01-10 11:43:13.978401Z] Finished read-buildbot-config step (success) 03:43:13 INFO - [mozharness: 2017-01-10 11:43:13.978618Z] Running download-and-extract step. 03:43:13 INFO - Running pre-action listener: _resource_record_pre_action 03:43:13 INFO - Running main action method: download_and_extract 03:43:13 INFO - mkdir: /builds/slave/test/build/tests 03:43: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')]} 03:43:13 INFO - trying https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.test_packages.json 03:43:13 INFO - Downloading https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:43:14 INFO - Downloaded 896 bytes. 03:43:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:43:14 INFO - Using the following test package requirements: 03:43:14 INFO - {u'common': [u'target.common.tests.zip'], 03:43:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:43:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:43:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:43:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:43:14 INFO - u'mozbase': [u'target.common.tests.zip'], 03:43:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:43:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:43:14 INFO - u'web-platform': [u'target.common.tests.zip', 03:43:14 INFO - u'target.web-platform.tests.zip'], 03:43:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:43:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:43:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip 03:43:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:43:14 INFO - Fetch https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip into memory 03:43:15 INFO - Expected file size: 20256386 03:43:15 INFO - Obtained file size: 20256386 03:43:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:43:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip 03:43:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:43:19 INFO - Fetch https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.common.tests.zip into memory 03:43:20 INFO - Expected file size: 20256386 03:43:20 INFO - Obtained file size: 20256386 03:43:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.talos.tests.zip 03:43:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:43:24 INFO - Fetch https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.talos.tests.zip into memory 03:43:25 INFO - Expected file size: 14403537 03:43:25 INFO - Obtained file size: 14403537 03:43: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')]} 03:43:25 INFO - trying https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 03:43:25 INFO - Downloading https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:43:26 INFO - Downloaded 55955154 bytes. 03:43:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 03:43:26 INFO - mkdir: /builds/slave/test/properties 03:43:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:26 INFO - Contents: 03:43:26 INFO - build_url:https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 03:43:26 INFO - Running post-action listener: _resource_record_post_action 03:43:26 INFO - Running post-action listener: set_extra_try_arguments 03:43:26 INFO - [mozharness: 2017-01-10 11:43:26.609904Z] Finished download-and-extract step (success) 03:43:26 INFO - [mozharness: 2017-01-10 11:43:26.610000Z] Running populate-webroot step. 03:43:26 INFO - Running pre-action listener: _resource_record_pre_action 03:43:26 INFO - Running main action method: populate_webroot 03:43:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:43:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:43:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:43:26 INFO - '--tppagecycles', 03:43:26 INFO - '7'], 03:43:26 INFO - 'tests': ['tsvgm']}}, 03:43:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:43:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:43:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['damp', 'tps']}, 03:43:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'tests': ['damp', 'tps']}, 03:43:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['dromaeo_dom']}, 03:43:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['basic_compositor_video']}, 03:43:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:43:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['a11yr', 03:43:26 INFO - 'ts_paint', 03:43:26 INFO - 'tpaint', 03:43:26 INFO - 'sessionrestore', 03:43:26 INFO - 'sessionrestore_no_auto_restore', 03:43:26 INFO - 'tabpaint']}, 03:43:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['tsvgx', 03:43:26 INFO - 'tsvgr_opacity', 03:43:26 INFO - 'tart', 03:43:26 INFO - 'tscrollx', 03:43:26 INFO - 'cart', 03:43:26 INFO - 'tsvg_static']}, 03:43:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:26 INFO - 'tsvgr_opacity', 03:43:26 INFO - 'tart', 03:43:26 INFO - 'tscrollx', 03:43:26 INFO - 'cart', 03:43:26 INFO - 'tsvg_static']}, 03:43:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'talos_options': ['--disable-e10s'], 03:43:26 INFO - 'tests': ['tp5o']}, 03:43:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:43:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'talos_options': ['--disable-e10s', 03:43:26 INFO - '--xperf_path', 03:43:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:26 INFO - 'tests': ['tp5n']}, 03:43:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:43:26 INFO - 'talos_options': ['--xperf_path', 03:43:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:26 INFO - 'tests': ['tp5n']}}, 03:43:26 INFO - 'talos.zip': {'path': '', 03:43:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:43:26 INFO - Running post-action listener: _resource_record_post_action 03:43:26 INFO - [mozharness: 2017-01-10 11:43:26.622992Z] Finished populate-webroot step (success) 03:43:26 INFO - [mozharness: 2017-01-10 11:43:26.623084Z] Running create-virtualenv step. 03:43:26 INFO - Running pre-action listener: _resource_record_pre_action 03:43:26 INFO - Running main action method: create_virtualenv 03:43:26 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:26 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 03:43:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:43:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:26 INFO - Using real prefix '/usr' 03:43:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:29 INFO - Installing pip.................done. 03:43:29 INFO - Return code: 0 03:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:43:29 INFO - Reading from file tmpfile_stdout 03:43:29 INFO - Output received: 03:43:29 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) 03:43:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43:29 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')]} 03:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:29 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')]} 03:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:29 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:29 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 03:43:29 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 03:43:29 INFO - Using env: {'DISPLAY': ':0', 03:43:29 INFO - 'HOME': '/home/cltbld', 03:43:29 INFO - 'LANG': 'en_US.UTF-8', 03:43:29 INFO - 'LANGUAGE': 'en_US:en', 03:43:29 INFO - 'LOGNAME': 'cltbld', 03:43:29 INFO - 'MAIL': '/var/mail/cltbld', 03:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:29 INFO - 'MOZ_NO_REMOTE': '1', 03:43:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:29 INFO - 'NO_EM_RESTART': '1', 03:43:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:29 INFO - 'PWD': '/builds/slave/test', 03:43:29 INFO - 'SHELL': '/bin/bash', 03:43:29 INFO - 'SHLVL': '1', 03:43:29 INFO - 'TERM': 'linux', 03:43:29 INFO - 'TMOUT': '86400', 03:43:29 INFO - 'USER': 'cltbld', 03:43:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:29 INFO - '_': '/tools/buildbot/bin/python'} 03:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:29 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 03:43:29 INFO - Cleaning up... 03:43:29 INFO - Return code: 0 03:43:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:29 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')]} 03:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:29 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')]} 03:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:29 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:29 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 03:43:29 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 03:43:29 INFO - Using env: {'DISPLAY': ':0', 03:43:29 INFO - 'HOME': '/home/cltbld', 03:43:29 INFO - 'LANG': 'en_US.UTF-8', 03:43:29 INFO - 'LANGUAGE': 'en_US:en', 03:43:29 INFO - 'LOGNAME': 'cltbld', 03:43:29 INFO - 'MAIL': '/var/mail/cltbld', 03:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:29 INFO - 'MOZ_NO_REMOTE': '1', 03:43:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:29 INFO - 'NO_EM_RESTART': '1', 03:43:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:29 INFO - 'PWD': '/builds/slave/test', 03:43:29 INFO - 'SHELL': '/bin/bash', 03:43:29 INFO - 'SHLVL': '1', 03:43:29 INFO - 'TERM': 'linux', 03:43:29 INFO - 'TMOUT': '86400', 03:43:29 INFO - 'USER': 'cltbld', 03:43:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:29 INFO - '_': '/tools/buildbot/bin/python'} 03:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:29 INFO - Downloading/unpacking psutil>=3.1.1 03:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:32 INFO - Installing collected packages: psutil 03:43:32 INFO - Running setup.py install for psutil 03:43:32 INFO - building 'psutil._psutil_linux' extension 03:43:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:43:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:43:32 INFO - building 'psutil._psutil_posix' extension 03:43:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:43:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:43:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:32 INFO - Successfully installed psutil 03:43:32 INFO - Cleaning up... 03:43:32 INFO - Return code: 0 03:43:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:43:32 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')]} 03:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:32 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')]} 03:43:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:32 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:32 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 03:43:32 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 03:43:32 INFO - Using env: {'DISPLAY': ':0', 03:43:32 INFO - 'HOME': '/home/cltbld', 03:43:32 INFO - 'LANG': 'en_US.UTF-8', 03:43:32 INFO - 'LANGUAGE': 'en_US:en', 03:43:32 INFO - 'LOGNAME': 'cltbld', 03:43:32 INFO - 'MAIL': '/var/mail/cltbld', 03:43:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:32 INFO - 'MOZ_NO_REMOTE': '1', 03:43:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:32 INFO - 'NO_EM_RESTART': '1', 03:43:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:32 INFO - 'PWD': '/builds/slave/test', 03:43:32 INFO - 'SHELL': '/bin/bash', 03:43:32 INFO - 'SHLVL': '1', 03:43:32 INFO - 'TERM': 'linux', 03:43:32 INFO - 'TMOUT': '86400', 03:43:32 INFO - 'USER': 'cltbld', 03:43:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:32 INFO - '_': '/tools/buildbot/bin/python'} 03:43:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:34 INFO - Installing collected packages: mozsystemmonitor 03:43:34 INFO - Running setup.py install for mozsystemmonitor 03:43:34 INFO - Successfully installed mozsystemmonitor 03:43:34 INFO - Cleaning up... 03:43:34 INFO - Return code: 0 03:43:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:43:35 INFO - Using env: {'DISPLAY': ':0', 03:43:35 INFO - 'HOME': '/home/cltbld', 03:43:35 INFO - 'LANG': 'en_US.UTF-8', 03:43:35 INFO - 'LANGUAGE': 'en_US:en', 03:43:35 INFO - 'LOGNAME': 'cltbld', 03:43:35 INFO - 'MAIL': '/var/mail/cltbld', 03:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:35 INFO - 'MOZ_NO_REMOTE': '1', 03:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:35 INFO - 'NO_EM_RESTART': '1', 03:43:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:35 INFO - 'PWD': '/builds/slave/test', 03:43:35 INFO - 'SHELL': '/bin/bash', 03:43:35 INFO - 'SHLVL': '1', 03:43:35 INFO - 'TERM': 'linux', 03:43:35 INFO - 'TMOUT': '86400', 03:43:35 INFO - 'USER': 'cltbld', 03:43:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:35 INFO - '_': '/tools/buildbot/bin/python'} 03:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:35 INFO - Downloading/unpacking jsonschema==2.5.1 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:37 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 03:43:37 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 03:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:37 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:37 INFO - warning: no files found matching '*.txt' 03:43:37 INFO - no previously-included directories found matching 'build' 03:43:37 INFO - no previously-included directories found matching 'dist' 03:43:37 INFO - no previously-included directories found matching '.git*' 03:43:37 INFO - Installing collected packages: jsonschema, functools32 03:43:37 INFO - Running setup.py install for functools32 03:43:37 INFO - warning: no files found matching '*.txt' 03:43:37 INFO - no previously-included directories found matching 'build' 03:43:37 INFO - no previously-included directories found matching 'dist' 03:43:37 INFO - no previously-included directories found matching '.git*' 03:43:37 INFO - Successfully installed jsonschema functools32 03:43:37 INFO - Cleaning up... 03:43:37 INFO - Return code: 0 03:43:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:43:37 INFO - Using env: {'DISPLAY': ':0', 03:43:37 INFO - 'HOME': '/home/cltbld', 03:43:37 INFO - 'LANG': 'en_US.UTF-8', 03:43:37 INFO - 'LANGUAGE': 'en_US:en', 03:43:37 INFO - 'LOGNAME': 'cltbld', 03:43:37 INFO - 'MAIL': '/var/mail/cltbld', 03:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:37 INFO - 'MOZ_NO_REMOTE': '1', 03:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:37 INFO - 'NO_EM_RESTART': '1', 03:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:37 INFO - 'PWD': '/builds/slave/test', 03:43:37 INFO - 'SHELL': '/bin/bash', 03:43:37 INFO - 'SHLVL': '1', 03:43:37 INFO - 'TERM': 'linux', 03:43:37 INFO - 'TMOUT': '86400', 03:43:37 INFO - 'USER': 'cltbld', 03:43:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:37 INFO - '_': '/tools/buildbot/bin/python'} 03:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:37 INFO - Cleaning up... 03:43:37 INFO - Return code: 0 03:43:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:43:37 INFO - Using env: {'DISPLAY': ':0', 03:43:37 INFO - 'HOME': '/home/cltbld', 03:43:37 INFO - 'LANG': 'en_US.UTF-8', 03:43:37 INFO - 'LANGUAGE': 'en_US:en', 03:43:37 INFO - 'LOGNAME': 'cltbld', 03:43:37 INFO - 'MAIL': '/var/mail/cltbld', 03:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:37 INFO - 'MOZ_NO_REMOTE': '1', 03:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:37 INFO - 'NO_EM_RESTART': '1', 03:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:37 INFO - 'PWD': '/builds/slave/test', 03:43:37 INFO - 'SHELL': '/bin/bash', 03:43:37 INFO - 'SHLVL': '1', 03:43:37 INFO - 'TERM': 'linux', 03:43:37 INFO - 'TMOUT': '86400', 03:43:37 INFO - 'USER': 'cltbld', 03:43:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:37 INFO - '_': '/tools/buildbot/bin/python'} 03:43:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43: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 03:43:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 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 03:43:40 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 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 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 03:43:40 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 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 INFO - Downloading docopt-0.6.1.tar.gz 03:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:40 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:40 INFO - Running setup.py install for blobuploader 03:43:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:41 INFO - Running setup.py install for requests 03:43:41 INFO - Running setup.py install for docopt 03:43:41 INFO - Successfully installed blobuploader requests docopt 03:43:41 INFO - Cleaning up... 03:43:41 INFO - Return code: 0 03:43:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:41 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')]} 03:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 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')]} 03:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:41 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 03:43:41 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 03:43:41 INFO - Using env: {'DISPLAY': ':0', 03:43:41 INFO - 'HOME': '/home/cltbld', 03:43:41 INFO - 'LANG': 'en_US.UTF-8', 03:43:41 INFO - 'LANGUAGE': 'en_US:en', 03:43:41 INFO - 'LOGNAME': 'cltbld', 03:43:41 INFO - 'MAIL': '/var/mail/cltbld', 03:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:41 INFO - 'MOZ_NO_REMOTE': '1', 03:43:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:41 INFO - 'NO_EM_RESTART': '1', 03:43:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:41 INFO - 'PWD': '/builds/slave/test', 03:43:41 INFO - 'SHELL': '/bin/bash', 03:43:41 INFO - 'SHLVL': '1', 03:43:41 INFO - 'TERM': 'linux', 03:43:41 INFO - 'TMOUT': '86400', 03:43:41 INFO - 'USER': 'cltbld', 03:43:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:41 INFO - '_': '/tools/buildbot/bin/python'} 03:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:41 INFO - Running setup.py (path:/tmp/pip-fY8oML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:41 INFO - Running setup.py (path:/tmp/pip-K00SH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:41 INFO - Running setup.py (path:/tmp/pip-e54KMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:41 INFO - Running setup.py (path:/tmp/pip-vej3PK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:42 INFO - Running setup.py (path:/tmp/pip-tztKR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:42 INFO - Running setup.py (path:/tmp/pip-YhZxCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:42 INFO - Running setup.py (path:/tmp/pip-TJ1NGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:42 INFO - Running setup.py (path:/tmp/pip-QQRzHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:42 INFO - Running setup.py (path:/tmp/pip-ZhchMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:42 INFO - Running setup.py (path:/tmp/pip-w_aqkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:42 INFO - Running setup.py (path:/tmp/pip-TkPJhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:42 INFO - Running setup.py (path:/tmp/pip-wCKY_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:42 INFO - Running setup.py (path:/tmp/pip-ii2k5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:42 INFO - Running setup.py (path:/tmp/pip-xmriUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:42 INFO - Running setup.py (path:/tmp/pip-D7FdBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:42 INFO - Running setup.py (path:/tmp/pip-X4D8nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:42 INFO - Running setup.py (path:/tmp/pip-L6_hgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:43 INFO - Running setup.py install for manifestparser 03:43:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Running setup.py install for mozcrash 03:43:43 INFO - Running setup.py install for mozdebug 03:43:43 INFO - Running setup.py install for mozdevice 03:43:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Running setup.py install for mozfile 03:43:43 INFO - Running setup.py install for mozhttpd 03:43:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Running setup.py install for mozinfo 03:43:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Running setup.py install for mozInstall 03:43:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:43 INFO - Running setup.py install for mozleak 03:43:43 INFO - Running setup.py install for mozlog 03:43:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Running setup.py install for moznetwork 03:43:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Running setup.py install for mozprocess 03:43:44 INFO - Running setup.py install for mozprofile 03:43:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Running setup.py install for mozrunner 03:43:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Running setup.py install for mozscreenshot 03:43:44 INFO - Running setup.py install for moztest 03:43:44 INFO - Running setup.py install for mozversion 03:43:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:44 INFO - Cleaning up... 03:43:44 INFO - Return code: 0 03:43:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:44 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')]} 03:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:44 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')]} 03:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:44 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:44 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 03:43:44 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 03:43:44 INFO - Using env: {'DISPLAY': ':0', 03:43:44 INFO - 'HOME': '/home/cltbld', 03:43:44 INFO - 'LANG': 'en_US.UTF-8', 03:43:44 INFO - 'LANGUAGE': 'en_US:en', 03:43:44 INFO - 'LOGNAME': 'cltbld', 03:43:44 INFO - 'MAIL': '/var/mail/cltbld', 03:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:44 INFO - 'MOZ_NO_REMOTE': '1', 03:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:44 INFO - 'NO_EM_RESTART': '1', 03:43:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:44 INFO - 'PWD': '/builds/slave/test', 03:43:44 INFO - 'SHELL': '/bin/bash', 03:43:44 INFO - 'SHLVL': '1', 03:43:44 INFO - 'TERM': 'linux', 03:43:44 INFO - 'TMOUT': '86400', 03:43:44 INFO - 'USER': 'cltbld', 03:43:44 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:44 INFO - '_': '/tools/buildbot/bin/python'} 03:43:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:45 INFO - Running setup.py (path:/tmp/pip-TrtmER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:45 INFO - Running setup.py (path:/tmp/pip-60hus9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:45 INFO - Running setup.py (path:/tmp/pip-LpaMAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:45 INFO - Running setup.py (path:/tmp/pip-VdzxBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:45 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)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:45 INFO - Running setup.py (path:/tmp/pip-B__CEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:45 INFO - Running setup.py (path:/tmp/pip-2Sfg1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:45 INFO - Running setup.py (path:/tmp/pip-3AwOP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:45 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)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:45 INFO - Running setup.py (path:/tmp/pip-JawX3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:45 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)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:45 INFO - Running setup.py (path:/tmp/pip-0cydvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:45 INFO - Running setup.py (path:/tmp/pip-nxxQvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:45 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)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:45 INFO - Running setup.py (path:/tmp/pip-Bcj4cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:45 INFO - Running setup.py (path:/tmp/pip-l5UIXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:45 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)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:45 INFO - Running setup.py (path:/tmp/pip-eKJrpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:45 INFO - Running setup.py (path:/tmp/pip-KjhfN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:46 INFO - Running setup.py (path:/tmp/pip-WrBIJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:46 INFO - Running setup.py (path:/tmp/pip-zjBerk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:46 INFO - Running setup.py (path:/tmp/pip-GJ1nPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:43:46 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)) 03:43:46 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)) 03:43:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:46 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 03:43:46 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 03:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:48 INFO - Downloading blessings-1.6.tar.gz 03:43:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:48 INFO - Installing collected packages: blessings 03:43:48 INFO - Running setup.py install for blessings 03:43:48 INFO - Successfully installed blessings 03:43:48 INFO - Cleaning up... 03:43:48 INFO - Return code: 0 03:43:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:48 INFO - Reading from file tmpfile_stdout 03:43:48 INFO - Current package versions: 03:43:48 INFO - argparse == 1.2.1 03:43:48 INFO - blessings == 1.6 03:43:48 INFO - blobuploader == 1.2.4 03:43:48 INFO - docopt == 0.6.1 03:43:48 INFO - functools32 == 3.2.3-2 03:43:48 INFO - jsonschema == 2.5.1 03:43:48 INFO - manifestparser == 1.1 03:43:48 INFO - mozInstall == 1.12 03:43:48 INFO - mozcrash == 1.0 03:43:48 INFO - mozdebug == 0.1 03:43:48 INFO - mozdevice == 0.49 03:43:48 INFO - mozfile == 1.2 03:43:48 INFO - mozhttpd == 0.7 03:43:48 INFO - mozinfo == 0.9 03:43:48 INFO - mozleak == 0.1 03:43:48 INFO - mozlog == 3.4 03:43:48 INFO - moznetwork == 0.27 03:43:48 INFO - mozprocess == 0.24 03:43:48 INFO - mozprofile == 0.28 03:43:48 INFO - mozrunner == 6.13 03:43:48 INFO - mozscreenshot == 0.1 03:43:48 INFO - mozsystemmonitor == 0.3 03:43:48 INFO - moztest == 0.8 03:43:48 INFO - mozversion == 1.4 03:43:48 INFO - psutil == 3.1.1 03:43:48 INFO - requests == 1.2.3 03:43:48 INFO - wsgiref == 0.1.2 03:43:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:48 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')]} 03:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:48 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')]} 03:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:48 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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:48 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 03:43:48 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 03:43:48 INFO - Using env: {'DISPLAY': ':0', 03:43:48 INFO - 'HOME': '/home/cltbld', 03:43:48 INFO - 'LANG': 'en_US.UTF-8', 03:43:48 INFO - 'LANGUAGE': 'en_US:en', 03:43:48 INFO - 'LOGNAME': 'cltbld', 03:43:48 INFO - 'MAIL': '/var/mail/cltbld', 03:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:48 INFO - 'MOZ_NO_REMOTE': '1', 03:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:48 INFO - 'NO_EM_RESTART': '1', 03:43:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:48 INFO - 'PWD': '/builds/slave/test', 03:43:48 INFO - 'SHELL': '/bin/bash', 03:43:48 INFO - 'SHLVL': '1', 03:43:48 INFO - 'TERM': 'linux', 03:43:48 INFO - 'TMOUT': '86400', 03:43:48 INFO - 'USER': 'cltbld', 03:43:48 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:48 INFO - '_': '/tools/buildbot/bin/python'} 03:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:43:49 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)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:43:49 INFO - Cleaning up... 03:43:49 INFO - Return code: 0 03:43:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:49 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')]} 03:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:49 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')]} 03:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xb481f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc58a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeba630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, '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': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:49 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 03:43:49 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 03:43:49 INFO - Using env: {'DISPLAY': ':0', 03:43:49 INFO - 'HOME': '/home/cltbld', 03:43:49 INFO - 'LANG': 'en_US.UTF-8', 03:43:49 INFO - 'LANGUAGE': 'en_US:en', 03:43:49 INFO - 'LOGNAME': 'cltbld', 03:43:49 INFO - 'MAIL': '/var/mail/cltbld', 03:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:49 INFO - 'MOZ_NO_REMOTE': '1', 03:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:49 INFO - 'NO_EM_RESTART': '1', 03:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:49 INFO - 'PWD': '/builds/slave/test', 03:43:49 INFO - 'SHELL': '/bin/bash', 03:43:49 INFO - 'SHLVL': '1', 03:43:49 INFO - 'TERM': 'linux', 03:43:49 INFO - 'TMOUT': '86400', 03:43:49 INFO - 'USER': 'cltbld', 03:43:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:49 INFO - '_': '/tools/buildbot/bin/python'} 03:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:49 INFO - Cleaning up... 03:43:49 INFO - Return code: 0 03:43:49 INFO - Running post-action listener: _resource_record_post_action 03:43:49 INFO - Running post-action listener: _start_resource_monitoring 03:43:49 INFO - Starting resource monitoring. 03:43:49 INFO - [mozharness: 2017-01-10 11:43:49.306312Z] Finished create-virtualenv step (success) 03:43:49 INFO - [mozharness: 2017-01-10 11:43:49.306765Z] Running install step. 03:43:49 INFO - Running pre-action listener: _resource_record_pre_action 03:43:49 INFO - Running main action method: install 03:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:49 INFO - Reading from file tmpfile_stdout 03:43:49 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:49 INFO - Reading from file tmpfile_stdout 03:43:49 INFO - Output received: 03:43:49 INFO - Usage: mozinstall [options] installer 03:43:49 INFO - Options: 03:43:49 INFO - -h, --help show this help message and exit 03:43:49 INFO - -d DEST, --destination=DEST 03:43:49 INFO - Directory to install application into. [default: 03:43:49 INFO - "/builds/slave/test"] 03:43:49 INFO - --app=APP Application being installed. [default: firefox] 03:43:49 INFO - mkdir: /builds/slave/test/build/application 03:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:44:05 INFO - Reading from file tmpfile_stdout 03:44:05 INFO - Output received: 03:44:05 INFO - /builds/slave/test/build/application/firefox/firefox 03:44:05 INFO - Running post-action listener: _resource_record_post_action 03:44:05 INFO - [mozharness: 2017-01-10 11:44:05.739867Z] Finished install step (success) 03:44:05 INFO - [mozharness: 2017-01-10 11:44:05.740145Z] Running run-tests step. 03:44:05 INFO - Running pre-action listener: _resource_record_pre_action 03:44:05 INFO - Running main action method: run_tests 03:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:44:05 INFO - Python 2.7.3 03:44:05 INFO - Return code: 0 03:44:05 INFO - grabbing minidump binary from tooltool 03:44:05 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')]} 03:44:05 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 0xf43850>, '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 0xeb86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeb8b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:44:05 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 03:44:05 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 03:44:05 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 03:44:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:44:05 INFO - Return code: 0 03:44:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:44:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:44:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:44:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:44:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:44:05 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/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 03:44:05 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/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 03:44:05 INFO - Using env: {'DISPLAY': ':0', 03:44:05 INFO - 'HOME': '/home/cltbld', 03:44:05 INFO - 'LANG': 'en_US.UTF-8', 03:44:05 INFO - 'LANGUAGE': 'en_US:en', 03:44:05 INFO - 'LOGNAME': 'cltbld', 03:44:05 INFO - 'MAIL': '/var/mail/cltbld', 03:44:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:05 INFO - 'MOZ_NO_REMOTE': '1', 03:44:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:05 INFO - 'NO_EM_RESTART': '1', 03:44:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:05 INFO - 'PWD': '/builds/slave/test', 03:44:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:05 INFO - 'SHELL': '/bin/bash', 03:44:05 INFO - 'SHLVL': '1', 03:44:05 INFO - 'TERM': 'linux', 03:44:05 INFO - 'TMOUT': '86400', 03:44:05 INFO - 'USER': 'cltbld', 03:44:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698', 03:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:05 INFO - '_': '/tools/buildbot/bin/python'} 03:44:05 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/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 03:44:06 INFO - mozversion application_buildid: 20170110110950 03:44:06 INFO - mozversion application_changeset: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 03:44:06 INFO - mozversion application_display_name: Nightly 03:44:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:44:06 INFO - mozversion application_name: Firefox 03:44:06 INFO - mozversion application_remotingname: firefox 03:44:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:44:06 INFO - mozversion application_vendor: Mozilla 03:44:06 INFO - mozversion application_version: 53.0a1 03:44:06 INFO - mozversion platform_buildid: 20170110110950 03:44:06 INFO - mozversion platform_changeset: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 03:44:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:44:06 INFO - mozversion platform_version: 53.0a1 03:44:06 INFO - using testdate: 1484048646 03:44:06 INFO - actual date: 1484048646 03:44:06 INFO - starting webserver on 'localhost:54504' 03:44:06 INFO - SUITE-START | Running 2 tests 03:44:06 INFO - TEST-START | dromaeo_css 03:44:06 INFO - operating with platform_type : linux_ 03:44:06 INFO - Initialising browser for dromaeo_css test... 03:44:06 INFO - TEST-INFO | started process 18998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXtdpo/profile http://localhost:54504/getInfo.html) 03:44:09 INFO - PROCESS | 18998 | __metrics Screen width/height:1600/1200 03:44:09 INFO - PROCESS | 18998 | colorDepth:24 03:44:09 INFO - PROCESS | 18998 | Browser inner width/height: 1024/697 03:44:09 INFO - PROCESS | 18998 | __metrics 03:44:10 INFO - PROCESS | 18998 | 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" 03:44:10 INFO - PROCESS | 18998 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:11 INFO - PROCESS | 18998 | console.error: PushService: 03:44:11 INFO - PROCESS | 18998 | stateChangeProcessEnqueue: Error transitioning state 03:44:11 INFO - PROCESS | 18998 | UnknownError 03:44:11 INFO - TEST-INFO | 18998: exit 0 03:44:11 INFO - Browser initialized. 03:44:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:44:11 INFO - TEST-INFO | started process 19079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXtdpo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:23 INFO - PROCESS | 19079 | RSS: Main: 152248320 03:44:23 INFO - PROCESS | 19079 | 03:47:29 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54504/tests/dromaeo/cssquery-ext.html) 03:47:30 INFO - PROCESS | 19079 | RSS: Main: 250679296 03:47:30 INFO - PROCESS | 19079 | 03:49:13 INFO - PROCESS | 19079 | ************************* 03:49:13 INFO - PROCESS | 19079 | A coding exception was thrown and uncaught in a Task. 03:49:13 INFO - PROCESS | 19079 | 03:49:13 INFO - PROCESS | 19079 | Full message: ReferenceError: fetch is not defined 03:49:13 INFO - PROCESS | 19079 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 03:49:13 INFO - PROCESS | 19079 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 03:49:13 INFO - PROCESS | 19079 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 03:49:13 INFO - PROCESS | 19079 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 03:49:13 INFO - PROCESS | 19079 | TaskImpl@resource://gre/modules/Task.jsm:277:3 03:49:13 INFO - PROCESS | 19079 | asyncFunction@resource://gre/modules/Task.jsm:252:14 03:49:13 INFO - PROCESS | 19079 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 03:49:13 INFO - PROCESS | 19079 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 03:49:13 INFO - PROCESS | 19079 | TaskImpl@resource://gre/modules/Task.jsm:277:3 03:49:13 INFO - PROCESS | 19079 | asyncFunction@resource://gre/modules/Task.jsm:252:14 03:49:13 INFO - PROCESS | 19079 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 03:49:13 INFO - PROCESS | 19079 | 03:49:13 INFO - PROCESS | 19079 | ************************* 03:50:33 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-ext.html (next: http://localhost:54504/tests/dromaeo/cssquery-jquery.html) 03:50:33 INFO - PROCESS | 19079 | RSS: Main: 237649920 03:50:33 INFO - PROCESS | 19079 | 03:53:35 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54504/tests/dromaeo/cssquery-mootools.html) 03:53:36 INFO - PROCESS | 19079 | RSS: Main: 202960896 03:53:36 INFO - PROCESS | 19079 | 03:56:38 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54504/tests/dromaeo/cssquery-prototype.html) 03:56:39 INFO - PROCESS | 19079 | RSS: Main: 201457664 03:56:39 INFO - PROCESS | 19079 | 03:59:42 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54504/tests/dromaeo/cssquery-yui.html) 03:59:42 INFO - PROCESS | 19079 | RSS: Main: 187346944 03:59:42 INFO - PROCESS | 19079 | 04:02:45 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:54504/tests/dromaeo/cssquery-yui.html (next: http://localhost:54504/tests/dromaeo/cssquery-dojo.html) 04:02:46 INFO - PROCESS | 19079 | RSS: Main: 250372096 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | __start_tp_report 04:02:46 INFO - PROCESS | 19079 | _x_x_mozilla_page_load 04:02:46 INFO - PROCESS | 19079 | _x_x_mozilla_page_load_details 04:02:46 INFO - PROCESS | 19079 | |i|pagename|runs| 04:02:46 INFO - PROCESS | 19079 | |0;dojo.html;2552.910648127315;2563.179782469608;2584;2589.300888760035;2596.2341109372733;4594.908101837961;4639.048994956034;4659.580637742602;4661.254199328108;4709.552592503711;4645.488996210414;4681.719096854191;4686.531346865312;4721;4723.976380118094;4265.573442655726;4282.207791558571;4316.028893498969;4349;4423.380726698265;3955.356564786278;4291.399204111427;4341.435613370258;4345.130973805236;4400.889977750549;4225.408442817978;4250.829966801324;4312.28847240205;4338.804753786056;4416.933745993812;4237.067845074079;4245.426867372896;4291.098869237464;4301.204277208695;4351.912961740747;4267.765272909989;4270.29540125882;4277.679174061957;4302.3546468030045;4319.892002699926;4634.629229661619;4642.573728682431;4650.0932318197965;4654.743989080599;4660.324252983299;4839.37088178538;4860.878478038077;4863.416390033183;4932.8520144395725;4977.402711674586;4954.529319714621;4991.4758950310425;5029.673071250357;5046.1169295373165;5093.898122037538;4734.479207287196;4735.21868891629;4740.6681532292405;4778.211595086772;4783.6651434399955;5149.794008239628;5152.819651312186;5158.535731784157;5161;5195.454477279907;4801.879953001133;4815.975920120377;4855.223164293696;4890.608751299887;4946.604271658259;4927.285543596158;4959.2561115832195;4974.452810190876;5059.569936555434;5071.923921141186;4876.244182151772;4957;4962.429320628102;4965.950340496549;5024.673396229234;4983.90032199354;4986.900261994812;4996.950030499721;5000.424951130593;5022.121128802445;4156.709030367906;4241.151769646083;4249.2563801334645;4258.446401967724;4287.63555097814;3651.598324184338;3679.7976111314133;3695.5011073504734;3708.7033037356946;3757.511523501927;4318.157959197989;4322.848700295537;4329.242382583098;4330.588594083557;4366.563343665608;5042.470540593258;5043.5712964398435;5045.873853153627;5053.343065401475;5089.389273287229;4206.432131662247;4248.299030659907;4248.34150706641;4264.0832221072;4266.765327906933;4501.077279157765;4522.819087236474;4524.117797029613;4551.0215303709865;4563.133004729091;4684.81260749573;4696.084263568571;4698.530146985274;4703.270992996091;4741.810327586996;4904.607631389552;4937.975310123426;4940.431850337257;4946;5021.874453138701;4673.836415725434;4677.508861569486;4679.087577922272;4704.012157446974;4712.057588482248;4901.5588597026435;4926.211806111005;4927.531884470969;4928.162212423825;4977.601791856715;4906.607471402351;4941.876453088702;4976.452590215001;4976.601871850171;5014.247862820606;4855.36880205571;4881.90236195267;4883.121038213156;4917.852464426073;4926.630502712239;4895.73073480962;4903.436104847988;4904.068227036852;4904.607631389552;4951.232558953368;4688.34363189147;4739.075880203465;4764.49972752856;4768.499307572655;4770.117528257283;4875.561199492063;4897.41231052276;4904.337914381513;4935.383077115361;4939.580135688507;4787.904241915212;4791.401074865642;4792.2092854678585;4794.400699912511;4841.031793641357 04:02:46 INFO - PROCESS | 19079 | |1;ext.html;512.2239806692861;531.2138035707151;539.4686234059453;552.0063885006987;552.682207730555;29393.412131757377;30045.69954300459;39010.21979560407;40670.593294067054;41003.669522962045;38395.23209535808;42859.35710964338;42928.35607465891;42991.78504107483;42995.35506967398;7660.846783064321;7817.2964433201005;7835.216478352177;7840;7844.529328240295;7301.780946571611;7658;7853.528788272693;9741.951290243538;10003.799924001516;10184.694459166238;10230.43732594707;10237.385756854575;10252.38485690861;10253.282270241087;10101.494925253744;10129.392236465836;10134.746631334201;10152.593896244141;10164.796704065951;9979.950100249525;10049.648262310857;10058.245631577662;10065.094141527297;10065.748356291078;40081;40415.797921010504;40458.19083618341;40570.39144412836;40615;3587.121155316964;3742.3076730804887;3742.438634204864;3765.2867945085195;3787;29840.850795745882;30000.8499957501;30119.397612047636;30305.696943030507;31410.37179256402;3334.299797042624;3365.646607106243;3377.7128944039787;3383.2218589724503;3386.542816719736;252029;252323;252621;253035;253223;54579.63362930956;54831.177532337046;54869.45130548723;54978.45021549813;55006.72496637571;58976.41023589709;59052.40947590555;59254.70372648109;59304.11043834346;59377.70311148417;14997.925010375096;15504.147271899928;15508.5347439577;15523;15530.068195908281;10009.899901001043;11371.601993930173;11416.94291528537;11418.543258269576;11429.657110286706;14988.400463981538;17072.658546828992;17167.914160429118;17474;18101;6472.514561407819;6495.30292174187;6495.350464953562;6508.707108180049;6576.342365763481;794.408165916396;811.9512829230265;819.5082950229814;821.4660470693977;843.409613270713;26111.216663500127;27345.31636709098;27518.724812752018;27771.861140694167;27860.721392785814;867.7657032601162;877.3595275448959;881.4667126388442;894.1371576428777;899.7120921305119;13911.582652520441;14416.42334306637;14536.418543258364;14623.85376146225;14797.704045919236;361361;364171;364462;365350;365413.7668986528;50575;51096.489035109174;51208.74395627998;51227;51366;1237.2143688757703;1273.6370134511806;1294.7022184897526;1295.2617008305176;1295.7732396830518;25141;26294;26686.932522698873;27152.72847271502;27180.592291116045;42655.57344426516;43514.34728479139;43525.56474435342;43542.34686479642;43571.34642980294;286233;286528;286680.13319867366;286885;289936;31757.20606984844;31807.250601217154;36773.63226367809;37166.62833371632;37246;34915.65084349124;36278.63721362858;36432.45351319679;36435.45346819853;36454.27091458209;35618.46572301469;35686;35725.64274357223;35967.100822479646;36650.633493664725;35566.822165889;35752.463713043806;36235.45646815353;36309.45535817018;36508.26983460369;33710.32579348448;34546.65453345434;34830.82584587061;35160.29679406346;35284;29448.171737674824;31543.52684709777;31802;31852.362952741278;31962;23462.38624320174;23583.29250122499;23870.641940370562;23873.151077986004;23951.28146155618 04:02:46 INFO - PROCESS | 19079 | |2;jquery.html;338.7019422907841;339.5365326329561;350.6370906112174;353.8566880413433;360.75468681296707;16941.15294235288;17837.821621783794;18283.90858045711;18291.451256462293;18497.0751462427;17866.553336166602;18157.7276340855;18636.068196590186;18762.812371876276;18777.403920666726;4704.929426058612;5115.9744201278945;5161.458046905077;5166.276721259027;5171.456997015315;3858.884233473;4063.634272915437;4074.5110586729634;5112.821051263207;5200.08998425276;5896.145058966447;5913.733881975321;5997.37027612101;5999.550033747487;6073.180120683717;5877.265341832271;6002.159697642333;6034.1853849730605;6059.939400606004;6070;5299.920501192485;5302.549283310885;5303.204519322133;5308.469153084699;5309.150535914235;21252.468688282763;21530.461738456506;21689.240876569245;21794.346169614935;22435.663465047874;2189.2228258968157;2227.6881236626887;2244.472548950995;2247.516783891455;2319.8724070176136;13397.799033014513;14005.159690418506;14097.2951352433;14229.501967431193;14572.417103315855;1727.5249306440703;1746.240385432341;1758.9912050439666;1759.2083562396972;1778.6175972166047;65986.67006664984;71589.64205178992;71920.64039679768;72159.27840721578;74249;9418.34071614994;9632.27757918159;9702.75743106414;9720.368176068532;9936.354136981074;12959.352032398343;13060.934695326463;13094.672633184247;13201.801972970414;13236.404361803741;6996.475264355092;7178.748743793943;7195.172555156119;7452.478326517092;7462.664180111799;6937.583744975318;6973.93026069743;7192.748253811092;7195.316444937723;7205.099362579677;6334.683265836689;6352.8411789704705;6412.578405294249;6441.838954026093;6599.934000660028;1665.5003498950266;1703.7018521758644;1706.78271458302;1715.5625315544457;1735.7222844344844;818.6561644109488;829.809143896895;832.4006715165071;846.2975730143966;854.2567965869733;17031.40390086341;17100.828991710172;18636.906815465834;18736.625267494575;18883.62232755337;832.9666813327399;836.5148214035845;853.4452605806151;858.0989960541407;860.6858496648804;9884.30809843304;10556.208284378703;10680.679579612624;10700.571977120984;10808.189385796097;473449;473601.4877470838;482454;486460;486621;74373.25626743663;75475.62262188653;75496.62251688706;76997.23002770012;78628;979.3536266064364;997.0627610046533;998.4907697074544;1004.7588578741196;1005.0251256281396;15389.383804752995;15606.531804045897;15620.453284135001;15805.52583422499;16193.190340482694;35635.28729425345;35909.461358080174;36304.81847590745;36605.450918236784;36832.81583592065;47900.52099478961;47969;48058;48173;49074.01851962869;7461.626918654154;7485.363744081814;7491.213422590549;7503.137139229058;7953.045634523663;11553.24903881245;12360.876391235972;12582.496700131893;12892.419841106985;13016.349182540645;5556;10317.27779055459;10825.188110891557;10898.673039808818;11170.324451332364;10528.578856845947;10743.140548756237;10797.514111864828;10855.837162442727;11124.61063862761;10537.52581133866;10772.622958196425;10934.125269978542;11075.501602428072;11442.199046066695;7560.924390756022;7633.274838890296;7643.121041080347;7694.499857509244;7733.072031356273;10578.312409693346;10585.100266477128;10752.40861752611;10778.407187604756;11000.504977276201 04:02:46 INFO - PROCESS | 19079 | |3;mootools.html;1327.3827670133387;1333.65325015496;1338.9330533473324;1345.0584590786452;1350.0549615269308;6235.594686345385;6526.347365263471;6528.31452697467;6545.50908681849;6552.475801935846;5767.625104368213;5956.0172571525645;6123.540734444912;6297.08692239625;6332.46174075203;1986.5430950881341;2012.949676258098;2015.1536354731047;2020.05057622917;2021.070307658479;1942.3493129801534;2034.450698311455;2043.8875861827594;2045.0490521907311;2047.2732180076082;1766.9028203448804;1817.9818201818011;1946.9902650486729;1963.4993076765477;1997.3009446693613;1985.8312043476321;1992.0537744571357;1995.2019192323041;1999.1203870297168;2002.1891134090718;1955.354732938127;1973.319204874316;1981.7919118492496;1982.5539253667953;1982.6827707566865;6636.070950066995;6653.401193892524;6671.299513551105;6673.265940746514;6674.866502669919;4028.395740638898;4123.195976784498;4130.958690413087;4134.91730165398;4139.689523285766;2665;2706.323419145214;2768.750812426871;2806.6912639609627;2823.407084512255;1029.330934892326;1073.3881687438163;1081.0702795595782;1108.972275693106;1123.3821398230941;8259.710910118118;8268.462549934235;8320.750377488625;8372.748817535483;8413.158684131598;3473.4095203815414;3643.4716966040305;3668.816559172031;3670.2292518570866;3728.2357116790995;7602;7618.238176182448;7626.58053807046;7627.923720762832;7668.6165691716305;4055.8174882130374;4096.979515102465;4102.774347410921;4119.155573107506;4194;3746.438034294823;3771.321162190777;3772.5095737554493;3774.414965680324;3784.810759462016;3052.526858336961;3118.8908388206305;3128.5150801625327;3154.1168472827644;3155.5108958111527;928.823523530527;948.6442584030989;954.5942974235921;960.2558017536466;976.1848856205006;833.8874251976027;857.3055824781947;865.485036403339;878.510923983854;881.8853549038583;4347.978260108679;4585.885352866205;4625.861224163281;4698.248280275208;4719.834805781781;827.4952279109739;837.6063250272363;839.970601028961;885.5527958381106;902.4044130873723;3191.69678880506;3226.3869864725625;3250.9187270317957;3313.9171520711693;3323.7839540429795;7480.8877866832045;7832.373410127176;7832.373410127176;7835.84328313442;7849.843003140019;7747.612619369122;7749.302562769378;7756.224377562312;7757.107932587712;7762.184970578121;6273.905891411632;6301.4328710416285;6314.305426403092;6349.206349206349;6374.043893416025;4515.661325400542;4610.700304480198;4658.976705116453;4663.487016428258;4725.456572494069;7052.435805135474;7057.36483716468;7085.610291434021;7154.785356439315;7178.533395329287;7153.177384600837;7234.457415693949;7288.380487658608;7309.707611695472;7344.192138864613;5568.387477377404;5598.10430331145;5655.321361436654;5658.971705141448;5710.400407957105;6222.502199823913;6277.089821975696;6283.654399008098;6284.277308109626;6298.02380631003;5494.230807686847;5548.389677135593;5550;5598.944010559842;5641.097424412074;5585.804496842591;5590.301212348457;5637.492625663546;5671.801486947937;5722.0272553665145;5090.49095090488;5133.178691409356;5136.717480538607;5148.021875843578;5156.4070131935305;3888.125171836314;3958.8614398495915;3988.8603898863403;4027.416024676464;4100.220958017967;3921.60783921617;3931.017245688578;4022.2156679447226;4051.39229115635;4175.519815221289 04:02:46 INFO - PROCESS | 19079 | |4;prototype.html;292.8257686676427;304.0877367896311;310.079065176426;315.80893559396566;317.764854008034;4719;4775.379200703908;4858.902821943559;4899.706017638944;4939.382577177853;4704.553067458594;4816.807327706888;4823.951760482394;4833.78247978841;4841.709497430148;3815.332316844562;3816.484774555427;3826.081740382316;3852.459016393437;3877.612238776128;3696.574893887197;3800.8859734208017;3801.9354580717445;3868.2263547290513;3868.903277418059;3804.239152169563;3829.636184562462;3830.157365379626;3848.537555728833;3856.595057518962;3815.389537673973;3847.826847791856;3854.5759966403675;3859;3863.150106976461;3238.352329534079;3312.9006129816144;3344.1973926257765;3403.1832360233366;3428.3486137634018;4742.9525704742855;4748.171953797812;4750.904981900343;4758.334582895994;4761.904761904742;4354.847580334705;4362.716423432498;4363.629091527224;4366.519682834885;4382.649388048949;4796.424429068499;5000.224920153366;5052.393712754493;5078.822241221576;5081.136206844845;3566.286742651454;3573.9992802015477;3574.2315402188656;3576.8390422431053;3580.8541266794637;5313.840584782462;5362.624616276862;5372;5391.946080539223;5402.972985135049;3574;3591.3535563598803;3653.50677658518;3668.63313668631;3677.687396571268;3666.8533258669886;3677.558692956862;3678.926421471609;3684.6683798458266;3689.096171438015;4076.9184616307507;4108.671306295549;4181.874543763692;4228.2811921972725;4231.978840105779;5081.9745901271;5097.949020509822;5122.385313762298;5128.589712822965;5136.998285334324;1215.9392030398444;1225.454672670653;1230.7846126927752;1234.703671118943;1241.6213055018125;795.6021989005498;834.3992325525612;834.6661335465741;847.9364047696447;850.1838235294064;4338.956610433918;4340.283853164256;4355.390245365651;4358.585934336233;4380.2697934315765;5115.462876397924;5144.32809336967;5153.587712982952;5182.870428239249;5202.453742356998;4222.577742225814;4314.482262128565;4363.672724545609;4366;4379.824807007748;2675.9464810703676;2728.7953403494816;2745.341118131634;2745.697973222944;2751.339678477151;4746.38297021385;4793.209120495079;4795.256735206049;4797.328374027639;4807.807687692523;5081.110805609003;5186.507281808297;5239.816606418757;5240.34495688039;5306.867328316823;4921.360223170965;4922.138625740481;4943.851684449401;4948.536024782667;4958.876028099255;5261.421573528003;5274.89450210986;5291.3121294231505;5292.168689750964;5306.593752655626;4930.587124523603;5148.613853961043;5192.3769147702515;5193.870153246199;5211.400688920823;4867.58625516835;4904;4909.165441874961;4909.288153217834;4921.434035086011;3827.808609569566;3842.193139440749;3885.4171874217964;3897.259520691173;3904.1606054698377;5105.974470127626;5239.4236633970995;5329.653572517774;5343.450399384135;5346.224797404432;4868.41579010521;4900.754962251945;4964.627652925973;5036.118679231193;5047.823326183566;5059.898802023865;5067.341245638044;5068.797248110034;5071.721055341992;5083.262926875508;4877.6829506081995;4981.975090124526;4983.700977941335;5012.4736902626155;5019.598432125495;5092.185250359925;5117.692938423557;5123.154679477844;5130.615203859651;5138.794448222029;5019.347484826949;5020.422651395136;5020.623453240948;5028.723420211923;5033.899322013466 04:02:46 INFO - PROCESS | 19079 | |5;yui.html;678.9898151527725;691.4433880726017;746.5632604926118;787.8739401695728;806.4153488720679;11969.7606047879;13158.473661053546;13195.736085278313;13234.669133271662;13247.271400072992;11525.654230373102;11585.652430427079;11671.066314694825;11673.533058677664;11676.824847627271;634.6287421858218;668.4218151299116;780.5160800303792;803.0564087197549;816.7345612675904;822.4325215601216;823.7199352220244;824.4064273722939;824.7360844529753;825.1913125137369;870.2211520688979;870.5299138465222;870.7257213977576;870.9738707838775;871.6469829718974;680.6120511308557;707.1690763353065;827.3257295304307;861.1990848510883;866.5060915278294;634.1692382978313;635.6630985577605;636.3032479435011;636.700750647195;636.8630744389978;12907.741845163138;12912.548060817826;12919.806202906964;12928.28894410807;12947.5468358607;13143.737125257538;13151.60545183646;13169.769033192148;13170.34148292591;13316.667083322898;11941.701457463547;12028.158028937978;12046.457909394097;12283;12369.304101993903;4163.167366526676;4193.811278492475;4194.412782210493;4375.5405682403525;4379.562043795627;61612.58916330579;62527;63058.748243952075;63821.68089159571;64282.35717642857;9336.579853906454;9489.098535639103;10136.239782016379;10188.032136946978;10202.744931376626;19206.423807285802;19592;19611.21555137807;19612.21551137967;19636.312729054414;6539.574927629689;6551.934480655228;6624.966875165594;6725.19297684281;6764.763233286812;3651.3062518121474;3654.5614526256486;3679.4848721179055;3709.4064949608473;3727.254549090193;2953.7784666150123;2999.925001874971;3005.218643152796;3010.3076292452856;3015.8341291229194;479.70977558577255;482.58015526491477;484.29776823605914;490.80122550367156;493.73091665042637;547.4552819944101;559.0329918672543;561.2871653000666;562.5948820733727;576.6453631016894;8145;8406.41155119148;8412.200840920103;8780.234226218721;8803.251723603438;527.0118527760449;549.3215878390185;550.5402988504563;561.2983770287141;563.6420872745754;3834.803855704699;4021.3766866135797;4031.33148834565;4089.6114869087387;4157.334826427757;65314;67338.66330668316;69860.65069674619;70001.64999174971;70231;47384;48177.75911120492;49954.50045499571;59640;60266;53105.7344713274;54875.72562137164;55160.44839551633;56649.71675141598;56929.71535142298;7389;7591.506552074097;7633.1985141559335;7725.150233474201;7822.608869556386;37622.43566346452;38059.809700951315;38317.616823831406;38496.80751596224;39665.40501892416;44501.33248001347;46630.53369466262;46749;46849;48802;8753;9140.131687489678;9437.622495100393;9466.52667366615;9547.665831695856;10075.697729068139;10107.89892101099;10174.949125254327;10209.693709188736;10259.897401026194;9649.807003860024;9826.852597211191;9906.554205060645;10020.64927727526;10250.948745256524;9631.662891799033;9756.170725488413;9815.656452024145;9909.752256193653;9930.304878658424;9266.305027123128;9273.397229180082;9551.713448596553;9588.280878933969;9662.56518456657;8137.226963438464;8179.754607361789;8290.917090829256;8343.95828020856;8586.356023298402;7428.1086269647985;7673.117591476828;7724.729634462767;7804.9219507806465;7944.960275198587 04:02:46 INFO - PROCESS | 19079 | __end_tp_report 04:02:46 INFO - PROCESS | 19079 | __start_cc_report 04:02:46 INFO - PROCESS | 19079 | _x_x_mozilla_cycle_collect,1020 04:02:46 INFO - PROCESS | 19079 | __end_cc_report 04:02:46 INFO - PROCESS | 19079 | __startTimestamp1484049766076__endTimestamp 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | ------- Summary: start ------- 04:02:46 INFO - PROCESS | 19079 | Number of tests: 6 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#0] dojo.html Cycles:165 Average:4615.31 Median:4739.08 stddev:488.43 (10.3%) stddev-sans-first:462.36 04:02:46 INFO - PROCESS | 19079 | Values: 2552.9 2563.2 2584.0 2589.3 2596.2 4594.9 4639.0 4659.6 4661.3 4709.6 4645.5 4681.7 4686.5 4721.0 4724.0 4265.6 4282.2 4316.0 4349.0 4423.4 3955.4 4291.4 4341.4 4345.1 4400.9 4225.4 4250.8 4312.3 4338.8 4416.9 4237.1 4245.4 4291.1 4301.2 4351.9 4267.8 4270.3 4277.7 4302.4 4319.9 4634.6 4642.6 4650.1 4654.7 4660.3 4839.4 4860.9 4863.4 4932.9 4977.4 4954.5 4991.5 5029.7 5046.1 5093.9 4734.5 4735.2 4740.7 4778.2 4783.7 5149.8 5152.8 5158.5 5161.0 5195.5 4801.9 4816.0 4855.2 4890.6 4946.6 4927.3 4959.3 4974.5 5059.6 5071.9 4876.2 4957.0 4962.4 4966.0 5024.7 4983.9 4986.9 4997.0 5000.4 5022.1 4156.7 4241.2 4249.3 4258.4 4287.6 3651.6 3679.8 3695.5 3708.7 3757.5 4318.2 4322.8 4329.2 4330.6 4366.6 5042.5 5043.6 5045.9 5053.3 5089.4 4206.4 4248.3 4248.3 4264.1 4266.8 4501.1 4522.8 4524.1 4551.0 4563.1 4684.8 4696.1 4698.5 4703.3 4741.8 4904.6 4938.0 4940.4 4946.0 5021.9 4673.8 4677.5 4679.1 4704.0 4712.1 4901.6 4926.2 4927.5 4928.2 4977.6 4906.6 4941.9 4976.5 4976.6 5014.2 4855.4 4881.9 4883.1 4917.9 4926.6 4895.7 4903.4 4904.1 4904.6 4951.2 4688.3 4739.1 4764.5 4768.5 4770.1 4875.6 4897.4 4904.3 4935.4 4939.6 4787.9 4791.4 4792.2 4794.4 4841.0 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#1] ext.html Cycles:180 Average:46384.11 Median:35592.64 stddev:79953.39 (224.6%) stddev-sans-first:80103.50 04:02:46 INFO - PROCESS | 19079 | Values: 512.2 531.2 539.5 552.0 552.7 29393.4 30045.7 39010.2 40670.6 41003.7 38395.2 42859.4 42928.4 42991.8 42995.4 7660.8 7817.3 7835.2 7840.0 7844.5 7301.8 7658.0 7853.5 9742.0 10003.8 10184.7 10230.4 10237.4 10252.4 10253.3 10101.5 10129.4 10134.7 10152.6 10164.8 9980.0 10049.6 10058.2 10065.1 10065.7 40081.0 40415.8 40458.2 40570.4 40615.0 3587.1 3742.3 3742.4 3765.3 3787.0 29840.9 30000.8 30119.4 30305.7 31410.4 3334.3 3365.6 3377.7 3383.2 3386.5 252029.0 252323.0 252621.0 253035.0 253223.0 54579.6 54831.2 54869.5 54978.5 55006.7 58976.4 59052.4 59254.7 59304.1 59377.7 14997.9 15504.1 15508.5 15523.0 15530.1 10009.9 11371.6 11416.9 11418.5 11429.7 14988.4 17072.7 17167.9 17474.0 18101.0 6472.5 6495.3 6495.4 6508.7 6576.3 794.4 812.0 819.5 821.5 843.4 26111.2 27345.3 27518.7 27771.9 27860.7 867.8 877.4 881.5 894.1 899.7 13911.6 14416.4 14536.4 14623.9 14797.7 361361.0 364171.0 364462.0 365350.0 365413.8 50575.0 51096.5 51208.7 51227.0 51366.0 1237.2 1273.6 1294.7 1295.3 1295.8 25141.0 26294.0 26686.9 27152.7 27180.6 42655.6 43514.3 43525.6 43542.3 43571.3 286233.0 286528.0 286680.1 286885.0 289936.0 31757.2 31807.3 36773.6 37166.6 37246.0 34915.7 36278.6 36432.5 36435.5 36454.3 35618.5 35686.0 35725.6 35967.1 36650.6 35566.8 35752.5 36235.5 36309.5 36508.3 33710.3 34546.7 34830.8 35160.3 35284.0 29448.2 31543.5 31802.0 31852.4 31962.0 23462.4 23583.3 23870.6 23873.2 23951.3 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#2] jquery.html Cycles:180 Average:27167.24 Median:3961.26 stddev:78782.35 (1988.8%) stddev-sans-first:78977.60 04:02:46 INFO - PROCESS | 19079 | Values: 338.7 339.5 350.6 353.9 360.8 16941.2 17837.8 18283.9 18291.5 18497.1 17866.6 18157.7 18636.1 18762.8 18777.4 4704.9 5116.0 5161.5 5166.3 5171.5 3858.9 4063.6 4074.5 5112.8 5200.1 5896.1 5913.7 5997.4 5999.6 6073.2 5877.3 6002.2 6034.2 6059.9 6070.0 5299.9 5302.5 5303.2 5308.5 5309.2 21252.5 21530.5 21689.2 21794.3 22435.7 2189.2 2227.7 2244.5 2247.5 2319.9 13397.8 14005.2 14097.3 14229.5 14572.4 1727.5 1746.2 1759.0 1759.2 1778.6 65986.7 71589.6 71920.6 72159.3 74249.0 9418.3 9632.3 9702.8 9720.4 9936.4 12959.4 13060.9 13094.7 13201.8 13236.4 6996.5 7178.7 7195.2 7452.5 7462.7 6937.6 6973.9 7192.7 7195.3 7205.1 6334.7 6352.8 6412.6 6441.8 6599.9 1665.5 1703.7 1706.8 1715.6 1735.7 818.7 829.8 832.4 846.3 854.3 17031.4 17100.8 18636.9 18736.6 18883.6 833.0 836.5 853.4 858.1 860.7 9884.3 10556.2 10680.7 10700.6 10808.2 473449.0 473601.5 482454.0 486460.0 486621.0 74373.3 75475.6 75496.6 76997.2 78628.0 979.4 997.1 998.5 1004.8 1005.0 15389.4 15606.5 15620.5 15805.5 16193.2 35635.3 35909.5 36304.8 36605.5 36832.8 47900.5 47969.0 48058.0 48173.0 49074.0 7461.6 7485.4 7491.2 7503.1 7953.0 11553.2 12360.9 12582.5 12892.4 13016.3 5556.0 10317.3 10825.2 10898.7 11170.3 10528.6 10743.1 10797.5 10855.8 11124.6 10537.5 10772.6 10934.1 11075.5 11442.2 7560.9 7633.3 7643.1 7694.5 7733.1 10578.3 10585.1 10752.4 10778.4 11000.5 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#3] mootools.html Cycles:180 Average:4351.96 Median:5111.83 stddev:2256.58 (44.1%) stddev-sans-first:2251.46 04:02:46 INFO - PROCESS | 19079 | Values: 1327.4 1333.7 1338.9 1345.1 1350.1 6235.6 6526.3 6528.3 6545.5 6552.5 5767.6 5956.0 6123.5 6297.1 6332.5 1986.5 2012.9 2015.2 2020.1 2021.1 1942.3 2034.5 2043.9 2045.0 2047.3 1766.9 1818.0 1947.0 1963.5 1997.3 1985.8 1992.1 1995.2 1999.1 2002.2 1955.4 1973.3 1981.8 1982.6 1982.7 6636.1 6653.4 6671.3 6673.3 6674.9 4028.4 4123.2 4131.0 4134.9 4139.7 2665.0 2706.3 2768.8 2806.7 2823.4 1029.3 1073.4 1081.1 1109.0 1123.4 8259.7 8268.5 8320.8 8372.7 8413.2 3473.4 3643.5 3668.8 3670.2 3728.2 7602.0 7618.2 7626.6 7627.9 7668.6 4055.8 4097.0 4102.8 4119.2 4194.0 3746.4 3771.3 3772.5 3774.4 3784.8 3052.5 3118.9 3128.5 3154.1 3155.5 928.8 948.6 954.6 960.3 976.2 833.9 857.3 865.5 878.5 881.9 4348.0 4585.9 4625.9 4698.2 4719.8 827.5 837.6 840.0 885.6 902.4 3191.7 3226.4 3250.9 3313.9 3323.8 7480.9 7832.4 7832.4 7835.8 7849.8 7747.6 7749.3 7756.2 7757.1 7762.2 6273.9 6301.4 6314.3 6349.2 6374.0 4515.7 4610.7 4659.0 4663.5 4725.5 7052.4 7057.4 7085.6 7154.8 7178.5 7153.2 7234.5 7288.4 7309.7 7344.2 5568.4 5598.1 5655.3 5659.0 5710.4 6222.5 6277.1 6283.7 6284.3 6298.0 5494.2 5548.4 5550.0 5598.9 5641.1 5585.8 5590.3 5637.5 5671.8 5722.0 5090.5 5133.2 5136.7 5148.0 5156.4 3888.1 3958.9 3988.9 4027.4 4100.2 3921.6 3931.0 4022.2 4051.4 4175.5 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#4] prototype.html Cycles:180 Average:4207.57 Median:4795.84 stddev:1227.08 (25.6%) stddev-sans-first:1194.82 04:02:46 INFO - PROCESS | 19079 | Values: 292.8 304.1 310.1 315.8 317.8 4719.0 4775.4 4858.9 4899.7 4939.4 4704.6 4816.8 4824.0 4833.8 4841.7 3815.3 3816.5 3826.1 3852.5 3877.6 3696.6 3800.9 3801.9 3868.2 3868.9 3804.2 3829.6 3830.2 3848.5 3856.6 3815.4 3847.8 3854.6 3859.0 3863.2 3238.4 3312.9 3344.2 3403.2 3428.3 4743.0 4748.2 4750.9 4758.3 4761.9 4354.8 4362.7 4363.6 4366.5 4382.6 4796.4 5000.2 5052.4 5078.8 5081.1 3566.3 3574.0 3574.2 3576.8 3580.9 5313.8 5362.6 5372.0 5391.9 5403.0 3574.0 3591.4 3653.5 3668.6 3677.7 3666.9 3677.6 3678.9 3684.7 3689.1 4076.9 4108.7 4181.9 4228.3 4232.0 5082.0 5097.9 5122.4 5128.6 5137.0 1215.9 1225.5 1230.8 1234.7 1241.6 795.6 834.4 834.7 847.9 850.2 4339.0 4340.3 4355.4 4358.6 4380.3 5115.5 5144.3 5153.6 5182.9 5202.5 4222.6 4314.5 4363.7 4366.0 4379.8 2675.9 2728.8 2745.3 2745.7 2751.3 4746.4 4793.2 4795.3 4797.3 4807.8 5081.1 5186.5 5239.8 5240.3 5306.9 4921.4 4922.1 4943.9 4948.5 4958.9 5261.4 5274.9 5291.3 5292.2 5306.6 4930.6 5148.6 5192.4 5193.9 5211.4 4867.6 4904.0 4909.2 4909.3 4921.4 3827.8 3842.2 3885.4 3897.3 3904.2 5106.0 5239.4 5329.7 5343.5 5346.2 4868.4 4900.8 4964.6 5036.1 5047.8 5059.9 5067.3 5068.8 5071.7 5083.3 4877.7 4982.0 4983.7 5012.5 5019.6 5092.2 5117.7 5123.2 5130.6 5138.8 5019.3 5020.4 5020.6 5028.7 5033.9 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - PROCESS | 19079 | [#5] yui.html Cycles:180 Average:14612.03 Median:56789.72 stddev:18819.35 (33.1%) stddev-sans-first:18843.06 04:02:46 INFO - PROCESS | 19079 | Values: 679.0 691.4 746.6 787.9 806.4 11969.8 13158.5 13195.7 13234.7 13247.3 11525.7 11585.7 11671.1 11673.5 11676.8 634.6 668.4 780.5 803.1 816.7 822.4 823.7 824.4 824.7 825.2 870.2 870.5 870.7 871.0 871.6 680.6 707.2 827.3 861.2 866.5 634.2 635.7 636.3 636.7 636.9 12907.7 12912.5 12919.8 12928.3 12947.5 13143.7 13151.6 13169.8 13170.3 13316.7 11941.7 12028.2 12046.5 12283.0 12369.3 4163.2 4193.8 4194.4 4375.5 4379.6 61612.6 62527.0 63058.7 63821.7 64282.4 9336.6 9489.1 10136.2 10188.0 10202.7 19206.4 19592.0 19611.2 19612.2 19636.3 6539.6 6551.9 6625.0 6725.2 6764.8 3651.3 3654.6 3679.5 3709.4 3727.3 2953.8 2999.9 3005.2 3010.3 3015.8 479.7 482.6 484.3 490.8 493.7 547.5 559.0 561.3 562.6 576.6 8145.0 8406.4 8412.2 8780.2 8803.3 527.0 549.3 550.5 561.3 563.6 3834.8 4021.4 4031.3 4089.6 4157.3 65314.0 67338.7 69860.7 70001.6 70231.0 47384.0 48177.8 49954.5 59640.0 60266.0 53105.7 54875.7 55160.4 56649.7 56929.7 7389.0 7591.5 7633.2 7725.2 7822.6 37622.4 38059.8 38317.6 38496.8 39665.4 44501.3 46630.5 46749.0 46849.0 48802.0 8753.0 9140.1 9437.6 9466.5 9547.7 10075.7 10107.9 10174.9 10209.7 10259.9 9649.8 9826.9 9906.6 10020.6 10250.9 9631.7 9756.2 9815.7 9909.8 9930.3 9266.3 9273.4 9551.7 9588.3 9662.6 8137.2 8179.8 8290.9 8344.0 8586.4 7428.1 7673.1 7724.7 7804.9 7945.0 04:02:46 INFO - PROCESS | 19079 | -------- Summary: end -------- 04:02:46 INFO - PROCESS | 19079 | 04:02:46 INFO - TEST-INFO | 19079: exit 0 04:02:46 INFO - TEST-OK | dromaeo_css | took 1120851ms 04:02:46 INFO - TEST-START | kraken 04:02:46 INFO - operating with platform_type : linux_ 04:02:46 INFO - Initialising browser for kraken test... 04:02:46 INFO - TEST-INFO | started process 19375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVDQicy/profile http://localhost:54504/getInfo.html) 04:02:50 INFO - PROCESS | 19375 | __metrics Screen width/height:1600/1200 04:02:50 INFO - PROCESS | 19375 | colorDepth:24 04:02:50 INFO - PROCESS | 19375 | Browser inner width/height: 1024/697 04:02:50 INFO - PROCESS | 19375 | __metrics 04:02:51 INFO - PROCESS | 19375 | 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" 04:02:51 INFO - PROCESS | 19375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:52 INFO - TEST-INFO | 19375: exit 0 04:02:52 INFO - Browser initialized. 04:02:52 INFO - Running cycle 1/1 for kraken test... 04:02:52 INFO - TEST-INFO | started process 19460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVDQicy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:04 INFO - PROCESS | 19460 | RSS: Main: 151588864 04:03:04 INFO - PROCESS | 19460 | 04:03:07 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54504/tests/kraken/driver.html?testName=audio-beat-detection) 04:03:08 INFO - PROCESS | 19460 | RSS: Main: 206077952 04:03:08 INFO - PROCESS | 19460 | 04:03:10 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54504/tests/kraken/driver.html?testName=audio-dft) 04:03:11 INFO - PROCESS | 19460 | RSS: Main: 185708544 04:03:11 INFO - PROCESS | 19460 | 04:03:14 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54504/tests/kraken/driver.html?testName=audio-fft) 04:03:15 INFO - PROCESS | 19460 | RSS: Main: 268013568 04:03:15 INFO - PROCESS | 19460 | 04:03:17 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54504/tests/kraken/driver.html?testName=audio-oscillator) 04:03:17 INFO - PROCESS | 19460 | RSS: Main: 179109888 04:03:17 INFO - PROCESS | 19460 | 04:03:20 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54504/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:03:20 INFO - PROCESS | 19460 | RSS: Main: 183275520 04:03:20 INFO - PROCESS | 19460 | 04:03:23 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54504/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:24 INFO - PROCESS | 19460 | RSS: Main: 222466048 04:03:24 INFO - PROCESS | 19460 | 04:03:27 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54504/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:28 INFO - PROCESS | 19460 | RSS: Main: 216698880 04:03:28 INFO - PROCESS | 19460 | 04:03:31 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54504/tests/kraken/driver.html?testName=json-parse-financial) 04:03:32 INFO - PROCESS | 19460 | RSS: Main: 218447872 04:03:32 INFO - PROCESS | 19460 | 04:03:33 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54504/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:34 INFO - PROCESS | 19460 | RSS: Main: 181923840 04:03:34 INFO - PROCESS | 19460 | 04:03:36 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:36 INFO - PROCESS | 19460 | RSS: Main: 189251584 04:03:36 INFO - PROCESS | 19460 | 04:03:39 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:39 INFO - PROCESS | 19460 | RSS: Main: 197783552 04:03:39 INFO - PROCESS | 19460 | 04:03:42 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:43 INFO - PROCESS | 19460 | RSS: Main: 206512128 04:03:43 INFO - PROCESS | 19460 | 04:03:46 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:46 INFO - PROCESS | 19460 | RSS: Main: 203538432 04:03:46 INFO - PROCESS | 19460 | 04:03:48 INFO - PROCESS | 19460 | Cycle 1(1): loaded http://localhost:54504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54504/tests/kraken/driver.html?testName=ai-astar) 04:03:48 INFO - PROCESS | 19460 | RSS: Main: 253480960 04:03:48 INFO - PROCESS | 19460 | 04:03:48 INFO - PROCESS | 19460 | __start_tp_report 04:03:48 INFO - PROCESS | 19460 | _x_x_mozilla_page_load 04:03:48 INFO - PROCESS | 19460 | _x_x_mozilla_page_load_details 04:03:48 INFO - PROCESS | 19460 | |i|pagename|runs| 04:03:48 INFO - PROCESS | 19460 | |0;ai-astar;105;114;113;113;114;114;113;113;115;114 04:03:48 INFO - PROCESS | 19460 | |1;audio-beat-detection;144;128;129;162;123;125;122;123;144;122 04:03:48 INFO - PROCESS | 19460 | |2;audio-dft;161;160;161;162;161;163;162;162;161;159 04:03:48 INFO - PROCESS | 19460 | |3;audio-fft;76;75;76;103;76;77;78;76;92;75 04:03:48 INFO - PROCESS | 19460 | |4;audio-oscillator;84;84;119;86;85;84;99;85;85;110 04:03:48 INFO - PROCESS | 19460 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 04:03:48 INFO - PROCESS | 19460 | |6;imaging-darkroom;149;149;149;150;150;149;148;150;150;149 04:03:48 INFO - PROCESS | 19460 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;95;95 04:03:48 INFO - PROCESS | 19460 | |8;json-parse-financial;71;67;74;67;68;68;70;70;67;69 04:03:48 INFO - PROCESS | 19460 | |9;json-stringify-tinderbox;66;66;67;67;67;67;67;68;67;67 04:03:48 INFO - PROCESS | 19460 | |10;stanford-crypto-aes;84;84;84;86;84;85;84;85;90;90 04:03:48 INFO - PROCESS | 19460 | |11;stanford-crypto-ccm;136;131;136;130;147;132;144;145;172;157 04:03:48 INFO - PROCESS | 19460 | |12;stanford-crypto-pbkdf2;177;180;180;178;181;177;177;179;177;176 04:03:48 INFO - PROCESS | 19460 | |13;stanford-crypto-sha256-iterative;72;69;70;68;68;68;68;70;68;67 04:03:48 INFO - PROCESS | 19460 | __end_tp_report 04:03:48 INFO - PROCESS | 19460 | __start_cc_report 04:03:48 INFO - PROCESS | 19460 | _x_x_mozilla_cycle_collect,1815 04:03:49 INFO - PROCESS | 19460 | __end_cc_report 04:03:49 INFO - PROCESS | 19460 | __startTimestamp1484049828990__endTimestamp 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | ------- Summary: start ------- 04:03:49 INFO - PROCESS | 19460 | Number of tests: 14 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 04:03:49 INFO - PROCESS | 19460 | Values: 105.0 114.0 113.0 113.0 114.0 114.0 113.0 113.0 115.0 114.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:128.50 stddev:13.43 (10.5%) stddev-sans-first:13.55 04:03:49 INFO - PROCESS | 19460 | Values: 144.0 128.0 129.0 162.0 123.0 125.0 122.0 123.0 144.0 122.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.14 (0.7%) stddev-sans-first:1.20 04:03:49 INFO - PROCESS | 19460 | Values: 161.0 160.0 161.0 162.0 161.0 163.0 162.0 162.0 161.0 159.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:9.42 (12.4%) stddev-sans-first:9.85 04:03:49 INFO - PROCESS | 19460 | Values: 76.0 75.0 76.0 103.0 76.0 77.0 78.0 76.0 92.0 75.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:85.00 stddev:12.81 (15.1%) stddev-sans-first:13.25 04:03:49 INFO - PROCESS | 19460 | Values: 84.0 84.0 119.0 86.0 85.0 84.0 99.0 85.0 85.0 110.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:03:49 INFO - PROCESS | 19460 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 04:03:49 INFO - PROCESS | 19460 | Values: 149.0 149.0 149.0 150.0 150.0 149.0 148.0 150.0 150.0 149.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:03:49 INFO - PROCESS | 19460 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#8] json-parse-financial Cycles:10 Average:69.10 Median:69.50 stddev:2.23 (3.2%) stddev-sans-first:2.26 04:03:49 INFO - PROCESS | 19460 | Values: 71.0 67.0 74.0 67.0 68.0 68.0 70.0 70.0 67.0 69.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 04:03:49 INFO - PROCESS | 19460 | Values: 66.0 66.0 67.0 67.0 67.0 67.0 67.0 68.0 67.0 67.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:2.41 (2.8%) stddev-sans-first:2.49 04:03:49 INFO - PROCESS | 19460 | Values: 84.0 84.0 84.0 86.0 84.0 85.0 84.0 85.0 90.0 90.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:144.50 stddev:13.29 (9.2%) stddev-sans-first:13.85 04:03:49 INFO - PROCESS | 19460 | Values: 136.0 131.0 136.0 130.0 147.0 132.0 144.0 145.0 172.0 157.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:178.50 stddev:1.69 (0.9%) stddev-sans-first:1.73 04:03:49 INFO - PROCESS | 19460 | Values: 177.0 180.0 180.0 178.0 181.0 177.0 177.0 179.0 177.0 176.0 04:03:49 INFO - PROCESS | 19460 | 04:03:49 INFO - PROCESS | 19460 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:68.50 stddev:1.48 (2.2%) stddev-sans-first:1.01 04:03:49 INFO - PROCESS | 19460 | Values: 72.0 69.0 70.0 68.0 68.0 68.0 68.0 70.0 68.0 67.0 04:03:49 INFO - PROCESS | 19460 | -------- Summary: end -------- 04:03:49 INFO - PROCESS | 19460 | 04:03:50 INFO - TEST-INFO | 19460: exit 0 04:03:50 INFO - TEST-OK | kraken | took 63332ms 04:03:50 INFO - SUITE-END | took 1184s 04:03:50 INFO - Completed test suite (00:19:44) 04:03:50 INFO - javascript benchmark 04:03:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2552.910648127315, 2563.179782469608, 2584.0, 2589.300888760035, 2596.2341109372733, 4594.908101837961, 4639.048994956034, 4659.580637742602, 4661.254199328108, 4709.552592503711, 4645.488996210414, 4681.719096854191, 4686.531346865312, 4721.0, 4723.976380118094, 4265.573442655726, 4282.207791558571, 4316.028893498969, 4349.0, 4423.380726698265, 3955.356564786278, 4291.399204111427, 4341.435613370258, 4345.130973805236, 4400.889977750549, 4225.408442817978, 4250.829966801324, 4312.28847240205, 4338.804753786056, 4416.933745993812, 4237.067845074079, 4245.426867372896, 4291.098869237464, 4301.204277208695, 4351.912961740747, 4267.765272909989, 4270.29540125882, 4277.679174061957, 4302.3546468030045, 4319.892002699926, 4634.629229661619, 4642.573728682431, 4650.0932318197965, 4654.743989080599, 4660.324252983299, 4839.37088178538, 4860.878478038077, 4863.416390033183, 4932.8520144395725, 4977.402711674586, 4954.529319714621, 4991.4758950310425, 5029.673071250357, 5046.1169295373165, 5093.898122037538, 4734.479207287196, 4735.21868891629, 4740.6681532292405, 4778.211595086772, 4783.6651434399955, 5149.794008239628, 5152.819651312186, 5158.535731784157, 5161.0, 5195.454477279907, 4801.879953001133, 4815.975920120377, 4855.223164293696, 4890.608751299887, 4946.604271658259, 4927.285543596158, 4959.2561115832195, 4974.452810190876, 5059.569936555434, 5071.923921141186, 4876.244182151772, 4957.0, 4962.429320628102, 4965.950340496549, 5024.673396229234, 4983.90032199354, 4986.900261994812, 4996.950030499721, 5000.424951130593, 5022.121128802445, 4156.709030367906, 4241.151769646083, 4249.2563801334645, 4258.446401967724, 4287.63555097814, 3651.598324184338, 3679.7976111314133, 3695.5011073504734, 3708.7033037356946, 3757.511523501927, 4318.157959197989, 4322.848700295537, 4329.242382583098, 4330.588594083557, 4366.563343665608, 5042.470540593258, 5043.5712964398435, 5045.873853153627, 5053.343065401475, 5089.389273287229, 4206.432131662247, 4248.299030659907, 4248.34150706641, 4264.0832221072, 4266.765327906933, 4501.077279157765, 4522.819087236474, 4524.117797029613, 4551.0215303709865, 4563.133004729091, 4684.81260749573, 4696.084263568571, 4698.530146985274, 4703.270992996091, 4741.810327586996, 4904.607631389552, 4937.975310123426, 4940.431850337257, 4946.0, 5021.874453138701, 4673.836415725434, 4677.508861569486, 4679.087577922272, 4704.012157446974, 4712.057588482248, 4901.5588597026435, 4926.211806111005, 4927.531884470969, 4928.162212423825, 4977.601791856715, 4906.607471402351, 4941.876453088702, 4976.452590215001, 4976.601871850171, 5014.247862820606, 4855.36880205571, 4881.90236195267, 4883.121038213156, 4917.852464426073, 4926.630502712239, 4895.73073480962, 4903.436104847988, 4904.068227036852, 4904.607631389552, 4951.232558953368, 4688.34363189147, 4739.075880203465, 4764.49972752856, 4768.499307572655, 4770.117528257283, 4875.561199492063, 4897.41231052276, 4904.337914381513, 4935.383077115361, 4939.580135688507, 4787.904241915212, 4791.401074865642, 4792.2092854678585, 4794.400699912511, 4841.031793641357], "value": 4583.591684843261, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.2239806692861, 531.2138035707151, 539.4686234059453, 552.0063885006987, 552.682207730555, 29393.412131757377, 30045.69954300459, 39010.21979560407, 40670.593294067054, 41003.669522962045, 38395.23209535808, 42859.35710964338, 42928.35607465891, 42991.78504107483, 42995.35506967398, 7660.846783064321, 7817.2964433201005, 7835.216478352177, 7840.0, 7844.529328240295, 7301.780946571611, 7658.0, 7853.528788272693, 9741.951290243538, 10003.799924001516, 10184.694459166238, 10230.43732594707, 10237.385756854575, 10252.38485690861, 10253.282270241087, 10101.494925253744, 10129.392236465836, 10134.746631334201, 10152.593896244141, 10164.796704065951, 9979.950100249525, 10049.648262310857, 10058.245631577662, 10065.094141527297, 10065.748356291078, 40081.0, 40415.797921010504, 40458.19083618341, 40570.39144412836, 40615.0, 3587.121155316964, 3742.3076730804887, 3742.438634204864, 3765.2867945085195, 3787.0, 29840.850795745882, 30000.8499957501, 30119.397612047636, 30305.696943030507, 31410.37179256402, 3334.299797042624, 3365.646607106243, 3377.7128944039787, 3383.2218589724503, 3386.542816719736, 252029.0, 252323.0, 252621.0, 253035.0, 253223.0, 54579.63362930956, 54831.177532337046, 54869.45130548723, 54978.45021549813, 55006.72496637571, 58976.41023589709, 59052.40947590555, 59254.70372648109, 59304.11043834346, 59377.70311148417, 14997.925010375096, 15504.147271899928, 15508.5347439577, 15523.0, 15530.068195908281, 10009.899901001043, 11371.601993930173, 11416.94291528537, 11418.543258269576, 11429.657110286706, 14988.400463981538, 17072.658546828992, 17167.914160429118, 17474.0, 18101.0, 6472.514561407819, 6495.30292174187, 6495.350464953562, 6508.707108180049, 6576.342365763481, 794.408165916396, 811.9512829230265, 819.5082950229814, 821.4660470693977, 843.409613270713, 26111.216663500127, 27345.31636709098, 27518.724812752018, 27771.861140694167, 27860.721392785814, 867.7657032601162, 877.3595275448959, 881.4667126388442, 894.1371576428777, 899.7120921305119, 13911.582652520441, 14416.42334306637, 14536.418543258364, 14623.85376146225, 14797.704045919236, 361361.0, 364171.0, 364462.0, 365350.0, 365413.7668986528, 50575.0, 51096.489035109174, 51208.74395627998, 51227.0, 51366.0, 1237.2143688757703, 1273.6370134511806, 1294.7022184897526, 1295.2617008305176, 1295.7732396830518, 25141.0, 26294.0, 26686.932522698873, 27152.72847271502, 27180.592291116045, 42655.57344426516, 43514.34728479139, 43525.56474435342, 43542.34686479642, 43571.34642980294, 286233.0, 286528.0, 286680.13319867366, 286885.0, 289936.0, 31757.20606984844, 31807.250601217154, 36773.63226367809, 37166.62833371632, 37246.0, 34915.65084349124, 36278.63721362858, 36432.45351319679, 36435.45346819853, 36454.27091458209, 35618.46572301469, 35686.0, 35725.64274357223, 35967.100822479646, 36650.633493664725, 35566.822165889, 35752.463713043806, 36235.45646815353, 36309.45535817018, 36508.26983460369, 33710.32579348448, 34546.65453345434, 34830.82584587061, 35160.29679406346, 35284.0, 29448.171737674824, 31543.52684709777, 31802.0, 31852.362952741278, 31962.0, 23462.38624320174, 23583.29250122499, 23870.641940370562, 23873.151077986004, 23951.28146155618], "value": 17905.822227054825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.7019422907841, 339.5365326329561, 350.6370906112174, 353.8566880413433, 360.75468681296707, 16941.15294235288, 17837.821621783794, 18283.90858045711, 18291.451256462293, 18497.0751462427, 17866.553336166602, 18157.7276340855, 18636.068196590186, 18762.812371876276, 18777.403920666726, 4704.929426058612, 5115.9744201278945, 5161.458046905077, 5166.276721259027, 5171.456997015315, 3858.884233473, 4063.634272915437, 4074.5110586729634, 5112.821051263207, 5200.08998425276, 5896.145058966447, 5913.733881975321, 5997.37027612101, 5999.550033747487, 6073.180120683717, 5877.265341832271, 6002.159697642333, 6034.1853849730605, 6059.939400606004, 6070.0, 5299.920501192485, 5302.549283310885, 5303.204519322133, 5308.469153084699, 5309.150535914235, 21252.468688282763, 21530.461738456506, 21689.240876569245, 21794.346169614935, 22435.663465047874, 2189.2228258968157, 2227.6881236626887, 2244.472548950995, 2247.516783891455, 2319.8724070176136, 13397.799033014513, 14005.159690418506, 14097.2951352433, 14229.501967431193, 14572.417103315855, 1727.5249306440703, 1746.240385432341, 1758.9912050439666, 1759.2083562396972, 1778.6175972166047, 65986.67006664984, 71589.64205178992, 71920.64039679768, 72159.27840721578, 74249.0, 9418.34071614994, 9632.27757918159, 9702.75743106414, 9720.368176068532, 9936.354136981074, 12959.352032398343, 13060.934695326463, 13094.672633184247, 13201.801972970414, 13236.404361803741, 6996.475264355092, 7178.748743793943, 7195.172555156119, 7452.478326517092, 7462.664180111799, 6937.583744975318, 6973.93026069743, 7192.748253811092, 7195.316444937723, 7205.099362579677, 6334.683265836689, 6352.8411789704705, 6412.578405294249, 6441.838954026093, 6599.934000660028, 1665.5003498950266, 1703.7018521758644, 1706.78271458302, 1715.5625315544457, 1735.7222844344844, 818.6561644109488, 829.809143896895, 832.4006715165071, 846.2975730143966, 854.2567965869733, 17031.40390086341, 17100.828991710172, 18636.906815465834, 18736.625267494575, 18883.62232755337, 832.9666813327399, 836.5148214035845, 853.4452605806151, 858.0989960541407, 860.6858496648804, 9884.30809843304, 10556.208284378703, 10680.679579612624, 10700.571977120984, 10808.189385796097, 473449.0, 473601.4877470838, 482454.0, 486460.0, 486621.0, 74373.25626743663, 75475.62262188653, 75496.62251688706, 76997.23002770012, 78628.0, 979.3536266064364, 997.0627610046533, 998.4907697074544, 1004.7588578741196, 1005.0251256281396, 15389.383804752995, 15606.531804045897, 15620.453284135001, 15805.52583422499, 16193.190340482694, 35635.28729425345, 35909.461358080174, 36304.81847590745, 36605.450918236784, 36832.81583592065, 47900.52099478961, 47969.0, 48058.0, 48173.0, 49074.01851962869, 7461.626918654154, 7485.363744081814, 7491.213422590549, 7503.137139229058, 7953.045634523663, 11553.24903881245, 12360.876391235972, 12582.496700131893, 12892.419841106985, 13016.349182540645, 5556.0, 10317.27779055459, 10825.188110891557, 10898.673039808818, 11170.324451332364, 10528.578856845947, 10743.140548756237, 10797.514111864828, 10855.837162442727, 11124.61063862761, 10537.52581133866, 10772.622958196425, 10934.125269978542, 11075.501602428072, 11442.199046066695, 7560.924390756022, 7633.274838890296, 7643.121041080347, 7694.499857509244, 7733.072031356273, 10578.312409693346, 10585.100266477128, 10752.40861752611, 10778.407187604756, 11000.504977276201], "value": 8562.251946722474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.3827670133387, 1333.65325015496, 1338.9330533473324, 1345.0584590786452, 1350.0549615269308, 6235.594686345385, 6526.347365263471, 6528.31452697467, 6545.50908681849, 6552.475801935846, 5767.625104368213, 5956.0172571525645, 6123.540734444912, 6297.08692239625, 6332.46174075203, 1986.5430950881341, 2012.949676258098, 2015.1536354731047, 2020.05057622917, 2021.070307658479, 1942.3493129801534, 2034.450698311455, 2043.8875861827594, 2045.0490521907311, 2047.2732180076082, 1766.9028203448804, 1817.9818201818011, 1946.9902650486729, 1963.4993076765477, 1997.3009446693613, 1985.8312043476321, 1992.0537744571357, 1995.2019192323041, 1999.1203870297168, 2002.1891134090718, 1955.354732938127, 1973.319204874316, 1981.7919118492496, 1982.5539253667953, 1982.6827707566865, 6636.070950066995, 6653.401193892524, 6671.299513551105, 6673.265940746514, 6674.866502669919, 4028.395740638898, 4123.195976784498, 4130.958690413087, 4134.91730165398, 4139.689523285766, 2665.0, 2706.323419145214, 2768.750812426871, 2806.6912639609627, 2823.407084512255, 1029.330934892326, 1073.3881687438163, 1081.0702795595782, 1108.972275693106, 1123.3821398230941, 8259.710910118118, 8268.462549934235, 8320.750377488625, 8372.748817535483, 8413.158684131598, 3473.4095203815414, 3643.4716966040305, 3668.816559172031, 3670.2292518570866, 3728.2357116790995, 7602.0, 7618.238176182448, 7626.58053807046, 7627.923720762832, 7668.6165691716305, 4055.8174882130374, 4096.979515102465, 4102.774347410921, 4119.155573107506, 4194.0, 3746.438034294823, 3771.321162190777, 3772.5095737554493, 3774.414965680324, 3784.810759462016, 3052.526858336961, 3118.8908388206305, 3128.5150801625327, 3154.1168472827644, 3155.5108958111527, 928.823523530527, 948.6442584030989, 954.5942974235921, 960.2558017536466, 976.1848856205006, 833.8874251976027, 857.3055824781947, 865.485036403339, 878.510923983854, 881.8853549038583, 4347.978260108679, 4585.885352866205, 4625.861224163281, 4698.248280275208, 4719.834805781781, 827.4952279109739, 837.6063250272363, 839.970601028961, 885.5527958381106, 902.4044130873723, 3191.69678880506, 3226.3869864725625, 3250.9187270317957, 3313.9171520711693, 3323.7839540429795, 7480.8877866832045, 7832.373410127176, 7832.373410127176, 7835.84328313442, 7849.843003140019, 7747.612619369122, 7749.302562769378, 7756.224377562312, 7757.107932587712, 7762.184970578121, 6273.905891411632, 6301.4328710416285, 6314.305426403092, 6349.206349206349, 6374.043893416025, 4515.661325400542, 4610.700304480198, 4658.976705116453, 4663.487016428258, 4725.456572494069, 7052.435805135474, 7057.36483716468, 7085.610291434021, 7154.785356439315, 7178.533395329287, 7153.177384600837, 7234.457415693949, 7288.380487658608, 7309.707611695472, 7344.192138864613, 5568.387477377404, 5598.10430331145, 5655.321361436654, 5658.971705141448, 5710.400407957105, 6222.502199823913, 6277.089821975696, 6283.654399008098, 6284.277308109626, 6298.02380631003, 5494.230807686847, 5548.389677135593, 5550.0, 5598.944010559842, 5641.097424412074, 5585.804496842591, 5590.301212348457, 5637.492625663546, 5671.801486947937, 5722.0272553665145, 5090.49095090488, 5133.178691409356, 5136.717480538607, 5148.021875843578, 5156.4070131935305, 3888.125171836314, 3958.8614398495915, 3988.8603898863403, 4027.416024676464, 4100.220958017967, 3921.60783921617, 3931.017245688578, 4022.2156679447226, 4051.39229115635, 4175.519815221289], "value": 3620.3269774979353, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.8257686676427, 304.0877367896311, 310.079065176426, 315.80893559396566, 317.764854008034, 4719.0, 4775.379200703908, 4858.902821943559, 4899.706017638944, 4939.382577177853, 4704.553067458594, 4816.807327706888, 4823.951760482394, 4833.78247978841, 4841.709497430148, 3815.332316844562, 3816.484774555427, 3826.081740382316, 3852.459016393437, 3877.612238776128, 3696.574893887197, 3800.8859734208017, 3801.9354580717445, 3868.2263547290513, 3868.903277418059, 3804.239152169563, 3829.636184562462, 3830.157365379626, 3848.537555728833, 3856.595057518962, 3815.389537673973, 3847.826847791856, 3854.5759966403675, 3859.0, 3863.150106976461, 3238.352329534079, 3312.9006129816144, 3344.1973926257765, 3403.1832360233366, 3428.3486137634018, 4742.9525704742855, 4748.171953797812, 4750.904981900343, 4758.334582895994, 4761.904761904742, 4354.847580334705, 4362.716423432498, 4363.629091527224, 4366.519682834885, 4382.649388048949, 4796.424429068499, 5000.224920153366, 5052.393712754493, 5078.822241221576, 5081.136206844845, 3566.286742651454, 3573.9992802015477, 3574.2315402188656, 3576.8390422431053, 3580.8541266794637, 5313.840584782462, 5362.624616276862, 5372.0, 5391.946080539223, 5402.972985135049, 3574.0, 3591.3535563598803, 3653.50677658518, 3668.63313668631, 3677.687396571268, 3666.8533258669886, 3677.558692956862, 3678.926421471609, 3684.6683798458266, 3689.096171438015, 4076.9184616307507, 4108.671306295549, 4181.874543763692, 4228.2811921972725, 4231.978840105779, 5081.9745901271, 5097.949020509822, 5122.385313762298, 5128.589712822965, 5136.998285334324, 1215.9392030398444, 1225.454672670653, 1230.7846126927752, 1234.703671118943, 1241.6213055018125, 795.6021989005498, 834.3992325525612, 834.6661335465741, 847.9364047696447, 850.1838235294064, 4338.956610433918, 4340.283853164256, 4355.390245365651, 4358.585934336233, 4380.2697934315765, 5115.462876397924, 5144.32809336967, 5153.587712982952, 5182.870428239249, 5202.453742356998, 4222.577742225814, 4314.482262128565, 4363.672724545609, 4366.0, 4379.824807007748, 2675.9464810703676, 2728.7953403494816, 2745.341118131634, 2745.697973222944, 2751.339678477151, 4746.38297021385, 4793.209120495079, 4795.256735206049, 4797.328374027639, 4807.807687692523, 5081.110805609003, 5186.507281808297, 5239.816606418757, 5240.34495688039, 5306.867328316823, 4921.360223170965, 4922.138625740481, 4943.851684449401, 4948.536024782667, 4958.876028099255, 5261.421573528003, 5274.89450210986, 5291.3121294231505, 5292.168689750964, 5306.593752655626, 4930.587124523603, 5148.613853961043, 5192.3769147702515, 5193.870153246199, 5211.400688920823, 4867.58625516835, 4904.0, 4909.165441874961, 4909.288153217834, 4921.434035086011, 3827.808609569566, 3842.193139440749, 3885.4171874217964, 3897.259520691173, 3904.1606054698377, 5105.974470127626, 5239.4236633970995, 5329.653572517774, 5343.450399384135, 5346.224797404432, 4868.41579010521, 4900.754962251945, 4964.627652925973, 5036.118679231193, 5047.823326183566, 5059.898802023865, 5067.341245638044, 5068.797248110034, 5071.721055341992, 5083.262926875508, 4877.6829506081995, 4981.975090124526, 4983.700977941335, 5012.4736902626155, 5019.598432125495, 5092.185250359925, 5117.692938423557, 5123.154679477844, 5130.615203859651, 5138.794448222029, 5019.347484826949, 5020.422651395136, 5020.623453240948, 5028.723420211923, 5033.899322013466], "value": 3815.3180198377518, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.9898151527725, 691.4433880726017, 746.5632604926118, 787.8739401695728, 806.4153488720679, 11969.7606047879, 13158.473661053546, 13195.736085278313, 13234.669133271662, 13247.271400072992, 11525.654230373102, 11585.652430427079, 11671.066314694825, 11673.533058677664, 11676.824847627271, 634.6287421858218, 668.4218151299116, 780.5160800303792, 803.0564087197549, 816.7345612675904, 822.4325215601216, 823.7199352220244, 824.4064273722939, 824.7360844529753, 825.1913125137369, 870.2211520688979, 870.5299138465222, 870.7257213977576, 870.9738707838775, 871.6469829718974, 680.6120511308557, 707.1690763353065, 827.3257295304307, 861.1990848510883, 866.5060915278294, 634.1692382978313, 635.6630985577605, 636.3032479435011, 636.700750647195, 636.8630744389978, 12907.741845163138, 12912.548060817826, 12919.806202906964, 12928.28894410807, 12947.5468358607, 13143.737125257538, 13151.60545183646, 13169.769033192148, 13170.34148292591, 13316.667083322898, 11941.701457463547, 12028.158028937978, 12046.457909394097, 12283.0, 12369.304101993903, 4163.167366526676, 4193.811278492475, 4194.412782210493, 4375.5405682403525, 4379.562043795627, 61612.58916330579, 62527.0, 63058.748243952075, 63821.68089159571, 64282.35717642857, 9336.579853906454, 9489.098535639103, 10136.239782016379, 10188.032136946978, 10202.744931376626, 19206.423807285802, 19592.0, 19611.21555137807, 19612.21551137967, 19636.312729054414, 6539.574927629689, 6551.934480655228, 6624.966875165594, 6725.19297684281, 6764.763233286812, 3651.3062518121474, 3654.5614526256486, 3679.4848721179055, 3709.4064949608473, 3727.254549090193, 2953.7784666150123, 2999.925001874971, 3005.218643152796, 3010.3076292452856, 3015.8341291229194, 479.70977558577255, 482.58015526491477, 484.29776823605914, 490.80122550367156, 493.73091665042637, 547.4552819944101, 559.0329918672543, 561.2871653000666, 562.5948820733727, 576.6453631016894, 8145.0, 8406.41155119148, 8412.200840920103, 8780.234226218721, 8803.251723603438, 527.0118527760449, 549.3215878390185, 550.5402988504563, 561.2983770287141, 563.6420872745754, 3834.803855704699, 4021.3766866135797, 4031.33148834565, 4089.6114869087387, 4157.334826427757, 65314.0, 67338.66330668316, 69860.65069674619, 70001.64999174971, 70231.0, 47384.0, 48177.75911120492, 49954.50045499571, 59640.0, 60266.0, 53105.7344713274, 54875.72562137164, 55160.44839551633, 56649.71675141598, 56929.71535142298, 7389.0, 7591.506552074097, 7633.1985141559335, 7725.150233474201, 7822.608869556386, 37622.43566346452, 38059.809700951315, 38317.616823831406, 38496.80751596224, 39665.40501892416, 44501.33248001347, 46630.53369466262, 46749.0, 46849.0, 48802.0, 8753.0, 9140.131687489678, 9437.622495100393, 9466.52667366615, 9547.665831695856, 10075.697729068139, 10107.89892101099, 10174.949125254327, 10209.693709188736, 10259.897401026194, 9649.807003860024, 9826.852597211191, 9906.554205060645, 10020.64927727526, 10250.948745256524, 9631.662891799033, 9756.170725488413, 9815.656452024145, 9909.752256193653, 9930.304878658424, 9266.305027123128, 9273.397229180082, 9551.713448596553, 9588.280878933969, 9662.56518456657, 8137.226963438464, 8179.754607361789, 8290.917090829256, 8343.95828020856, 8586.356023298402, 7428.1086269647985, 7673.117591476828, 7724.729634462767, 7804.9219507806465, 7944.960275198587], "value": 6100.284392539675, "unit": "score"}], "name": "dromaeo_css", "value": 6243.273431456891, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 115.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 128.0, 129.0, 162.0, 123.0, 125.0, 122.0, 123.0, 144.0, 122.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 161.0, 163.0, 162.0, 162.0, 161.0, 159.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 103.0, 76.0, 77.0, 78.0, 76.0, 92.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 119.0, 86.0, 85.0, 84.0, 99.0, 85.0, 85.0, 110.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 150.0, 149.0, 148.0, 150.0, 150.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 74.0, 67.0, 68.0, 68.0, 70.0, 70.0, 67.0, 69.0], "value": 69.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 84.0, 85.0, 84.0, 85.0, 90.0, 90.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 131.0, 136.0, 130.0, 147.0, 132.0, 144.0, 145.0, 172.0, 157.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 180.0, 178.0, 181.0, 177.0, 177.0, 179.0, 177.0, 176.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 70.0, 68.0, 68.0, 68.0, 68.0, 70.0, 68.0, 67.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1551.6000000000001, "alertThreshold": 2.0}]} 04:03:50 INFO - Return code: 0 04:03:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:50 INFO - # TBPL SUCCESS # 04:03:50 INFO - Running post-action listener: _resource_record_post_action 04:03:50 INFO - [mozharness: 2017-01-10 12:03:50.783938Z] Finished run-tests step (success) 04:03:50 INFO - Running post-run listener: _resource_record_post_run 04:03:50 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 383307776; Read time: 24; Write time: 221252 04:03:50 INFO - TinderboxPrint: CPU usage
13.1% 04:03:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 04:03:50 INFO - TinderboxPrint: I/O write bytes / time
383,307,776 / 221,252 04:03:50 INFO - TinderboxPrint: CPU idle
8,332.9 (86.9%) 04:03:50 INFO - TinderboxPrint: CPU user
1,210.1 (12.6%) 04:03:50 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99905536; Read time: 0; Write time: 82016 04:03:50 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 283402240; Read time: 0; Write time: 139236 04:03:51 INFO - Running post-run listener: _upload_blobber_files 04:03:51 INFO - Blob upload gear active. 04:03:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:51 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 04:03:51 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'] 04:03:51 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 04:03:51 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:54 INFO - (blobuploader) - INFO - Done attempting. 04:03:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:03:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:03:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:55 INFO - (blobuploader) - INFO - Done attempting. 04:03:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:03:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:03:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:56 INFO - (blobuploader) - INFO - Done attempting. 04:03:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:03:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:57 INFO - (blobuploader) - INFO - Done attempting. 04:03:57 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:57 INFO - Return code: 0 04:03:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:57 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b866b6f44262f2e0f30479d62ada5d2549d444db78a7233b5334899ff0c05a0f9fe2af9e54459c671be6025ce233e34ebcc56d906a0f893044f7fe7175466e75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be11d87f2bd594c3f8111806a1b610ade239e8578d27439b85a302845cdf6e272fe54037459099a439ddc14e946f5b632f4dd8525658a4ea1d48c0cf7661df3e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4838036e141fe8516d310dff9de986abbe5384aed0c1542017384f50412832f91d9738ee3d8698c30d7faf5dc5318c0f30749831c827d02e6474bf07696b9f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7acffa57a7d5b29e7373713ec4a40fb3724fc85d9b649af0a20bdc28cabe72f5b1f5ecabba913757fa2b975ce68e8f4b9dbd975c20bd83b6121cf2e3ba088892"} 04:03:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:57 INFO - Contents: 04:03:57 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b866b6f44262f2e0f30479d62ada5d2549d444db78a7233b5334899ff0c05a0f9fe2af9e54459c671be6025ce233e34ebcc56d906a0f893044f7fe7175466e75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be11d87f2bd594c3f8111806a1b610ade239e8578d27439b85a302845cdf6e272fe54037459099a439ddc14e946f5b632f4dd8525658a4ea1d48c0cf7661df3e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4838036e141fe8516d310dff9de986abbe5384aed0c1542017384f50412832f91d9738ee3d8698c30d7faf5dc5318c0f30749831c827d02e6474bf07696b9f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7acffa57a7d5b29e7373713ec4a40fb3724fc85d9b649af0a20bdc28cabe72f5b1f5ecabba913757fa2b975ce68e8f4b9dbd975c20bd83b6121cf2e3ba088892"} 04:03:57 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:57 INFO - Copying logs to upload dir... 04:03:57 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.172165 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-10 04:03:57.575135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 04:03:57.578505) ========= 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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b866b6f44262f2e0f30479d62ada5d2549d444db78a7233b5334899ff0c05a0f9fe2af9e54459c671be6025ce233e34ebcc56d906a0f893044f7fe7175466e75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be11d87f2bd594c3f8111806a1b610ade239e8578d27439b85a302845cdf6e272fe54037459099a439ddc14e946f5b632f4dd8525658a4ea1d48c0cf7661df3e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4838036e141fe8516d310dff9de986abbe5384aed0c1542017384f50412832f91d9738ee3d8698c30d7faf5dc5318c0f30749831c827d02e6474bf07696b9f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7acffa57a7d5b29e7373713ec4a40fb3724fc85d9b649af0a20bdc28cabe72f5b1f5ecabba913757fa2b975ce68e8f4b9dbd975c20bd83b6121cf2e3ba088892"} build_url:https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011956 build_url: 'https://queue.taskcluster.net/v1/task/aZ7EFsWuSLy6BkxBTfeDHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b866b6f44262f2e0f30479d62ada5d2549d444db78a7233b5334899ff0c05a0f9fe2af9e54459c671be6025ce233e34ebcc56d906a0f893044f7fe7175466e75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be11d87f2bd594c3f8111806a1b610ade239e8578d27439b85a302845cdf6e272fe54037459099a439ddc14e946f5b632f4dd8525658a4ea1d48c0cf7661df3e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4838036e141fe8516d310dff9de986abbe5384aed0c1542017384f50412832f91d9738ee3d8698c30d7faf5dc5318c0f30749831c827d02e6474bf07696b9f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7acffa57a7d5b29e7373713ec4a40fb3724fc85d9b649af0a20bdc28cabe72f5b1f5ecabba913757fa2b975ce68e8f4b9dbd975c20bd83b6121cf2e3ba088892"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 04:03:57.604965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:03:57.605355) ========= 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=20e666a3d4cfbb71505a330e000001b4-1484048588.979333-1597739698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:03:57.688137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 04:03:57.688469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 04:03:57.688779) ========= ========= Total master_lag: 0.17 =========