builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1479435135.05 results: success (0) buildid: 20161117165528 builduid: d69e7ea8f29140b88845a69bb396a6a2 revision: 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.053244) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.053673) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.053991) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.139553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.139925) ========= 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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-17 18:12:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.8M=0s 2016-11-17 18:12:15 (88.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.282303) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.282629) ========= 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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021197 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:15.341768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 18:12:15.342139) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 --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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-17 18:12:15,725 truncating revision to first 12 chars 2016-11-17 18:12:15,725 Setting DEBUG logging. 2016-11-17 18:12:15,725 attempt 1/10 2016-11-17 18:12:15,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2ffb3d6fa715?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 18:12:16,310 unpacking tar archive at: graphics-2ffb3d6fa715/testing/mozharness/ program finished with exit code 0 elapsedTime=1.196241 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 18:12:16.553786) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:16.554111) ========= script_repo_revision: 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:16.554531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:16.554861) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 18:12:16.569018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-17 18:12:16.569398) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:12:17 INFO - MultiFileLogger online at 20161117 18:12:17 in /builds/slave/test 18:12:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:12:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:12:17 INFO - {'append_to_log': False, 18:12:17 INFO - 'base_work_dir': '/builds/slave/test', 18:12:17 INFO - 'blob_upload_branch': 'Graphics', 18:12:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:12:17 INFO - 'branch': 'Graphics', 18:12:17 INFO - 'buildbot_json_path': 'buildprops.json', 18:12:17 INFO - 'config_files': ('talos/linux_config.py',), 18:12:17 INFO - 'default_actions': ('clobber', 18:12:17 INFO - 'read-buildbot-config', 18:12:17 INFO - 'download-and-extract', 18:12:17 INFO - 'populate-webroot', 18:12:17 INFO - 'create-virtualenv', 18:12:17 INFO - 'install', 18:12:17 INFO - 'run-tests'), 18:12:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:12:17 INFO - 'download_minidump_stackwalk': True, 18:12:17 INFO - 'download_symbols': 'ondemand', 18:12:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:12:17 INFO - 'tooltool.py': '/tools/tooltool.py', 18:12:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:12:17 INFO - '/tools/misc-python/virtualenv.py')}, 18:12:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:12:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:12:17 INFO - 'installer_path': 'installer.exe', 18:12:17 INFO - 'log_level': 'info', 18:12:17 INFO - 'log_name': 'talos', 18:12:17 INFO - 'log_to_console': True, 18:12:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:12:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:12:17 INFO - 'opt_config_files': (), 18:12:17 INFO - 'pip_index': False, 18:12:17 INFO - 'sps_profile': False, 18:12:17 INFO - 'sps_profile_interval': 0, 18:12:17 INFO - 'suite': 'dromaeojs', 18:12:17 INFO - 'system_bits': '32', 18:12:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:12:17 INFO - 'title': 'talos-linux64-ix-053', 18:12:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:12:17 INFO - 'use_talos_json': True, 18:12:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:12:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:12:17 INFO - 'work_dir': 'build'} 18:12:17 INFO - [mozharness: 2016-11-18 02:12:17.050610Z] Running clobber step. 18:12:17 INFO - Running pre-action listener: _resource_record_pre_action 18:12:17 INFO - Running main action method: clobber 18:12:17 INFO - rmtree: /builds/slave/test/build 18:12:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:12:17 INFO - Running post-action listener: _resource_record_post_action 18:12:17 INFO - [mozharness: 2016-11-18 02:12:17.503517Z] Finished clobber step (success) 18:12:17 INFO - [mozharness: 2016-11-18 02:12:17.503617Z] Running read-buildbot-config step. 18:12:17 INFO - Running pre-action listener: _resource_record_pre_action 18:12:17 INFO - Running main action method: read_buildbot_config 18:12:17 INFO - Using buildbot properties: 18:12:17 INFO - { 18:12:17 INFO - "project": "", 18:12:17 INFO - "product": "firefox", 18:12:17 INFO - "script_repo_revision": "default", 18:12:17 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 18:12:17 INFO - "repository": "", 18:12:17 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 18:12:17 INFO - "buildid": "20161117165528", 18:12:17 INFO - "pgo_build": "False", 18:12:17 INFO - "basedir": "/builds/slave/test", 18:12:17 INFO - "buildnumber": 4, 18:12:17 INFO - "slavename": "talos-linux64-ix-053", 18:12:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:12:17 INFO - "platform": "ubuntu64_hw", 18:12:17 INFO - "scheduler": "tests-graphics-linux64-talos", 18:12:17 INFO - "branch": "graphics", 18:12:17 INFO - "repo_path": "projects/graphics", 18:12:17 INFO - "revision": "2ffb3d6fa715db68f5065a80e1633cf28bfcd761", 18:12:17 INFO - "stage_platform": "linux64", 18:12:17 INFO - "builduid": "d69e7ea8f29140b88845a69bb396a6a2", 18:12:17 INFO - "slavebuilddir": "test" 18:12:17 INFO - } 18:12:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:12:17 INFO - Running post-action listener: _resource_record_post_action 18:12:17 INFO - [mozharness: 2016-11-18 02:12:17.506029Z] Finished read-buildbot-config step (success) 18:12:17 INFO - [mozharness: 2016-11-18 02:12:17.506117Z] Running download-and-extract step. 18:12:17 INFO - Running pre-action listener: _resource_record_pre_action 18:12:17 INFO - Running main action method: download_and_extract 18:12:17 INFO - mkdir: /builds/slave/test/build/tests 18:12:17 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')]} 18:12:17 INFO - trying https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:12:17 INFO - Downloading https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:12:18 INFO - Downloaded 1361 bytes. 18:12:18 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:12:18 INFO - Using the following test package requirements: 18:12:18 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:12:18 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:12:18 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:12:18 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'jsshell-linux-x86_64.zip'], 18:12:18 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:12:18 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:12:18 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:12:18 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:12:18 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:12:18 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:12:18 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:12:18 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:12:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:12:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:12:18 INFO - Fetch https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:12:18 INFO - Http code: 200 18:12:18 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 18:12:18 INFO - x-amz-cf-id: 8C5UJFGt4KAgRfVfRPhYhzF4or2zQ5qC_7OitSOVpukNdMiekon9Og== 18:12:18 INFO - x-amz-version-id: oF_GV6qEfdvFJZEBoN2wQGHCtfUNFtoC 18:12:18 INFO - Expected file size: 20361480 18:12:18 INFO - Obtained file size: 20361480 18:12:22 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:12:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:12:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:12:22 INFO - Fetch https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:12:23 INFO - Http code: 200 18:12:23 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 18:12:23 INFO - x-amz-cf-id: NSKucg3prTqYAPXF0Ng1zMWSh96A2ONjMLo4rsg8EoJ3v2IOllsLpQ== 18:12:23 INFO - x-amz-version-id: oF_GV6qEfdvFJZEBoN2wQGHCtfUNFtoC 18:12:23 INFO - Expected file size: 20361480 18:12:23 INFO - Obtained file size: 20361480 18:12:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:12:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:12:28 INFO - Fetch https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:12:28 INFO - Http code: 200 18:12:28 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 18:12:28 INFO - x-amz-cf-id: Oms0zc-fqHYtMjKffwagkozwhoREp_kvbMWig82rNhXiQtW8NzqcKQ== 18:12:28 INFO - x-amz-version-id: XNMrnK.Pn778cCogm3KoZay4qBuGcQGT 18:12:29 INFO - Expected file size: 14409392 18:12:29 INFO - Obtained file size: 14409392 18:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:29 INFO - trying https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:12:31 INFO - Downloaded 55731824 bytes. 18:12:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:12:31 INFO - mkdir: /builds/slave/test/properties 18:12:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:31 INFO - Contents: 18:12:31 INFO - build_url:https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:12:31 INFO - Running post-action listener: _resource_record_post_action 18:12:31 INFO - Running post-action listener: set_extra_try_arguments 18:12:31 INFO - [mozharness: 2016-11-18 02:12:31.908928Z] Finished download-and-extract step (success) 18:12:31 INFO - [mozharness: 2016-11-18 02:12:31.909154Z] Running populate-webroot step. 18:12:31 INFO - Running pre-action listener: _resource_record_pre_action 18:12:31 INFO - Running main action method: populate_webroot 18:12:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:12:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:12:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:12:31 INFO - '--tppagecycles', 18:12:31 INFO - '7'], 18:12:31 INFO - 'tests': ['tsvgm']}}, 18:12:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:12:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:12:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:12:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:12:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['damp', 'tps']}, 18:12:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'tests': ['damp', 'tps']}, 18:12:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['dromaeo_dom']}, 18:12:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['basic_compositor_video']}, 18:12:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:12:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['a11yr', 18:12:31 INFO - 'ts_paint', 18:12:31 INFO - 'tpaint', 18:12:31 INFO - 'sessionrestore', 18:12:31 INFO - 'sessionrestore_no_auto_restore', 18:12:31 INFO - 'tabpaint']}, 18:12:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['tsvgx', 18:12:31 INFO - 'tsvgr_opacity', 18:12:31 INFO - 'tart', 18:12:31 INFO - 'tscrollx', 18:12:31 INFO - 'cart']}, 18:12:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:12:31 INFO - 'tsvgr_opacity', 18:12:31 INFO - 'tart', 18:12:31 INFO - 'tscrollx', 18:12:31 INFO - 'cart']}, 18:12:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'talos_options': ['--disable-e10s'], 18:12:31 INFO - 'tests': ['tp5o']}, 18:12:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:12:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'talos_options': ['--disable-e10s', 18:12:31 INFO - '--xperf_path', 18:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:31 INFO - 'tests': ['tp5n']}, 18:12:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:12:31 INFO - 'talos_options': ['--xperf_path', 18:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:31 INFO - 'tests': ['tp5n']}}, 18:12:31 INFO - 'talos.zip': {'path': '', 18:12:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:12:31 INFO - Running post-action listener: _resource_record_post_action 18:12:31 INFO - [mozharness: 2016-11-18 02:12:31.925925Z] Finished populate-webroot step (success) 18:12:31 INFO - [mozharness: 2016-11-18 02:12:31.926012Z] Running create-virtualenv step. 18:12:31 INFO - Running pre-action listener: _resource_record_pre_action 18:12:31 INFO - Running main action method: create_virtualenv 18:12:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:31 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 18:12:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:12:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:32 INFO - Using real prefix '/usr' 18:12:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:34 INFO - Installing pip.................done. 18:12:34 INFO - Return code: 0 18:12:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:12:34 INFO - Reading from file tmpfile_stdout 18:12:34 INFO - Output received: 18:12:34 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) 18:12:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:12:34 INFO - Using env: {'DISPLAY': ':0', 18:12:34 INFO - 'HOME': '/home/cltbld', 18:12:34 INFO - 'LANG': 'en_US.UTF-8', 18:12:34 INFO - 'LANGUAGE': 'en_US:en', 18:12:34 INFO - 'LOGNAME': 'cltbld', 18:12:34 INFO - 'MAIL': '/var/mail/cltbld', 18:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:34 INFO - 'MOZ_NO_REMOTE': '1', 18:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:34 INFO - 'NO_EM_RESTART': '1', 18:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:34 INFO - 'PWD': '/builds/slave/test', 18:12:34 INFO - 'SHELL': '/bin/bash', 18:12:34 INFO - 'SHLVL': '1', 18:12:34 INFO - 'TERM': 'linux', 18:12:34 INFO - 'TMOUT': '86400', 18:12:34 INFO - 'USER': 'cltbld', 18:12:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:34 INFO - '_': '/tools/buildbot/bin/python'} 18:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:34 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 18:12:34 INFO - Cleaning up... 18:12:34 INFO - Return code: 0 18:12:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:12:34 INFO - Using env: {'DISPLAY': ':0', 18:12:34 INFO - 'HOME': '/home/cltbld', 18:12:34 INFO - 'LANG': 'en_US.UTF-8', 18:12:34 INFO - 'LANGUAGE': 'en_US:en', 18:12:34 INFO - 'LOGNAME': 'cltbld', 18:12:34 INFO - 'MAIL': '/var/mail/cltbld', 18:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:34 INFO - 'MOZ_NO_REMOTE': '1', 18:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:34 INFO - 'NO_EM_RESTART': '1', 18:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:34 INFO - 'PWD': '/builds/slave/test', 18:12:34 INFO - 'SHELL': '/bin/bash', 18:12:34 INFO - 'SHLVL': '1', 18:12:34 INFO - 'TERM': 'linux', 18:12:34 INFO - 'TMOUT': '86400', 18:12:34 INFO - 'USER': 'cltbld', 18:12:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:34 INFO - '_': '/tools/buildbot/bin/python'} 18:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:35 INFO - Downloading/unpacking psutil>=3.1.1 18:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:37 INFO - Installing collected packages: psutil 18:12:37 INFO - Running setup.py install for psutil 18:12:37 INFO - building 'psutil._psutil_linux' extension 18:12:37 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 18:12:37 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 18:12:37 INFO - building 'psutil._psutil_posix' extension 18:12:37 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 18:12:37 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 18:12:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:37 INFO - Successfully installed psutil 18:12:37 INFO - Cleaning up... 18:12:37 INFO - Return code: 0 18:12:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:12:37 INFO - Using env: {'DISPLAY': ':0', 18:12:37 INFO - 'HOME': '/home/cltbld', 18:12:37 INFO - 'LANG': 'en_US.UTF-8', 18:12:37 INFO - 'LANGUAGE': 'en_US:en', 18:12:37 INFO - 'LOGNAME': 'cltbld', 18:12:37 INFO - 'MAIL': '/var/mail/cltbld', 18:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:37 INFO - 'MOZ_NO_REMOTE': '1', 18:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:37 INFO - 'NO_EM_RESTART': '1', 18:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:37 INFO - 'PWD': '/builds/slave/test', 18:12:37 INFO - 'SHELL': '/bin/bash', 18:12:37 INFO - 'SHLVL': '1', 18:12:37 INFO - 'TERM': 'linux', 18:12:37 INFO - 'TMOUT': '86400', 18:12:37 INFO - 'USER': 'cltbld', 18:12:37 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:37 INFO - '_': '/tools/buildbot/bin/python'} 18:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:39 INFO - Installing collected packages: mozsystemmonitor 18:12:39 INFO - Running setup.py install for mozsystemmonitor 18:12:40 INFO - Successfully installed mozsystemmonitor 18:12:40 INFO - Cleaning up... 18:12:40 INFO - Return code: 0 18:12:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:40 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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:40 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 18:12:40 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 18:12:40 INFO - Using env: {'DISPLAY': ':0', 18:12:40 INFO - 'HOME': '/home/cltbld', 18:12:40 INFO - 'LANG': 'en_US.UTF-8', 18:12:40 INFO - 'LANGUAGE': 'en_US:en', 18:12:40 INFO - 'LOGNAME': 'cltbld', 18:12:40 INFO - 'MAIL': '/var/mail/cltbld', 18:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:40 INFO - 'MOZ_NO_REMOTE': '1', 18:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:40 INFO - 'NO_EM_RESTART': '1', 18:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:40 INFO - 'PWD': '/builds/slave/test', 18:12:40 INFO - 'SHELL': '/bin/bash', 18:12:40 INFO - 'SHLVL': '1', 18:12:40 INFO - 'TERM': 'linux', 18:12:40 INFO - 'TMOUT': '86400', 18:12:40 INFO - 'USER': 'cltbld', 18:12:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:40 INFO - '_': '/tools/buildbot/bin/python'} 18:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:40 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:42 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:42 INFO - warning: no files found matching '*.txt' 18:12:42 INFO - no previously-included directories found matching 'build' 18:12:42 INFO - no previously-included directories found matching 'dist' 18:12:42 INFO - no previously-included directories found matching '.git*' 18:12:42 INFO - Installing collected packages: jsonschema, functools32 18:12:42 INFO - Running setup.py install for functools32 18:12:42 INFO - warning: no files found matching '*.txt' 18:12:42 INFO - no previously-included directories found matching 'build' 18:12:42 INFO - no previously-included directories found matching 'dist' 18:12:42 INFO - no previously-included directories found matching '.git*' 18:12:42 INFO - Successfully installed jsonschema functools32 18:12:42 INFO - Cleaning up... 18:12:42 INFO - Return code: 0 18:12:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:42 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')]} 18:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:42 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')]} 18:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:42 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 18:12:42 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 18:12:42 INFO - Using env: {'DISPLAY': ':0', 18:12:42 INFO - 'HOME': '/home/cltbld', 18:12:42 INFO - 'LANG': 'en_US.UTF-8', 18:12:42 INFO - 'LANGUAGE': 'en_US:en', 18:12:42 INFO - 'LOGNAME': 'cltbld', 18:12:42 INFO - 'MAIL': '/var/mail/cltbld', 18:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:42 INFO - 'MOZ_NO_REMOTE': '1', 18:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:42 INFO - 'NO_EM_RESTART': '1', 18:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:42 INFO - 'PWD': '/builds/slave/test', 18:12:42 INFO - 'SHELL': '/bin/bash', 18:12:42 INFO - 'SHLVL': '1', 18:12:42 INFO - 'TERM': 'linux', 18:12:42 INFO - 'TMOUT': '86400', 18:12:42 INFO - 'USER': 'cltbld', 18:12:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:42 INFO - '_': '/tools/buildbot/bin/python'} 18:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:43 INFO - Cleaning up... 18:12:43 INFO - Return code: 0 18:12:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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', '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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:12: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 blobuploader==1.2.4 18:12:43 INFO - Using env: {'DISPLAY': ':0', 18:12:43 INFO - 'HOME': '/home/cltbld', 18:12:43 INFO - 'LANG': 'en_US.UTF-8', 18:12:43 INFO - 'LANGUAGE': 'en_US:en', 18:12:43 INFO - 'LOGNAME': 'cltbld', 18:12:43 INFO - 'MAIL': '/var/mail/cltbld', 18:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:43 INFO - 'MOZ_NO_REMOTE': '1', 18:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:43 INFO - 'NO_EM_RESTART': '1', 18:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:43 INFO - 'PWD': '/builds/slave/test', 18:12:43 INFO - 'SHELL': '/bin/bash', 18:12:43 INFO - 'SHLVL': '1', 18:12:43 INFO - 'TERM': 'linux', 18:12:43 INFO - 'TMOUT': '86400', 18:12:43 INFO - 'USER': 'cltbld', 18:12:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:43 INFO - '_': '/tools/buildbot/bin/python'} 18:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:45 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 18:12:45 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 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:45 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 18:12:45 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 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:45 INFO - Downloading docopt-0.6.1.tar.gz 18:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:45 INFO - Running setup.py install for blobuploader 18:12:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:46 INFO - Running setup.py install for requests 18:12:46 INFO - Running setup.py install for docopt 18:12:46 INFO - Successfully installed blobuploader requests docopt 18:12:46 INFO - Cleaning up... 18:12:46 INFO - Return code: 0 18:12:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:46 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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:46 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 18:12:46 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 18:12:46 INFO - Using env: {'DISPLAY': ':0', 18:12:46 INFO - 'HOME': '/home/cltbld', 18:12:46 INFO - 'LANG': 'en_US.UTF-8', 18:12:46 INFO - 'LANGUAGE': 'en_US:en', 18:12:46 INFO - 'LOGNAME': 'cltbld', 18:12:46 INFO - 'MAIL': '/var/mail/cltbld', 18:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:46 INFO - 'MOZ_NO_REMOTE': '1', 18:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:46 INFO - 'NO_EM_RESTART': '1', 18:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:46 INFO - 'PWD': '/builds/slave/test', 18:12:46 INFO - 'SHELL': '/bin/bash', 18:12:46 INFO - 'SHLVL': '1', 18:12:46 INFO - 'TERM': 'linux', 18:12:46 INFO - 'TMOUT': '86400', 18:12:46 INFO - 'USER': 'cltbld', 18:12:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:46 INFO - '_': '/tools/buildbot/bin/python'} 18:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:46 INFO - Running setup.py (path:/tmp/pip-ExxUoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:46 INFO - Running setup.py (path:/tmp/pip-InEgSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:46 INFO - Running setup.py (path:/tmp/pip-Dc0RoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:46 INFO - Running setup.py (path:/tmp/pip-MAFFeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:47 INFO - Running setup.py (path:/tmp/pip-TuQel8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:47 INFO - Running setup.py (path:/tmp/pip-qOo87q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:47 INFO - Running setup.py (path:/tmp/pip-C_i7nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:47 INFO - Running setup.py (path:/tmp/pip-s4gcMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:47 INFO - Running setup.py (path:/tmp/pip-bXQvJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:47 INFO - Running setup.py (path:/tmp/pip-8Dv7kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:47 INFO - Running setup.py (path:/tmp/pip-zDQ8si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:47 INFO - Running setup.py (path:/tmp/pip-uXrbq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:47 INFO - Running setup.py (path:/tmp/pip-uwKzfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:47 INFO - Running setup.py (path:/tmp/pip-h3HUGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:47 INFO - Running setup.py (path:/tmp/pip-Zj8IsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:47 INFO - Running setup.py (path:/tmp/pip-KljJqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:47 INFO - Running setup.py (path:/tmp/pip-VdUDNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:48 INFO - Running setup.py install for manifestparser 18:12:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Running setup.py install for mozcrash 18:12:48 INFO - Running setup.py install for mozdebug 18:12:48 INFO - Running setup.py install for mozdevice 18:12:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Running setup.py install for mozfile 18:12:48 INFO - Running setup.py install for mozhttpd 18:12:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Running setup.py install for mozinfo 18:12:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Running setup.py install for mozInstall 18:12:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:48 INFO - Running setup.py install for mozleak 18:12:48 INFO - Running setup.py install for mozlog 18:12:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Running setup.py install for moznetwork 18:12:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Running setup.py install for mozprocess 18:12:49 INFO - Running setup.py install for mozprofile 18:12:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Running setup.py install for mozrunner 18:12:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Running setup.py install for mozscreenshot 18:12:49 INFO - Running setup.py install for moztest 18:12:49 INFO - Running setup.py install for mozversion 18:12:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:49 INFO - Cleaning up... 18:12:49 INFO - Return code: 0 18:12:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:49 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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:49 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 18:12:49 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 18:12:49 INFO - Using env: {'DISPLAY': ':0', 18:12:49 INFO - 'HOME': '/home/cltbld', 18:12:49 INFO - 'LANG': 'en_US.UTF-8', 18:12:49 INFO - 'LANGUAGE': 'en_US:en', 18:12:49 INFO - 'LOGNAME': 'cltbld', 18:12:49 INFO - 'MAIL': '/var/mail/cltbld', 18:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:49 INFO - 'MOZ_NO_REMOTE': '1', 18:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:49 INFO - 'NO_EM_RESTART': '1', 18:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:49 INFO - 'PWD': '/builds/slave/test', 18:12:49 INFO - 'SHELL': '/bin/bash', 18:12:49 INFO - 'SHLVL': '1', 18:12:49 INFO - 'TERM': 'linux', 18:12:49 INFO - 'TMOUT': '86400', 18:12:49 INFO - 'USER': 'cltbld', 18:12:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:49 INFO - '_': '/tools/buildbot/bin/python'} 18:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:50 INFO - Running setup.py (path:/tmp/pip-i6ldJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:50 INFO - Running setup.py (path:/tmp/pip-REdcWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:50 INFO - Running setup.py (path:/tmp/pip-rgQAbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:50 INFO - Running setup.py (path:/tmp/pip-qzR0k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:50 INFO - Running setup.py (path:/tmp/pip-ggu6V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:50 INFO - Running setup.py (path:/tmp/pip-42mXoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:50 INFO - Running setup.py (path:/tmp/pip-dRgaFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:50 INFO - Running setup.py (path:/tmp/pip-e060nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:50 INFO - Running setup.py (path:/tmp/pip-6AUgA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:50 INFO - Running setup.py (path:/tmp/pip-FFmdfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:50 INFO - Running setup.py (path:/tmp/pip-KV9pQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:50 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:50 INFO - Running setup.py (path:/tmp/pip-jR6jj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:50 INFO - Running setup.py (path:/tmp/pip-sF94DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:51 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)) 18:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:51 INFO - Running setup.py (path:/tmp/pip-Y9_T09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:51 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)) 18:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:51 INFO - Running setup.py (path:/tmp/pip-XGCkrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:51 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)) 18:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:51 INFO - Running setup.py (path:/tmp/pip-1lUmRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 18:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:51 INFO - Running setup.py (path:/tmp/pip-SXSrTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:51 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)) 18:12:51 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)) 18:12:51 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)) 18:12:51 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)) 18:12:51 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:51 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:51 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 18:12:51 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 18:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:53 INFO - Downloading blessings-1.6.tar.gz 18:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:53 INFO - Installing collected packages: blessings 18:12:53 INFO - Running setup.py install for blessings 18:12:53 INFO - Successfully installed blessings 18:12:53 INFO - Cleaning up... 18:12:53 INFO - Return code: 0 18:12:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:53 INFO - Reading from file tmpfile_stdout 18:12:53 INFO - Current package versions: 18:12:53 INFO - argparse == 1.2.1 18:12:53 INFO - blessings == 1.6 18:12:53 INFO - blobuploader == 1.2.4 18:12:53 INFO - docopt == 0.6.1 18:12:53 INFO - functools32 == 3.2.3-2 18:12:53 INFO - jsonschema == 2.5.1 18:12:53 INFO - manifestparser == 1.1 18:12:53 INFO - mozInstall == 1.12 18:12:53 INFO - mozcrash == 1.0 18:12:53 INFO - mozdebug == 0.1 18:12:53 INFO - mozdevice == 0.48 18:12:53 INFO - mozfile == 1.2 18:12:53 INFO - mozhttpd == 0.7 18:12:53 INFO - mozinfo == 0.9 18:12:53 INFO - mozleak == 0.1 18:12:53 INFO - mozlog == 3.4 18:12:53 INFO - moznetwork == 0.27 18:12:53 INFO - mozprocess == 0.23 18:12:53 INFO - mozprofile == 0.28 18:12:53 INFO - mozrunner == 6.13 18:12:53 INFO - mozscreenshot == 0.1 18:12:53 INFO - mozsystemmonitor == 0.3 18:12:53 INFO - moztest == 0.7 18:12:53 INFO - mozversion == 1.4 18:12:53 INFO - psutil == 3.1.1 18:12:53 INFO - requests == 1.2.3 18:12:53 INFO - wsgiref == 0.1.2 18:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:53 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')]} 18:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:53 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')]} 18:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:53 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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:53 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 18:12:53 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 18:12:53 INFO - Using env: {'DISPLAY': ':0', 18:12:53 INFO - 'HOME': '/home/cltbld', 18:12:53 INFO - 'LANG': 'en_US.UTF-8', 18:12:53 INFO - 'LANGUAGE': 'en_US:en', 18:12:53 INFO - 'LOGNAME': 'cltbld', 18:12:53 INFO - 'MAIL': '/var/mail/cltbld', 18:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:53 INFO - 'MOZ_NO_REMOTE': '1', 18:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:53 INFO - 'NO_EM_RESTART': '1', 18:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:53 INFO - 'PWD': '/builds/slave/test', 18:12:53 INFO - 'SHELL': '/bin/bash', 18:12:53 INFO - 'SHLVL': '1', 18:12:53 INFO - 'TERM': 'linux', 18:12:53 INFO - 'TMOUT': '86400', 18:12:53 INFO - 'USER': 'cltbld', 18:12:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:53 INFO - '_': '/tools/buildbot/bin/python'} 18:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 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)) 18:12:53 INFO - Cleaning up... 18:12:53 INFO - Return code: 0 18:12:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:53 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')]} 18:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:53 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')]} 18:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:53 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 0x7f389bb8ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, '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': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', '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 18:12:53 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 18:12:53 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 18:12:53 INFO - Using env: {'DISPLAY': ':0', 18:12:53 INFO - 'HOME': '/home/cltbld', 18:12:53 INFO - 'LANG': 'en_US.UTF-8', 18:12:53 INFO - 'LANGUAGE': 'en_US:en', 18:12:53 INFO - 'LOGNAME': 'cltbld', 18:12:53 INFO - 'MAIL': '/var/mail/cltbld', 18:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:53 INFO - 'MOZ_NO_REMOTE': '1', 18:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:53 INFO - 'NO_EM_RESTART': '1', 18:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:53 INFO - 'PWD': '/builds/slave/test', 18:12:53 INFO - 'SHELL': '/bin/bash', 18:12:53 INFO - 'SHLVL': '1', 18:12:53 INFO - 'TERM': 'linux', 18:12:53 INFO - 'TMOUT': '86400', 18:12:53 INFO - 'USER': 'cltbld', 18:12:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:53 INFO - '_': '/tools/buildbot/bin/python'} 18:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:54 INFO - Cleaning up... 18:12:54 INFO - Return code: 0 18:12:54 INFO - Running post-action listener: _resource_record_post_action 18:12:54 INFO - Running post-action listener: _start_resource_monitoring 18:12:54 INFO - Starting resource monitoring. 18:12:54 INFO - [mozharness: 2016-11-18 02:12:54.202810Z] Finished create-virtualenv step (success) 18:12:54 INFO - [mozharness: 2016-11-18 02:12:54.203277Z] Running install step. 18:12:54 INFO - Running pre-action listener: _resource_record_pre_action 18:12:54 INFO - Running main action method: install 18:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:54 INFO - Reading from file tmpfile_stdout 18:12:54 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:54 INFO - Reading from file tmpfile_stdout 18:12:54 INFO - Output received: 18:12:54 INFO - Usage: mozinstall [options] installer 18:12:54 INFO - Options: 18:12:54 INFO - -h, --help show this help message and exit 18:12:54 INFO - -d DEST, --destination=DEST 18:12:54 INFO - Directory to install application into. [default: 18:12:54 INFO - "/builds/slave/test"] 18:12:54 INFO - --app=APP Application being installed. [default: firefox] 18:12:54 INFO - mkdir: /builds/slave/test/build/application 18:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:13:10 INFO - Reading from file tmpfile_stdout 18:13:10 INFO - Output received: 18:13:10 INFO - /builds/slave/test/build/application/firefox/firefox 18:13:10 INFO - Running post-action listener: _resource_record_post_action 18:13:10 INFO - [mozharness: 2016-11-18 02:13:10.663896Z] Finished install step (success) 18:13:10 INFO - [mozharness: 2016-11-18 02:13:10.664149Z] Running run-tests step. 18:13:10 INFO - Running pre-action listener: _resource_record_pre_action 18:13:10 INFO - Running main action method: run_tests 18:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:13:10 INFO - Python 2.7.3 18:13:10 INFO - Return code: 0 18:13:10 INFO - grabbing minidump binary from tooltool 18:13:10 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')]} 18:13:10 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 0x2b79fc0>, '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 0x2b7aec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:13:10 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 18:13:10 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 18:13:10 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 18:13:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:13:10 INFO - Return code: 0 18:13:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:13:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:13:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:13:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:13:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 18:13:10 INFO - Using env: {'DISPLAY': ':0', 18:13:10 INFO - 'HOME': '/home/cltbld', 18:13:10 INFO - 'LANG': 'en_US.UTF-8', 18:13:10 INFO - 'LANGUAGE': 'en_US:en', 18:13:10 INFO - 'LOGNAME': 'cltbld', 18:13:10 INFO - 'MAIL': '/var/mail/cltbld', 18:13:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:13:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:10 INFO - 'MOZ_NO_REMOTE': '1', 18:13:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:10 INFO - 'NO_EM_RESTART': '1', 18:13:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:10 INFO - 'PWD': '/builds/slave/test', 18:13:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:13:10 INFO - 'SHELL': '/bin/bash', 18:13:10 INFO - 'SHLVL': '1', 18:13:10 INFO - 'TERM': 'linux', 18:13:10 INFO - 'TMOUT': '86400', 18:13:10 INFO - 'USER': 'cltbld', 18:13:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181', 18:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:10 INFO - '_': '/tools/buildbot/bin/python'} 18:13:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:13:10 INFO - mozversion application_buildid: 20161117165528 18:13:10 INFO - mozversion application_changeset: 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 18:13:10 INFO - mozversion application_display_name: Nightly 18:13:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:13:10 INFO - mozversion application_name: Firefox 18:13:10 INFO - mozversion application_remotingname: firefox 18:13:10 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:13:10 INFO - mozversion application_vendor: Mozilla 18:13:10 INFO - mozversion application_version: 53.0a1 18:13:10 INFO - mozversion platform_buildid: 20161117165528 18:13:10 INFO - mozversion platform_changeset: 2ffb3d6fa715db68f5065a80e1633cf28bfcd761 18:13:10 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:13:10 INFO - mozversion platform_version: 53.0a1 18:13:10 INFO - using testdate: 1479435190 18:13:10 INFO - actual date: 1479435190 18:13:10 INFO - starting webserver on 'localhost:51085' 18:13:10 INFO - SUITE-START | Running 2 tests 18:13:10 INFO - TEST-START | dromaeo_css 18:13:10 INFO - operating with platform_type : linux_ 18:13:10 INFO - Initialising browser for dromaeo_css test... 18:13:10 INFO - TEST-INFO | started process 4003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSMFDIb/profile http://localhost:51085/getInfo.html) 18:13:15 INFO - PROCESS | 4003 | __metrics Screen width/height:1600/1200 18:13:15 INFO - PROCESS | 4003 | colorDepth:24 18:13:15 INFO - PROCESS | 4003 | Browser inner width/height: 1024/697 18:13:15 INFO - PROCESS | 4003 | __metrics 18:13:16 INFO - PROCESS | 4003 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:13:16 INFO - PROCESS | 4003 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:13:16 INFO - PROCESS | 4003 | console.error: PushService: 18:13:16 INFO - PROCESS | 4003 | stateChangeProcessEnqueue: Error transitioning state 18:13:16 INFO - PROCESS | 4003 | UnknownError 18:13:17 INFO - TEST-INFO | 4003: exit 0 18:13:17 INFO - Browser initialized. 18:13:17 INFO - Running cycle 1/1 for dromaeo_css test... 18:13:17 INFO - TEST-INFO | started process 4085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSMFDIb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:29 INFO - PROCESS | 4085 | RSS: Main: 174911488 18:13:29 INFO - PROCESS | 4085 | 18:14:21 INFO - PROCESS | 4085 | 1479435261285 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:16:34 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51085/tests/dromaeo/cssquery-ext.html) 18:16:35 INFO - PROCESS | 4085 | RSS: Main: 264720384 18:16:35 INFO - PROCESS | 4085 | 18:19:38 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-ext.html (next: http://localhost:51085/tests/dromaeo/cssquery-jquery.html) 18:19:38 INFO - PROCESS | 4085 | RSS: Main: 276819968 18:19:38 INFO - PROCESS | 4085 | 18:22:41 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51085/tests/dromaeo/cssquery-mootools.html) 18:22:41 INFO - PROCESS | 4085 | RSS: Main: 222179328 18:22:41 INFO - PROCESS | 4085 | 18:25:44 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51085/tests/dromaeo/cssquery-prototype.html) 18:25:44 INFO - PROCESS | 4085 | RSS: Main: 220217344 18:25:44 INFO - PROCESS | 4085 | 18:28:48 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51085/tests/dromaeo/cssquery-yui.html) 18:28:48 INFO - PROCESS | 4085 | RSS: Main: 220241920 18:28:48 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | Cycle 1(1): loaded http://localhost:51085/tests/dromaeo/cssquery-yui.html (next: http://localhost:51085/tests/dromaeo/cssquery-dojo.html) 18:31:51 INFO - PROCESS | 4085 | RSS: Main: 313589760 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | __start_tp_report 18:31:51 INFO - PROCESS | 4085 | _x_x_mozilla_page_load 18:31:51 INFO - PROCESS | 4085 | _x_x_mozilla_page_load_details 18:31:51 INFO - PROCESS | 4085 | |i|pagename|runs| 18:31:51 INFO - PROCESS | 4085 | |0;dojo.html;2475.2079334612927;2500.9499810003795;2517.1944977607172;2517.6601158843564;2528.4943011397727;4287.121140166266;4314.029343397738;4327.350897365397;4378.627816635586;4411.294192929132;4394.978025109878;4448.377227188196;4474.217012022899;4499.91000179997;4588.564086411786;4018.658414034796;4037.4751282333405;4072.8981775455554;4121.732087414323;4155.645273562567;4071.877843664695;4132.793360331972;4176.770277634729;4199.286121359361;4207.389928460371;4185.351270553069;4197.97901010496;4216.114615930658;4220.704550681454;4233.597808208231;3997.5802540733093;4019.437278780973;4023.1752490739327;4076.5719599441927;4080.163566468867;4063.7358571692744;4064.8374065037365;4068.410080538335;4071.2061148076136;4075.551689314173;4382.802773875183;4399.912001759979;4437.800298986554;4460.264056430718;4464.419625448671;4731.195696731564;4732.597729193023;4748.216544270192;4778.068276686047;4798.1123492154065;4767.284907263904;4768.880777980578;4780.450248221464;4809.326694262806;4837.564619184255;4329.393884856123;4371.322445021026;4375.037491751809;4456.153330867125;4476.910461790779;4752.928706069411;4788.736619485892;4800.591949684316;4817.614590832725;4858.562729354375;4562.749048802281;4621.098885717252;4622.260438329851;4623.699459535187;4667.299905014207;4569.748663823521;4699.365585645963;4700.435947686231;4702.36518070063;4716.716996980193;4582.839600614029;4615.930761038587;4615.976920115445;4617.076584683077;4617.746023968714;4654.86035418938;4666.323383109498;4669.929951050737;4673.813047478131;4677.368555244999;3977.5226972762875;4003.6997225208224;4015.578364271768;4015.718899677053;4018.9196216076098;3542.291541691672;3547.184147646056;3574.4995700601935;3581.015220814245;3591.9281614367565;4096.938545921814;4099.733517321364;4101.589841015874;4117.464729585135;4131.607497287753;4596.5863072323655;4651.744154071558;4737.573618374294;4779.665423420397;4817.084753896818;4043.9595604043584;4055.6755459563165;4079.265732168179;4091.386292056156;4135.234981528367;4306.0957198987935;4307.935380969288;4310.137972405531;4314.201872653569;4360.803763830571;4462.44219472566;4462.509123996358;4473.977630111829;4499.190145773661;4514.119746649372;4537.160625284333;4559.224931761675;4570.428696412949;4703.3415321855655;4720.787564559534;4455.665825063068;4461.353103799996;4461.598456138963;4501.099780043939;4504.864854054384;4691.70977981058;4701.576858082789;4707.082118986902;4710.222813235915;4743;4524.705894116872;4552.385427967182;4766.237402015661;4777.187878060808;4785.641076919175;4635.466921303958;4659.440867095835;4671.229247174178;4685.718856868599;4779.044191161712;4572.81708731647;4650.744209068534;4666.533346665306;4685.765711714333;4724.952750472451;4385.539518350655;4571.908561828811;4618.930716039329;4621.953780462152;4622.075584883104;4518.615917647036;4553.430821147356;4587.266037433995;4590.977045114753;4637.60580350674;4499.302608095751;4516.661250406167;4523.909521809611;4554.931676024796;4561.042181141997 18:31:51 INFO - PROCESS | 4085 | |1;ext.html;538.1873371209474;546.3580293155546;551.7489542258272;555.8332500249924;566.4675205307011;22739.545209095828;23343.182988595403;23366;27378;28503.857480712566;27408.588871166947;27829.860850695717;27879.442411151816;27888.581671274897;27893.16320510388;5335.279737235482;5478.096114141182;5478.45215478453;5481.616286859921;5483.177523371486;5427.755750991195;5497.532709719659;5497.890042199154;5500.092484740013;5500.559955203594;5440.727963601824;5496.835094947138;5497.285352904117;5501.834944951638;5503.807366742165;5488.807891723791;5507.807226747084;5513.062779327524;5513.669179849222;5519.558435325164;5571.4428557144365;5621.578381621354;5625.40933202012;5626.465485778845;5629;25214.24357269285;25508.107216247343;25510.489790204276;25520.23439296824;25540.616890746653;2966.688497707731;3055.1903745507457;3071.9078427647205;3414.129397003773;3558.875439359636;17952.371666991592;18177.363792267206;18254;18258.36095736656;18345.26618935241;2649.4436168404463;2695.9730402696114;2724.5368287391193;2726.4410795786816;2830.844303563303;229271;231049;231071;231213;231501;30689;31905;44135.55864441315;44429.555704442544;44490.76920769668;32590;32847.551007144386;32859.1785205367;32864;32877.671223287936;12424.06819488542;12662.49350026007;12667.176633518791;12678.683032924066;12690.048246381373;10017.14854237398;10193.23550733683;10204.948975255076;10205.438700871375;10214.591416343412;7653.770386888402;7667.8849817252785;7745.883811742829;7753.961230193926;7760.611969401507;3010.759139268897;3047.695230476979;3052.3132295233395;3055.8319292438687;3129.0456410794473;866.2420382165606;874.4316194473643;911.9407238529475;916.867054277126;932.5710173320213;26178.73821261763;26237.868810655826;26989.595156072675;27097;27123.457530849668;763.2443880558208;925.4586067150658;939.2579861909152;967.690339091484;1007.773251018515;13200.73598528024;14195.148291102567;14319.498817541336;14427.711445771236;14469.710605787825;365421;367723;368970;369728;370411;42964.78517607455;43643;43676.344854827206;43739.34390984138;43784.78107609441;1059.3167407022427;1103.3435106111851;1142.1890457774907;1175.8530183727034;1182.296533562529;24456.43207463262;25681.743182567934;26579.601305980814;26806.865965670826;26920.461590768467;39867.60132398639;40280.395794062526;40373;40404.79797600993;40429.595704042586;270525;270660;272157;272694;272732;23718;24525;24846.627300590146;24937.376565586004;24946.251612451655;35804.4629330555;35881.64118358887;36182.819085904404;37161.070973225884;37250.441243381916;30570.847145764128;30712;30842.691573083983;31094.503784042194;31533.52699709552;31361.8431907839;31615.20961975969;31662.208444789063;31753;32803;31567;31789.523157152198;31901;31973.840130799195;32609.347813043132;26434.471310574067;28795.272283663046;29569.556456652736;29812.963157290396;29989;22691.319260422213;23137.305880823602;23301.3009609712;23448.413789655395;23598.17406390768 18:31:51 INFO - PROCESS | 4085 | |2;jquery.html;338.6867693652355;355.4579266618405;363.4530032301387;366.58758896241727;373.3224198080484;9747.22022238221;10431;11514.366709830956;11775.823362649546;12582.182158159712;13922.303884805744;14194;14213.78679319811;14253.714925701506;15528.844711552882;3866.8646597369097;4127.669786417079;4128.896777580554;4135.1316223593085;4135.97932010341;3527.8412471438846;3747.9812600936957;4053.837846486137;4110.97944510277;4148.502179738435;3840.750351227175;3863.80680965952;3864;3872.593377695344;3873.6707379872746;3862.439946207784;3866.574676785551;3871.0709429736944;3871.7676939383728;3873.1091848874776;3344.3311337732553;3573.8391772370305;3591.3356029134434;3630.5280313559333;3774.2640185163905;9612;9688.564014619358;9695.854562181574;9775.755606109833;9840.311178217527;1744.1453687693065;1866.6826639471187;1889.4898377438014;1903.4860587641397;2236.150262900104;7121.359077683033;7246.71013159473;7286.1256649201905;7351.852962940765;7713.267239612228;1238.4055653286473;1328.4818920620964;1335.5659410691565;1358.4701966233176;1369.5685858954398;65585.67207163981;68979.31020689779;69712.65143674299;70532;71028.64485677538;9235.168834804765;9285.675001374919;9686.370385925033;9804.460754658563;9866;12720.300383478812;12737.363131843556;12757.744845103047;12785.552505662257;12786.936065319614;6646;6895.724171033203;6905.792826215222;7052.801023825962;7116.857662846817;6439.291677915426;6538.280789113193;6630.270670226271;6643.900341494881;6810.489213309022;3978.1844721831953;4054.614811592894;4090.8363665453644;4095.406166105898;4194.2450358935685;1648.5301689018572;1690.7801985741776;1692.9746053809204;1695.533728224748;1752.6601986348112;745.4409193105171;793.2226418110284;805.472415567804;815.685960905044;840.5587066789984;15562.844371556366;16924.661506769797;17042.14789260532;17043.914780426017;17076.2315695794;796.9681212751541;846.5555583318807;848.8625242175515;854.5001174312972;855.170484629908;8668.956655216809;8815.735527934045;9046.964433002522;9609.519524023772;9774.706758797247;374428;374492;380287;384331;385059;48442.27336589954;50091;75191;78668;79787.60106199431;913.5614904845711;920.9677661281823;940.9482478463614;943.698016634684;948.3693343926212;14530;14717.484888028868;15006;15185.392584296946;15480.516883701077;32647.408785033153;34648.30703385868;35243.82378088093;37646.67063287757;39230.6076939227;48465;48514;48652;50142;50160.49839501704;6405.839854003687;7156.606386648576;7349.595772232578;7569.47013709035;7921.683132674629;9950.651727189514;12113.576024839089;12156.75686486283;12179.512819487476;12293.139480236297;10292.125169360688;10539.104176145114;10556;10584.947075264574;10842.512086956263;10123.392596444237;10209.693709188736;10347.689569312932;10580.89419105799;10652.680419587425;10413.687589372332;10600.416977066026;10621.468926553796;10829.133669306279;11148.554057837595;6459.677016149268;6523.11937888379;6634.4692424604955;6710.66446677674;6753.1220941277315;7628.0464941882265;8851.69019084329;10290;10310.587576496857;10409 18:31:51 INFO - PROCESS | 4085 | |3;mootools.html;1209.72781124247;1286.6075846866704;1294.4304506017352;1333.1734324718673;1437.879800832194;5502.257195278637;6063.545234107443;6227.5017998560115;6261.780837670683;6440.967795161017;6287.308396076428;6288.654124023177;6328.145700330466;6349.873002539946;6352.555321127523;1673.230314055533;1713.083500327325;1714.8027976782641;1715.1424287856073;1719.3466482736605;1701.2174399776056;1725.3443691397315;1734.2109340250156;1734.4363081998401;1774.4676597020907;1695.1693670101622;1709.1795937949792;1711.1700825099817;1724.1379310344828;1724.689555879941;1685.561753943971;1691.0530103142219;1706.7013272677232;1715.785526809149;1718.0979985507583;1732.8267173282698;1752.0363799910126;1753.7720096387516;1754.1492376197534;1756.0429565886495;6030.427109424598;6189.071639254103;6193.3496982816605;6247.031710084944;6250.968745156245;3984.1234928315725;4105.568915263884;4107;4111.321631930728;4112.3420252759415;2504.3238325652155;2599.493098845726;2666.4267182555845;2673.665791776028;2679.477501887133;745.1803016681661;752.2702978111278;771.6103367799282;782.0927723840375;786.1195461083565;8540.871886921701;8712.69505567302;8721.171488708562;8733.214010739;8734.69428569997;3530.6853193438274;3560.750747447654;3604.6395360463744;3663.432168013962;3663.8717644882836;7071.504994650325;7082.733525271967;7086.291370862976;7092.290770922867;7097.680604372816;4056.6146216109423;4059.06641472463;4061.7156799023783;4063.1264278180333;4063.309237429644;3380.256343604403;3494.2836718472654;3762.6425489578446;3828.5214348206473;3847.461355410189;2717.565189569659;2800.173948685143;2863.2841789552613;3013.141254742388;3057.5719399284117;902.1609902790468;916.6516723644972;937.2642475656577;944.7165850244899;945.3382632157517;627.4541149200134;651.2770824384929;738.759903031517;745.3925051083459;747.8317378589883;4293.141371725667;4381.6494680425485;4477.126960242721;4501.977490112528;4717.669763116549;802.1497212954171;804.5213098206558;808.8220591469867;817.1746535998684;833.6040380819062;2955.9556606650917;2963.8369889656274;2988.521836506192;3057.877684892624;3105.239216391953;7548.735794247175;7766.378689704923;7800.414968877357;7972.561509116938;7979.960100199578;7930.603469826486;7931.762047138595;7947.205279472122;7947.682092716343;7949.880751788728;6076.635401875901;6079.574429789872;6164.660943648053;6194.163787888668;6205.069239614094;4369.279068953587;4385.407969924019;4460.977695111504;4463.933041004452;4606.424196975378;7291.927080729125;7299.379552738077;7321.231270716711;7361.043064401593;7372.336489715952;7438.293362130589;7510.586917719578;7530.021097257321;7532.17146113938;7535.246475352421;5370.221317908803;5474.39781624029;5478.369987451494;5478.698671573102;5479.520529457104;5567.610267281251;5757.193992841089;5902.852428689145;6207.782727604693;6297.370262973666;5508.91736623943;5596.104623260258;5644;5652.434756524314;5693.800716974886;5610.326760788568;5624.29696287964;5667.631603945731;5689.687067211343;5840.474357307863;4923.778429970588;4984.551390374883;4989;4991;4991.026749783758;3863.150106976461;3875.4961854958674;3904.1996390739264;3959.8614048508166;4047.696422768245;3866.8066596670615;3905.3946638271113;3937.8818635440985;3989.1423343981182;4040.9595904040584 18:31:51 INFO - PROCESS | 4085 | |4;prototype.html;277.6848277205372;279.1291171544781;286.57014478282576;289.3807252479692;289.6046895986978;4501.437320334958;4616.7525876193895;4624.456548898105;4658.953410465894;4689.718616882981;4457.91084178317;4534.36518887356;4552.317152427137;4591.24244499658;4591.380163677903;3585.529932727582;3612.988363258292;3619.7285203609636;3628.3853685110134;3642.690371318428;3457.7060949819297;3632.0035970324457;3635.1275693833554;3636.0182750657304;3641.0533261352107;3388.9322213555715;3564.7148228141687;3579.6241394653575;3598.9280214395694;3604.098975256186;3578.248567800765;3590.1563132663805;3593.4070878305047;3598.6325196425323;3603.171270607762;3143.5637381029655;3143.984280078585;3149.4173577887937;3149.7637677174303;3159.2891599389955;4317.503487098992;4514.774261286922;4520.86437406875;4528.117017181651;4531.116432295704;4054.6350828425584;4060.3909413587903;4071.592840715905;4075.4701888754566;4081.9387709184384;4861.927071093936;4864.829730959399;4865.618395215327;4870.613399434287;4872.9756351218375;3409.3010932758725;3410.6248312685584;3413.8293085345636;3417.641147679483;3418.29924865402;4914.827981020647;5025.271335656309;5036.169032109698;5043.394792624908;5060.2156665716875;3457.291255292659;3457.8271086445575;3461.3077384523194;3462.255615042778;3468.1849765305133;3497.8663015560487;3507.228409749851;3513.80674062929;3519.524864143359;3521.4189658706027;3800.7529510581726;4022.396640503889;4033.233685599727;4043.838246470167;4061.878143655695;4809.859612472416;4837.854864354075;4850.6362022847725;4850.975745121323;4854.83008094715;1127.2672762704308;1151.0446329546455;1183.7040739815047;1189.8036823923956;1191.1304747534348;796.6853093028337;814.9103598604149;817.1297568089965;819.1358117186426;821.5235163605095;4149.647279981175;4151.190517848991;4171.165766846643;4171.872345104019;4176.665866730655;4789.377380940456;4794.321987304415;4809.86968062505;4812.735299558558;4827.031375703917;4031.258744054013;4132.462779838662;4154.605312495369;4314.50383205929;4328.8484903028875;2569.948601027969;2578.742125787406;2578.9355266118496;2580.8193426459966;2582.264054744389;4533.161365147458;4544.431946006749;4560.749158796298;4569.680122391467;4573.542645735466;4735;4938.333325001079;4955.702657840541;4976.5023497649945;5000.924616284201;4545.999880026455;4546.909061818811;4551.840685575989;4552.544745525421;4556;4831.613470922248;4840.6127509799835;4843.515648435269;4858.5384388483035;4866.659333846596;4591.405855691523;4736.573708366263;4765.356676848581;4766.547178018082;4786.617070634411;4543.295789152687;4560.429946256718;4561.407017087758;4567.990084363003;4568.862934111982;3352.916177095592;3355.3223388305846;3371.5111308859587;3371.690392081432;3384.847681854273;4652.9298261400745;4852.272159176152;4861.295112208781;4865.9756701216265;4871.80512779499;4615.953840461687;4668.463126740468;4672.345871577913;4683.555062269079;4729.952700472952;4606.723596584082;4649.163150632783;4649.697769644962;4663.2745884023125;4664.883377915444;4450.243458612108;4576.244919588231;4579.656525760514;4594.977025114853;4610.74640894754;4310;4779.187538118459;4804.975975120102;4812.494688057704;4818.277258411266;4662.018642077036;4707.4821769606;4716.410448693913;4717.740524271198;4720.244760838249 18:31:51 INFO - PROCESS | 4085 | |5;yui.html;626.4800215820869;633.6103296472669;640.2029473305735;679.8232459560514;689.0353505092869;10378.688639340822;11603.941980290108;11611.709707257323;11616.593419230328;11621.825672614917;10726.302790318643;10830.350178989247;10899.400532970703;10913.890861091386;11028.393438360887;601.266454924993;619.662284055191;726.3317747672154;737.0492065235853;749.4753672429322;695.7808290388537;708.7873637908607;713.233274230202;713.7751608243412;714.2571725405572;805.1746959366637;806.3549160671469;806.3831169155601;806.8870358149863;806.899137607799;631.2456614345858;648.0926702616327;743.7917383132732;799.9320057795095;800.8118092248346;573.8651416917021;577.1400613086513;577.4802677590161;582.4757718053743;582.7785441532191;10343.793124137475;10467.52896119669;10478.10936070435;10485.423301718403;10595.735106622318;9411;9695.90304096964;9792.461414622192;9844.261680374;10219.540120694586;8126.756197314031;8194.05768336637;8201.343892488587;8249.876251856227;8264.917350826476;2370.668106465096;2448.9387765305833;2470.086068154776;2491.700995880506;2588.184721812642;48274.27588586174;48299.51700482985;48482.27276590819;48642;48774;6969.7909062728195;7096.609686467294;7192.352688257977;7233.276672332725;7240.094988126484;14752.483663071742;14811.703765924836;14849.628759280888;14855.182964937032;14920.104793712411;6239.563230573907;6334.936650633527;6567.901481477782;6598.538102332886;6732.192136943598;3473.3053389322235;3526.1360966563357;3548.4854696068924;3636.5999740028574;3711.4247291669835;2626.1727555819857;2661.7471340222646;2669.3460102275067;2701.8108732388546;2705.8241214321006;468.72111093899076;474.29567092867063;478.98216290382936;479.33851285226166;481.4367190387255;453.7436348463108;505.76987470701476;518.6550943622449;525.7581512504291;536.471575498134;6784.185897692209;6796.966015169893;6894.896576551356;6951.096357473597;7006.4745144114395;459.1734877220989;469.9060187962421;481.64599019720475;514.8326793792032;518.7302602646678;3305.3719793239206;3323.3851737428654;3395;3402.1324562236473;3474.409350410436;68976;69016.30983690098;69623;71634;71782.64108679528;37413.81293093517;39430.40854387187;48798.51201488011;48988.755056224494;61711.691441542505;57217;58714;58757.70621146867;59773.99999999913;60569;6528.510361722985;6723.663816809238;6736.259011508632;6872.759453419107;6978.162620485574;37922.43116353312;38740.6125938737;38756.6124338753;39604.80197598993;40679.79660101799;42924.57075429206;43192.35211471893;47025;52309;54097.45902540924;8223.753287401387;8567.357448191286;8869.423487473552;8921.286297096087;9029.864552031857;9918.801623967625;10041.246906481896;10203.795924081625;10563.788724225626;10565.524551395052;8540.231379175904;9040;9366.159874451443;9415.482148481626;9619.855702164334;8991.865122023306;9004.279657627243;9034.412763170372;9335.113164249386;9354;8895;8911.777205569913;8970.147835955573;9090.363674542983;9178.816423671622;7475.813104672427;7793.571353575608;7906.52560846351;7982.481138725964;8120.228578285053;7823.687052517836;7847.686092556234;7993.120756716873;8060;8155.673773049249 18:31:51 INFO - PROCESS | 4085 | __end_tp_report 18:31:51 INFO - PROCESS | 4085 | __start_cc_report 18:31:51 INFO - PROCESS | 4085 | _x_x_mozilla_cycle_collect,1342 18:31:51 INFO - PROCESS | 4085 | __end_cc_report 18:31:51 INFO - PROCESS | 4085 | __startTimestamp1479436311947__endTimestamp 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | ------- Summary: start ------- 18:31:51 INFO - PROCESS | 4085 | Number of tests: 6 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#0] dojo.html Cycles:165 Average:4376.98 Median:4499.91 stddev:440.59 (9.8%) stddev-sans-first:415.91 18:31:51 INFO - PROCESS | 4085 | Values: 2475.2 2500.9 2517.2 2517.7 2528.5 4287.1 4314.0 4327.4 4378.6 4411.3 4395.0 4448.4 4474.2 4499.9 4588.6 4018.7 4037.5 4072.9 4121.7 4155.6 4071.9 4132.8 4176.8 4199.3 4207.4 4185.4 4198.0 4216.1 4220.7 4233.6 3997.6 4019.4 4023.2 4076.6 4080.2 4063.7 4064.8 4068.4 4071.2 4075.6 4382.8 4399.9 4437.8 4460.3 4464.4 4731.2 4732.6 4748.2 4778.1 4798.1 4767.3 4768.9 4780.5 4809.3 4837.6 4329.4 4371.3 4375.0 4456.2 4476.9 4752.9 4788.7 4800.6 4817.6 4858.6 4562.7 4621.1 4622.3 4623.7 4667.3 4569.7 4699.4 4700.4 4702.4 4716.7 4582.8 4615.9 4616.0 4617.1 4617.7 4654.9 4666.3 4669.9 4673.8 4677.4 3977.5 4003.7 4015.6 4015.7 4018.9 3542.3 3547.2 3574.5 3581.0 3591.9 4096.9 4099.7 4101.6 4117.5 4131.6 4596.6 4651.7 4737.6 4779.7 4817.1 4044.0 4055.7 4079.3 4091.4 4135.2 4306.1 4307.9 4310.1 4314.2 4360.8 4462.4 4462.5 4474.0 4499.2 4514.1 4537.2 4559.2 4570.4 4703.3 4720.8 4455.7 4461.4 4461.6 4501.1 4504.9 4691.7 4701.6 4707.1 4710.2 4743.0 4524.7 4552.4 4766.2 4777.2 4785.6 4635.5 4659.4 4671.2 4685.7 4779.0 4572.8 4650.7 4666.5 4685.8 4725.0 4385.5 4571.9 4618.9 4622.0 4622.1 4518.6 4553.4 4587.3 4591.0 4637.6 4499.3 4516.7 4523.9 4554.9 4561.0 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#1] ext.html Cycles:180 Average:40798.83 Median:30777.35 stddev:78378.34 (254.7%) stddev-sans-first:78539.92 18:31:51 INFO - PROCESS | 4085 | Values: 538.2 546.4 551.7 555.8 566.5 22739.5 23343.2 23366.0 27378.0 28503.9 27408.6 27829.9 27879.4 27888.6 27893.2 5335.3 5478.1 5478.5 5481.6 5483.2 5427.8 5497.5 5497.9 5500.1 5500.6 5440.7 5496.8 5497.3 5501.8 5503.8 5488.8 5507.8 5513.1 5513.7 5519.6 5571.4 5621.6 5625.4 5626.5 5629.0 25214.2 25508.1 25510.5 25520.2 25540.6 2966.7 3055.2 3071.9 3414.1 3558.9 17952.4 18177.4 18254.0 18258.4 18345.3 2649.4 2696.0 2724.5 2726.4 2830.8 229271.0 231049.0 231071.0 231213.0 231501.0 30689.0 31905.0 44135.6 44429.6 44490.8 32590.0 32847.6 32859.2 32864.0 32877.7 12424.1 12662.5 12667.2 12678.7 12690.0 10017.1 10193.2 10204.9 10205.4 10214.6 7653.8 7667.9 7745.9 7754.0 7760.6 3010.8 3047.7 3052.3 3055.8 3129.0 866.2 874.4 911.9 916.9 932.6 26178.7 26237.9 26989.6 27097.0 27123.5 763.2 925.5 939.3 967.7 1007.8 13200.7 14195.1 14319.5 14427.7 14469.7 365421.0 367723.0 368970.0 369728.0 370411.0 42964.8 43643.0 43676.3 43739.3 43784.8 1059.3 1103.3 1142.2 1175.9 1182.3 24456.4 25681.7 26579.6 26806.9 26920.5 39867.6 40280.4 40373.0 40404.8 40429.6 270525.0 270660.0 272157.0 272694.0 272732.0 23718.0 24525.0 24846.6 24937.4 24946.3 35804.5 35881.6 36182.8 37161.1 37250.4 30570.8 30712.0 30842.7 31094.5 31533.5 31361.8 31615.2 31662.2 31753.0 32803.0 31567.0 31789.5 31901.0 31973.8 32609.3 26434.5 28795.3 29569.6 29813.0 29989.0 22691.3 23137.3 23301.3 23448.4 23598.2 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#2] jquery.html Cycles:180 Average:22720.50 Median:3872.85 stddev:62740.14 (1620.0%) stddev-sans-first:62893.63 18:31:51 INFO - PROCESS | 4085 | Values: 338.7 355.5 363.5 366.6 373.3 9747.2 10431.0 11514.4 11775.8 12582.2 13922.3 14194.0 14213.8 14253.7 15528.8 3866.9 4127.7 4128.9 4135.1 4136.0 3527.8 3748.0 4053.8 4111.0 4148.5 3840.8 3863.8 3864.0 3872.6 3873.7 3862.4 3866.6 3871.1 3871.8 3873.1 3344.3 3573.8 3591.3 3630.5 3774.3 9612.0 9688.6 9695.9 9775.8 9840.3 1744.1 1866.7 1889.5 1903.5 2236.2 7121.4 7246.7 7286.1 7351.9 7713.3 1238.4 1328.5 1335.6 1358.5 1369.6 65585.7 68979.3 69712.7 70532.0 71028.6 9235.2 9285.7 9686.4 9804.5 9866.0 12720.3 12737.4 12757.7 12785.6 12786.9 6646.0 6895.7 6905.8 7052.8 7116.9 6439.3 6538.3 6630.3 6643.9 6810.5 3978.2 4054.6 4090.8 4095.4 4194.2 1648.5 1690.8 1693.0 1695.5 1752.7 745.4 793.2 805.5 815.7 840.6 15562.8 16924.7 17042.1 17043.9 17076.2 797.0 846.6 848.9 854.5 855.2 8669.0 8815.7 9047.0 9609.5 9774.7 374428.0 374492.0 380287.0 384331.0 385059.0 48442.3 50091.0 75191.0 78668.0 79787.6 913.6 921.0 940.9 943.7 948.4 14530.0 14717.5 15006.0 15185.4 15480.5 32647.4 34648.3 35243.8 37646.7 39230.6 48465.0 48514.0 48652.0 50142.0 50160.5 6405.8 7156.6 7349.6 7569.5 7921.7 9950.7 12113.6 12156.8 12179.5 12293.1 10292.1 10539.1 10556.0 10584.9 10842.5 10123.4 10209.7 10347.7 10580.9 10652.7 10413.7 10600.4 10621.5 10829.1 11148.6 6459.7 6523.1 6634.5 6710.7 6753.1 7628.0 8851.7 10290.0 10310.6 10409.0 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#3] mootools.html Cycles:180 Average:4241.16 Median:5422.31 stddev:2325.64 (42.9%) stddev-sans-first:2321.01 18:31:51 INFO - PROCESS | 4085 | Values: 1209.7 1286.6 1294.4 1333.2 1437.9 5502.3 6063.5 6227.5 6261.8 6441.0 6287.3 6288.7 6328.1 6349.9 6352.6 1673.2 1713.1 1714.8 1715.1 1719.3 1701.2 1725.3 1734.2 1734.4 1774.5 1695.2 1709.2 1711.2 1724.1 1724.7 1685.6 1691.1 1706.7 1715.8 1718.1 1732.8 1752.0 1753.8 1754.1 1756.0 6030.4 6189.1 6193.3 6247.0 6251.0 3984.1 4105.6 4107.0 4111.3 4112.3 2504.3 2599.5 2666.4 2673.7 2679.5 745.2 752.3 771.6 782.1 786.1 8540.9 8712.7 8721.2 8733.2 8734.7 3530.7 3560.8 3604.6 3663.4 3663.9 7071.5 7082.7 7086.3 7092.3 7097.7 4056.6 4059.1 4061.7 4063.1 4063.3 3380.3 3494.3 3762.6 3828.5 3847.5 2717.6 2800.2 2863.3 3013.1 3057.6 902.2 916.7 937.3 944.7 945.3 627.5 651.3 738.8 745.4 747.8 4293.1 4381.6 4477.1 4502.0 4717.7 802.1 804.5 808.8 817.2 833.6 2956.0 2963.8 2988.5 3057.9 3105.2 7548.7 7766.4 7800.4 7972.6 7980.0 7930.6 7931.8 7947.2 7947.7 7949.9 6076.6 6079.6 6164.7 6194.2 6205.1 4369.3 4385.4 4461.0 4463.9 4606.4 7291.9 7299.4 7321.2 7361.0 7372.3 7438.3 7510.6 7530.0 7532.2 7535.2 5370.2 5474.4 5478.4 5478.7 5479.5 5567.6 5757.2 5902.9 6207.8 6297.4 5508.9 5596.1 5644.0 5652.4 5693.8 5610.3 5624.3 5667.6 5689.7 5840.5 4923.8 4984.6 4989.0 4991.0 4991.0 3863.2 3875.5 3904.2 3959.9 4047.7 3866.8 3905.4 3937.9 3989.1 4041.0 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#4] prototype.html Cycles:180 Average:3943.93 Median:4538.83 stddev:1141.88 (25.2%) stddev-sans-first:1111.43 18:31:51 INFO - PROCESS | 4085 | Values: 277.7 279.1 286.6 289.4 289.6 4501.4 4616.8 4624.5 4659.0 4689.7 4457.9 4534.4 4552.3 4591.2 4591.4 3585.5 3613.0 3619.7 3628.4 3642.7 3457.7 3632.0 3635.1 3636.0 3641.1 3388.9 3564.7 3579.6 3598.9 3604.1 3578.2 3590.2 3593.4 3598.6 3603.2 3143.6 3144.0 3149.4 3149.8 3159.3 4317.5 4514.8 4520.9 4528.1 4531.1 4054.6 4060.4 4071.6 4075.5 4081.9 4861.9 4864.8 4865.6 4870.6 4873.0 3409.3 3410.6 3413.8 3417.6 3418.3 4914.8 5025.3 5036.2 5043.4 5060.2 3457.3 3457.8 3461.3 3462.3 3468.2 3497.9 3507.2 3513.8 3519.5 3521.4 3800.8 4022.4 4033.2 4043.8 4061.9 4809.9 4837.9 4850.6 4851.0 4854.8 1127.3 1151.0 1183.7 1189.8 1191.1 796.7 814.9 817.1 819.1 821.5 4149.6 4151.2 4171.2 4171.9 4176.7 4789.4 4794.3 4809.9 4812.7 4827.0 4031.3 4132.5 4154.6 4314.5 4328.8 2569.9 2578.7 2578.9 2580.8 2582.3 4533.2 4544.4 4560.7 4569.7 4573.5 4735.0 4938.3 4955.7 4976.5 5000.9 4546.0 4546.9 4551.8 4552.5 4556.0 4831.6 4840.6 4843.5 4858.5 4866.7 4591.4 4736.6 4765.4 4766.5 4786.6 4543.3 4560.4 4561.4 4568.0 4568.9 3352.9 3355.3 3371.5 3371.7 3384.8 4652.9 4852.3 4861.3 4866.0 4871.8 4616.0 4668.5 4672.3 4683.6 4730.0 4606.7 4649.2 4649.7 4663.3 4664.9 4450.2 4576.2 4579.7 4595.0 4610.7 4310.0 4779.2 4805.0 4812.5 4818.3 4662.0 4707.5 4716.4 4717.7 4720.2 18:31:51 INFO - PROCESS | 4085 | 18:31:51 INFO - PROCESS | 4085 | [#5] yui.html Cycles:180 Average:13466.16 Median:31165.68 stddev:18247.53 (58.6%) stddev-sans-first:18273.25 18:31:51 INFO - PROCESS | 4085 | Values: 626.5 633.6 640.2 679.8 689.0 10378.7 11603.9 11611.7 11616.6 11621.8 10726.3 10830.4 10899.4 10913.9 11028.4 601.3 619.7 726.3 737.0 749.5 695.8 708.8 713.2 713.8 714.3 805.2 806.4 806.4 806.9 806.9 631.2 648.1 743.8 799.9 800.8 573.9 577.1 577.5 582.5 582.8 10343.8 10467.5 10478.1 10485.4 10595.7 9411.0 9695.9 9792.5 9844.3 10219.5 8126.8 8194.1 8201.3 8249.9 8264.9 2370.7 2448.9 2470.1 2491.7 2588.2 48274.3 48299.5 48482.3 48642.0 48774.0 6969.8 7096.6 7192.4 7233.3 7240.1 14752.5 14811.7 14849.6 14855.2 14920.1 6239.6 6334.9 6567.9 6598.5 6732.2 3473.3 3526.1 3548.5 3636.6 3711.4 2626.2 2661.7 2669.3 2701.8 2705.8 468.7 474.3 479.0 479.3 481.4 453.7 505.8 518.7 525.8 536.5 6784.2 6797.0 6894.9 6951.1 7006.5 459.2 469.9 481.6 514.8 518.7 3305.4 3323.4 3395.0 3402.1 3474.4 68976.0 69016.3 69623.0 71634.0 71782.6 37413.8 39430.4 48798.5 48988.8 61711.7 57217.0 58714.0 58757.7 59774.0 60569.0 6528.5 6723.7 6736.3 6872.8 6978.2 37922.4 38740.6 38756.6 39604.8 40679.8 42924.6 43192.4 47025.0 52309.0 54097.5 8223.8 8567.4 8869.4 8921.3 9029.9 9918.8 10041.2 10203.8 10563.8 10565.5 8540.2 9040.0 9366.2 9415.5 9619.9 8991.9 9004.3 9034.4 9335.1 9354.0 8895.0 8911.8 8970.1 9090.4 9178.8 7475.8 7793.6 7906.5 7982.5 8120.2 7823.7 7847.7 7993.1 8060.0 8155.7 18:31:51 INFO - PROCESS | 4085 | -------- Summary: end -------- 18:31:51 INFO - PROCESS | 4085 | 18:31:52 INFO - TEST-INFO | 4085: exit 0 18:31:52 INFO - TEST-OK | dromaeo_css | took 1121973ms 18:31:52 INFO - TEST-START | kraken 18:31:52 INFO - operating with platform_type : linux_ 18:31:52 INFO - Initialising browser for kraken test... 18:31:52 INFO - TEST-INFO | started process 4392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa88C1g/profile http://localhost:51085/getInfo.html) 18:31:57 INFO - PROCESS | 4392 | __metrics Screen width/height:1600/1200 18:31:57 INFO - PROCESS | 4392 | colorDepth:24 18:31:57 INFO - PROCESS | 4392 | Browser inner width/height: 1024/697 18:31:57 INFO - PROCESS | 4392 | __metrics 18:31:58 INFO - PROCESS | 4392 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:31:58 INFO - PROCESS | 4392 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:58 INFO - PROCESS | 4392 | console.error: PushService: 18:31:58 INFO - PROCESS | 4392 | stateChangeProcessEnqueue: Error transitioning state 18:31:58 INFO - PROCESS | 4392 | UnknownError 18:31:59 INFO - TEST-INFO | 4392: exit 0 18:31:59 INFO - Browser initialized. 18:31:59 INFO - Running cycle 1/1 for kraken test... 18:31:59 INFO - TEST-INFO | started process 4471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa88C1g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:11 INFO - PROCESS | 4471 | RSS: Main: 174305280 18:32:11 INFO - PROCESS | 4471 | 18:32:14 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51085/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:15 INFO - PROCESS | 4471 | RSS: Main: 231325696 18:32:15 INFO - PROCESS | 4471 | 18:32:18 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51085/tests/kraken/driver.html?testName=audio-dft) 18:32:18 INFO - PROCESS | 4471 | RSS: Main: 218038272 18:32:18 INFO - PROCESS | 4471 | 18:32:22 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51085/tests/kraken/driver.html?testName=audio-fft) 18:32:22 INFO - PROCESS | 4471 | RSS: Main: 300691456 18:32:22 INFO - PROCESS | 4471 | 18:32:24 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51085/tests/kraken/driver.html?testName=audio-oscillator) 18:32:25 INFO - PROCESS | 4471 | RSS: Main: 211410944 18:32:25 INFO - PROCESS | 4471 | 18:32:27 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51085/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:28 INFO - PROCESS | 4471 | RSS: Main: 214847488 18:32:28 INFO - PROCESS | 4471 | 18:32:31 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51085/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:32 INFO - PROCESS | 4471 | RSS: Main: 254214144 18:32:32 INFO - PROCESS | 4471 | 18:32:35 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51085/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:36 INFO - PROCESS | 4471 | RSS: Main: 254066688 18:32:36 INFO - PROCESS | 4471 | 18:32:39 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51085/tests/kraken/driver.html?testName=json-parse-financial) 18:32:39 INFO - PROCESS | 4471 | RSS: Main: 252981248 18:32:39 INFO - PROCESS | 4471 | 18:32:41 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51085/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:41 INFO - PROCESS | 4471 | RSS: Main: 214847488 18:32:41 INFO - PROCESS | 4471 | 18:32:43 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:44 INFO - PROCESS | 4471 | RSS: Main: 221876224 18:32:44 INFO - PROCESS | 4471 | 18:32:46 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:47 INFO - PROCESS | 4471 | RSS: Main: 230846464 18:32:47 INFO - PROCESS | 4471 | 18:32:50 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:51 INFO - PROCESS | 4471 | RSS: Main: 239726592 18:32:51 INFO - PROCESS | 4471 | 18:32:54 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:54 INFO - PROCESS | 4471 | RSS: Main: 235761664 18:32:54 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | Cycle 1(1): loaded http://localhost:51085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51085/tests/kraken/driver.html?testName=ai-astar) 18:32:56 INFO - PROCESS | 4471 | RSS: Main: 285450240 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | __start_tp_report 18:32:56 INFO - PROCESS | 4471 | _x_x_mozilla_page_load 18:32:56 INFO - PROCESS | 4471 | _x_x_mozilla_page_load_details 18:32:56 INFO - PROCESS | 4471 | |i|pagename|runs| 18:32:56 INFO - PROCESS | 4471 | |0;ai-astar;107;116;117;117;116;116;115;116;115;116 18:32:56 INFO - PROCESS | 4471 | |1;audio-beat-detection;156;128;128;168;131;131;129;134;183;132 18:32:56 INFO - PROCESS | 4471 | |2;audio-dft;161;161;162;160;162;163;164;164;163;161 18:32:56 INFO - PROCESS | 4471 | |3;audio-fft;77;76;77;108;78;76;78;76;97;76 18:32:56 INFO - PROCESS | 4471 | |4;audio-oscillator;84;87;125;84;83;83;106;83;84;122 18:32:56 INFO - PROCESS | 4471 | |5;imaging-gaussian-blur;117;118;118;117;118;117;117;117;119;117 18:32:56 INFO - PROCESS | 4471 | |6;imaging-darkroom;146;147;147;147;146;146;147;148;147;146 18:32:56 INFO - PROCESS | 4471 | |7;imaging-desaturate;96;96;95;95;96;96;95;94;95;95 18:32:56 INFO - PROCESS | 4471 | |8;json-parse-financial;64;63;66;65;65;64;64;64;64;64 18:32:56 INFO - PROCESS | 4471 | |9;json-stringify-tinderbox;65;66;66;67;67;67;66;67;67;66 18:32:56 INFO - PROCESS | 4471 | |10;stanford-crypto-aes;84;93;83;83;88;84;93;83;84;89 18:32:56 INFO - PROCESS | 4471 | |11;stanford-crypto-ccm;145;144;140;141;142;143;145;142;194;149 18:32:56 INFO - PROCESS | 4471 | |12;stanford-crypto-pbkdf2;184;179;179;183;177;181;176;179;178;178 18:32:56 INFO - PROCESS | 4471 | |13;stanford-crypto-sha256-iterative;69;71;70;68;68;70;68;70;69;69 18:32:56 INFO - PROCESS | 4471 | __end_tp_report 18:32:56 INFO - PROCESS | 4471 | __start_cc_report 18:32:56 INFO - PROCESS | 4471 | _x_x_mozilla_cycle_collect,1959 18:32:56 INFO - PROCESS | 4471 | __end_cc_report 18:32:56 INFO - PROCESS | 4471 | __startTimestamp1479436376660__endTimestamp 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | ------- Summary: start ------- 18:32:56 INFO - PROCESS | 4471 | Number of tests: 14 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:2.92 (2.5%) stddev-sans-first:0.71 18:32:56 INFO - PROCESS | 4471 | Values: 107.0 116.0 117.0 117.0 116.0 116.0 115.0 116.0 115.0 116.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:133.00 stddev:19.78 (14.9%) stddev-sans-first:20.32 18:32:56 INFO - PROCESS | 4471 | Values: 156.0 128.0 128.0 168.0 131.0 131.0 129.0 134.0 183.0 132.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.37 (0.8%) stddev-sans-first:1.39 18:32:56 INFO - PROCESS | 4471 | Values: 161.0 161.0 162.0 160.0 162.0 163.0 164.0 164.0 163.0 161.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#3] audio-fft Cycles:10 Average:81.90 Median:77.00 stddev:11.19 (14.5%) stddev-sans-first:11.73 18:32:56 INFO - PROCESS | 4471 | Values: 77.0 76.0 77.0 108.0 78.0 76.0 78.0 76.0 97.0 76.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.50 stddev:17.00 (20.4%) stddev-sans-first:17.63 18:32:56 INFO - PROCESS | 4471 | Values: 84.0 87.0 125.0 84.0 83.0 83.0 106.0 83.0 84.0 122.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 18:32:56 INFO - PROCESS | 4471 | Values: 117.0 118.0 118.0 117.0 118.0 117.0 117.0 117.0 119.0 117.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 18:32:56 INFO - PROCESS | 4471 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 147.0 148.0 147.0 146.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 18:32:56 INFO - PROCESS | 4471 | Values: 96.0 96.0 95.0 95.0 96.0 96.0 95.0 94.0 95.0 95.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:32:56 INFO - PROCESS | 4471 | Values: 64.0 63.0 66.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.53 18:32:56 INFO - PROCESS | 4471 | Values: 65.0 66.0 66.0 67.0 67.0 67.0 66.0 67.0 67.0 66.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:4.06 (4.7%) stddev-sans-first:4.21 18:32:56 INFO - PROCESS | 4471 | Values: 84.0 93.0 83.0 83.0 88.0 84.0 93.0 83.0 84.0 89.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:144.50 stddev:16.19 (11.2%) stddev-sans-first:17.12 18:32:56 INFO - PROCESS | 4471 | Values: 145.0 144.0 140.0 141.0 142.0 143.0 145.0 142.0 194.0 149.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:179.00 stddev:2.55 (1.4%) stddev-sans-first:2.09 18:32:56 INFO - PROCESS | 4471 | Values: 184.0 179.0 179.0 183.0 177.0 181.0 176.0 179.0 178.0 178.0 18:32:56 INFO - PROCESS | 4471 | 18:32:56 INFO - PROCESS | 4471 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.03 (1.5%) stddev-sans-first:1.09 18:32:56 INFO - PROCESS | 4471 | Values: 69.0 71.0 70.0 68.0 68.0 70.0 68.0 70.0 69.0 69.0 18:32:56 INFO - PROCESS | 4471 | -------- Summary: end -------- 18:32:56 INFO - PROCESS | 4471 | 18:32:57 INFO - TEST-INFO | 4471: exit 0 18:32:58 INFO - TEST-OK | kraken | took 65119ms 18:32:58 INFO - SUITE-END | took 1187s 18:32:58 INFO - Completed test suite (00:19:47) 18:32:58 INFO - javascript benchmark 18:32:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2475.2079334612927, 2500.9499810003795, 2517.1944977607172, 2517.6601158843564, 2528.4943011397727, 4287.121140166266, 4314.029343397738, 4327.350897365397, 4378.627816635586, 4411.294192929132, 4394.978025109878, 4448.377227188196, 4474.217012022899, 4499.91000179997, 4588.564086411786, 4018.658414034796, 4037.4751282333405, 4072.8981775455554, 4121.732087414323, 4155.645273562567, 4071.877843664695, 4132.793360331972, 4176.770277634729, 4199.286121359361, 4207.389928460371, 4185.351270553069, 4197.97901010496, 4216.114615930658, 4220.704550681454, 4233.597808208231, 3997.5802540733093, 4019.437278780973, 4023.1752490739327, 4076.5719599441927, 4080.163566468867, 4063.7358571692744, 4064.8374065037365, 4068.410080538335, 4071.2061148076136, 4075.551689314173, 4382.802773875183, 4399.912001759979, 4437.800298986554, 4460.264056430718, 4464.419625448671, 4731.195696731564, 4732.597729193023, 4748.216544270192, 4778.068276686047, 4798.1123492154065, 4767.284907263904, 4768.880777980578, 4780.450248221464, 4809.326694262806, 4837.564619184255, 4329.393884856123, 4371.322445021026, 4375.037491751809, 4456.153330867125, 4476.910461790779, 4752.928706069411, 4788.736619485892, 4800.591949684316, 4817.614590832725, 4858.562729354375, 4562.749048802281, 4621.098885717252, 4622.260438329851, 4623.699459535187, 4667.299905014207, 4569.748663823521, 4699.365585645963, 4700.435947686231, 4702.36518070063, 4716.716996980193, 4582.839600614029, 4615.930761038587, 4615.976920115445, 4617.076584683077, 4617.746023968714, 4654.86035418938, 4666.323383109498, 4669.929951050737, 4673.813047478131, 4677.368555244999, 3977.5226972762875, 4003.6997225208224, 4015.578364271768, 4015.718899677053, 4018.9196216076098, 3542.291541691672, 3547.184147646056, 3574.4995700601935, 3581.015220814245, 3591.9281614367565, 4096.938545921814, 4099.733517321364, 4101.589841015874, 4117.464729585135, 4131.607497287753, 4596.5863072323655, 4651.744154071558, 4737.573618374294, 4779.665423420397, 4817.084753896818, 4043.9595604043584, 4055.6755459563165, 4079.265732168179, 4091.386292056156, 4135.234981528367, 4306.0957198987935, 4307.935380969288, 4310.137972405531, 4314.201872653569, 4360.803763830571, 4462.44219472566, 4462.509123996358, 4473.977630111829, 4499.190145773661, 4514.119746649372, 4537.160625284333, 4559.224931761675, 4570.428696412949, 4703.3415321855655, 4720.787564559534, 4455.665825063068, 4461.353103799996, 4461.598456138963, 4501.099780043939, 4504.864854054384, 4691.70977981058, 4701.576858082789, 4707.082118986902, 4710.222813235915, 4743.0, 4524.705894116872, 4552.385427967182, 4766.237402015661, 4777.187878060808, 4785.641076919175, 4635.466921303958, 4659.440867095835, 4671.229247174178, 4685.718856868599, 4779.044191161712, 4572.81708731647, 4650.744209068534, 4666.533346665306, 4685.765711714333, 4724.952750472451, 4385.539518350655, 4571.908561828811, 4618.930716039329, 4621.953780462152, 4622.075584883104, 4518.615917647036, 4553.430821147356, 4587.266037433995, 4590.977045114753, 4637.60580350674, 4499.302608095751, 4516.661250406167, 4523.909521809611, 4554.931676024796, 4561.042181141997], "value": 4349.930590944257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.1873371209474, 546.3580293155546, 551.7489542258272, 555.8332500249924, 566.4675205307011, 22739.545209095828, 23343.182988595403, 23366.0, 27378.0, 28503.857480712566, 27408.588871166947, 27829.860850695717, 27879.442411151816, 27888.581671274897, 27893.16320510388, 5335.279737235482, 5478.096114141182, 5478.45215478453, 5481.616286859921, 5483.177523371486, 5427.755750991195, 5497.532709719659, 5497.890042199154, 5500.092484740013, 5500.559955203594, 5440.727963601824, 5496.835094947138, 5497.285352904117, 5501.834944951638, 5503.807366742165, 5488.807891723791, 5507.807226747084, 5513.062779327524, 5513.669179849222, 5519.558435325164, 5571.4428557144365, 5621.578381621354, 5625.40933202012, 5626.465485778845, 5629.0, 25214.24357269285, 25508.107216247343, 25510.489790204276, 25520.23439296824, 25540.616890746653, 2966.688497707731, 3055.1903745507457, 3071.9078427647205, 3414.129397003773, 3558.875439359636, 17952.371666991592, 18177.363792267206, 18254.0, 18258.36095736656, 18345.26618935241, 2649.4436168404463, 2695.9730402696114, 2724.5368287391193, 2726.4410795786816, 2830.844303563303, 229271.0, 231049.0, 231071.0, 231213.0, 231501.0, 30689.0, 31905.0, 44135.55864441315, 44429.555704442544, 44490.76920769668, 32590.0, 32847.551007144386, 32859.1785205367, 32864.0, 32877.671223287936, 12424.06819488542, 12662.49350026007, 12667.176633518791, 12678.683032924066, 12690.048246381373, 10017.14854237398, 10193.23550733683, 10204.948975255076, 10205.438700871375, 10214.591416343412, 7653.770386888402, 7667.8849817252785, 7745.883811742829, 7753.961230193926, 7760.611969401507, 3010.759139268897, 3047.695230476979, 3052.3132295233395, 3055.8319292438687, 3129.0456410794473, 866.2420382165606, 874.4316194473643, 911.9407238529475, 916.867054277126, 932.5710173320213, 26178.73821261763, 26237.868810655826, 26989.595156072675, 27097.0, 27123.457530849668, 763.2443880558208, 925.4586067150658, 939.2579861909152, 967.690339091484, 1007.773251018515, 13200.73598528024, 14195.148291102567, 14319.498817541336, 14427.711445771236, 14469.710605787825, 365421.0, 367723.0, 368970.0, 369728.0, 370411.0, 42964.78517607455, 43643.0, 43676.344854827206, 43739.34390984138, 43784.78107609441, 1059.3167407022427, 1103.3435106111851, 1142.1890457774907, 1175.8530183727034, 1182.296533562529, 24456.43207463262, 25681.743182567934, 26579.601305980814, 26806.865965670826, 26920.461590768467, 39867.60132398639, 40280.395794062526, 40373.0, 40404.79797600993, 40429.595704042586, 270525.0, 270660.0, 272157.0, 272694.0, 272732.0, 23718.0, 24525.0, 24846.627300590146, 24937.376565586004, 24946.251612451655, 35804.4629330555, 35881.64118358887, 36182.819085904404, 37161.070973225884, 37250.441243381916, 30570.847145764128, 30712.0, 30842.691573083983, 31094.503784042194, 31533.52699709552, 31361.8431907839, 31615.20961975969, 31662.208444789063, 31753.0, 32803.0, 31567.0, 31789.523157152198, 31901.0, 31973.840130799195, 32609.347813043132, 26434.471310574067, 28795.272283663046, 29569.556456652736, 29812.963157290396, 29989.0, 22691.319260422213, 23137.305880823602, 23301.3009609712, 23448.413789655395, 23598.17406390768], "value": 14073.585901961302, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6867693652355, 355.4579266618405, 363.4530032301387, 366.58758896241727, 373.3224198080484, 9747.22022238221, 10431.0, 11514.366709830956, 11775.823362649546, 12582.182158159712, 13922.303884805744, 14194.0, 14213.78679319811, 14253.714925701506, 15528.844711552882, 3866.8646597369097, 4127.669786417079, 4128.896777580554, 4135.1316223593085, 4135.97932010341, 3527.8412471438846, 3747.9812600936957, 4053.837846486137, 4110.97944510277, 4148.502179738435, 3840.750351227175, 3863.80680965952, 3864.0, 3872.593377695344, 3873.6707379872746, 3862.439946207784, 3866.574676785551, 3871.0709429736944, 3871.7676939383728, 3873.1091848874776, 3344.3311337732553, 3573.8391772370305, 3591.3356029134434, 3630.5280313559333, 3774.2640185163905, 9612.0, 9688.564014619358, 9695.854562181574, 9775.755606109833, 9840.311178217527, 1744.1453687693065, 1866.6826639471187, 1889.4898377438014, 1903.4860587641397, 2236.150262900104, 7121.359077683033, 7246.71013159473, 7286.1256649201905, 7351.852962940765, 7713.267239612228, 1238.4055653286473, 1328.4818920620964, 1335.5659410691565, 1358.4701966233176, 1369.5685858954398, 65585.67207163981, 68979.31020689779, 69712.65143674299, 70532.0, 71028.64485677538, 9235.168834804765, 9285.675001374919, 9686.370385925033, 9804.460754658563, 9866.0, 12720.300383478812, 12737.363131843556, 12757.744845103047, 12785.552505662257, 12786.936065319614, 6646.0, 6895.724171033203, 6905.792826215222, 7052.801023825962, 7116.857662846817, 6439.291677915426, 6538.280789113193, 6630.270670226271, 6643.900341494881, 6810.489213309022, 3978.1844721831953, 4054.614811592894, 4090.8363665453644, 4095.406166105898, 4194.2450358935685, 1648.5301689018572, 1690.7801985741776, 1692.9746053809204, 1695.533728224748, 1752.6601986348112, 745.4409193105171, 793.2226418110284, 805.472415567804, 815.685960905044, 840.5587066789984, 15562.844371556366, 16924.661506769797, 17042.14789260532, 17043.914780426017, 17076.2315695794, 796.9681212751541, 846.5555583318807, 848.8625242175515, 854.5001174312972, 855.170484629908, 8668.956655216809, 8815.735527934045, 9046.964433002522, 9609.519524023772, 9774.706758797247, 374428.0, 374492.0, 380287.0, 384331.0, 385059.0, 48442.27336589954, 50091.0, 75191.0, 78668.0, 79787.60106199431, 913.5614904845711, 920.9677661281823, 940.9482478463614, 943.698016634684, 948.3693343926212, 14530.0, 14717.484888028868, 15006.0, 15185.392584296946, 15480.516883701077, 32647.408785033153, 34648.30703385868, 35243.82378088093, 37646.67063287757, 39230.6076939227, 48465.0, 48514.0, 48652.0, 50142.0, 50160.49839501704, 6405.839854003687, 7156.606386648576, 7349.595772232578, 7569.47013709035, 7921.683132674629, 9950.651727189514, 12113.576024839089, 12156.75686486283, 12179.512819487476, 12293.139480236297, 10292.125169360688, 10539.104176145114, 10556.0, 10584.947075264574, 10842.512086956263, 10123.392596444237, 10209.693709188736, 10347.689569312932, 10580.89419105799, 10652.680419587425, 10413.687589372332, 10600.416977066026, 10621.468926553796, 10829.133669306279, 11148.554057837595, 6459.677016149268, 6523.11937888379, 6634.4692424604955, 6710.66446677674, 6753.1220941277315, 7628.0464941882265, 8851.69019084329, 10290.0, 10310.587576496857, 10409.0], "value": 7261.886384006326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1209.72781124247, 1286.6075846866704, 1294.4304506017352, 1333.1734324718673, 1437.879800832194, 5502.257195278637, 6063.545234107443, 6227.5017998560115, 6261.780837670683, 6440.967795161017, 6287.308396076428, 6288.654124023177, 6328.145700330466, 6349.873002539946, 6352.555321127523, 1673.230314055533, 1713.083500327325, 1714.8027976782641, 1715.1424287856073, 1719.3466482736605, 1701.2174399776056, 1725.3443691397315, 1734.2109340250156, 1734.4363081998401, 1774.4676597020907, 1695.1693670101622, 1709.1795937949792, 1711.1700825099817, 1724.1379310344828, 1724.689555879941, 1685.561753943971, 1691.0530103142219, 1706.7013272677232, 1715.785526809149, 1718.0979985507583, 1732.8267173282698, 1752.0363799910126, 1753.7720096387516, 1754.1492376197534, 1756.0429565886495, 6030.427109424598, 6189.071639254103, 6193.3496982816605, 6247.031710084944, 6250.968745156245, 3984.1234928315725, 4105.568915263884, 4107.0, 4111.321631930728, 4112.3420252759415, 2504.3238325652155, 2599.493098845726, 2666.4267182555845, 2673.665791776028, 2679.477501887133, 745.1803016681661, 752.2702978111278, 771.6103367799282, 782.0927723840375, 786.1195461083565, 8540.871886921701, 8712.69505567302, 8721.171488708562, 8733.214010739, 8734.69428569997, 3530.6853193438274, 3560.750747447654, 3604.6395360463744, 3663.432168013962, 3663.8717644882836, 7071.504994650325, 7082.733525271967, 7086.291370862976, 7092.290770922867, 7097.680604372816, 4056.6146216109423, 4059.06641472463, 4061.7156799023783, 4063.1264278180333, 4063.309237429644, 3380.256343604403, 3494.2836718472654, 3762.6425489578446, 3828.5214348206473, 3847.461355410189, 2717.565189569659, 2800.173948685143, 2863.2841789552613, 3013.141254742388, 3057.5719399284117, 902.1609902790468, 916.6516723644972, 937.2642475656577, 944.7165850244899, 945.3382632157517, 627.4541149200134, 651.2770824384929, 738.759903031517, 745.3925051083459, 747.8317378589883, 4293.141371725667, 4381.6494680425485, 4477.126960242721, 4501.977490112528, 4717.669763116549, 802.1497212954171, 804.5213098206558, 808.8220591469867, 817.1746535998684, 833.6040380819062, 2955.9556606650917, 2963.8369889656274, 2988.521836506192, 3057.877684892624, 3105.239216391953, 7548.735794247175, 7766.378689704923, 7800.414968877357, 7972.561509116938, 7979.960100199578, 7930.603469826486, 7931.762047138595, 7947.205279472122, 7947.682092716343, 7949.880751788728, 6076.635401875901, 6079.574429789872, 6164.660943648053, 6194.163787888668, 6205.069239614094, 4369.279068953587, 4385.407969924019, 4460.977695111504, 4463.933041004452, 4606.424196975378, 7291.927080729125, 7299.379552738077, 7321.231270716711, 7361.043064401593, 7372.336489715952, 7438.293362130589, 7510.586917719578, 7530.021097257321, 7532.17146113938, 7535.246475352421, 5370.221317908803, 5474.39781624029, 5478.369987451494, 5478.698671573102, 5479.520529457104, 5567.610267281251, 5757.193992841089, 5902.852428689145, 6207.782727604693, 6297.370262973666, 5508.91736623943, 5596.104623260258, 5644.0, 5652.434756524314, 5693.800716974886, 5610.326760788568, 5624.29696287964, 5667.631603945731, 5689.687067211343, 5840.474357307863, 4923.778429970588, 4984.551390374883, 4989.0, 4991.0, 4991.026749783758, 3863.150106976461, 3875.4961854958674, 3904.1996390739264, 3959.8614048508166, 4047.696422768245, 3866.8066596670615, 3905.3946638271113, 3937.8818635440985, 3989.1423343981182, 4040.9595904040584], "value": 3436.433371060466, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.6848277205372, 279.1291171544781, 286.57014478282576, 289.3807252479692, 289.6046895986978, 4501.437320334958, 4616.7525876193895, 4624.456548898105, 4658.953410465894, 4689.718616882981, 4457.91084178317, 4534.36518887356, 4552.317152427137, 4591.24244499658, 4591.380163677903, 3585.529932727582, 3612.988363258292, 3619.7285203609636, 3628.3853685110134, 3642.690371318428, 3457.7060949819297, 3632.0035970324457, 3635.1275693833554, 3636.0182750657304, 3641.0533261352107, 3388.9322213555715, 3564.7148228141687, 3579.6241394653575, 3598.9280214395694, 3604.098975256186, 3578.248567800765, 3590.1563132663805, 3593.4070878305047, 3598.6325196425323, 3603.171270607762, 3143.5637381029655, 3143.984280078585, 3149.4173577887937, 3149.7637677174303, 3159.2891599389955, 4317.503487098992, 4514.774261286922, 4520.86437406875, 4528.117017181651, 4531.116432295704, 4054.6350828425584, 4060.3909413587903, 4071.592840715905, 4075.4701888754566, 4081.9387709184384, 4861.927071093936, 4864.829730959399, 4865.618395215327, 4870.613399434287, 4872.9756351218375, 3409.3010932758725, 3410.6248312685584, 3413.8293085345636, 3417.641147679483, 3418.29924865402, 4914.827981020647, 5025.271335656309, 5036.169032109698, 5043.394792624908, 5060.2156665716875, 3457.291255292659, 3457.8271086445575, 3461.3077384523194, 3462.255615042778, 3468.1849765305133, 3497.8663015560487, 3507.228409749851, 3513.80674062929, 3519.524864143359, 3521.4189658706027, 3800.7529510581726, 4022.396640503889, 4033.233685599727, 4043.838246470167, 4061.878143655695, 4809.859612472416, 4837.854864354075, 4850.6362022847725, 4850.975745121323, 4854.83008094715, 1127.2672762704308, 1151.0446329546455, 1183.7040739815047, 1189.8036823923956, 1191.1304747534348, 796.6853093028337, 814.9103598604149, 817.1297568089965, 819.1358117186426, 821.5235163605095, 4149.647279981175, 4151.190517848991, 4171.165766846643, 4171.872345104019, 4176.665866730655, 4789.377380940456, 4794.321987304415, 4809.86968062505, 4812.735299558558, 4827.031375703917, 4031.258744054013, 4132.462779838662, 4154.605312495369, 4314.50383205929, 4328.8484903028875, 2569.948601027969, 2578.742125787406, 2578.9355266118496, 2580.8193426459966, 2582.264054744389, 4533.161365147458, 4544.431946006749, 4560.749158796298, 4569.680122391467, 4573.542645735466, 4735.0, 4938.333325001079, 4955.702657840541, 4976.5023497649945, 5000.924616284201, 4545.999880026455, 4546.909061818811, 4551.840685575989, 4552.544745525421, 4556.0, 4831.613470922248, 4840.6127509799835, 4843.515648435269, 4858.5384388483035, 4866.659333846596, 4591.405855691523, 4736.573708366263, 4765.356676848581, 4766.547178018082, 4786.617070634411, 4543.295789152687, 4560.429946256718, 4561.407017087758, 4567.990084363003, 4568.862934111982, 3352.916177095592, 3355.3223388305846, 3371.5111308859587, 3371.690392081432, 3384.847681854273, 4652.9298261400745, 4852.272159176152, 4861.295112208781, 4865.9756701216265, 4871.80512779499, 4615.953840461687, 4668.463126740468, 4672.345871577913, 4683.555062269079, 4729.952700472952, 4606.723596584082, 4649.163150632783, 4649.697769644962, 4663.2745884023125, 4664.883377915444, 4450.243458612108, 4576.244919588231, 4579.656525760514, 4594.977025114853, 4610.74640894754, 4310.0, 4779.187538118459, 4804.975975120102, 4812.494688057704, 4818.277258411266, 4662.018642077036, 4707.4821769606, 4716.410448693913, 4717.740524271198, 4720.244760838249], "value": 3580.462572996864, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.4800215820869, 633.6103296472669, 640.2029473305735, 679.8232459560514, 689.0353505092869, 10378.688639340822, 11603.941980290108, 11611.709707257323, 11616.593419230328, 11621.825672614917, 10726.302790318643, 10830.350178989247, 10899.400532970703, 10913.890861091386, 11028.393438360887, 601.266454924993, 619.662284055191, 726.3317747672154, 737.0492065235853, 749.4753672429322, 695.7808290388537, 708.7873637908607, 713.233274230202, 713.7751608243412, 714.2571725405572, 805.1746959366637, 806.3549160671469, 806.3831169155601, 806.8870358149863, 806.899137607799, 631.2456614345858, 648.0926702616327, 743.7917383132732, 799.9320057795095, 800.8118092248346, 573.8651416917021, 577.1400613086513, 577.4802677590161, 582.4757718053743, 582.7785441532191, 10343.793124137475, 10467.52896119669, 10478.10936070435, 10485.423301718403, 10595.735106622318, 9411.0, 9695.90304096964, 9792.461414622192, 9844.261680374, 10219.540120694586, 8126.756197314031, 8194.05768336637, 8201.343892488587, 8249.876251856227, 8264.917350826476, 2370.668106465096, 2448.9387765305833, 2470.086068154776, 2491.700995880506, 2588.184721812642, 48274.27588586174, 48299.51700482985, 48482.27276590819, 48642.0, 48774.0, 6969.7909062728195, 7096.609686467294, 7192.352688257977, 7233.276672332725, 7240.094988126484, 14752.483663071742, 14811.703765924836, 14849.628759280888, 14855.182964937032, 14920.104793712411, 6239.563230573907, 6334.936650633527, 6567.901481477782, 6598.538102332886, 6732.192136943598, 3473.3053389322235, 3526.1360966563357, 3548.4854696068924, 3636.5999740028574, 3711.4247291669835, 2626.1727555819857, 2661.7471340222646, 2669.3460102275067, 2701.8108732388546, 2705.8241214321006, 468.72111093899076, 474.29567092867063, 478.98216290382936, 479.33851285226166, 481.4367190387255, 453.7436348463108, 505.76987470701476, 518.6550943622449, 525.7581512504291, 536.471575498134, 6784.185897692209, 6796.966015169893, 6894.896576551356, 6951.096357473597, 7006.4745144114395, 459.1734877220989, 469.9060187962421, 481.64599019720475, 514.8326793792032, 518.7302602646678, 3305.3719793239206, 3323.3851737428654, 3395.0, 3402.1324562236473, 3474.409350410436, 68976.0, 69016.30983690098, 69623.0, 71634.0, 71782.64108679528, 37413.81293093517, 39430.40854387187, 48798.51201488011, 48988.755056224494, 61711.691441542505, 57217.0, 58714.0, 58757.70621146867, 59773.99999999913, 60569.0, 6528.510361722985, 6723.663816809238, 6736.259011508632, 6872.759453419107, 6978.162620485574, 37922.43116353312, 38740.6125938737, 38756.6124338753, 39604.80197598993, 40679.79660101799, 42924.57075429206, 43192.35211471893, 47025.0, 52309.0, 54097.45902540924, 8223.753287401387, 8567.357448191286, 8869.423487473552, 8921.286297096087, 9029.864552031857, 9918.801623967625, 10041.246906481896, 10203.795924081625, 10563.788724225626, 10565.524551395052, 8540.231379175904, 9040.0, 9366.159874451443, 9415.482148481626, 9619.855702164334, 8991.865122023306, 9004.279657627243, 9034.412763170372, 9335.113164249386, 9354.0, 8895.0, 8911.777205569913, 8970.147835955573, 9090.363674542983, 9178.816423671622, 7475.813104672427, 7793.571353575608, 7906.52560846351, 7982.481138725964, 8120.228578285053, 7823.687052517836, 7847.686092556234, 7993.120756716873, 8060.0, 8155.673773049249], "value": 5417.325990950518, "unit": "score"}], "name": "dromaeo_css", "value": 5562.923430347683, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 117.0, 117.0, 116.0, 116.0, 115.0, 116.0, 115.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 128.0, 168.0, 131.0, 131.0, 129.0, 134.0, 183.0, 132.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 162.0, 163.0, 164.0, 164.0, 163.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 108.0, 78.0, 76.0, 78.0, 76.0, 97.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 125.0, 84.0, 83.0, 83.0, 106.0, 83.0, 84.0, 122.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 119.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 148.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 83.0, 83.0, 88.0, 84.0, 93.0, 83.0, 84.0, 89.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 140.0, 141.0, 142.0, 143.0, 145.0, 142.0, 194.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 179.0, 179.0, 183.0, 177.0, 181.0, 176.0, 179.0, 178.0, 178.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 70.0, 68.0, 68.0, 70.0, 68.0, 70.0, 69.0, 69.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1568.9000000000003, "alertThreshold": 2.0}]} 18:32:58 INFO - Return code: 0 18:32:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:58 INFO - # TBPL SUCCESS # 18:32:58 INFO - Running post-action listener: _resource_record_post_action 18:32:58 INFO - [mozharness: 2016-11-18 02:32:58.450504Z] Finished run-tests step (success) 18:32:58 INFO - Running post-run listener: _resource_record_post_run 18:32:58 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 401629184; Read time: 56; Write time: 293108 18:32:58 INFO - TinderboxPrint: CPU usage
13.1% 18:32:58 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 56 18:32:58 INFO - TinderboxPrint: I/O write bytes / time
401,629,184 / 293,108 18:32:58 INFO - TinderboxPrint: CPU idle
8,348.4 (86.9%) 18:32:58 INFO - TinderboxPrint: CPU user
1,207.1 (12.6%) 18:32:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:58 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 110350336; Read time: 44; Write time: 71648 18:32:58 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 290385920; Read time: 0; Write time: 221436 18:32:59 INFO - Running post-run listener: _upload_blobber_files 18:32:59 INFO - Blob upload gear active. 18:32:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:59 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 18:32:59 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:59 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:32:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:33:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:00 INFO - (blobuploader) - INFO - Done attempting. 18:33:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:33:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:03 INFO - (blobuploader) - INFO - Done attempting. 18:33:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:33:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:33:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:04 INFO - (blobuploader) - INFO - Done attempting. 18:33:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:33:04 INFO - Return code: 0 18:33:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:33:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:33:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f699cbdf4e51b5c4dd8b3f5b6f09a12b785065ac9476858eac5f460d6dbab9e317ea29c30f678264c4689d8a3e410a6b96ace4d79fb6272e36a56d21ced1e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29dc836eff83e1624d0c10f8df73fffa2d1a75cfb15366559e6507ec8004acf05659464737bfabc1ff7a9302f59904b7991a4d9f0795e751f556912771b99514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/34694d2b740d554fe2f45c89636d1475ca7f648a55a994c89c0bbe3de356816d40503c1b3e36231b93477c287a74a958ab35eeb8b2b36ab5e388917133f5d883"} 18:33:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:33:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:33:04 INFO - Contents: 18:33:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f699cbdf4e51b5c4dd8b3f5b6f09a12b785065ac9476858eac5f460d6dbab9e317ea29c30f678264c4689d8a3e410a6b96ace4d79fb6272e36a56d21ced1e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29dc836eff83e1624d0c10f8df73fffa2d1a75cfb15366559e6507ec8004acf05659464737bfabc1ff7a9302f59904b7991a4d9f0795e751f556912771b99514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/34694d2b740d554fe2f45c89636d1475ca7f648a55a994c89c0bbe3de356816d40503c1b3e36231b93477c287a74a958ab35eeb8b2b36ab5e388917133f5d883"} 18:33:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:33:04 INFO - Copying logs to upload dir... 18:33:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:33:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.829500 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-17 18:33:04.701533) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 18:33:04.706545) ========= 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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f699cbdf4e51b5c4dd8b3f5b6f09a12b785065ac9476858eac5f460d6dbab9e317ea29c30f678264c4689d8a3e410a6b96ace4d79fb6272e36a56d21ced1e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29dc836eff83e1624d0c10f8df73fffa2d1a75cfb15366559e6507ec8004acf05659464737bfabc1ff7a9302f59904b7991a4d9f0795e751f556912771b99514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/34694d2b740d554fe2f45c89636d1475ca7f648a55a994c89c0bbe3de356816d40503c1b3e36231b93477c287a74a958ab35eeb8b2b36ab5e388917133f5d883"} build_url:https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/V64vm022QLKSWYboHx_DnQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f699cbdf4e51b5c4dd8b3f5b6f09a12b785065ac9476858eac5f460d6dbab9e317ea29c30f678264c4689d8a3e410a6b96ace4d79fb6272e36a56d21ced1e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/29dc836eff83e1624d0c10f8df73fffa2d1a75cfb15366559e6507ec8004acf05659464737bfabc1ff7a9302f59904b7991a4d9f0795e751f556912771b99514", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/34694d2b740d554fe2f45c89636d1475ca7f648a55a994c89c0bbe3de356816d40503c1b3e36231b93477c287a74a958ab35eeb8b2b36ab5e388917133f5d883"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 18:33:04.739362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:33:04.739751) ========= 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=920470bf432669ad7e3a5387000001ca-1479432618.222086-909842181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 18:33:04.796389) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 18:33:04.796762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 18:33:04.797111) ========= ========= Total master_lag: 0.45 =========