builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1490027222.34 results: success (0) revision: 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.342116) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.342758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.343462) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.359334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.359952) ========= 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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-20 09:27:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-03-20 09:27:02 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120247 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.601963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.602701) ========= 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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018548 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.638317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:02.639183) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a --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 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a --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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-20 09:27:02,714 truncating revision to first 12 chars 2017-03-20 09:27:02,714 Setting DEBUG logging. 2017-03-20 09:27:02,714 attempt 1/10 2017-03-20 09:27:02,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05bfa2831c0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 09:27:03,136 unpacking tar archive at: mozilla-central-05bfa2831c0b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:03.381763) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:03.382173) ========= script_repo_revision: 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:03.382619) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:03.382945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 09:27:03.394170) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-20 09:27:03.394742) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:27:03 INFO - MultiFileLogger online at 20170320 09:27:03 in /builds/slave/test 09:27:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:27:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:27:03 INFO - {'append_to_log': False, 09:27:03 INFO - 'base_work_dir': '/builds/slave/test', 09:27:03 INFO - 'blob_upload_branch': 'Firefox', 09:27:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:27:03 INFO - 'branch': 'Firefox', 09:27:03 INFO - 'buildbot_json_path': 'buildprops.json', 09:27:03 INFO - 'config_files': ('talos/linux_config.py',), 09:27:03 INFO - 'default_actions': ('clobber', 09:27:03 INFO - 'read-buildbot-config', 09:27:03 INFO - 'download-and-extract', 09:27:03 INFO - 'populate-webroot', 09:27:03 INFO - 'create-virtualenv', 09:27:03 INFO - 'install', 09:27:03 INFO - 'run-tests'), 09:27:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:27:03 INFO - 'download_minidump_stackwalk': True, 09:27:03 INFO - 'download_symbols': 'ondemand', 09:27:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:27:03 INFO - 'tooltool.py': '/tools/tooltool.py', 09:27:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:27:03 INFO - '/tools/misc-python/virtualenv.py')}, 09:27:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:27:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:27:03 INFO - 'gecko_profile': False, 09:27:03 INFO - 'gecko_profile_interval': 0, 09:27:03 INFO - 'installer_path': 'installer.exe', 09:27:03 INFO - 'log_level': 'info', 09:27:03 INFO - 'log_name': 'talos', 09:27:03 INFO - 'log_to_console': True, 09:27:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:27:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:27:03 INFO - 'opt_config_files': (), 09:27:03 INFO - 'pip_index': False, 09:27:03 INFO - 'suite': 'dromaeojs', 09:27:03 INFO - 'system_bits': '32', 09:27:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:27:03 INFO - 'title': 'talos-linux64-ix-003', 09:27:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:27:03 INFO - 'use_talos_json': True, 09:27:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:27:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:27:03 INFO - 'work_dir': 'build'} 09:27:03 INFO - [mozharness: 2017-03-20 16:27:03.570766Z] Running clobber step. 09:27:03 INFO - Running pre-action listener: _resource_record_pre_action 09:27:03 INFO - Running main action method: clobber 09:27:03 INFO - rmtree: /builds/slave/test/build 09:27:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:27:04 INFO - Running post-action listener: _resource_record_post_action 09:27:04 INFO - [mozharness: 2017-03-20 16:27:04.489717Z] Finished clobber step (success) 09:27:04 INFO - [mozharness: 2017-03-20 16:27:04.489818Z] Running read-buildbot-config step. 09:27:04 INFO - Running pre-action listener: _resource_record_pre_action 09:27:04 INFO - Running main action method: read_buildbot_config 09:27:04 INFO - Using buildbot properties: 09:27:04 INFO - { 09:27:04 INFO - "project": "", 09:27:04 INFO - "product": "firefox", 09:27:04 INFO - "who": "ffxbld@noreply.mozilla.org", 09:27:04 INFO - "installer_path": "public/build/target.tar.bz2", 09:27:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:27:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:27:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:27:04 INFO - "stage_platform": "linux64-pgo", 09:27:04 INFO - "basedir": "/builds/slave/test", 09:27:04 INFO - "buildnumber": 84, 09:27:04 INFO - "platform": "ubuntu64_hw", 09:27:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:27:04 INFO - "slavebuilddir": "test", 09:27:04 INFO - "taskId": "Vol2hf3nSiG_WEf3uwggrw", 09:27:04 INFO - "branch": "mozilla-central", 09:27:04 INFO - "script_repo_revision": "production", 09:27:04 INFO - "revision": "05bfa2831c0ba4a26fa72328ffe6a99aba9c356a", 09:27:04 INFO - "slavename": "talos-linux64-ix-003", 09:27:04 INFO - "repo_path": "mozilla-central" 09:27:04 INFO - } 09:27:04 INFO - Finding installer, test and symbols from parent task. 09:27:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vol2hf3nSiG_WEf3uwggrw'}, attempt #1 09:27:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vol2hf3nSiG_WEf3uwggrw'}, attempt #1 09:27:05 INFO - Task dependencies: Do7ElnV2RK20TMLf3VC8wQ 09:27:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ'}, attempt #1 09:27:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 09:27:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/test_packages.json 09:27:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.crashreporter-symbols.zip 09:27:05 INFO - Running post-action listener: _resource_record_post_action 09:27:05 INFO - [mozharness: 2017-03-20 16:27:05.897468Z] Finished read-buildbot-config step (success) 09:27:05 INFO - [mozharness: 2017-03-20 16:27:05.897691Z] Running download-and-extract step. 09:27:05 INFO - Running pre-action listener: _resource_record_pre_action 09:27:05 INFO - Running main action method: download_and_extract 09:27:05 INFO - mkdir: /builds/slave/test/build/tests 09:27: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')]} 09:27:05 INFO - trying https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.test_packages.json 09:27:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:27:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:27:06 INFO - Downloaded 986 bytes. 09:27:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:27:06 INFO - Using the following test package requirements: 09:27:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:27:06 INFO - u'common': [u'target.common.tests.zip'], 09:27:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:27:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:27:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:27:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:27:06 INFO - u'mozbase': [u'target.common.tests.zip'], 09:27:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:27:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:27:06 INFO - u'web-platform': [u'target.common.tests.zip', 09:27:06 INFO - u'target.web-platform.tests.zip'], 09:27:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:27:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:27:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip 09:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:27:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip into memory 09:27:07 INFO - Expected file size: 60314257 09:27:07 INFO - Obtained file size: 60314257 09:27:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:27:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip 09:27:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:27:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.common.tests.zip into memory 09:27:18 INFO - Expected file size: 60314257 09:27:18 INFO - Obtained file size: 60314257 09:27:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.talos.tests.zip 09:27:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:27:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.talos.tests.zip into memory 09:27:31 INFO - Expected file size: 14404171 09:27:31 INFO - Obtained file size: 14404171 09:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:31 INFO - trying https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 09:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:27:32 INFO - Downloaded 67266808 bytes. 09:27:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 09:27:32 INFO - mkdir: /builds/slave/test/properties 09:27:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:32 INFO - Contents: 09:27:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 09:27:32 INFO - Running post-action listener: _resource_record_post_action 09:27:32 INFO - Running post-action listener: set_extra_try_arguments 09:27:32 INFO - [mozharness: 2017-03-20 16:27:32.813837Z] Finished download-and-extract step (success) 09:27:32 INFO - [mozharness: 2017-03-20 16:27:32.813934Z] Running populate-webroot step. 09:27:32 INFO - Running pre-action listener: _resource_record_pre_action 09:27:32 INFO - Running main action method: populate_webroot 09:27:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:32 INFO - '--tppagecycles', 09:27:32 INFO - '7'], 09:27:32 INFO - 'tests': ['tsvgm']}}, 09:27:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['damp', 'tps']}, 09:27:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'tests': ['damp', 'tps']}, 09:27:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['dromaeo_dom']}, 09:27:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['basic_compositor_video']}, 09:27:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['a11yr', 09:27:32 INFO - 'ts_paint', 09:27:32 INFO - 'tpaint', 09:27:32 INFO - 'sessionrestore', 09:27:32 INFO - 'sessionrestore_no_auto_restore', 09:27:32 INFO - 'tabpaint']}, 09:27:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['tsvgx', 09:27:32 INFO - 'tsvgr_opacity', 09:27:32 INFO - 'tart', 09:27:32 INFO - 'tscrollx', 09:27:32 INFO - 'cart', 09:27:32 INFO - 'tsvg_static']}, 09:27:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:32 INFO - 'tsvgr_opacity', 09:27:32 INFO - 'tart', 09:27:32 INFO - 'tscrollx', 09:27:32 INFO - 'cart', 09:27:32 INFO - 'tsvg_static']}, 09:27:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'talos_options': ['--disable-e10s'], 09:27:32 INFO - 'tests': ['tp5o']}, 09:27:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:27:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'talos_options': ['--disable-e10s', 09:27:32 INFO - '--xperf_path', 09:27:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:32 INFO - 'tests': ['tp5n']}, 09:27:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:27:32 INFO - 'talos_options': ['--xperf_path', 09:27:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:32 INFO - 'tests': ['tp5n']}}, 09:27:32 INFO - 'talos.zip': {'path': '', 09:27:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:32 INFO - Running post-action listener: _resource_record_post_action 09:27:32 INFO - [mozharness: 2017-03-20 16:27:32.827067Z] Finished populate-webroot step (success) 09:27:32 INFO - [mozharness: 2017-03-20 16:27:32.827158Z] Running create-virtualenv step. 09:27:32 INFO - Running pre-action listener: _resource_record_pre_action 09:27:32 INFO - Running main action method: create_virtualenv 09:27:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:32 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 09:27:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:32 INFO - Using real prefix '/usr' 09:27:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:35 INFO - Installing pip.................done. 09:27:35 INFO - Return code: 0 09:27:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:35 INFO - Reading from file tmpfile_stdout 09:27:35 INFO - Output received: 09:27:35 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) 09:27:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27: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', 'pip>=1.5'] in /builds/slave/test/build 09:27: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 pip>=1.5 09:27:35 INFO - Using env: {'DISPLAY': ':0', 09:27:35 INFO - 'HOME': '/home/cltbld', 09:27:35 INFO - 'LANG': 'en_US.UTF-8', 09:27:35 INFO - 'LANGUAGE': 'en_US:en', 09:27:35 INFO - 'LOGNAME': 'cltbld', 09:27:35 INFO - 'MAIL': '/var/mail/cltbld', 09:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:35 INFO - 'MOZ_NO_REMOTE': '1', 09:27:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:35 INFO - 'NO_EM_RESTART': '1', 09:27:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:35 INFO - 'PWD': '/builds/slave/test', 09:27:35 INFO - 'SHELL': '/bin/bash', 09:27:35 INFO - 'SHLVL': '1', 09:27:35 INFO - 'TERM': 'linux', 09:27:35 INFO - 'TMOUT': '86400', 09:27:35 INFO - 'USER': 'cltbld', 09:27:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:35 INFO - '_': '/tools/buildbot/bin/python'} 09:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:35 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 09:27:35 INFO - Cleaning up... 09:27:35 INFO - Return code: 0 09:27:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', '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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:27: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 psutil>=3.1.1 09:27:35 INFO - Using env: {'DISPLAY': ':0', 09:27:35 INFO - 'HOME': '/home/cltbld', 09:27:35 INFO - 'LANG': 'en_US.UTF-8', 09:27:35 INFO - 'LANGUAGE': 'en_US:en', 09:27:35 INFO - 'LOGNAME': 'cltbld', 09:27:35 INFO - 'MAIL': '/var/mail/cltbld', 09:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:35 INFO - 'MOZ_NO_REMOTE': '1', 09:27:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:35 INFO - 'NO_EM_RESTART': '1', 09:27:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:35 INFO - 'PWD': '/builds/slave/test', 09:27:35 INFO - 'SHELL': '/bin/bash', 09:27:35 INFO - 'SHLVL': '1', 09:27:35 INFO - 'TERM': 'linux', 09:27:35 INFO - 'TMOUT': '86400', 09:27:35 INFO - 'USER': 'cltbld', 09:27:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:35 INFO - '_': '/tools/buildbot/bin/python'} 09:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:36 INFO - Downloading/unpacking psutil>=3.1.1 09:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:38 INFO - Installing collected packages: psutil 09:27:38 INFO - Running setup.py install for psutil 09:27:38 INFO - building 'psutil._psutil_linux' extension 09:27:38 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 09:27:38 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 09:27:38 INFO - building 'psutil._psutil_posix' extension 09:27:38 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 09:27:38 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 09:27:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:38 INFO - Successfully installed psutil 09:27:38 INFO - Cleaning up... 09:27:38 INFO - Return code: 0 09:27:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:38 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')]} 09:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 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')]} 09:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:38 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 09:27:38 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 09:27:38 INFO - Using env: {'DISPLAY': ':0', 09:27:38 INFO - 'HOME': '/home/cltbld', 09:27:38 INFO - 'LANG': 'en_US.UTF-8', 09:27:38 INFO - 'LANGUAGE': 'en_US:en', 09:27:38 INFO - 'LOGNAME': 'cltbld', 09:27:38 INFO - 'MAIL': '/var/mail/cltbld', 09:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:38 INFO - 'MOZ_NO_REMOTE': '1', 09:27:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:38 INFO - 'NO_EM_RESTART': '1', 09:27:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:38 INFO - 'PWD': '/builds/slave/test', 09:27:38 INFO - 'SHELL': '/bin/bash', 09:27:38 INFO - 'SHLVL': '1', 09:27:38 INFO - 'TERM': 'linux', 09:27:38 INFO - 'TMOUT': '86400', 09:27:38 INFO - 'USER': 'cltbld', 09:27:38 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:38 INFO - '_': '/tools/buildbot/bin/python'} 09:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:41 INFO - Installing collected packages: mozsystemmonitor 09:27:41 INFO - Running setup.py install for mozsystemmonitor 09:27:41 INFO - Successfully installed mozsystemmonitor 09:27:41 INFO - Cleaning up... 09:27:41 INFO - Return code: 0 09:27:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:41 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:41 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 09:27:41 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 09:27:41 INFO - Using env: {'DISPLAY': ':0', 09:27:41 INFO - 'HOME': '/home/cltbld', 09:27:41 INFO - 'LANG': 'en_US.UTF-8', 09:27:41 INFO - 'LANGUAGE': 'en_US:en', 09:27:41 INFO - 'LOGNAME': 'cltbld', 09:27:41 INFO - 'MAIL': '/var/mail/cltbld', 09:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:41 INFO - 'MOZ_NO_REMOTE': '1', 09:27:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:41 INFO - 'NO_EM_RESTART': '1', 09:27:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:41 INFO - 'PWD': '/builds/slave/test', 09:27:41 INFO - 'SHELL': '/bin/bash', 09:27:41 INFO - 'SHLVL': '1', 09:27:41 INFO - 'TERM': 'linux', 09:27:41 INFO - 'TMOUT': '86400', 09:27:41 INFO - 'USER': 'cltbld', 09:27:41 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:41 INFO - '_': '/tools/buildbot/bin/python'} 09:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:43 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 09:27:43 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 09:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:43 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:43 INFO - warning: no files found matching '*.txt' 09:27:43 INFO - no previously-included directories found matching 'build' 09:27:43 INFO - no previously-included directories found matching 'dist' 09:27:43 INFO - no previously-included directories found matching '.git*' 09:27:43 INFO - Installing collected packages: jsonschema, functools32 09:27:43 INFO - Running setup.py install for functools32 09:27:43 INFO - warning: no files found matching '*.txt' 09:27:43 INFO - no previously-included directories found matching 'build' 09:27:43 INFO - no previously-included directories found matching 'dist' 09:27:43 INFO - no previously-included directories found matching '.git*' 09:27:43 INFO - Successfully installed jsonschema functools32 09:27:43 INFO - Cleaning up... 09:27:43 INFO - Return code: 0 09:27:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:43 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 09:27:43 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 09:27:43 INFO - Using env: {'DISPLAY': ':0', 09:27:43 INFO - 'HOME': '/home/cltbld', 09:27:43 INFO - 'LANG': 'en_US.UTF-8', 09:27:43 INFO - 'LANGUAGE': 'en_US:en', 09:27:43 INFO - 'LOGNAME': 'cltbld', 09:27:43 INFO - 'MAIL': '/var/mail/cltbld', 09:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:43 INFO - 'MOZ_NO_REMOTE': '1', 09:27:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:43 INFO - 'NO_EM_RESTART': '1', 09:27:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:43 INFO - 'PWD': '/builds/slave/test', 09:27:43 INFO - 'SHELL': '/bin/bash', 09:27:43 INFO - 'SHLVL': '1', 09:27:43 INFO - 'TERM': 'linux', 09:27:43 INFO - 'TMOUT': '86400', 09:27:43 INFO - 'USER': 'cltbld', 09:27:43 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:43 INFO - '_': '/tools/buildbot/bin/python'} 09:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:44 INFO - Cleaning up... 09:27:44 INFO - Return code: 0 09:27:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:44 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 09:27:44 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 09:27:44 INFO - Using env: {'DISPLAY': ':0', 09:27:44 INFO - 'HOME': '/home/cltbld', 09:27:44 INFO - 'LANG': 'en_US.UTF-8', 09:27:44 INFO - 'LANGUAGE': 'en_US:en', 09:27:44 INFO - 'LOGNAME': 'cltbld', 09:27:44 INFO - 'MAIL': '/var/mail/cltbld', 09:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:44 INFO - 'MOZ_NO_REMOTE': '1', 09:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:44 INFO - 'NO_EM_RESTART': '1', 09:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:44 INFO - 'PWD': '/builds/slave/test', 09:27:44 INFO - 'SHELL': '/bin/bash', 09:27:44 INFO - 'SHLVL': '1', 09:27:44 INFO - 'TERM': 'linux', 09:27:44 INFO - 'TMOUT': '86400', 09:27:44 INFO - 'USER': 'cltbld', 09:27:44 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:44 INFO - '_': '/tools/buildbot/bin/python'} 09:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:44 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:44 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 09:27:44 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 09:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:46 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:47 INFO - Downloading docopt-0.6.1.tar.gz 09:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:47 INFO - Running setup.py install for blobuploader 09:27:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:47 INFO - Running setup.py install for requests 09:27:47 INFO - Running setup.py install for docopt 09:27:47 INFO - Successfully installed blobuploader requests docopt 09:27:47 INFO - Cleaning up... 09:27:47 INFO - Return code: 0 09:27:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:47 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')]} 09:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:47 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')]} 09:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:48 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:48 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 09:27:48 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 09:27:48 INFO - Using env: {'DISPLAY': ':0', 09:27:48 INFO - 'HOME': '/home/cltbld', 09:27:48 INFO - 'LANG': 'en_US.UTF-8', 09:27:48 INFO - 'LANGUAGE': 'en_US:en', 09:27:48 INFO - 'LOGNAME': 'cltbld', 09:27:48 INFO - 'MAIL': '/var/mail/cltbld', 09:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:48 INFO - 'MOZ_NO_REMOTE': '1', 09:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:48 INFO - 'NO_EM_RESTART': '1', 09:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:48 INFO - 'PWD': '/builds/slave/test', 09:27:48 INFO - 'SHELL': '/bin/bash', 09:27:48 INFO - 'SHLVL': '1', 09:27:48 INFO - 'TERM': 'linux', 09:27:48 INFO - 'TMOUT': '86400', 09:27:48 INFO - 'USER': 'cltbld', 09:27:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:48 INFO - '_': '/tools/buildbot/bin/python'} 09:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:48 INFO - Running setup.py (path:/tmp/pip-5SkvOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:48 INFO - Running setup.py (path:/tmp/pip-irRukP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:48 INFO - Running setup.py (path:/tmp/pip-h45Jbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:48 INFO - Running setup.py (path:/tmp/pip-wpGKZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:48 INFO - Running setup.py (path:/tmp/pip-7OOebp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:48 INFO - Running setup.py (path:/tmp/pip-36VTRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:48 INFO - Running setup.py (path:/tmp/pip-zAQZQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:48 INFO - Running setup.py (path:/tmp/pip-ruL76z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:48 INFO - Running setup.py (path:/tmp/pip-6d1bu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:48 INFO - Running setup.py (path:/tmp/pip-3JkUms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:48 INFO - Running setup.py (path:/tmp/pip-uIN5Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:48 INFO - Running setup.py (path:/tmp/pip-DuayOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:49 INFO - Running setup.py (path:/tmp/pip-f1PBle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:49 INFO - Running setup.py (path:/tmp/pip-jzJwyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:49 INFO - Running setup.py (path:/tmp/pip-H9T9dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:49 INFO - Running setup.py (path:/tmp/pip-NfjWiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:49 INFO - Running setup.py (path:/tmp/pip-iDVsJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:49 INFO - Running setup.py install for manifestparser 09:27:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:49 INFO - Running setup.py install for mozcrash 09:27:49 INFO - Running setup.py install for mozdebug 09:27:49 INFO - Running setup.py install for mozdevice 09:27:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:49 INFO - Running setup.py install for mozfile 09:27:49 INFO - Running setup.py install for mozhttpd 09:27:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozinfo 09:27:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozInstall 09:27:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozleak 09:27:50 INFO - Running setup.py install for mozlog 09:27:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for moznetwork 09:27:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozprocess 09:27:50 INFO - Running setup.py install for mozprofile 09:27:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozrunner 09:27:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:50 INFO - Running setup.py install for mozscreenshot 09:27:50 INFO - Running setup.py install for moztest 09:27:51 INFO - Running setup.py install for mozversion 09:27:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:51 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')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 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')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:51 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 09:27:51 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 09:27:51 INFO - Using env: {'DISPLAY': ':0', 09:27:51 INFO - 'HOME': '/home/cltbld', 09:27:51 INFO - 'LANG': 'en_US.UTF-8', 09:27:51 INFO - 'LANGUAGE': 'en_US:en', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MAIL': '/var/mail/cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SHLVL': '1', 09:27:51 INFO - 'TERM': 'linux', 09:27:51 INFO - 'TMOUT': '86400', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - '_': '/tools/buildbot/bin/python'} 09:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:51 INFO - Running setup.py (path:/tmp/pip-x1LdMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:51 INFO - Running setup.py (path:/tmp/pip-69qbYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:51 INFO - Running setup.py (path:/tmp/pip-eBSHE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:51 INFO - Running setup.py (path:/tmp/pip-rfPbVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:51 INFO - Running setup.py (path:/tmp/pip-Fcmi46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:51 INFO - Running setup.py (path:/tmp/pip-OqKhCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:51 INFO - Running setup.py (path:/tmp/pip-ikW_2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:51 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)) 09:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:51 INFO - Running setup.py (path:/tmp/pip-BaO1WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:52 INFO - Running setup.py (path:/tmp/pip-kxhagZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:52 INFO - Running setup.py (path:/tmp/pip-UJXOCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:52 INFO - Running setup.py (path:/tmp/pip-kLTIiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:52 INFO - Running setup.py (path:/tmp/pip-YnvTO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:52 INFO - Running setup.py (path:/tmp/pip-VY89Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:52 INFO - Running setup.py (path:/tmp/pip-uVWf_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:52 INFO - Running setup.py (path:/tmp/pip-9M8GSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:52 INFO - Running setup.py (path:/tmp/pip-tagjNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:52 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)) 09:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:52 INFO - Running setup.py (path:/tmp/pip-UzhudD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:52 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)) 09:27:52 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)) 09:27:52 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)) 09:27:52 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)) 09:27:52 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)) 09:27:52 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)) 09:27:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:52 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 09:27:52 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 09:27:52 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 09:27:52 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 09:27:52 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 09:27:52 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 09:27:54 INFO - Downloading blessings-1.6.tar.gz 09:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:54 INFO - Installing collected packages: blessings 09:27:54 INFO - Running setup.py install for blessings 09:27:54 INFO - Successfully installed blessings 09:27:54 INFO - Cleaning up... 09:27:54 INFO - Return code: 0 09:27:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:55 INFO - Reading from file tmpfile_stdout 09:27:55 INFO - Current package versions: 09:27:55 INFO - argparse == 1.2.1 09:27:55 INFO - blessings == 1.6 09:27:55 INFO - blobuploader == 1.2.4 09:27:55 INFO - docopt == 0.6.1 09:27:55 INFO - functools32 == 3.2.3-2 09:27:55 INFO - jsonschema == 2.5.1 09:27:55 INFO - manifestparser == 1.1 09:27:55 INFO - mozInstall == 1.12 09:27:55 INFO - mozcrash == 1.0 09:27:55 INFO - mozdebug == 0.1 09:27:55 INFO - mozdevice == 0.49 09:27:55 INFO - mozfile == 1.2 09:27:55 INFO - mozhttpd == 0.7 09:27:55 INFO - mozinfo == 0.9 09:27:55 INFO - mozleak == 0.1 09:27:55 INFO - mozlog == 3.4 09:27:55 INFO - moznetwork == 0.27 09:27:55 INFO - mozprocess == 0.25 09:27:55 INFO - mozprofile == 0.28 09:27:55 INFO - mozrunner == 6.13 09:27:55 INFO - mozscreenshot == 0.1 09:27:55 INFO - mozsystemmonitor == 0.3 09:27:55 INFO - moztest == 0.8 09:27:55 INFO - mozversion == 1.4 09:27:55 INFO - psutil == 3.1.1 09:27:55 INFO - requests == 1.2.3 09:27:55 INFO - wsgiref == 0.1.2 09:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:55 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')]} 09:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:55 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')]} 09:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:55 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:55 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 09:27:55 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 09:27:55 INFO - Using env: {'DISPLAY': ':0', 09:27:55 INFO - 'HOME': '/home/cltbld', 09:27:55 INFO - 'LANG': 'en_US.UTF-8', 09:27:55 INFO - 'LANGUAGE': 'en_US:en', 09:27:55 INFO - 'LOGNAME': 'cltbld', 09:27:55 INFO - 'MAIL': '/var/mail/cltbld', 09:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:55 INFO - 'MOZ_NO_REMOTE': '1', 09:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:55 INFO - 'NO_EM_RESTART': '1', 09:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:55 INFO - 'PWD': '/builds/slave/test', 09:27:55 INFO - 'SHELL': '/bin/bash', 09:27:55 INFO - 'SHLVL': '1', 09:27:55 INFO - 'TERM': 'linux', 09:27:55 INFO - 'TMOUT': '86400', 09:27:55 INFO - 'USER': 'cltbld', 09:27:55 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:55 INFO - '_': '/tools/buildbot/bin/python'} 09:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 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)) 09:27:55 INFO - Cleaning up... 09:27:55 INFO - Return code: 0 09:27:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:55 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')]} 09:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:55 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')]} 09:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:55 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 0x127f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x151d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, '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': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', '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 09:27:55 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 09:27:55 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 09:27:55 INFO - Using env: {'DISPLAY': ':0', 09:27:55 INFO - 'HOME': '/home/cltbld', 09:27:55 INFO - 'LANG': 'en_US.UTF-8', 09:27:55 INFO - 'LANGUAGE': 'en_US:en', 09:27:55 INFO - 'LOGNAME': 'cltbld', 09:27:55 INFO - 'MAIL': '/var/mail/cltbld', 09:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:55 INFO - 'MOZ_NO_REMOTE': '1', 09:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:55 INFO - 'NO_EM_RESTART': '1', 09:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:55 INFO - 'PWD': '/builds/slave/test', 09:27:55 INFO - 'SHELL': '/bin/bash', 09:27:55 INFO - 'SHLVL': '1', 09:27:55 INFO - 'TERM': 'linux', 09:27:55 INFO - 'TMOUT': '86400', 09:27:55 INFO - 'USER': 'cltbld', 09:27:55 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:55 INFO - '_': '/tools/buildbot/bin/python'} 09:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:55 INFO - Cleaning up... 09:27:55 INFO - Return code: 0 09:27:55 INFO - Running post-action listener: _resource_record_post_action 09:27:55 INFO - Running post-action listener: _start_resource_monitoring 09:27:55 INFO - Starting resource monitoring. 09:27:55 INFO - [mozharness: 2017-03-20 16:27:55.627144Z] Finished create-virtualenv step (success) 09:27:55 INFO - [mozharness: 2017-03-20 16:27:55.627658Z] Running install step. 09:27:55 INFO - Running pre-action listener: _resource_record_pre_action 09:27:55 INFO - Running main action method: install 09:27:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:55 INFO - Reading from file tmpfile_stdout 09:27:55 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:55 INFO - Reading from file tmpfile_stdout 09:27:55 INFO - Output received: 09:27:55 INFO - Usage: mozinstall [options] installer 09:27:55 INFO - Options: 09:27:55 INFO - -h, --help show this help message and exit 09:27:55 INFO - -d DEST, --destination=DEST 09:27:55 INFO - Directory to install application into. [default: 09:27:55 INFO - "/builds/slave/test"] 09:27:55 INFO - --app=APP Application being installed. [default: firefox] 09:27:55 INFO - mkdir: /builds/slave/test/build/application 09:27:55 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'] 09:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:28:13 INFO - Reading from file tmpfile_stdout 09:28:13 INFO - Output received: 09:28:13 INFO - /builds/slave/test/build/application/firefox/firefox 09:28:13 INFO - Running post-action listener: _resource_record_post_action 09:28:13 INFO - [mozharness: 2017-03-20 16:28:13.313081Z] Finished install step (success) 09:28:13 INFO - [mozharness: 2017-03-20 16:28:13.313361Z] Running run-tests step. 09:28:13 INFO - Running pre-action listener: _resource_record_pre_action 09:28:13 INFO - Running main action method: run_tests 09:28:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:13 INFO - Python 2.7.3 09:28:13 INFO - Return code: 0 09:28:13 INFO - grabbing minidump binary from tooltool 09:28: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')]} 09:28:13 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 0x151a400>, '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 0x151b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x151b840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:13 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 09:28:13 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 09:28:13 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 09:28:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:13 INFO - Return code: 0 09:28:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:28:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:13 INFO - ENV: RUST_BACKTRACE is now 1 09:28:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:28:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 09:28:13 INFO - Using env: {'DISPLAY': ':0', 09:28:13 INFO - 'HOME': '/home/cltbld', 09:28:13 INFO - 'LANG': 'en_US.UTF-8', 09:28:13 INFO - 'LANGUAGE': 'en_US:en', 09:28:13 INFO - 'LOGNAME': 'cltbld', 09:28:13 INFO - 'MAIL': '/var/mail/cltbld', 09:28:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:13 INFO - 'MOZ_NO_REMOTE': '1', 09:28:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:13 INFO - 'NO_EM_RESTART': '1', 09:28:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:13 INFO - 'PWD': '/builds/slave/test', 09:28:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:13 INFO - 'RUST_BACKTRACE': '1', 09:28:13 INFO - 'SHELL': '/bin/bash', 09:28:13 INFO - 'SHLVL': '1', 09:28:13 INFO - 'TERM': 'linux', 09:28:13 INFO - 'TMOUT': '86400', 09:28:13 INFO - 'USER': 'cltbld', 09:28:13 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1490027221.671450-551078829', 09:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:13 INFO - '_': '/tools/buildbot/bin/python'} 09:28:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:28:13 INFO - mozversion application_buildid: 20170320152513 09:28:13 INFO - mozversion application_changeset: 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a 09:28:13 INFO - mozversion application_display_name: Nightly 09:28:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:13 INFO - mozversion application_name: Firefox 09:28:13 INFO - mozversion application_remotingname: firefox 09:28:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:28:13 INFO - mozversion application_vendor: Mozilla 09:28:13 INFO - mozversion application_version: 55.0a1 09:28:13 INFO - mozversion platform_buildid: 20170320152513 09:28:13 INFO - mozversion platform_changeset: 05bfa2831c0ba4a26fa72328ffe6a99aba9c356a 09:28:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:28:13 INFO - mozversion platform_version: 55.0a1 09:28:13 INFO - using testdate: 1490027293 09:28:13 INFO - actual date: 1490027293 09:28:13 INFO - starting webserver on 'localhost:45542' 09:28:13 INFO - SUITE-START | Running 2 tests 09:28:13 INFO - TEST-START | dromaeo_css 09:28:13 INFO - operating with platform_type : linux_ 09:28:13 INFO - Initialising browser for dromaeo_css test... 09:28:13 INFO - TEST-INFO | started process 15682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDA28Bf/profile http://localhost:45542/getInfo.html) 09:28:17 INFO - PID 15682 | __metrics Screen width/height:1600/1200 09:28:17 INFO - PID 15682 | colorDepth:24 09:28:17 INFO - PID 15682 | Browser inner width/height: 1024/697 09:28:17 INFO - PID 15682 | __metrics 09:28:18 INFO - PID 15682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:28:18 INFO - PID 15682 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:19 INFO - TEST-INFO | 15682: exit 0 09:28:19 INFO - Browser initialized. 09:28:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:19 INFO - TEST-INFO | started process 15775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDA28Bf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:31 INFO - PID 15775 | RSS: Main: 148992000 09:28:31 INFO - PID 15775 | 09:31:33 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45542/tests/dromaeo/cssquery-ext.html) 09:31:35 INFO - PID 15775 | RSS: Main: 289468416 09:31:35 INFO - PID 15775 | 09:33:21 INFO - PID 15775 | ************************* 09:33:21 INFO - PID 15775 | A coding exception was thrown and uncaught in a Task. 09:33:21 INFO - PID 15775 | 09:33:21 ERROR - PID 15775 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:33:21 INFO - PID 15775 | Full stack: 09:33:21 INFO - PID 15775 | ************************* 09:34:37 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-ext.html (next: http://localhost:45542/tests/dromaeo/cssquery-jquery.html) 09:34:38 INFO - PID 15775 | RSS: Main: 236650496 09:34:38 INFO - PID 15775 | 09:37:39 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45542/tests/dromaeo/cssquery-mootools.html) 09:37:40 INFO - PID 15775 | RSS: Main: 177262592 09:37:40 INFO - PID 15775 | 09:40:42 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45542/tests/dromaeo/cssquery-prototype.html) 09:40:43 INFO - PID 15775 | RSS: Main: 178450432 09:40:43 INFO - PID 15775 | 09:43:45 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45542/tests/dromaeo/cssquery-yui.html) 09:43:46 INFO - PID 15775 | RSS: Main: 190922752 09:43:46 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | Cycle 1(1): loaded http://localhost:45542/tests/dromaeo/cssquery-yui.html (next: http://localhost:45542/tests/dromaeo/cssquery-dojo.html) 09:46:49 INFO - PID 15775 | RSS: Main: 281214976 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | __start_tp_report 09:46:49 INFO - PID 15775 | _x_x_mozilla_page_load 09:46:49 INFO - PID 15775 | _x_x_mozilla_page_load_details 09:46:49 INFO - PID 15775 | |i|pagename|runs| 09:46:49 INFO - PID 15775 | |0;dojo.html;3250.154959710475;3262.902112936613;3270.2805382815773;3306.7519936004796;3326.417876871547;5384.3538775346915;5472.2338872557875;5552.305961754781;5589.301337332833;5590.972045139769;5472.863178420542;5572;5573.637713548616;5574.247476590659;5576.219329293902;4961.404631444232;4998.200287953928;5017.19724844025;5020.246962955549;5069.2649565812935;5033.597312215014;5080.949190508085;5081.923771143436;5086.059079070365;5148.304978827867;5026;5037.798488060474;5055.79776808929;5087.465816089313;5095.312132862072;5064.974675126619;5078.289039534487;5081.339425874613;5083.79664813407;5119.923201151985;4847.442544107437;5019.698818070891;5052.4189718182515;5060.2409638554145;5061.848144555669;5476.91784623231;5503.669779813224;5563.582731295129;5569.693666848321;5582.190582365534;5950.196723442323;6007.579469437142;6012.218411606507;6015.608985415934;6024.126501657279;6040.697965101727;6120.449159575615;6141.938580614182;6147.78482753106;6149.877002459971;5509.889802203974;5533.778648854081;5540.584456165804;5603.7758489659955;5641.12562552805;6137.328339575531;6148.077788331786;6151.323354431009;6154.261488621394;6154.969225153935;5899.882002360015;5901.675407852524;5903.586748927534;5909.940900591025;5945.1973983512535;5871.148683440877;5871.911921321183;5933.3473317934995;6000.789972350947;6014.097885317237;5907.232059832194;5949.315828679757;5958.255218097738;6018.729157187937;6024.006039003515;5858.882822343615;5897.52819774417;5903.645781253139;5974.2830860297045;5980.312264089598;4623.283391074388;4667.276572131325;4668.463126740468;4688.2733176357715;4707.129181101507;4115.7324773890205;4147.564505726916;4186.162767446523;4188.371744238389;4204.453421055305;5568.44315568448;5578.693171875586;5643.5767317451355;5648.63283886546;5652.85867853307;6031.125486804389;6046.032634778414;6047.818565443044;6048.516118710492;6049.032154855202;5595.776168953278;5609.4390560943575;5614.045612245928;5614.9438505615235;5618.0449323615085;5055.9241611375855;5098.107831129611;5106.66806657566;5144.845654630367;5161.3032240647035;6223.875522489525;6252.843678907972;6252.843678908063;6255.812325630239;6256.280527739277;5980.1328807321825;5996.910046349221;5996.970015149896;6001.519878409629;6051.6066455682;6079.969600151971;6155.1075094112;6168.444839964424;6184.350643182401;6227.96886015567;5896.262967129109;5897.970510147594;5899.4985426239255;5913.58604897653;5940.702964851654;6021.909671354845;6033.064874944391;6040.879182416415;6041.848953776015;6044.939550604614;5739.454751798572;5740.253767010262;5863.296404431496;5867.6772777497645;5887.087501437284;5872.500837428866;5888.823335299948;5895.587308888336;5918.970405147947;5930.851728706817;5835.854103647443;5859.384764599656;5871.6183448075735;5879.882402352015;5886.20536227621;5721.9700453918695;5760.9918264304415;5764.279465066867;5881.5588830837005;5925.525957923442;5754.19441278213;5756.16535602327;5758.856028599319;5771.278590176228;5811.622244554091 09:46:49 INFO - PID 15775 | |1;ext.html;685.3523420367751;740.8110931712414;766.0998326965814;814.5357146426536;826.5825757992213;28459.430811383787;35635.643643563555;36260.81869590655;36540.81729591355;36570.81714591423;38223.23553528935;38480.422793658116;38673.419898701475;38693.61306386935;38824.22351552967;7139.85720285594;7279.563226206418;8369.581520923944;8571.14288571141;8579.742607721779;9896.059874311928;10085.64700235492;10097.747556311115;10104.747381315488;10108.797824043515;10372.429516376596;10535.26253162279;10538.841917371246;10540.20948428867;10738.355698658066;10618.150547956184;10678.038976492115;10690.251682382237;10700.946495267473;10716.732081697943;10283.691489255334;10335.379877207391;10350.896491035144;10351.79296414075;10357.585696572129;38488.422673659916;38548.61451385478;38750.41874371887;38788;38854.805725971186;3706.1475860551955;3747.887563373076;3753.906152346186;3776.339140650375;3826.1773718650625;27363.726362736317;28133.718662813313;28309.57535636967;28394.43211135766;28936.27659308513;4097.13960068386;4118.835246590133;4120.608542188487;4125.855595054189;4129.236091323085;193675;194438;194659;194774;195137;47248;47558.28662570064;47561.524384756405;47600.33595296661;47623.761881191065;48828.267575986385;49064.26403603949;49161;49245;49264;14091.436342546389;14272.714545709236;14337.426502939765;14390.85609143895;14445.06107103035;10062.044105809937;10166.949165254126;10447.634332798316;11385.203035787581;11403.258788178742;13074.673133171747;13082.934585327013;13153.473861045642;13209.867901321057;13271;5335.371183401471;5407.810726624549;5459.563234941195;5471.616986810965;5547.583931205176;1064.536926436987;1105.871841961592;1127.932324060559;1168.4683469021745;1187.1808452167977;26118.86940565285;26365.34086647811;26372.8681356592;26403.20790376292;26588;1170.9063274937985;1182.2433642468827;1188.732535179595;1211.975760484803;1214.9939250303692;13703.24923432996;13812.240326781923;13942.512012079731;13983.230922299372;14016.439342426189;255502;255584;256714;256887;257124;49183.75408122937;49254.50745492571;49263.261051084264;49285;49417.75291123521;1362.8092067110613;1418.7446259673359;1425.0523401937803;1425.387083554061;1520.8098987626547;23740.169094081622;24200.152994645105;24719.50560988806;24740.134095306577;25026;38306.61693383031;38467.2306553873;38510.61489385182;38532.80733596314;38537.80731096327;235291;236958;237759;238568;239097;25107.49785004253;25553.48893022166;25570.744292556836;32058.358832822745;32271.67728322687;33423;34915.65084349226;35163;35180.824095879354;35691.28617427689;33293.50059749057;33358.33283334368;33987;34242.657573423945;34640.826795866866;32810.343793123524;33102.5034624476;33689.66310336865;33784.15539611431;34666.48000279947;34241;34759.47860782141;34924;34987.65012349844;35002.82498587592;29236.56145157867;29291.560626591043;30061;30280.242993924447;30313.848430758586;24290.392740181636;24378.51242975166;24401.633975490025;24425.14511992072;24749.381265468506 09:46:49 INFO - PID 15775 | |2;jquery.html;431.8639628517019;435.9694821362504;437.96058354748067;441.0891509033847;456.17660123477134;19805.633042382793;21004.369868903937;21671;21840.344789656298;21871.343859684235;19553.217871285167;19988.200471981105;20962.16151353944;21212.575748485022;21275.255366062192;6345.809625711225;6660.76687315944;6663.06717059612;6664.666766661648;6665.266820649725;6185.103160041791;6546.378094081055;6580.967095164518;6587.769428070019;6588.93411065888;6478.902816457757;6602.306757790411;6605.471562275006;6605.735770569171;6650.600963942179;6574.375434333754;6576;6619.966900165468;6622.701978410983;6624.205095388536;6433.035044743326;7131.572105673677;7434.702611895517;7469.47713660044;7480.214577469342;26390.208293751028;27056.188314350406;27179.456410871673;27939.301517462023;27985;3247.285597168619;3290.2925870937866;3312.2216279174377;3330.7343209580204;3332.9333413331597;16876.493705188863;16960.74558881618;17192.742108868377;17996.820031799645;18005.909970450193;2034.5829105033433;2113.365990202933;2117.523557199633;2124.3626911926362;2141.3575927221773;72470.63764681196;75166.24833751701;83272;87157;91615.54192229063;19475.8052419474;19688.31090911811;19711;19739.802601974083;19998.300059497848;35803.28393432169;35852.28295434129;36179.81910090433;36261;36407;9053.456792592466;9278.238867393326;9374.531273436301;9465.858012129824;9847.753806154904;7468.767653337247;7504.174540800507;7533.886991695129;7705.383569314441;7738.651760670783;10773.461326933622;10778.784424311627;10949.945250273699;11025;11139.610113646146;3822;4148.875533733993;4224.669229192795;4245.97877010613;4412.448443944507;883.4478450968145;921.7004473546124;931.4271722890509;931.459753343073;956.416585882611;21265.57468850614;22114;22202.11191552352;22238.666420003712;22271.22050728249;915.231205787142;916.6150216908915;923.8013827027221;926.4626516620344;942.7218970403748;11047.613333533287;11856.703582410337;11973.221740586834;12115.212511186744;12269.325187114795;383482;387870;389758;394948;597246;109685;110295.00722395208;111173.44413278044;113310;113403;1291.0897817039154;1314.2705798282045;1326.5209291639753;1345.1256683155832;1346.4748747988292;19588.608227835648;19731.802681972997;20326.898365508077;20567.485812854797;20577.691334629693;41836.58163418327;47966.76016619895;48863;49677.50322496729;49882.50117498779;63493.67643469446;64322.35677643164;64505.354946451815;65251;66360.6681966587;11857.881421185677;12132;12133.21134126279;12252.448639811051;12493;12791.744165116832;13938.93030534841;14005.719885602028;14251.714965700421;14330.641733956734;11933.52265909354;12064.095192860395;12133.575324863587;12138.453769580567;12814;12084.697882553006;12210.511579536718;12277.877221227673;12325.815112773495;12388.504459821506;8288.627011784363;12531.93734031324;12665.303408312631;12762.42569084375;12785.87214127884;11861.110416718608;11951;12095.57665481704;12259.75480490403;12309.815352769894;12289;12336.62990110298;12391.75216495683;12429.937850310691;12717.618471445872 09:46:49 INFO - PID 15775 | |3;mootools.html;1501.759718445049;1524.138861543228;1532.9463468778588;1539.3919401836279;1619.7570364445335;10163.644272450465;10549.47252637369;10550;10554.630587929405;10560.735981600454;10456;10732.19508536859;10742.086922611586;10769.461526923662;10774.946125269382;2260.8982595783145;2292.9656055159185;2293.885305734715;2295.701558797354;2296.552172326397;2188.387251569563;2231.4309850987975;2272.9659055114184;2273.374821923976;2276.2147059264526;2210.3810932938723;2215.711957445538;2215.8116560092412;2238.0600147937826;2239.8096161826184;2127.1491403438595;2170.663547150194;2192.682061101147;2194.462356722613;2195.1438938813794;2119.4065661614773;2141.8929053547417;2143.0998980428258;2145.731783527059;2148.0870629982196;10768.676939691823;10800.1899857511;10823.296485728402;10876.238663293572;10877.945610271978;5334.706591137486;5473.589480788957;5507.4767897050115;5515.61390702651;5516;3113.408452394038;3233.5772260205435;3414.4536874100027;3420.863165473378;3428.245785927092;1202.9338386388745;1220.206865537399;1232.2975903734875;1246.1401632873287;1260.287937315414;13894.513692020731;14312.785308220386;15352.462663806713;15749.055056696634;15775.92112039444;4257.659387249013;4455.086707225011;4555.703879247838;4583.289590113538;4586.243269860503;11574.074074074053;11578.189526733047;11578.47896844644;11582.305061696325;11616.186866919403;4775.235962246094;4945.381827271591;5464.863378415572;5489.39616642169;5627.803026894039;5107.284980102789;5216.921746173811;5218.7912483500995;5224.921626175535;5225.16397376418;3577.695895848882;3591.1381268495375;3649.0694872807794;3710.4990826238654;3717.0893131183034;959.4627008875052;971.8590804333332;992.6426486464867;1002.4937406609761;1007.0835539658875;915.6749353979242;934.444005816538;936.3118108190474;948.3314263444255;962.6967505235829;6273.8745225095245;6296.338884417162;6330.651814150266;6344;6564.245111812107;956.3305686019675;965.5365424596092;972.2756546372856;977.4233202410612;979.2313034268066;3935.409688546684;3935.704822138294;3937.153511994935;3992.321305378093;4112.938305925413;13521.256330901895;13912.930435347758;13929.442822287197;13941.442342306194;13947.58157255284;13764.31178441074;13803.516876909462;13841.930790345983;13855.376508057161;13886.097403668931;8259.876101858357;9330.813383732288;9652.951735241419;9665.71002869915;9674.951625241829;5362.51737343641;5982.162497250301;6296.811095667137;6322.272938612118;6515.54391192612;11054.613088542184;11294.040006599202;11299.548018079186;11361.943190283995;11517.25137866036;11337.149713771665;12172.2696638202;12177.269363837844;12184.086193535697;12203.010262485748;10104.69685909424;10158.796824063626;10255.384676919408;10255.538500767632;10263.23025773055;9837.95081024619;9857.457839818879;9949.801003980025;9950.900490994998;9959.601615935282;8847.646094156162;8925.866112008196;9035.45787252767;9094.909050909671;9097.13577210164;8667.393282470166;8677.566121693764;8697.782555436164;8813.779655508664;8978.281737461119;8103.716369927024;8318.667253309799;8319;8322.750317490485;8324.750257492286;5768.278965129359;5823.621464604787;5889.764409423575;5943.821685349447;6023.45788879003;5849.883002339844;5861.413858614104;5918.567231006093;6068.302145253352;6163 09:46:49 INFO - PID 15775 | |4;prototype.html;374.97937613431253;376.60644626365445;377.74124724563677;387.93405121129405;388.72012151251096;5725.398833122519;5805.419458054193;5810.273715785527;5842.737076831542;5860.959574765709;5560.471755183261;5668.546516278698;5733.598648094635;5735.971320143393;5741.913871291934;4465.5311192324825;4471.575200355961;4472.709273897202;4492.438445194351;4559.996800703856;4729.00399162707;4730.787114579852;4750.16872047392;4753.809847606091;4756.5243475652505;4699.624030077585;4704.788284527187;4718.292256161586;4724.645651576128;4746.62347919103;4480.283154695266;4720.103180395714;4737.620990320766;4749.311349854269;4769.332293478916;4361.651067914591;4369.169857727022;4372.169287835333;4376.6498680105515;4405.207062728708;5573.331200255955;5583.64981011394;5608.411116832755;5610.915836262459;5622.156676498517;5398.244245805551;5542.307211598551;5548.8624831909365;5551.9722401388135;5553.555715542747;6014.654539460867;6031.668258245794;6036.124761909498;6037.849053773691;6061.090836374535;4885.60915126789;4896.551724137931;4903.926441103386;4912.115819152551;4912.901741965141;6021.187139736167;6249;6352.333005034498;6385.020643600452;6411.691790955252;4937.950620493821;4939.135651261086;5058.266551350033;5068.898622027539;5069.619778516626;5123.538881500683;5131.974340128276;5137.1010073237785;5144.202648589475;5165.586753059746;5358.383785864676;5479.863003424946;5506.311711036121;5508.366537848118;5521.69630670317;6016.69916504173;6036.124761909587;6043.8791224175275;6050.334463209044;6052.636841789506;1771.645670865832;1779.0482092080674;1785.8392744653045;1790.113893622665;1798.4424828303006;1322;1323.636000099961;1335.6843509143487;1340.0083937886088;1359.8436179839446;5810.7966221182905;5814.476697097281;5831.85080056773;5841.591088623755;5843.2695913010875;6004.39956004396;6205.596636218722;6243.656598887014;6259.311475737665;6269.435750782452;5796.652200867877;5838.2702162229725;5840.123981402823;5841.211436456109;5859.414058594107;3278.622958359819;3284.802911825298;3294.390537750524;3294.884679036222;3314.303996160785;6233.376662333821;6442.226932768098;6478.449331806849;6500.804975850732;6501.577397469149;6324.6521441321165;6349.238091428966;6350.491960643138;6366.140571022853;6366.522510811707;6303.149074874833;6349.46029587481;6432.742690292336;6471.5146364022885;6493.545451818327;6191.690415479298;6197.659128747962;6207.689615519296;6208.317085120543;6225.941589929814;5983.970080149571;6137.41694539018;6153.031030070335;6187.628742275478;6205.379462053939;6391.296957334596;6416.967915160394;6424.2933277338525;6433.388828061326;6490.8052758417325;5007.148784706682;5029.396472423333;5062.2406639002975;5069.264956581202;5082.186850103965;6040.456358927718;6168.537359698131;6188.133661287513;6200.596961197508;6229.595076320024;6031.487323577545;6050.485708714808;6053;6066.939330606814;6069.2110025696375;5908.61593996406;6059.242594675666;6060.454559089703;6081.5742897996715;6114.847128821637;6025.487833534024;6035.9094613579955;6059.515238780798;6082.452579267887;6086.543509236736;6000.459958603572;6023.27720673522;6094.7562097517375;6110.847228819315;6116.724747386467;5750.971245143747;5884.823035393024;5897.144913987534;5928.8814223716145;5940.7920722776225 09:46:49 INFO - PID 15775 | |5;yui.html;735.24637246822;739.8890166475024;786.3630459327941;807.9353651707863;834.4659417972499;15353.385864565418;16051.357945682188;16056.116913569764;16060.839391606081;16194.75707864383;14975.550733477985;15158.090514569107;15211.54365369038;15215.543533694006;15227.39090436384;827.5159031966292;843.1568431568431;854.1287886355914;861.1216559109736;863.0894406401244;850.9234168924795;859.70769938221;861.7414775567336;862.8921384826897;863.6890719341063;885.1281487734608;889.5152142082549;896.4218079338823;896.995515022424;898.3890954151175;879.8196369744219;889.1908363389349;889.4707648948897;891.2068259249306;892.8437523433438;649.2436311696868;653.3629711031706;653.4706887421204;655.3741177175785;657.4411750012451;16522.25649845748;16661;16720;17303.653926921517;17678.646427071515;15620.921895390564;16423.425180118757;16660;16707;17079.694241277517;12274.631761047183;12515.061370397256;12683.365831708377;13398.665033374145;13448.058635895492;3628.89113326598;3666.7616604920595;3670.926581468356;3740.513733214692;3810.1808111255937;65126;89376.55311723465;90761;90934.5453272736;92482.07517924802;11901.166918315635;12050;12135.211211271238;12377.62867113988;12466.5013399463;23070.192543260906;23167.884160579426;23181.304560863202;23411.297661070195;23415.41461463443;8050.919490805017;8124.5937703114605;8149.14433984435;8242;8480.915190848136;4276.700630955866;4304.354346847936;4319.07139964909;4326.2212801696;4430.069685366045;3582.247727977102;3611.8916432507067;3625.093726568358;3635.0730563706084;3669.5779985301497;612.8835521250948;613.7207570555387;621.6891554222889;631.8136149835809;633.654658211276;715.4705517916808;718.2279050021248;735.6615956659996;751.8045308219902;761.975143432083;10675.023578667955;11838.230515016496;12057.15599907998;12190.57332993341;12504.124711270124;591.704147926037;608.5375114912698;609.4180058044557;654.3652656922777;722.1911459165768;4781.856544303677;5194.22086687;5234.450382709761;5259.421463638997;5260.763265653055;69386.65306673435;72316;73176;74757.77664456905;75509.6224518874;62749;63063;65894.34105658882;66226.66886665535;66600.00099998504;59704;60213.37652858182;60235.69882150561;60294.99999999912;60388.396116039155;9520.714378568655;9639.951800240953;9683.370580912218;9700.272479564203;9761;38315.23369532538;38629;39074.80462597783;39621;39947.80026099851;43271.56728432801;45667.31499027584;45989;46300.53699463097;47972.7601361991;12482.812757808459;12553.748925021631;12556.246625202517;12904.354782261038;12951.093423460266;13598.320083995563;13788.586342409744;13812.24032678172;13838.446462141805;14043.50847720325;12842.807357889826;12890.162139460905;13001.4149363277;13356.866431335562;13435.12671676338;12629.810552841898;13423.597292081253;13480.797788033384;13731.107478013897;13946.721065578835;12445.75108497843;13183.209007459584;13362.198268103944;13379.397927093109;13777.173369597856;10713.35719856811;10729.731756706145;11078.279911805706;11478.483468243781;11567.248128871597;9186.994297315783;10381.169506439617;10544.314619549705;10727;10790.568377265135 09:46:49 INFO - PID 15775 | __end_tp_report 09:46:49 INFO - PID 15775 | __start_cc_report 09:46:49 INFO - PID 15775 | _x_x_mozilla_cycle_collect,1559 09:46:49 INFO - PID 15775 | __end_cc_report 09:46:49 INFO - PID 15775 | __startTimestamp1490028409424__endTimestamp 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | ------- Summary: start ------- 09:46:49 INFO - PID 15775 | Number of tests: 6 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#0] dojo.html Cycles:165 Average:5560.24 Median:5764.28 stddev:627.21 (10.9%) stddev-sans-first:602.38 09:46:49 INFO - PID 15775 | Values: 3250.2 3262.9 3270.3 3306.8 3326.4 5384.4 5472.2 5552.3 5589.3 5591.0 5472.9 5572.0 5573.6 5574.2 5576.2 4961.4 4998.2 5017.2 5020.2 5069.3 5033.6 5080.9 5081.9 5086.1 5148.3 5026.0 5037.8 5055.8 5087.5 5095.3 5065.0 5078.3 5081.3 5083.8 5119.9 4847.4 5019.7 5052.4 5060.2 5061.8 5476.9 5503.7 5563.6 5569.7 5582.2 5950.2 6007.6 6012.2 6015.6 6024.1 6040.7 6120.4 6141.9 6147.8 6149.9 5509.9 5533.8 5540.6 5603.8 5641.1 6137.3 6148.1 6151.3 6154.3 6155.0 5899.9 5901.7 5903.6 5909.9 5945.2 5871.1 5871.9 5933.3 6000.8 6014.1 5907.2 5949.3 5958.3 6018.7 6024.0 5858.9 5897.5 5903.6 5974.3 5980.3 4623.3 4667.3 4668.5 4688.3 4707.1 4115.7 4147.6 4186.2 4188.4 4204.5 5568.4 5578.7 5643.6 5648.6 5652.9 6031.1 6046.0 6047.8 6048.5 6049.0 5595.8 5609.4 5614.0 5614.9 5618.0 5055.9 5098.1 5106.7 5144.8 5161.3 6223.9 6252.8 6252.8 6255.8 6256.3 5980.1 5996.9 5997.0 6001.5 6051.6 6080.0 6155.1 6168.4 6184.4 6228.0 5896.3 5898.0 5899.5 5913.6 5940.7 6021.9 6033.1 6040.9 6041.8 6044.9 5739.5 5740.3 5863.3 5867.7 5887.1 5872.5 5888.8 5895.6 5919.0 5930.9 5835.9 5859.4 5871.6 5879.9 5886.2 5722.0 5761.0 5764.3 5881.6 5925.5 5754.2 5756.2 5758.9 5771.3 5811.6 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#1] ext.html Cycles:180 Average:38970.37 Median:28426.93 stddev:59895.88 (210.7%) stddev-sans-first:59994.92 09:46:49 INFO - PID 15775 | Values: 685.4 740.8 766.1 814.5 826.6 28459.4 35635.6 36260.8 36540.8 36570.8 38223.2 38480.4 38673.4 38693.6 38824.2 7139.9 7279.6 8369.6 8571.1 8579.7 9896.1 10085.6 10097.7 10104.7 10108.8 10372.4 10535.3 10538.8 10540.2 10738.4 10618.2 10678.0 10690.3 10700.9 10716.7 10283.7 10335.4 10350.9 10351.8 10357.6 38488.4 38548.6 38750.4 38788.0 38854.8 3706.1 3747.9 3753.9 3776.3 3826.2 27363.7 28133.7 28309.6 28394.4 28936.3 4097.1 4118.8 4120.6 4125.9 4129.2 193675.0 194438.0 194659.0 194774.0 195137.0 47248.0 47558.3 47561.5 47600.3 47623.8 48828.3 49064.3 49161.0 49245.0 49264.0 14091.4 14272.7 14337.4 14390.9 14445.1 10062.0 10166.9 10447.6 11385.2 11403.3 13074.7 13082.9 13153.5 13209.9 13271.0 5335.4 5407.8 5459.6 5471.6 5547.6 1064.5 1105.9 1127.9 1168.5 1187.2 26118.9 26365.3 26372.9 26403.2 26588.0 1170.9 1182.2 1188.7 1212.0 1215.0 13703.2 13812.2 13942.5 13983.2 14016.4 255502.0 255584.0 256714.0 256887.0 257124.0 49183.8 49254.5 49263.3 49285.0 49417.8 1362.8 1418.7 1425.1 1425.4 1520.8 23740.2 24200.2 24719.5 24740.1 25026.0 38306.6 38467.2 38510.6 38532.8 38537.8 235291.0 236958.0 237759.0 238568.0 239097.0 25107.5 25553.5 25570.7 32058.4 32271.7 33423.0 34915.7 35163.0 35180.8 35691.3 33293.5 33358.3 33987.0 34242.7 34640.8 32810.3 33102.5 33689.7 33784.2 34666.5 34241.0 34759.5 34924.0 34987.7 35002.8 29236.6 29291.6 30061.0 30280.2 30313.8 24290.4 24378.5 24401.6 24425.1 24749.4 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#2] jquery.html Cycles:180 Average:30401.59 Median:27117.82 stddev:73061.73 (269.4%) stddev-sans-first:73232.04 09:46:49 INFO - PID 15775 | Values: 431.9 436.0 438.0 441.1 456.2 19805.6 21004.4 21671.0 21840.3 21871.3 19553.2 19988.2 20962.2 21212.6 21275.3 6345.8 6660.8 6663.1 6664.7 6665.3 6185.1 6546.4 6581.0 6587.8 6588.9 6478.9 6602.3 6605.5 6605.7 6650.6 6574.4 6576.0 6620.0 6622.7 6624.2 6433.0 7131.6 7434.7 7469.5 7480.2 26390.2 27056.2 27179.5 27939.3 27985.0 3247.3 3290.3 3312.2 3330.7 3332.9 16876.5 16960.7 17192.7 17996.8 18005.9 2034.6 2113.4 2117.5 2124.4 2141.4 72470.6 75166.2 83272.0 87157.0 91615.5 19475.8 19688.3 19711.0 19739.8 19998.3 35803.3 35852.3 36179.8 36261.0 36407.0 9053.5 9278.2 9374.5 9465.9 9847.8 7468.8 7504.2 7533.9 7705.4 7738.7 10773.5 10778.8 10949.9 11025.0 11139.6 3822.0 4148.9 4224.7 4246.0 4412.4 883.4 921.7 931.4 931.5 956.4 21265.6 22114.0 22202.1 22238.7 22271.2 915.2 916.6 923.8 926.5 942.7 11047.6 11856.7 11973.2 12115.2 12269.3 383482.0 387870.0 389758.0 394948.0 597246.0 109685.0 110295.0 111173.4 113310.0 113403.0 1291.1 1314.3 1326.5 1345.1 1346.5 19588.6 19731.8 20326.9 20567.5 20577.7 41836.6 47966.8 48863.0 49677.5 49882.5 63493.7 64322.4 64505.4 65251.0 66360.7 11857.9 12132.0 12133.2 12252.4 12493.0 12791.7 13938.9 14005.7 14251.7 14330.6 11933.5 12064.1 12133.6 12138.5 12814.0 12084.7 12210.5 12277.9 12325.8 12388.5 8288.6 12531.9 12665.3 12762.4 12785.9 11861.1 11951.0 12095.6 12259.8 12309.8 12289.0 12336.6 12391.8 12429.9 12717.6 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#3] mootools.html Cycles:180 Average:6597.85 Median:3424.55 stddev:4196.96 (122.6%) stddev-sans-first:4191.26 09:46:49 INFO - PID 15775 | Values: 1501.8 1524.1 1532.9 1539.4 1619.8 10163.6 10549.5 10550.0 10554.6 10560.7 10456.0 10732.2 10742.1 10769.5 10774.9 2260.9 2293.0 2293.9 2295.7 2296.6 2188.4 2231.4 2273.0 2273.4 2276.2 2210.4 2215.7 2215.8 2238.1 2239.8 2127.1 2170.7 2192.7 2194.5 2195.1 2119.4 2141.9 2143.1 2145.7 2148.1 10768.7 10800.2 10823.3 10876.2 10877.9 5334.7 5473.6 5507.5 5515.6 5516.0 3113.4 3233.6 3414.5 3420.9 3428.2 1202.9 1220.2 1232.3 1246.1 1260.3 13894.5 14312.8 15352.5 15749.1 15775.9 4257.7 4455.1 4555.7 4583.3 4586.2 11574.1 11578.2 11578.5 11582.3 11616.2 4775.2 4945.4 5464.9 5489.4 5627.8 5107.3 5216.9 5218.8 5224.9 5225.2 3577.7 3591.1 3649.1 3710.5 3717.1 959.5 971.9 992.6 1002.5 1007.1 915.7 934.4 936.3 948.3 962.7 6273.9 6296.3 6330.7 6344.0 6564.2 956.3 965.5 972.3 977.4 979.2 3935.4 3935.7 3937.2 3992.3 4112.9 13521.3 13912.9 13929.4 13941.4 13947.6 13764.3 13803.5 13841.9 13855.4 13886.1 8259.9 9330.8 9653.0 9665.7 9675.0 5362.5 5982.2 6296.8 6322.3 6515.5 11054.6 11294.0 11299.5 11361.9 11517.3 11337.1 12172.3 12177.3 12184.1 12203.0 10104.7 10158.8 10255.4 10255.5 10263.2 9838.0 9857.5 9949.8 9950.9 9959.6 8847.6 8925.9 9035.5 9094.9 9097.1 8667.4 8677.6 8697.8 8813.8 8978.3 8103.7 8318.7 8319.0 8322.8 8324.8 5768.3 5823.6 5889.8 5943.8 6023.5 5849.9 5861.4 5918.6 6068.3 6163.0 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#4] prototype.html Cycles:180 Average:5235.39 Median:5812.64 stddev:1426.85 (24.5%) stddev-sans-first:1383.43 09:46:49 INFO - PID 15775 | Values: 375.0 376.6 377.7 387.9 388.7 5725.4 5805.4 5810.3 5842.7 5861.0 5560.5 5668.5 5733.6 5736.0 5741.9 4465.5 4471.6 4472.7 4492.4 4560.0 4729.0 4730.8 4750.2 4753.8 4756.5 4699.6 4704.8 4718.3 4724.6 4746.6 4480.3 4720.1 4737.6 4749.3 4769.3 4361.7 4369.2 4372.2 4376.6 4405.2 5573.3 5583.6 5608.4 5610.9 5622.2 5398.2 5542.3 5548.9 5552.0 5553.6 6014.7 6031.7 6036.1 6037.8 6061.1 4885.6 4896.6 4903.9 4912.1 4912.9 6021.2 6249.0 6352.3 6385.0 6411.7 4938.0 4939.1 5058.3 5068.9 5069.6 5123.5 5132.0 5137.1 5144.2 5165.6 5358.4 5479.9 5506.3 5508.4 5521.7 6016.7 6036.1 6043.9 6050.3 6052.6 1771.6 1779.0 1785.8 1790.1 1798.4 1322.0 1323.6 1335.7 1340.0 1359.8 5810.8 5814.5 5831.9 5841.6 5843.3 6004.4 6205.6 6243.7 6259.3 6269.4 5796.7 5838.3 5840.1 5841.2 5859.4 3278.6 3284.8 3294.4 3294.9 3314.3 6233.4 6442.2 6478.4 6500.8 6501.6 6324.7 6349.2 6350.5 6366.1 6366.5 6303.1 6349.5 6432.7 6471.5 6493.5 6191.7 6197.7 6207.7 6208.3 6225.9 5984.0 6137.4 6153.0 6187.6 6205.4 6391.3 6417.0 6424.3 6433.4 6490.8 5007.1 5029.4 5062.2 5069.3 5082.2 6040.5 6168.5 6188.1 6200.6 6229.6 6031.5 6050.5 6053.0 6066.9 6069.2 5908.6 6059.2 6060.5 6081.6 6114.8 6025.5 6035.9 6059.5 6082.5 6086.5 6000.5 6023.3 6094.8 6110.8 6116.7 5751.0 5884.8 5897.1 5928.9 5940.8 09:46:49 INFO - PID 15775 | 09:46:49 INFO - PID 15775 | [#5] yui.html Cycles:180 Average:17301.30 Median:4311.71 stddev:21713.56 (503.6%) stddev-sans-first:21738.84 09:46:49 INFO - PID 15775 | Values: 735.2 739.9 786.4 807.9 834.5 15353.4 16051.4 16056.1 16060.8 16194.8 14975.6 15158.1 15211.5 15215.5 15227.4 827.5 843.2 854.1 861.1 863.1 850.9 859.7 861.7 862.9 863.7 885.1 889.5 896.4 897.0 898.4 879.8 889.2 889.5 891.2 892.8 649.2 653.4 653.5 655.4 657.4 16522.3 16661.0 16720.0 17303.7 17678.6 15620.9 16423.4 16660.0 16707.0 17079.7 12274.6 12515.1 12683.4 13398.7 13448.1 3628.9 3666.8 3670.9 3740.5 3810.2 65126.0 89376.6 90761.0 90934.5 92482.1 11901.2 12050.0 12135.2 12377.6 12466.5 23070.2 23167.9 23181.3 23411.3 23415.4 8050.9 8124.6 8149.1 8242.0 8480.9 4276.7 4304.4 4319.1 4326.2 4430.1 3582.2 3611.9 3625.1 3635.1 3669.6 612.9 613.7 621.7 631.8 633.7 715.5 718.2 735.7 751.8 762.0 10675.0 11838.2 12057.2 12190.6 12504.1 591.7 608.5 609.4 654.4 722.2 4781.9 5194.2 5234.5 5259.4 5260.8 69386.7 72316.0 73176.0 74757.8 75509.6 62749.0 63063.0 65894.3 66226.7 66600.0 59704.0 60213.4 60235.7 60295.0 60388.4 9520.7 9640.0 9683.4 9700.3 9761.0 38315.2 38629.0 39074.8 39621.0 39947.8 43271.6 45667.3 45989.0 46300.5 47972.8 12482.8 12553.7 12556.2 12904.4 12951.1 13598.3 13788.6 13812.2 13838.4 14043.5 12842.8 12890.2 13001.4 13356.9 13435.1 12629.8 13423.6 13480.8 13731.1 13946.7 12445.8 13183.2 13362.2 13379.4 13777.2 10713.4 10729.7 11078.3 11478.5 11567.2 9187.0 10381.2 10544.3 10727.0 10790.6 09:46:49 INFO - PID 15775 | -------- Summary: end -------- 09:46:49 INFO - PID 15775 | 09:46:50 INFO - TEST-INFO | 15775: exit 0 09:46:50 INFO - TEST-OK | dromaeo_css | took 1116647ms 09:46:50 INFO - TEST-START | kraken 09:46:50 INFO - operating with platform_type : linux_ 09:46:50 INFO - Initialising browser for kraken test... 09:46:50 INFO - TEST-INFO | started process 16100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa833Eu/profile http://localhost:45542/getInfo.html) 09:46:54 INFO - PID 16100 | __metrics Screen width/height:1600/1200 09:46:54 INFO - PID 16100 | colorDepth:24 09:46:54 INFO - PID 16100 | Browser inner width/height: 1024/697 09:46:54 INFO - PID 16100 | __metrics 09:46:55 INFO - PID 16100 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:46:55 INFO - PID 16100 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:46:56 INFO - TEST-INFO | 16100: exit 0 09:46:56 INFO - Browser initialized. 09:46:56 INFO - Running cycle 1/1 for kraken test... 09:46:56 INFO - TEST-INFO | started process 16196 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa833Eu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:08 INFO - PID 16196 | RSS: Main: 144621568 09:47:08 INFO - PID 16196 | 09:47:10 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45542/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:11 INFO - PID 16196 | RSS: Main: 199008256 09:47:11 INFO - PID 16196 | 09:47:14 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45542/tests/kraken/driver.html?testName=audio-dft) 09:47:14 INFO - PID 16196 | RSS: Main: 179380224 09:47:14 INFO - PID 16196 | 09:47:17 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45542/tests/kraken/driver.html?testName=audio-fft) 09:47:18 INFO - PID 16196 | RSS: Main: 260845568 09:47:18 INFO - PID 16196 | 09:47:20 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45542/tests/kraken/driver.html?testName=audio-oscillator) 09:47:21 INFO - PID 16196 | RSS: Main: 172916736 09:47:21 INFO - PID 16196 | 09:47:23 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45542/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:23 INFO - PID 16196 | RSS: Main: 175849472 09:47:23 INFO - PID 16196 | 09:47:26 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45542/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:27 INFO - PID 16196 | RSS: Main: 214843392 09:47:27 INFO - PID 16196 | 09:47:30 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45542/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:31 INFO - PID 16196 | RSS: Main: 230223872 09:47:31 INFO - PID 16196 | 09:47:34 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45542/tests/kraken/driver.html?testName=json-parse-financial) 09:47:34 INFO - PID 16196 | RSS: Main: 244412416 09:47:34 INFO - PID 16196 | 09:47:36 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45542/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:36 INFO - PID 16196 | RSS: Main: 178167808 09:47:36 INFO - PID 16196 | 09:47:38 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:39 INFO - PID 16196 | RSS: Main: 183554048 09:47:39 INFO - PID 16196 | 09:47:41 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:41 INFO - PID 16196 | RSS: Main: 194183168 09:47:41 INFO - PID 16196 | 09:47:44 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:45 INFO - PID 16196 | RSS: Main: 202420224 09:47:45 INFO - PID 16196 | 09:47:48 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:48 INFO - PID 16196 | RSS: Main: 199512064 09:47:48 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | Cycle 1(1): loaded http://localhost:45542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45542/tests/kraken/driver.html?testName=ai-astar) 09:47:50 INFO - PID 16196 | RSS: Main: 267747328 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | __start_tp_report 09:47:50 INFO - PID 16196 | _x_x_mozilla_page_load 09:47:50 INFO - PID 16196 | _x_x_mozilla_page_load_details 09:47:50 INFO - PID 16196 | |i|pagename|runs| 09:47:50 INFO - PID 16196 | |0;ai-astar;110;114;114;115;114;114;113;116;114;112 09:47:50 INFO - PID 16196 | |1;audio-beat-detection;149;117;117;166;119;118;117;122;143;118 09:47:50 INFO - PID 16196 | |2;audio-dft;162;161;161;162;159;161;164;162;161;163 09:47:50 INFO - PID 16196 | |3;audio-fft;73;74;75;104;75;75;74;76;92;73 09:47:50 INFO - PID 16196 | |4;audio-oscillator;83;83;104;83;83;83;104;83;88;111 09:47:50 INFO - PID 16196 | |5;imaging-gaussian-blur;113;113;114;113;112;113;113;113;113;113 09:47:50 INFO - PID 16196 | |6;imaging-darkroom;147;147;147;147;146;146;146;147;146;146 09:47:50 INFO - PID 16196 | |7;imaging-desaturate;95;94;94;94;94;94;93;95;94;94 09:47:50 INFO - PID 16196 | |8;json-parse-financial;56;58;56;58;58;58;58;58;57;57 09:47:50 INFO - PID 16196 | |9;json-stringify-tinderbox;54;55;54;54;54;55;55;54;54;54 09:47:50 INFO - PID 16196 | |10;stanford-crypto-aes;78;78;79;79;79;78;79;78;78;80 09:47:50 INFO - PID 16196 | |11;stanford-crypto-ccm;136;124;129;132;124;125;127;124;186;133 09:47:50 INFO - PID 16196 | |12;stanford-crypto-pbkdf2;184;187;183;184;187;177;182;186;181;177 09:47:50 INFO - PID 16196 | |13;stanford-crypto-sha256-iterative;65;68;65;68;67;66;63;65;65;68 09:47:50 INFO - PID 16196 | __end_tp_report 09:47:50 INFO - PID 16196 | __start_cc_report 09:47:50 INFO - PID 16196 | _x_x_mozilla_cycle_collect,1793 09:47:50 INFO - PID 16196 | __end_cc_report 09:47:50 INFO - PID 16196 | __startTimestamp1490028470820__endTimestamp 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | ------- Summary: start ------- 09:47:50 INFO - PID 16196 | Number of tests: 14 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.12 09:47:50 INFO - PID 16196 | Values: 110.0 114.0 114.0 115.0 114.0 114.0 113.0 116.0 114.0 112.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:120.50 stddev:17.60 (14.6%) stddev-sans-first:17.04 09:47:50 INFO - PID 16196 | Values: 149.0 117.0 117.0 166.0 119.0 118.0 117.0 122.0 143.0 118.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 09:47:50 INFO - PID 16196 | Values: 162.0 161.0 161.0 162.0 159.0 161.0 164.0 162.0 161.0 163.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#3] audio-fft Cycles:10 Average:79.10 Median:75.00 stddev:10.40 (13.9%) stddev-sans-first:10.79 09:47:50 INFO - PID 16196 | Values: 73.0 74.0 75.0 104.0 75.0 75.0 74.0 76.0 92.0 73.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:83.00 stddev:11.20 (13.5%) stddev-sans-first:11.54 09:47:50 INFO - PID 16196 | Values: 83.0 83.0 104.0 83.0 83.0 83.0 104.0 83.0 88.0 111.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:47:50 INFO - PID 16196 | Values: 113.0 113.0 114.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:47:50 INFO - PID 16196 | Values: 147.0 147.0 147.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:47:50 INFO - PID 16196 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 93.0 95.0 94.0 94.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:0.84 (1.5%) stddev-sans-first:0.73 09:47:50 INFO - PID 16196 | Values: 56.0 58.0 56.0 58.0 58.0 58.0 58.0 58.0 57.0 57.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 09:47:50 INFO - PID 16196 | Values: 54.0 55.0 54.0 54.0 54.0 55.0 55.0 54.0 54.0 54.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#10] stanford-crypto-aes Cycles:10 Average:78.60 Median:79.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 09:47:50 INFO - PID 16196 | Values: 78.0 78.0 79.0 79.0 79.0 78.0 79.0 78.0 78.0 80.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:130.50 stddev:18.76 (14.4%) stddev-sans-first:19.89 09:47:50 INFO - PID 16196 | Values: 136.0 124.0 129.0 132.0 124.0 125.0 127.0 124.0 186.0 133.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:184.00 stddev:3.65 (2.0%) stddev-sans-first:3.84 09:47:50 INFO - PID 16196 | Values: 184.0 187.0 183.0 184.0 187.0 177.0 182.0 186.0 181.0 177.0 09:47:50 INFO - PID 16196 | 09:47:50 INFO - PID 16196 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.70 (2.6%) stddev-sans-first:1.76 09:47:50 INFO - PID 16196 | Values: 65.0 68.0 65.0 68.0 67.0 66.0 63.0 65.0 65.0 68.0 09:47:50 INFO - PID 16196 | -------- Summary: end -------- 09:47:50 INFO - PID 16196 | 09:47:51 INFO - TEST-INFO | 16196: exit 0 09:47:51 INFO - TEST-OK | kraken | took 61673ms 09:47:51 INFO - SUITE-END | took 1178s 09:47:51 INFO - Completed test suite (00:19:38) 09:47:51 INFO - javascript benchmark 09:47:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7574.145108605423, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3250.154959710475, 3262.902112936613, 3270.2805382815773, 3306.7519936004796, 3326.417876871547, 5384.3538775346915, 5472.2338872557875, 5552.305961754781, 5589.301337332833, 5590.972045139769, 5472.863178420542, 5572.0, 5573.637713548616, 5574.247476590659, 5576.219329293902, 4961.404631444232, 4998.200287953928, 5017.19724844025, 5020.246962955549, 5069.2649565812935, 5033.597312215014, 5080.949190508085, 5081.923771143436, 5086.059079070365, 5148.304978827867, 5026.0, 5037.798488060474, 5055.79776808929, 5087.465816089313, 5095.312132862072, 5064.974675126619, 5078.289039534487, 5081.339425874613, 5083.79664813407, 5119.923201151985, 4847.442544107437, 5019.698818070891, 5052.4189718182515, 5060.2409638554145, 5061.848144555669, 5476.91784623231, 5503.669779813224, 5563.582731295129, 5569.693666848321, 5582.190582365534, 5950.196723442323, 6007.579469437142, 6012.218411606507, 6015.608985415934, 6024.126501657279, 6040.697965101727, 6120.449159575615, 6141.938580614182, 6147.78482753106, 6149.877002459971, 5509.889802203974, 5533.778648854081, 5540.584456165804, 5603.7758489659955, 5641.12562552805, 6137.328339575531, 6148.077788331786, 6151.323354431009, 6154.261488621394, 6154.969225153935, 5899.882002360015, 5901.675407852524, 5903.586748927534, 5909.940900591025, 5945.1973983512535, 5871.148683440877, 5871.911921321183, 5933.3473317934995, 6000.789972350947, 6014.097885317237, 5907.232059832194, 5949.315828679757, 5958.255218097738, 6018.729157187937, 6024.006039003515, 5858.882822343615, 5897.52819774417, 5903.645781253139, 5974.2830860297045, 5980.312264089598, 4623.283391074388, 4667.276572131325, 4668.463126740468, 4688.2733176357715, 4707.129181101507, 4115.7324773890205, 4147.564505726916, 4186.162767446523, 4188.371744238389, 4204.453421055305, 5568.44315568448, 5578.693171875586, 5643.5767317451355, 5648.63283886546, 5652.85867853307, 6031.125486804389, 6046.032634778414, 6047.818565443044, 6048.516118710492, 6049.032154855202, 5595.776168953278, 5609.4390560943575, 5614.045612245928, 5614.9438505615235, 5618.0449323615085, 5055.9241611375855, 5098.107831129611, 5106.66806657566, 5144.845654630367, 5161.3032240647035, 6223.875522489525, 6252.843678907972, 6252.843678908063, 6255.812325630239, 6256.280527739277, 5980.1328807321825, 5996.910046349221, 5996.970015149896, 6001.519878409629, 6051.6066455682, 6079.969600151971, 6155.1075094112, 6168.444839964424, 6184.350643182401, 6227.96886015567, 5896.262967129109, 5897.970510147594, 5899.4985426239255, 5913.58604897653, 5940.702964851654, 6021.909671354845, 6033.064874944391, 6040.879182416415, 6041.848953776015, 6044.939550604614, 5739.454751798572, 5740.253767010262, 5863.296404431496, 5867.6772777497645, 5887.087501437284, 5872.500837428866, 5888.823335299948, 5895.587308888336, 5918.970405147947, 5930.851728706817, 5835.854103647443, 5859.384764599656, 5871.6183448075735, 5879.882402352015, 5886.20536227621, 5721.9700453918695, 5760.9918264304415, 5764.279465066867, 5881.5588830837005, 5925.525957923442, 5754.19441278213, 5756.16535602327, 5758.856028599319, 5771.278590176228, 5811.622244554091], "value": 5518.653392109213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.3523420367751, 740.8110931712414, 766.0998326965814, 814.5357146426536, 826.5825757992213, 28459.430811383787, 35635.643643563555, 36260.81869590655, 36540.81729591355, 36570.81714591423, 38223.23553528935, 38480.422793658116, 38673.419898701475, 38693.61306386935, 38824.22351552967, 7139.85720285594, 7279.563226206418, 8369.581520923944, 8571.14288571141, 8579.742607721779, 9896.059874311928, 10085.64700235492, 10097.747556311115, 10104.747381315488, 10108.797824043515, 10372.429516376596, 10535.26253162279, 10538.841917371246, 10540.20948428867, 10738.355698658066, 10618.150547956184, 10678.038976492115, 10690.251682382237, 10700.946495267473, 10716.732081697943, 10283.691489255334, 10335.379877207391, 10350.896491035144, 10351.79296414075, 10357.585696572129, 38488.422673659916, 38548.61451385478, 38750.41874371887, 38788.0, 38854.805725971186, 3706.1475860551955, 3747.887563373076, 3753.906152346186, 3776.339140650375, 3826.1773718650625, 27363.726362736317, 28133.718662813313, 28309.57535636967, 28394.43211135766, 28936.27659308513, 4097.13960068386, 4118.835246590133, 4120.608542188487, 4125.855595054189, 4129.236091323085, 193675.0, 194438.0, 194659.0, 194774.0, 195137.0, 47248.0, 47558.28662570064, 47561.524384756405, 47600.33595296661, 47623.761881191065, 48828.267575986385, 49064.26403603949, 49161.0, 49245.0, 49264.0, 14091.436342546389, 14272.714545709236, 14337.426502939765, 14390.85609143895, 14445.06107103035, 10062.044105809937, 10166.949165254126, 10447.634332798316, 11385.203035787581, 11403.258788178742, 13074.673133171747, 13082.934585327013, 13153.473861045642, 13209.867901321057, 13271.0, 5335.371183401471, 5407.810726624549, 5459.563234941195, 5471.616986810965, 5547.583931205176, 1064.536926436987, 1105.871841961592, 1127.932324060559, 1168.4683469021745, 1187.1808452167977, 26118.86940565285, 26365.34086647811, 26372.8681356592, 26403.20790376292, 26588.0, 1170.9063274937985, 1182.2433642468827, 1188.732535179595, 1211.975760484803, 1214.9939250303692, 13703.24923432996, 13812.240326781923, 13942.512012079731, 13983.230922299372, 14016.439342426189, 255502.0, 255584.0, 256714.0, 256887.0, 257124.0, 49183.75408122937, 49254.50745492571, 49263.261051084264, 49285.0, 49417.75291123521, 1362.8092067110613, 1418.7446259673359, 1425.0523401937803, 1425.387083554061, 1520.8098987626547, 23740.169094081622, 24200.152994645105, 24719.50560988806, 24740.134095306577, 25026.0, 38306.61693383031, 38467.2306553873, 38510.61489385182, 38532.80733596314, 38537.80731096327, 235291.0, 236958.0, 237759.0, 238568.0, 239097.0, 25107.49785004253, 25553.48893022166, 25570.744292556836, 32058.358832822745, 32271.67728322687, 33423.0, 34915.65084349226, 35163.0, 35180.824095879354, 35691.28617427689, 33293.50059749057, 33358.33283334368, 33987.0, 34242.657573423945, 34640.826795866866, 32810.343793123524, 33102.5034624476, 33689.66310336865, 33784.15539611431, 34666.48000279947, 34241.0, 34759.47860782141, 34924.0, 34987.65012349844, 35002.82498587592, 29236.56145157867, 29291.560626591043, 30061.0, 30280.242993924447, 30313.848430758586, 24290.392740181636, 24378.51242975166, 24401.633975490025, 24425.14511992072, 24749.381265468506], "value": 17403.057671037994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.8639628517019, 435.9694821362504, 437.96058354748067, 441.0891509033847, 456.17660123477134, 19805.633042382793, 21004.369868903937, 21671.0, 21840.344789656298, 21871.343859684235, 19553.217871285167, 19988.200471981105, 20962.16151353944, 21212.575748485022, 21275.255366062192, 6345.809625711225, 6660.76687315944, 6663.06717059612, 6664.666766661648, 6665.266820649725, 6185.103160041791, 6546.378094081055, 6580.967095164518, 6587.769428070019, 6588.93411065888, 6478.902816457757, 6602.306757790411, 6605.471562275006, 6605.735770569171, 6650.600963942179, 6574.375434333754, 6576.0, 6619.966900165468, 6622.701978410983, 6624.205095388536, 6433.035044743326, 7131.572105673677, 7434.702611895517, 7469.47713660044, 7480.214577469342, 26390.208293751028, 27056.188314350406, 27179.456410871673, 27939.301517462023, 27985.0, 3247.285597168619, 3290.2925870937866, 3312.2216279174377, 3330.7343209580204, 3332.9333413331597, 16876.493705188863, 16960.74558881618, 17192.742108868377, 17996.820031799645, 18005.909970450193, 2034.5829105033433, 2113.365990202933, 2117.523557199633, 2124.3626911926362, 2141.3575927221773, 72470.63764681196, 75166.24833751701, 83272.0, 87157.0, 91615.54192229063, 19475.8052419474, 19688.31090911811, 19711.0, 19739.802601974083, 19998.300059497848, 35803.28393432169, 35852.28295434129, 36179.81910090433, 36261.0, 36407.0, 9053.456792592466, 9278.238867393326, 9374.531273436301, 9465.858012129824, 9847.753806154904, 7468.767653337247, 7504.174540800507, 7533.886991695129, 7705.383569314441, 7738.651760670783, 10773.461326933622, 10778.784424311627, 10949.945250273699, 11025.0, 11139.610113646146, 3822.0, 4148.875533733993, 4224.669229192795, 4245.97877010613, 4412.448443944507, 883.4478450968145, 921.7004473546124, 931.4271722890509, 931.459753343073, 956.416585882611, 21265.57468850614, 22114.0, 22202.11191552352, 22238.666420003712, 22271.22050728249, 915.231205787142, 916.6150216908915, 923.8013827027221, 926.4626516620344, 942.7218970403748, 11047.613333533287, 11856.703582410337, 11973.221740586834, 12115.212511186744, 12269.325187114795, 383482.0, 387870.0, 389758.0, 394948.0, 597246.0, 109685.0, 110295.00722395208, 111173.44413278044, 113310.0, 113403.0, 1291.0897817039154, 1314.2705798282045, 1326.5209291639753, 1345.1256683155832, 1346.4748747988292, 19588.608227835648, 19731.802681972997, 20326.898365508077, 20567.485812854797, 20577.691334629693, 41836.58163418327, 47966.76016619895, 48863.0, 49677.50322496729, 49882.50117498779, 63493.67643469446, 64322.35677643164, 64505.354946451815, 65251.0, 66360.6681966587, 11857.881421185677, 12132.0, 12133.21134126279, 12252.448639811051, 12493.0, 12791.744165116832, 13938.93030534841, 14005.719885602028, 14251.714965700421, 14330.641733956734, 11933.52265909354, 12064.095192860395, 12133.575324863587, 12138.453769580567, 12814.0, 12084.697882553006, 12210.511579536718, 12277.877221227673, 12325.815112773495, 12388.504459821506, 8288.627011784363, 12531.93734031324, 12665.303408312631, 12762.42569084375, 12785.87214127884, 11861.110416718608, 11951.0, 12095.57665481704, 12259.75480490403, 12309.815352769894, 12289.0, 12336.62990110298, 12391.75216495683, 12429.937850310691, 12717.618471445872], "value": 11228.303646182268, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.759718445049, 1524.138861543228, 1532.9463468778588, 1539.3919401836279, 1619.7570364445335, 10163.644272450465, 10549.47252637369, 10550.0, 10554.630587929405, 10560.735981600454, 10456.0, 10732.19508536859, 10742.086922611586, 10769.461526923662, 10774.946125269382, 2260.8982595783145, 2292.9656055159185, 2293.885305734715, 2295.701558797354, 2296.552172326397, 2188.387251569563, 2231.4309850987975, 2272.9659055114184, 2273.374821923976, 2276.2147059264526, 2210.3810932938723, 2215.711957445538, 2215.8116560092412, 2238.0600147937826, 2239.8096161826184, 2127.1491403438595, 2170.663547150194, 2192.682061101147, 2194.462356722613, 2195.1438938813794, 2119.4065661614773, 2141.8929053547417, 2143.0998980428258, 2145.731783527059, 2148.0870629982196, 10768.676939691823, 10800.1899857511, 10823.296485728402, 10876.238663293572, 10877.945610271978, 5334.706591137486, 5473.589480788957, 5507.4767897050115, 5515.61390702651, 5516.0, 3113.408452394038, 3233.5772260205435, 3414.4536874100027, 3420.863165473378, 3428.245785927092, 1202.9338386388745, 1220.206865537399, 1232.2975903734875, 1246.1401632873287, 1260.287937315414, 13894.513692020731, 14312.785308220386, 15352.462663806713, 15749.055056696634, 15775.92112039444, 4257.659387249013, 4455.086707225011, 4555.703879247838, 4583.289590113538, 4586.243269860503, 11574.074074074053, 11578.189526733047, 11578.47896844644, 11582.305061696325, 11616.186866919403, 4775.235962246094, 4945.381827271591, 5464.863378415572, 5489.39616642169, 5627.803026894039, 5107.284980102789, 5216.921746173811, 5218.7912483500995, 5224.921626175535, 5225.16397376418, 3577.695895848882, 3591.1381268495375, 3649.0694872807794, 3710.4990826238654, 3717.0893131183034, 959.4627008875052, 971.8590804333332, 992.6426486464867, 1002.4937406609761, 1007.0835539658875, 915.6749353979242, 934.444005816538, 936.3118108190474, 948.3314263444255, 962.6967505235829, 6273.8745225095245, 6296.338884417162, 6330.651814150266, 6344.0, 6564.245111812107, 956.3305686019675, 965.5365424596092, 972.2756546372856, 977.4233202410612, 979.2313034268066, 3935.409688546684, 3935.704822138294, 3937.153511994935, 3992.321305378093, 4112.938305925413, 13521.256330901895, 13912.930435347758, 13929.442822287197, 13941.442342306194, 13947.58157255284, 13764.31178441074, 13803.516876909462, 13841.930790345983, 13855.376508057161, 13886.097403668931, 8259.876101858357, 9330.813383732288, 9652.951735241419, 9665.71002869915, 9674.951625241829, 5362.51737343641, 5982.162497250301, 6296.811095667137, 6322.272938612118, 6515.54391192612, 11054.613088542184, 11294.040006599202, 11299.548018079186, 11361.943190283995, 11517.25137866036, 11337.149713771665, 12172.2696638202, 12177.269363837844, 12184.086193535697, 12203.010262485748, 10104.69685909424, 10158.796824063626, 10255.384676919408, 10255.538500767632, 10263.23025773055, 9837.95081024619, 9857.457839818879, 9949.801003980025, 9950.900490994998, 9959.601615935282, 8847.646094156162, 8925.866112008196, 9035.45787252767, 9094.909050909671, 9097.13577210164, 8667.393282470166, 8677.566121693764, 8697.782555436164, 8813.779655508664, 8978.281737461119, 8103.716369927024, 8318.667253309799, 8319.0, 8322.750317490485, 8324.750257492286, 5768.278965129359, 5823.621464604787, 5889.764409423575, 5943.821685349447, 6023.45788879003, 5849.883002339844, 5861.413858614104, 5918.567231006093, 6068.302145253352, 6163.0], "value": 4991.305965661562, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.97937613431253, 376.60644626365445, 377.74124724563677, 387.93405121129405, 388.72012151251096, 5725.398833122519, 5805.419458054193, 5810.273715785527, 5842.737076831542, 5860.959574765709, 5560.471755183261, 5668.546516278698, 5733.598648094635, 5735.971320143393, 5741.913871291934, 4465.5311192324825, 4471.575200355961, 4472.709273897202, 4492.438445194351, 4559.996800703856, 4729.00399162707, 4730.787114579852, 4750.16872047392, 4753.809847606091, 4756.5243475652505, 4699.624030077585, 4704.788284527187, 4718.292256161586, 4724.645651576128, 4746.62347919103, 4480.283154695266, 4720.103180395714, 4737.620990320766, 4749.311349854269, 4769.332293478916, 4361.651067914591, 4369.169857727022, 4372.169287835333, 4376.6498680105515, 4405.207062728708, 5573.331200255955, 5583.64981011394, 5608.411116832755, 5610.915836262459, 5622.156676498517, 5398.244245805551, 5542.307211598551, 5548.8624831909365, 5551.9722401388135, 5553.555715542747, 6014.654539460867, 6031.668258245794, 6036.124761909498, 6037.849053773691, 6061.090836374535, 4885.60915126789, 4896.551724137931, 4903.926441103386, 4912.115819152551, 4912.901741965141, 6021.187139736167, 6249.0, 6352.333005034498, 6385.020643600452, 6411.691790955252, 4937.950620493821, 4939.135651261086, 5058.266551350033, 5068.898622027539, 5069.619778516626, 5123.538881500683, 5131.974340128276, 5137.1010073237785, 5144.202648589475, 5165.586753059746, 5358.383785864676, 5479.863003424946, 5506.311711036121, 5508.366537848118, 5521.69630670317, 6016.69916504173, 6036.124761909587, 6043.8791224175275, 6050.334463209044, 6052.636841789506, 1771.645670865832, 1779.0482092080674, 1785.8392744653045, 1790.113893622665, 1798.4424828303006, 1322.0, 1323.636000099961, 1335.6843509143487, 1340.0083937886088, 1359.8436179839446, 5810.7966221182905, 5814.476697097281, 5831.85080056773, 5841.591088623755, 5843.2695913010875, 6004.39956004396, 6205.596636218722, 6243.656598887014, 6259.311475737665, 6269.435750782452, 5796.652200867877, 5838.2702162229725, 5840.123981402823, 5841.211436456109, 5859.414058594107, 3278.622958359819, 3284.802911825298, 3294.390537750524, 3294.884679036222, 3314.303996160785, 6233.376662333821, 6442.226932768098, 6478.449331806849, 6500.804975850732, 6501.577397469149, 6324.6521441321165, 6349.238091428966, 6350.491960643138, 6366.140571022853, 6366.522510811707, 6303.149074874833, 6349.46029587481, 6432.742690292336, 6471.5146364022885, 6493.545451818327, 6191.690415479298, 6197.659128747962, 6207.689615519296, 6208.317085120543, 6225.941589929814, 5983.970080149571, 6137.41694539018, 6153.031030070335, 6187.628742275478, 6205.379462053939, 6391.296957334596, 6416.967915160394, 6424.2933277338525, 6433.388828061326, 6490.8052758417325, 5007.148784706682, 5029.396472423333, 5062.2406639002975, 5069.264956581202, 5082.186850103965, 6040.456358927718, 6168.537359698131, 6188.133661287513, 6200.596961197508, 6229.595076320024, 6031.487323577545, 6050.485708714808, 6053.0, 6066.939330606814, 6069.2110025696375, 5908.61593996406, 6059.242594675666, 6060.454559089703, 6081.5742897996715, 6114.847128821637, 6025.487833534024, 6035.9094613579955, 6059.515238780798, 6082.452579267887, 6086.543509236736, 6000.459958603572, 6023.27720673522, 6094.7562097517375, 6110.847228819315, 6116.724747386467, 5750.971245143747, 5884.823035393024, 5897.144913987534, 5928.8814223716145, 5940.7920722776225], "value": 4799.685235529895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.24637246822, 739.8890166475024, 786.3630459327941, 807.9353651707863, 834.4659417972499, 15353.385864565418, 16051.357945682188, 16056.116913569764, 16060.839391606081, 16194.75707864383, 14975.550733477985, 15158.090514569107, 15211.54365369038, 15215.543533694006, 15227.39090436384, 827.5159031966292, 843.1568431568431, 854.1287886355914, 861.1216559109736, 863.0894406401244, 850.9234168924795, 859.70769938221, 861.7414775567336, 862.8921384826897, 863.6890719341063, 885.1281487734608, 889.5152142082549, 896.4218079338823, 896.995515022424, 898.3890954151175, 879.8196369744219, 889.1908363389349, 889.4707648948897, 891.2068259249306, 892.8437523433438, 649.2436311696868, 653.3629711031706, 653.4706887421204, 655.3741177175785, 657.4411750012451, 16522.25649845748, 16661.0, 16720.0, 17303.653926921517, 17678.646427071515, 15620.921895390564, 16423.425180118757, 16660.0, 16707.0, 17079.694241277517, 12274.631761047183, 12515.061370397256, 12683.365831708377, 13398.665033374145, 13448.058635895492, 3628.89113326598, 3666.7616604920595, 3670.926581468356, 3740.513733214692, 3810.1808111255937, 65126.0, 89376.55311723465, 90761.0, 90934.5453272736, 92482.07517924802, 11901.166918315635, 12050.0, 12135.211211271238, 12377.62867113988, 12466.5013399463, 23070.192543260906, 23167.884160579426, 23181.304560863202, 23411.297661070195, 23415.41461463443, 8050.919490805017, 8124.5937703114605, 8149.14433984435, 8242.0, 8480.915190848136, 4276.700630955866, 4304.354346847936, 4319.07139964909, 4326.2212801696, 4430.069685366045, 3582.247727977102, 3611.8916432507067, 3625.093726568358, 3635.0730563706084, 3669.5779985301497, 612.8835521250948, 613.7207570555387, 621.6891554222889, 631.8136149835809, 633.654658211276, 715.4705517916808, 718.2279050021248, 735.6615956659996, 751.8045308219902, 761.975143432083, 10675.023578667955, 11838.230515016496, 12057.15599907998, 12190.57332993341, 12504.124711270124, 591.704147926037, 608.5375114912698, 609.4180058044557, 654.3652656922777, 722.1911459165768, 4781.856544303677, 5194.22086687, 5234.450382709761, 5259.421463638997, 5260.763265653055, 69386.65306673435, 72316.0, 73176.0, 74757.77664456905, 75509.6224518874, 62749.0, 63063.0, 65894.34105658882, 66226.66886665535, 66600.00099998504, 59704.0, 60213.37652858182, 60235.69882150561, 60294.99999999912, 60388.396116039155, 9520.714378568655, 9639.951800240953, 9683.370580912218, 9700.272479564203, 9761.0, 38315.23369532538, 38629.0, 39074.80462597783, 39621.0, 39947.80026099851, 43271.56728432801, 45667.31499027584, 45989.0, 46300.53699463097, 47972.7601361991, 12482.812757808459, 12553.748925021631, 12556.246625202517, 12904.354782261038, 12951.093423460266, 13598.320083995563, 13788.586342409744, 13812.24032678172, 13838.446462141805, 14043.50847720325, 12842.807357889826, 12890.162139460905, 13001.4149363277, 13356.866431335562, 13435.12671676338, 12629.810552841898, 13423.597292081253, 13480.797788033384, 13731.107478013897, 13946.721065578835, 12445.75108497843, 13183.209007459584, 13362.198268103944, 13379.397927093109, 13777.173369597856, 10713.35719856811, 10729.731756706145, 11078.279911805706, 11478.483468243781, 11567.248128871597, 9186.994297315783, 10381.169506439617, 10544.314619549705, 10727.0, 10790.568377265135], "value": 7307.464925821024, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1500.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 116.0, 114.0, 112.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 117.0, 166.0, 119.0, 118.0, 117.0, 122.0, 143.0, 118.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 159.0, 161.0, 164.0, 162.0, 161.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 104.0, 75.0, 75.0, 74.0, 76.0, 92.0, 73.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 104.0, 83.0, 83.0, 83.0, 104.0, 83.0, 88.0, 111.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 79.0, 79.0, 78.0, 79.0, 78.0, 78.0, 80.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 124.0, 129.0, 132.0, 124.0, 125.0, 127.0, 124.0, 186.0, 133.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 183.0, 184.0, 187.0, 177.0, 182.0, 186.0, 181.0, 177.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 68.0, 67.0, 66.0, 63.0, 65.0, 65.0, 68.0], "value": 66.0, "unit": "score"}]}]} 09:47:52 INFO - Return code: 0 09:47:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:52 ERROR - # TBPL SUCCESS # 09:47:52 INFO - Running post-action listener: _resource_record_post_action 09:47:52 INFO - [mozharness: 2017-03-20 16:47:52.145746Z] Finished run-tests step (success) 09:47:52 INFO - Running post-run listener: _resource_record_post_run 09:47:52 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 339968; Write bytes: 377491456; Read time: 92; Write time: 237440 09:47:52 INFO - TinderboxPrint: CPU usage
13.0% 09:47:52 INFO - TinderboxPrint: I/O read bytes / time
339,968 / 92 09:47:52 INFO - TinderboxPrint: I/O write bytes / time
377,491,456 / 237,440 09:47:52 INFO - TinderboxPrint: CPU idle
8,307.4 (87.0%) 09:47:52 INFO - TinderboxPrint: CPU user
1,192.2 (12.5%) 09:47:52 INFO - TinderboxPrint: Swap in / out
0 / 0 09:47:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 122777600; Read time: 0; Write time: 104572 09:47:52 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 335872; Write bytes: 254713856; Read time: 80; Write time: 132868 09:47:52 INFO - Running post-run listener: _upload_blobber_files 09:47:52 INFO - Blob upload gear active. 09:47:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:52 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 09:47:52 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:47:52 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:47:53 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:47:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:47:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:53 INFO - (blobuploader) - INFO - Done attempting. 09:47:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:54 INFO - (blobuploader) - INFO - Done attempting. 09:47:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:47:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:47:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:55 INFO - (blobuploader) - INFO - Done attempting. 09:47:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:58 INFO - (blobuploader) - INFO - Done attempting. 09:47:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:58 INFO - Return code: 0 09:47:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e62a8140d4a08a29701bd9e4193ee07d3f313bcfee6c33d36e1e4f8727df29bd7f7465e49255f68e55e8cad1431f6493721fe166112aa3fe8384f7d0265c36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d691426d9a02dd61197adf405abe1a4dd00a9c03ef8b85d839e03e56cb0c509c72331d27003bb185368a806e175f2348144fb6041408735fb873039713ca7821", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41cbe309d56e8390f51643837a56a28cb3932a93886fd2bc7c25cb1eeeb95deba34869c49483f3f89e96fce5b087fdeb0ecfba462458b7cb3947fc646ca30869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b9b8f6a46d2bd0a53e980abf591f260b9a7e7e75e710bd0d9f6f7af11459677678b0e4a26915dc4cba626c9e4c445f6ce3d35464434c75caf37bc9bd9da330"} 09:47:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:58 INFO - Contents: 09:47:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e62a8140d4a08a29701bd9e4193ee07d3f313bcfee6c33d36e1e4f8727df29bd7f7465e49255f68e55e8cad1431f6493721fe166112aa3fe8384f7d0265c36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d691426d9a02dd61197adf405abe1a4dd00a9c03ef8b85d839e03e56cb0c509c72331d27003bb185368a806e175f2348144fb6041408735fb873039713ca7821", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41cbe309d56e8390f51643837a56a28cb3932a93886fd2bc7c25cb1eeeb95deba34869c49483f3f89e96fce5b087fdeb0ecfba462458b7cb3947fc646ca30869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b9b8f6a46d2bd0a53e980abf591f260b9a7e7e75e710bd0d9f6f7af11459677678b0e4a26915dc4cba626c9e4c445f6ce3d35464434c75caf37bc9bd9da330"} 09:47:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:58 INFO - Copying logs to upload dir... 09:47:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.183660 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-20 09:47:58.593562) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 09:47:58.597381) ========= 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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e62a8140d4a08a29701bd9e4193ee07d3f313bcfee6c33d36e1e4f8727df29bd7f7465e49255f68e55e8cad1431f6493721fe166112aa3fe8384f7d0265c36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d691426d9a02dd61197adf405abe1a4dd00a9c03ef8b85d839e03e56cb0c509c72331d27003bb185368a806e175f2348144fb6041408735fb873039713ca7821", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41cbe309d56e8390f51643837a56a28cb3932a93886fd2bc7c25cb1eeeb95deba34869c49483f3f89e96fce5b087fdeb0ecfba462458b7cb3947fc646ca30869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b9b8f6a46d2bd0a53e980abf591f260b9a7e7e75e710bd0d9f6f7af11459677678b0e4a26915dc4cba626c9e4c445f6ce3d35464434c75caf37bc9bd9da330"} build_url:https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/Do7ElnV2RK20TMLf3VC8wQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e62a8140d4a08a29701bd9e4193ee07d3f313bcfee6c33d36e1e4f8727df29bd7f7465e49255f68e55e8cad1431f6493721fe166112aa3fe8384f7d0265c36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d691426d9a02dd61197adf405abe1a4dd00a9c03ef8b85d839e03e56cb0c509c72331d27003bb185368a806e175f2348144fb6041408735fb873039713ca7821", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41cbe309d56e8390f51643837a56a28cb3932a93886fd2bc7c25cb1eeeb95deba34869c49483f3f89e96fce5b087fdeb0ecfba462458b7cb3947fc646ca30869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95b9b8f6a46d2bd0a53e980abf591f260b9a7e7e75e710bd0d9f6f7af11459677678b0e4a26915dc4cba626c9e4c445f6ce3d35464434c75caf37bc9bd9da330"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 09:47:58.621940) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:47:58.622266) ========= 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=7de08804244eb36560b44971000001c3-1490027221.671450-551078829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:47:58.707833) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 09:47:58.708174) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 09:47:58.708508) ========= ========= Total master_lag: 0.27 =========