builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1477099300.04 results: success (0) buildid: 20161021163002 builduid: e7f21ccdebc8454b828314afc4f72340 revision: 5639a9f476d08f300c079117e61697f5026b6367 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.036788) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.037353) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.037653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.052165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.052484) ========= 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=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-21 18:21:39-- 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% 46.8M=0s 2016-10-21 18:21:39 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075960 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.142395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.142720) ========= 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=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013113 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:40.191989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 18:21:40.192347) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5639a9f476d08f300c079117e61697f5026b6367 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5639a9f476d08f300c079117e61697f5026b6367 --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=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-21 18:21:40,074 truncating revision to first 12 chars 2016-10-21 18:21:40,074 Setting DEBUG logging. 2016-10-21 18:21:40,074 attempt 1/10 2016-10-21 18:21:40,074 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5639a9f476d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-21 18:21:40,932 unpacking tar archive at: mozilla-central-5639a9f476d0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.178774 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 18:21:41.385760) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:41.386116) ========= script_repo_revision: 5639a9f476d08f300c079117e61697f5026b6367 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:41.386523) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:41.386794) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 18:21:41.397034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-21 18:21:41.397315) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:21:41 INFO - MultiFileLogger online at 20161021 18:21:41 in /builds/slave/test 18:21:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:21:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:21:41 INFO - {'append_to_log': False, 18:21:41 INFO - 'base_work_dir': '/builds/slave/test', 18:21:41 INFO - 'blob_upload_branch': 'Firefox', 18:21:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:21:41 INFO - 'branch': 'Firefox', 18:21:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:21:41 INFO - 'config_files': ('talos/linux_config.py',), 18:21:41 INFO - 'default_actions': ('clobber', 18:21:41 INFO - 'read-buildbot-config', 18:21:41 INFO - 'download-and-extract', 18:21:41 INFO - 'populate-webroot', 18:21:41 INFO - 'create-virtualenv', 18:21:41 INFO - 'install', 18:21:41 INFO - 'run-tests'), 18:21:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:21:41 INFO - 'download_minidump_stackwalk': True, 18:21:41 INFO - 'download_symbols': 'ondemand', 18:21:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:21:41 INFO - 'tooltool.py': '/tools/tooltool.py', 18:21:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:21:41 INFO - '/tools/misc-python/virtualenv.py')}, 18:21:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:21:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:21:41 INFO - 'installer_path': 'installer.exe', 18:21:41 INFO - 'log_level': 'info', 18:21:41 INFO - 'log_name': 'talos', 18:21:41 INFO - 'log_to_console': True, 18:21:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:21:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:21:41 INFO - 'opt_config_files': (), 18:21:41 INFO - 'pip_index': False, 18:21:41 INFO - 'sps_profile': False, 18:21:41 INFO - 'sps_profile_interval': 0, 18:21:41 INFO - 'suite': 'dromaeojs', 18:21:41 INFO - 'system_bits': '32', 18:21:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:21:41 INFO - 'title': 'talos-linux64-ix-014', 18:21:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:21:41 INFO - 'use_talos_json': True, 18:21:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:21:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:21:41 INFO - 'work_dir': 'build'} 18:21:41 INFO - [mozharness: 2016-10-22 01:21:41.375349Z] Running clobber step. 18:21:41 INFO - Running pre-action listener: _resource_record_pre_action 18:21:41 INFO - Running main action method: clobber 18:21:41 INFO - rmtree: /builds/slave/test/build 18:21:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:21:41 INFO - Running post-action listener: _resource_record_post_action 18:21:41 INFO - [mozharness: 2016-10-22 01:21:41.830920Z] Finished clobber step (success) 18:21:41 INFO - [mozharness: 2016-10-22 01:21:41.831022Z] Running read-buildbot-config step. 18:21:41 INFO - Running pre-action listener: _resource_record_pre_action 18:21:41 INFO - Running main action method: read_buildbot_config 18:21:41 INFO - Using buildbot properties: 18:21:41 INFO - { 18:21:41 INFO - "project": "", 18:21:41 INFO - "product": "firefox", 18:21:41 INFO - "script_repo_revision": "production", 18:21:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:21:41 INFO - "repository": "", 18:21:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:21:41 INFO - "buildid": "20161021163002", 18:21:41 INFO - "pgo_build": "True", 18:21:41 INFO - "basedir": "/builds/slave/test", 18:21:41 INFO - "buildnumber": 600, 18:21:41 INFO - "slavename": "talos-linux64-ix-014", 18:21:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:21:41 INFO - "platform": "ubuntu64_hw", 18:21:41 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:21:41 INFO - "branch": "mozilla-central", 18:21:41 INFO - "repo_path": "mozilla-central", 18:21:41 INFO - "revision": "5639a9f476d08f300c079117e61697f5026b6367", 18:21:41 INFO - "stage_platform": "linux64-pgo", 18:21:41 INFO - "builduid": "e7f21ccdebc8454b828314afc4f72340", 18:21:41 INFO - "slavebuilddir": "test" 18:21:41 INFO - } 18:21:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:21:41 INFO - Running post-action listener: _resource_record_post_action 18:21:41 INFO - [mozharness: 2016-10-22 01:21:41.833899Z] Finished read-buildbot-config step (success) 18:21:41 INFO - [mozharness: 2016-10-22 01:21:41.833988Z] Running download-and-extract step. 18:21:41 INFO - Running pre-action listener: _resource_record_pre_action 18:21:41 INFO - Running main action method: download_and_extract 18:21:41 INFO - mkdir: /builds/slave/test/build/tests 18:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:41 INFO - trying https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:21:42 INFO - Downloaded 1361 bytes. 18:21:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:21:42 INFO - Using the following test package requirements: 18:21:42 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:21:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:21:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:21:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'jsshell-linux-x86_64.zip'], 18:21:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:21:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:21:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:21:42 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:21:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:21:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:21:42 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:21:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:21:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:21:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:21:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:21:42 INFO - Http code: 200 18:21:42 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 18:21:42 INFO - x-amz-cf-id: E0RsZVttc-IMcNlHWA8H4d5rvMAHrSdj6VaJNS9riMp5J6g8Zq2pfg== 18:21:42 INFO - x-amz-version-id: KFmGU6fGSnwISMaTGOL7loGUGbvA9LNO 18:21:43 INFO - Expected file size: 22032385 18:21:43 INFO - Obtained file size: 22032385 18:21:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:21:47 INFO - Http code: 200 18:21:47 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 18:21:47 INFO - x-amz-cf-id: 2oexPXr-oNV6h2W9nz7tI20IROHpLM7_WhE7jEOIbJLD1wQQxfhzuA== 18:21:47 INFO - x-amz-version-id: KFmGU6fGSnwISMaTGOL7loGUGbvA9LNO 18:21:48 INFO - Expected file size: 22032385 18:21:48 INFO - Obtained file size: 22032385 18:21:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:21:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:21:52 INFO - Fetch https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:21:53 INFO - Http code: 200 18:21:53 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 18:21:53 INFO - x-amz-cf-id: UYFQ_pDkFKaed9_cVYS2DOAtvUTwrbZs8FfB05mkNEIFTTHT8-0U8g== 18:21:53 INFO - x-amz-version-id: bemM0TQVzdcrkJgxNQIMqdlpk1KvIdkz 18:21:53 INFO - Expected file size: 14409028 18:21:53 INFO - Obtained file size: 14409028 18:21: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:21:53 INFO - trying https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:21:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:21:54 INFO - Downloaded 59350983 bytes. 18:21:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:21:54 INFO - mkdir: /builds/slave/test/properties 18:21:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:21:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:21:54 INFO - Contents: 18:21:54 INFO - build_url:https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:21:55 INFO - Running post-action listener: _resource_record_post_action 18:21:55 INFO - Running post-action listener: set_extra_try_arguments 18:21:55 INFO - [mozharness: 2016-10-22 01:21:55.364976Z] Finished download-and-extract step (success) 18:21:55 INFO - [mozharness: 2016-10-22 01:21:55.365203Z] Running populate-webroot step. 18:21:55 INFO - Running pre-action listener: _resource_record_pre_action 18:21:55 INFO - Running main action method: populate_webroot 18:21:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:21:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:21:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:21:55 INFO - '--tppagecycles', 18:21:55 INFO - '7'], 18:21:55 INFO - 'tests': ['tsvgm']}}, 18:21:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:21:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:21:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:21:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:21:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['damp', 'tps']}, 18:21:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'tests': ['damp', 'tps']}, 18:21:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['dromaeo_dom']}, 18:21:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:21:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['basic_compositor_video']}, 18:21:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:21:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'other-e10s': {'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['a11yr', 18:21:55 INFO - 'ts_paint', 18:21:55 INFO - 'tpaint', 18:21:55 INFO - 'sessionrestore', 18:21:55 INFO - 'sessionrestore_no_auto_restore', 18:21:55 INFO - 'tabpaint']}, 18:21:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['tsvgx', 18:21:55 INFO - 'tsvgr_opacity', 18:21:55 INFO - 'tart', 18:21:55 INFO - 'tscrollx', 18:21:55 INFO - 'cart']}, 18:21:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:21:55 INFO - 'tsvgr_opacity', 18:21:55 INFO - 'tart', 18:21:55 INFO - 'tscrollx', 18:21:55 INFO - 'cart']}, 18:21:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'talos_options': ['--disable-e10s'], 18:21:55 INFO - 'tests': ['tp5o']}, 18:21:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'tests': ['tp5o']}, 18:21:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'talos_options': ['--disable-e10s', 18:21:55 INFO - '--xperf_path', 18:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:55 INFO - 'tests': ['tp5n']}, 18:21:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:55 INFO - 'talos_options': ['--xperf_path', 18:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:55 INFO - 'tests': ['tp5n']}}, 18:21:55 INFO - 'talos.zip': {'path': '', 18:21:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:21:55 INFO - Running post-action listener: _resource_record_post_action 18:21:55 INFO - [mozharness: 2016-10-22 01:21:55.384995Z] Finished populate-webroot step (success) 18:21:55 INFO - [mozharness: 2016-10-22 01:21:55.385084Z] Running create-virtualenv step. 18:21:55 INFO - Running pre-action listener: _resource_record_pre_action 18:21:55 INFO - Running main action method: create_virtualenv 18:21:55 INFO - Creating virtualenv /builds/slave/test/build/venv 18:21:55 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:21:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:21:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:21:55 INFO - Using real prefix '/usr' 18:21:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:21:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:21:57 INFO - Installing pip.................done. 18:21:57 INFO - Return code: 0 18:21:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:21:58 INFO - Reading from file tmpfile_stdout 18:21:58 INFO - Output received: 18:21:58 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:21:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:21:58 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:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:58 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:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:58 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:21:58 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:21:58 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:21:58 INFO - Using env: {'DISPLAY': ':0', 18:21:58 INFO - 'HOME': '/home/cltbld', 18:21:58 INFO - 'LANG': 'en_US.UTF-8', 18:21:58 INFO - 'LANGUAGE': 'en_US:en', 18:21:58 INFO - 'LOGNAME': 'cltbld', 18:21:58 INFO - 'MAIL': '/var/mail/cltbld', 18:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:58 INFO - 'MOZ_NO_REMOTE': '1', 18:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:58 INFO - 'NO_EM_RESTART': '1', 18:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:58 INFO - 'PWD': '/builds/slave/test', 18:21:58 INFO - 'SHELL': '/bin/bash', 18:21:58 INFO - 'SHLVL': '1', 18:21:58 INFO - 'TERM': 'linux', 18:21:58 INFO - 'TMOUT': '86400', 18:21:58 INFO - 'USER': 'cltbld', 18:21:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:58 INFO - '_': '/tools/buildbot/bin/python'} 18:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:58 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:21:58 INFO - Cleaning up... 18:21:58 INFO - Return code: 0 18:21:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:21:58 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:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:58 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:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:58 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:21:58 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:21:58 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:21:58 INFO - Using env: {'DISPLAY': ':0', 18:21:58 INFO - 'HOME': '/home/cltbld', 18:21:58 INFO - 'LANG': 'en_US.UTF-8', 18:21:58 INFO - 'LANGUAGE': 'en_US:en', 18:21:58 INFO - 'LOGNAME': 'cltbld', 18:21:58 INFO - 'MAIL': '/var/mail/cltbld', 18:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:58 INFO - 'MOZ_NO_REMOTE': '1', 18:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:58 INFO - 'NO_EM_RESTART': '1', 18:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:58 INFO - 'PWD': '/builds/slave/test', 18:21:58 INFO - 'SHELL': '/bin/bash', 18:21:58 INFO - 'SHLVL': '1', 18:21:58 INFO - 'TERM': 'linux', 18:21:58 INFO - 'TMOUT': '86400', 18:21:58 INFO - 'USER': 'cltbld', 18:21:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:58 INFO - '_': '/tools/buildbot/bin/python'} 18:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:58 INFO - Downloading/unpacking psutil>=3.1.1 18:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:58 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:21:58 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:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:00 INFO - Installing collected packages: psutil 18:22:00 INFO - Running setup.py install for psutil 18:22:00 INFO - building 'psutil._psutil_linux' extension 18:22:00 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:22:00 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:22:00 INFO - building 'psutil._psutil_posix' extension 18:22:00 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:22:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:22:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:01 INFO - Successfully installed psutil 18:22:01 INFO - Cleaning up... 18:22:01 INFO - Return code: 0 18:22:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:01 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:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:01 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:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:01 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:01 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:22:01 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:22:01 INFO - Using env: {'DISPLAY': ':0', 18:22:01 INFO - 'HOME': '/home/cltbld', 18:22:01 INFO - 'LANG': 'en_US.UTF-8', 18:22:01 INFO - 'LANGUAGE': 'en_US:en', 18:22:01 INFO - 'LOGNAME': 'cltbld', 18:22:01 INFO - 'MAIL': '/var/mail/cltbld', 18:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:01 INFO - 'MOZ_NO_REMOTE': '1', 18:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:01 INFO - 'NO_EM_RESTART': '1', 18:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:01 INFO - 'PWD': '/builds/slave/test', 18:22:01 INFO - 'SHELL': '/bin/bash', 18:22:01 INFO - 'SHLVL': '1', 18:22:01 INFO - 'TERM': 'linux', 18:22:01 INFO - 'TMOUT': '86400', 18:22:01 INFO - 'USER': 'cltbld', 18:22:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:01 INFO - '_': '/tools/buildbot/bin/python'} 18:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:01 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:22:01 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:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:03 INFO - Installing collected packages: mozsystemmonitor 18:22:03 INFO - Running setup.py install for mozsystemmonitor 18:22:03 INFO - Successfully installed mozsystemmonitor 18:22:03 INFO - Cleaning up... 18:22:03 INFO - Return code: 0 18:22:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:03 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:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:03 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:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:03 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:22:03 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:22:03 INFO - Using env: {'DISPLAY': ':0', 18:22:03 INFO - 'HOME': '/home/cltbld', 18:22:03 INFO - 'LANG': 'en_US.UTF-8', 18:22:03 INFO - 'LANGUAGE': 'en_US:en', 18:22:03 INFO - 'LOGNAME': 'cltbld', 18:22:03 INFO - 'MAIL': '/var/mail/cltbld', 18:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:03 INFO - 'MOZ_NO_REMOTE': '1', 18:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:03 INFO - 'NO_EM_RESTART': '1', 18:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:03 INFO - 'PWD': '/builds/slave/test', 18:22:03 INFO - 'SHELL': '/bin/bash', 18:22:03 INFO - 'SHLVL': '1', 18:22:03 INFO - 'TERM': 'linux', 18:22:03 INFO - 'TMOUT': '86400', 18:22:03 INFO - 'USER': 'cltbld', 18:22:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:03 INFO - '_': '/tools/buildbot/bin/python'} 18:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:03 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:03 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:22:03 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:22:03 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:22:03 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:22:03 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:22:03 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:22:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:05 INFO - warning: no files found matching '*.txt' 18:22:05 INFO - no previously-included directories found matching 'build' 18:22:05 INFO - no previously-included directories found matching 'dist' 18:22:05 INFO - no previously-included directories found matching '.git*' 18:22:05 INFO - Installing collected packages: jsonschema, functools32 18:22:06 INFO - Running setup.py install for functools32 18:22:06 INFO - warning: no files found matching '*.txt' 18:22:06 INFO - no previously-included directories found matching 'build' 18:22:06 INFO - no previously-included directories found matching 'dist' 18:22:06 INFO - no previously-included directories found matching '.git*' 18:22:06 INFO - Successfully installed jsonschema functools32 18:22:06 INFO - Cleaning up... 18:22:06 INFO - Return code: 0 18:22:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:06 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:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:06 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:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:06 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:06 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:22:06 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:22:06 INFO - Using env: {'DISPLAY': ':0', 18:22:06 INFO - 'HOME': '/home/cltbld', 18:22:06 INFO - 'LANG': 'en_US.UTF-8', 18:22:06 INFO - 'LANGUAGE': 'en_US:en', 18:22:06 INFO - 'LOGNAME': 'cltbld', 18:22:06 INFO - 'MAIL': '/var/mail/cltbld', 18:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:06 INFO - 'MOZ_NO_REMOTE': '1', 18:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:06 INFO - 'NO_EM_RESTART': '1', 18:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:06 INFO - 'PWD': '/builds/slave/test', 18:22:06 INFO - 'SHELL': '/bin/bash', 18:22:06 INFO - 'SHLVL': '1', 18:22:06 INFO - 'TERM': 'linux', 18:22:06 INFO - 'TMOUT': '86400', 18:22:06 INFO - 'USER': 'cltbld', 18:22:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:06 INFO - '_': '/tools/buildbot/bin/python'} 18:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:06 INFO - Cleaning up... 18:22:06 INFO - Return code: 0 18:22:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:06 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:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:06 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:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:06 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:06 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:22:06 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:22:06 INFO - Using env: {'DISPLAY': ':0', 18:22:06 INFO - 'HOME': '/home/cltbld', 18:22:06 INFO - 'LANG': 'en_US.UTF-8', 18:22:06 INFO - 'LANGUAGE': 'en_US:en', 18:22:06 INFO - 'LOGNAME': 'cltbld', 18:22:06 INFO - 'MAIL': '/var/mail/cltbld', 18:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:06 INFO - 'MOZ_NO_REMOTE': '1', 18:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:06 INFO - 'NO_EM_RESTART': '1', 18:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:06 INFO - 'PWD': '/builds/slave/test', 18:22:06 INFO - 'SHELL': '/bin/bash', 18:22:06 INFO - 'SHLVL': '1', 18:22:06 INFO - 'TERM': 'linux', 18:22:06 INFO - 'TMOUT': '86400', 18:22:06 INFO - 'USER': 'cltbld', 18:22:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:06 INFO - '_': '/tools/buildbot/bin/python'} 18:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:06 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:06 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:22:06 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:22:06 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:22:06 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:22:06 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:22:06 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:22:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:08 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:22:08 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:22:08 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:22:08 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:22:08 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:22:08 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:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 INFO - Downloading docopt-0.6.1.tar.gz 18:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:09 INFO - Running setup.py install for blobuploader 18:22:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:09 INFO - Running setup.py install for requests 18:22:09 INFO - Running setup.py install for docopt 18:22:09 INFO - Successfully installed blobuploader requests docopt 18:22:09 INFO - Cleaning up... 18:22:09 INFO - Return code: 0 18:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:09 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:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:09 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:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:09 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:09 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:22:09 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:22:09 INFO - Using env: {'DISPLAY': ':0', 18:22:09 INFO - 'HOME': '/home/cltbld', 18:22:09 INFO - 'LANG': 'en_US.UTF-8', 18:22:09 INFO - 'LANGUAGE': 'en_US:en', 18:22:09 INFO - 'LOGNAME': 'cltbld', 18:22:09 INFO - 'MAIL': '/var/mail/cltbld', 18:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:09 INFO - 'MOZ_NO_REMOTE': '1', 18:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:09 INFO - 'NO_EM_RESTART': '1', 18:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:09 INFO - 'PWD': '/builds/slave/test', 18:22:09 INFO - 'SHELL': '/bin/bash', 18:22:09 INFO - 'SHLVL': '1', 18:22:09 INFO - 'TERM': 'linux', 18:22:09 INFO - 'TMOUT': '86400', 18:22:09 INFO - 'USER': 'cltbld', 18:22:09 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:09 INFO - '_': '/tools/buildbot/bin/python'} 18:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:10 INFO - Running setup.py (path:/tmp/pip-rl0daY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:10 INFO - Running setup.py (path:/tmp/pip-sbq2Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:10 INFO - Running setup.py (path:/tmp/pip-_s5KDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:10 INFO - Running setup.py (path:/tmp/pip-Y4OY9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:10 INFO - Running setup.py (path:/tmp/pip-H4iN80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:10 INFO - Running setup.py (path:/tmp/pip-OxjQKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:10 INFO - Running setup.py (path:/tmp/pip-pDD1rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:10 INFO - Running setup.py (path:/tmp/pip-QBN1zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:10 INFO - Running setup.py (path:/tmp/pip-zNkLIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:10 INFO - Running setup.py (path:/tmp/pip-xIkbJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:10 INFO - Running setup.py (path:/tmp/pip-qkbIem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:10 INFO - Running setup.py (path:/tmp/pip-mh9xzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:11 INFO - Running setup.py (path:/tmp/pip-0Npdjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:11 INFO - Running setup.py (path:/tmp/pip-55nNjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:11 INFO - Running setup.py (path:/tmp/pip-hqYco7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:11 INFO - Running setup.py (path:/tmp/pip-3njih2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:11 INFO - Running setup.py (path:/tmp/pip-n9ZXHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:11 INFO - Running setup.py install for manifestparser 18:22:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:11 INFO - Running setup.py install for mozcrash 18:22:11 INFO - Running setup.py install for mozdebug 18:22:11 INFO - Running setup.py install for mozdevice 18:22:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:22:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:11 INFO - Running setup.py install for mozfile 18:22:11 INFO - Running setup.py install for mozhttpd 18:22:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:11 INFO - Running setup.py install for mozinfo 18:22:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for mozInstall 18:22:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for mozleak 18:22:12 INFO - Running setup.py install for mozlog 18:22:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for moznetwork 18:22:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for mozprocess 18:22:12 INFO - Running setup.py install for mozprofile 18:22:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for mozrunner 18:22:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:12 INFO - Running setup.py install for mozscreenshot 18:22:12 INFO - Running setup.py install for moztest 18:22:13 INFO - Running setup.py install for mozversion 18:22:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:13 INFO - Cleaning up... 18:22:13 INFO - Return code: 0 18:22:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:13 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:13 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:22:13 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:22:13 INFO - Using env: {'DISPLAY': ':0', 18:22:13 INFO - 'HOME': '/home/cltbld', 18:22:13 INFO - 'LANG': 'en_US.UTF-8', 18:22:13 INFO - 'LANGUAGE': 'en_US:en', 18:22:13 INFO - 'LOGNAME': 'cltbld', 18:22:13 INFO - 'MAIL': '/var/mail/cltbld', 18:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:13 INFO - 'MOZ_NO_REMOTE': '1', 18:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:13 INFO - 'NO_EM_RESTART': '1', 18:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:13 INFO - 'PWD': '/builds/slave/test', 18:22:13 INFO - 'SHELL': '/bin/bash', 18:22:13 INFO - 'SHLVL': '1', 18:22:13 INFO - 'TERM': 'linux', 18:22:13 INFO - 'TMOUT': '86400', 18:22:13 INFO - 'USER': 'cltbld', 18:22:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:13 INFO - '_': '/tools/buildbot/bin/python'} 18:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:13 INFO - Running setup.py (path:/tmp/pip-ANb2ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:13 INFO - Running setup.py (path:/tmp/pip-vGq87P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:13 INFO - Running setup.py (path:/tmp/pip-DKCJ3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:13 INFO - Running setup.py (path:/tmp/pip-26fVLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:13 INFO - Running setup.py (path:/tmp/pip-UqGF7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:13 INFO - Running setup.py (path:/tmp/pip-TKlWg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:13 INFO - Running setup.py (path:/tmp/pip-5IM3Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:13 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:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:13 INFO - Running setup.py (path:/tmp/pip-_566TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:14 INFO - Running setup.py (path:/tmp/pip-xV3_ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:14 INFO - Running setup.py (path:/tmp/pip-V_hVgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:14 INFO - Running setup.py (path:/tmp/pip-YWwOui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:14 INFO - Running setup.py (path:/tmp/pip-vlUoIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:14 INFO - Running setup.py (path:/tmp/pip-G_I7b9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:14 INFO - Running setup.py (path:/tmp/pip-SxcZNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:14 INFO - Running setup.py (path:/tmp/pip-FddVL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:14 INFO - Running setup.py (path:/tmp/pip-iDGKWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:14 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:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:14 INFO - Running setup.py (path:/tmp/pip-Us32r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:14 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:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:14 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:22:14 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:22:14 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:22:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:14 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:22:14 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:22:14 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:22:14 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:22:14 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:22:14 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:22:16 INFO - Downloading blessings-1.6.tar.gz 18:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:16 INFO - Installing collected packages: blessings 18:22:16 INFO - Running setup.py install for blessings 18:22:16 INFO - Successfully installed blessings 18:22:16 INFO - Cleaning up... 18:22:16 INFO - Return code: 0 18:22:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:17 INFO - Reading from file tmpfile_stdout 18:22:17 INFO - Current package versions: 18:22:17 INFO - argparse == 1.2.1 18:22:17 INFO - blessings == 1.6 18:22:17 INFO - blobuploader == 1.2.4 18:22:17 INFO - docopt == 0.6.1 18:22:17 INFO - functools32 == 3.2.3-2 18:22:17 INFO - jsonschema == 2.5.1 18:22:17 INFO - manifestparser == 1.1 18:22:17 INFO - mozInstall == 1.12 18:22:17 INFO - mozcrash == 0.17 18:22:17 INFO - mozdebug == 0.1 18:22:17 INFO - mozdevice == 0.48 18:22:17 INFO - mozfile == 1.2 18:22:17 INFO - mozhttpd == 0.7 18:22:17 INFO - mozinfo == 0.9 18:22:17 INFO - mozleak == 0.1 18:22:17 INFO - mozlog == 3.4 18:22:17 INFO - moznetwork == 0.27 18:22:17 INFO - mozprocess == 0.23 18:22:17 INFO - mozprofile == 0.28 18:22:17 INFO - mozrunner == 6.12 18:22:17 INFO - mozscreenshot == 0.1 18:22:17 INFO - mozsystemmonitor == 0.3 18:22:17 INFO - moztest == 0.7 18:22:17 INFO - mozversion == 1.4 18:22:17 INFO - psutil == 3.1.1 18:22:17 INFO - requests == 1.2.3 18:22:17 INFO - wsgiref == 0.1.2 18:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:17 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:17 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:22:17 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:22:17 INFO - Using env: {'DISPLAY': ':0', 18:22:17 INFO - 'HOME': '/home/cltbld', 18:22:17 INFO - 'LANG': 'en_US.UTF-8', 18:22:17 INFO - 'LANGUAGE': 'en_US:en', 18:22:17 INFO - 'LOGNAME': 'cltbld', 18:22:17 INFO - 'MAIL': '/var/mail/cltbld', 18:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:17 INFO - 'MOZ_NO_REMOTE': '1', 18:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:17 INFO - 'NO_EM_RESTART': '1', 18:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:17 INFO - 'PWD': '/builds/slave/test', 18:22:17 INFO - 'SHELL': '/bin/bash', 18:22:17 INFO - 'SHLVL': '1', 18:22:17 INFO - 'TERM': 'linux', 18:22:17 INFO - 'TMOUT': '86400', 18:22:17 INFO - 'USER': 'cltbld', 18:22:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:17 INFO - '_': '/tools/buildbot/bin/python'} 18:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 INFO - Cleaning up... 18:22:17 INFO - Return code: 0 18:22:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22: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:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:17 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 0x7f4467475f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, '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': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', '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:22:17 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:22:17 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:22:17 INFO - Using env: {'DISPLAY': ':0', 18:22:17 INFO - 'HOME': '/home/cltbld', 18:22:17 INFO - 'LANG': 'en_US.UTF-8', 18:22:17 INFO - 'LANGUAGE': 'en_US:en', 18:22:17 INFO - 'LOGNAME': 'cltbld', 18:22:17 INFO - 'MAIL': '/var/mail/cltbld', 18:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:17 INFO - 'MOZ_NO_REMOTE': '1', 18:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:17 INFO - 'NO_EM_RESTART': '1', 18:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:17 INFO - 'PWD': '/builds/slave/test', 18:22:17 INFO - 'SHELL': '/bin/bash', 18:22:17 INFO - 'SHLVL': '1', 18:22:17 INFO - 'TERM': 'linux', 18:22:17 INFO - 'TMOUT': '86400', 18:22:17 INFO - 'USER': 'cltbld', 18:22:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:17 INFO - '_': '/tools/buildbot/bin/python'} 18:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:17 INFO - Cleaning up... 18:22:17 INFO - Return code: 0 18:22:17 INFO - Running post-action listener: _resource_record_post_action 18:22:17 INFO - Running post-action listener: _start_resource_monitoring 18:22:17 INFO - Starting resource monitoring. 18:22:17 INFO - [mozharness: 2016-10-22 01:22:17.543586Z] Finished create-virtualenv step (success) 18:22:17 INFO - [mozharness: 2016-10-22 01:22:17.544092Z] Running install step. 18:22:17 INFO - Running pre-action listener: _resource_record_pre_action 18:22:17 INFO - Running main action method: install 18:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:17 INFO - Reading from file tmpfile_stdout 18:22:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:17 INFO - Reading from file tmpfile_stdout 18:22:17 INFO - Output received: 18:22:17 INFO - Usage: mozinstall [options] installer 18:22:17 INFO - Options: 18:22:17 INFO - -h, --help show this help message and exit 18:22:17 INFO - -d DEST, --destination=DEST 18:22:17 INFO - Directory to install application into. [default: 18:22:17 INFO - "/builds/slave/test"] 18:22:17 INFO - --app=APP Application being installed. [default: firefox] 18:22:17 INFO - mkdir: /builds/slave/test/build/application 18:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:22:34 INFO - Reading from file tmpfile_stdout 18:22:34 INFO - Output received: 18:22:34 INFO - /builds/slave/test/build/application/firefox/firefox 18:22:34 INFO - Running post-action listener: _resource_record_post_action 18:22:34 INFO - [mozharness: 2016-10-22 01:22:34.733032Z] Finished install step (success) 18:22:34 INFO - [mozharness: 2016-10-22 01:22:34.733278Z] Running run-tests step. 18:22:34 INFO - Running pre-action listener: _resource_record_pre_action 18:22:34 INFO - Running main action method: run_tests 18:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:22:34 INFO - Python 2.7.3 18:22:34 INFO - Return code: 0 18:22:34 INFO - grabbing minidump binary from tooltool 18:22: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:22:34 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 0x1ba3930>, '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 0x1ba4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:22:34 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:22:34 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:22:34 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:22:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:22:34 INFO - Return code: 0 18:22:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:22:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:22:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:22:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:22:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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:22:34 INFO - Using env: {'DISPLAY': ':0', 18:22:34 INFO - 'HOME': '/home/cltbld', 18:22:34 INFO - 'LANG': 'en_US.UTF-8', 18:22:34 INFO - 'LANGUAGE': 'en_US:en', 18:22:34 INFO - 'LOGNAME': 'cltbld', 18:22:34 INFO - 'MAIL': '/var/mail/cltbld', 18:22:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:22:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:34 INFO - 'MOZ_NO_REMOTE': '1', 18:22:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:34 INFO - 'NO_EM_RESTART': '1', 18:22:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:34 INFO - 'PWD': '/builds/slave/test', 18:22:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:22:34 INFO - 'SHELL': '/bin/bash', 18:22:34 INFO - 'SHLVL': '1', 18:22:34 INFO - 'TERM': 'linux', 18:22:34 INFO - 'TMOUT': '86400', 18:22:34 INFO - 'USER': 'cltbld', 18:22:34 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073', 18:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:34 INFO - '_': '/tools/buildbot/bin/python'} 18:22:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:22:35 INFO - mozversion application_buildid: 20161021163002 18:22:35 INFO - mozversion application_changeset: 5639a9f476d08f300c079117e61697f5026b6367 18:22:35 INFO - mozversion application_display_name: Nightly 18:22:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:22:35 INFO - mozversion application_name: Firefox 18:22:35 INFO - mozversion application_remotingname: firefox 18:22:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:22:35 INFO - mozversion application_vendor: Mozilla 18:22:35 INFO - mozversion application_version: 52.0a1 18:22:35 INFO - mozversion platform_buildid: 20161021163002 18:22:35 INFO - mozversion platform_changeset: 5639a9f476d08f300c079117e61697f5026b6367 18:22:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:22:35 INFO - mozversion platform_version: 52.0a1 18:22:35 INFO - using testdate: 1477099355 18:22:35 INFO - actual date: 1477099355 18:22:35 INFO - starting webserver on 'localhost:48857' 18:22:35 INFO - SUITE-START | Running 2 tests 18:22:35 INFO - TEST-START | dromaeo_css 18:22:35 INFO - operating with platform_type : linux_ 18:22:35 INFO - Initialising browser for dromaeo_css test... 18:22:35 INFO - TEST-INFO | started process 4552 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnZ7O9x/profile http://localhost:48857/getInfo.html) 18:22:38 INFO - PROCESS | 4552 | __metrics Screen width/height:1600/1200 18:22:38 INFO - PROCESS | 4552 | colorDepth:24 18:22:38 INFO - PROCESS | 4552 | Browser inner width/height: 1024/697 18:22:38 INFO - PROCESS | 4552 | __metrics 18:22:39 INFO - PROCESS | 4552 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:22:39 INFO - PROCESS | 4552 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:22:40 INFO - PROCESS | 4552 | console.error: PushService: 18:22:40 INFO - PROCESS | 4552 | stateChangeProcessEnqueue: Error transitioning state 18:22:40 INFO - PROCESS | 4552 | UnknownError 18:22:41 INFO - TEST-INFO | 4552: exit 0 18:22:41 INFO - Browser initialized. 18:22:41 INFO - Running cycle 1/1 for dromaeo_css test... 18:22:41 INFO - TEST-INFO | started process 4636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnZ7O9x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:53 INFO - PROCESS | 4636 | RSS: Main: 171581440 18:22:53 INFO - PROCESS | 4636 | 18:23:44 INFO - PROCESS | 4636 | 1477099424850 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:25:54 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48857/tests/dromaeo/cssquery-ext.html) 18:25:57 INFO - PROCESS | 4636 | RSS: Main: 400961536 18:25:57 INFO - PROCESS | 4636 | 18:28:59 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-ext.html (next: http://localhost:48857/tests/dromaeo/cssquery-jquery.html) 18:29:00 INFO - PROCESS | 4636 | RSS: Main: 281227264 18:29:00 INFO - PROCESS | 4636 | 18:32:02 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48857/tests/dromaeo/cssquery-mootools.html) 18:32:03 INFO - PROCESS | 4636 | RSS: Main: 245932032 18:32:03 INFO - PROCESS | 4636 | 18:35:05 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48857/tests/dromaeo/cssquery-prototype.html) 18:35:05 INFO - PROCESS | 4636 | RSS: Main: 232009728 18:35:05 INFO - PROCESS | 4636 | 18:38:08 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48857/tests/dromaeo/cssquery-yui.html) 18:38:09 INFO - PROCESS | 4636 | RSS: Main: 218505216 18:38:09 INFO - PROCESS | 4636 | 18:41:11 INFO - PROCESS | 4636 | Cycle 1(1): loaded http://localhost:48857/tests/dromaeo/cssquery-yui.html (next: http://localhost:48857/tests/dromaeo/cssquery-dojo.html) 18:41:12 INFO - PROCESS | 4636 | RSS: Main: 308973568 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | __start_tp_report 18:41:12 INFO - PROCESS | 4636 | _x_x_mozilla_page_load 18:41:12 INFO - PROCESS | 4636 | _x_x_mozilla_page_load_details 18:41:12 INFO - PROCESS | 4636 | |i|pagename|runs| 18:41:12 INFO - PROCESS | 4636 | |0;dojo.html;3010;3061.5101583746605;3075.492543730284;3108.2074071111865;3111.2688518198233;5095.745212739367;5158.509941555556;5191.896162076761;5269.8155564555245;5308.517054545633;5208.427073021964;5233.162693968966;5284.550813180885;5311.044012077825;5388.7305634718205;4776.737279449629;4813.326134341195;4836.153673107192;4845.903081938359;4858.805647774085;4753.595944344735;4863.659543831933;4887.608991280689;4894.5105489451125;4894.608431325503;4840.975795121037;4891.1195984722735;4897.608191344684;4898.06936682031;4963.230699241623;4703.129920964599;4725.243960966229;4727.739974301412;4781.0676918001;4879.975600122012;4773;4796.52034796521;4805;4858.028394321114;4867.36724225852;5249.816256431045;5253.264542963988;5284.6564973277;5290.894182116336;5355.678659280456;5458.235846981426;5510.91733623996;5641.12562552805;5643.887122257573;5651.2370829937845;5646.350669672999;5692.573057020699;5750.137479378085;5758.42415758425;5760.308762948473;5303.151495760737;5304.92042619361;5304.9734751326;5306.124489459235;5313.043652142652;5599.468050535193;5773.711314434346;5774.2782152230975;5784.21913041734;5849;5440.646357986719;5477.4248703886315;5523.061079616395;5531.640443371168;5534.335879694462;5530.695811730394;5569.944300557024;5586.748596313176;5595.328560572757;5689.260396148474;5603.7478313475995;5614.101743721067;5619.381867994517;5655.095184770417;5687.175359572839;5537.944620553823;5586.826766379025;5600.85997850057;5610.214569960209;5618.887622247614;4028.9194216115516;4048.8785336439955;4049.1901619675596;4071.491063617048;4124.566920473367;3686.3733165361955;3706.5181526401393;3722.3671975764187;3745.157339598623;3751.2122454284904;5297.417284098746;5311.7344132793205;5311.973440132774;5314.62797604164;5377.3816011159215;5350.571954243651;5461.590380721462;5463.153211252262;5588.469095435927;5594.888102238014;5262.71055091966;5306.575473962073;5328.254044433782;5407.45925407464;5407.621466497386;4670.136024835417;4713.95286047142;4717.268823332389;4727.598154156866;4775.832845850448;5752.309722833287;5760.8271751847515;5768.480836724799;5768.826935191951;5772.451617096369;5393.433689462707;5423.159410291411;5423.484768946943;5444.047291723853;5586.413426590149;5636.351819540805;5639.943600563942;5667.4615911488345;5713.628614140234;5805.332386775575;5469.535089517276;5477.260569823023;5481.040817856939;5543.695096769717;5547.41752116022;5530.446955304437;5530.7511161997;5576.944230557805;5577.44225577439;5597.832065038056;5333.066713325075;5403.459654034722;5405.108157154183;5487.310780244122;5552.3892371839875;5425.674459532441;5431.8098866539485;5435.10320797064;5437.891242175056;5439.564834813126;5346.572274217975;5349.518543331119;5351.545118664957;5353.009693206769;5380.596455265792;5427.918581221206;5451.945480545302;5469.2616496772425;5474.315710536183;5495.587830912617;5378.112611419072;5423.620346575702;5477.808276710296;5483.5064844162625;5488.9725551371985 18:41:12 INFO - PROCESS | 4636 | |1;ext.html;603.6197195766667;627.0594108836747;630.8422894276431;633.3381616211059;655.1515787055765;31764.20589485261;43104;43560.12879742407;43937.56062439374;47287.763561182146;44177.116457670825;44301.8924526887;44640.3303950441;44722.77638611802;44770.10459790802;8809.823803523941;8950.149735775123;8952.239059679903;8952.955235223848;8962.955185224066;8021.799455013613;8202.302804261644;8805.471671699686;10362.171026317914;10643.733906652318;10860.565577376896;10912.50893709785;10923.563057477691;10927.344359338465;10932.617358392457;10735.51690173944;10780.676579702622;10802.51388687511;10806.945965270203;10811.243212975016;10495.212859035526;10551.261411701184;10553.47232638365;10560.2079844012;10561.577536898514;42047.57952420498;42226.36660450096;42321.788391057846;42341.788291058656;42380.57619423797;3523.2953409317984;3559.9110022249392;3617.8733744318824;3742.4947632069866;4009.83960641574;30427;31430.528542071886;31481.685183148104;31558.36883262322;31675;4136.648384887289;4376.956230437687;4391.9780401098105;4551.044280701057;4573.382593349888;325160;325566;327608.94452415325;327834;329481.9999999976;32765.344693106006;34166.658333416344;34175.65824341775;56538.43461565414;56949.715251424306;35129.29741405157;35340.46989295162;35460.46809297863;35473;35504.11239719028;14810.555683329518;14900.552983410515;14970.775438368215;14979.101253924799;14987.400503979938;11505.654830355103;12626.179298345578;12732.80900786489;12734.363281835871;12742.80885786714;7699.422543309274;7949.3243074339325;8167.224113709188;8180.386471014698;8293.377996650155;3063.6170478690165;3065.509518477033;3091.30445649727;3104.98447507761;3135.576697145856;892.2237653241722;922.801597656507;933.4021068502396;984.542187882638;1048.9982067125877;27506.76219570124;28827;30453.543196852064;30630.234244144074;30939.69060309413;1038.2213339995003;1041.0838462153408;1043.3202543904308;1071.6624263357019;1097.6761855252719;15550.533483995498;16196.595085122966;16214;16406.425775097814;16619.66760664804;512738;512918;516093;517058;517406;56880.71559642175;57358.13962790561;57390.7130464345;57439.42560574424;57461.712691436274;1213.660175150959;1234.6357824441811;1263.2420547671325;1334.0594880609146;1344.9058565900293;28988.855055724587;31367.04026235008;31429.52855707212;31529.527057093703;32101.67898320987;48509.51490485191;49419.50580494149;49421.752891235315;49461.50538494569;49491.75254123707;324760;327634;328101;330977.3804523944;331012;27059.458810824068;27575;27838.16485505438;27999.58000630033;29635.259118522208;45766;45909;46539.76730116463;47010;47282.76358618185;39387.21225575415;39407;39609.40585891157;41000;41501.792491037355;39483.21033579255;39921;40088;40919.38620920629;41171.38242926419;38997.41503877387;39017;39442.802785985885;40283.39574906437;40389;33311.83344083264;35345.823270883484;35696.28607427785;35735.533058367706;36102.8194859024;24405.51188976175;24592.508149836543;24890.50218995646;25330.733463327128;25386.492270154864 18:41:12 INFO - PROCESS | 4636 | |2;jquery.html;349.51766562144235;367.75331625788596;381.15193694030785;384.7422227107838;403.8970062634028;14508.492202772904;14832.555023349303;15921.601959951007;18313.7252941206;19526.121324540396;17866.910665446656;18600.16299266533;18933.905330473328;19042.714359284622;19224.61550768984;5352.545033672132;5601.523870470995;5608.383077861432;5609.439056094378;5610.69141197234;4553.385292985437;5503.944960550383;6148.323684394713;6197;6274.623522588637;5930.673812940287;6212.720427580747;6214.471769899564;6216.689165541728;6219.40915613016;6041.637501749887;6123.969380153093;6133.325334213256;6153.261608606951;6163.8150855474405;4923.335349727777;5175.094358487249;5448.45515448456;5449.264349312832;5457.536109430703;13134.34328283582;13339.799903001365;13358.532451364155;13444.932775336061;13487.66280842977;2117.110813458351;2289.278877153691;2596.234110937278;2773.486904922596;2777.180731684158;9526;9661.227101831837;9899.554520046615;10212.948935255276;10228.028337307944;1763.3387479695114;1860.83252507275;1883.2467013194694;1892.0539730134933;1937.8159074887863;80132;81637.59181204115;82207.58896205481;82853.58573207095;83212;10317.27779055474;10530.262881598368;10601.787964240671;10628.25602207838;10659.147268218523;14620.853791462161;14630.561083167522;14637.92681036588;14644.633884152981;14662.193579353025;7669.693212271558;7835.138134805167;7904.881426778603;7991.520508769492;8079.676812927417;7445.329920307198;7580.128285247157;7720.459567830198;7722.304992550585;7731.072271327476;4401.22978478765;4456.8133784344345;4463.062756821104;4621.376114224604;4632.328312394751;1776.0108678280408;1802.8287312705272;1805.4673871208024;1813.165943665902;1814.637072585488;901.5672477210976;914.977125571866;919.0763282900642;935.2331088507494;980.8185485685171;17312;18305.359312424;18605.534861628566;18736.437906862815;19055.714164287547;905.1898550797;919.5494207838245;954.651552183462;961.1637875048752;996.725900377402;9950.164203202052;10027.448490333101;10449.111825494767;10455.84316235257;10463.162946964225;462021;466209;472103;482041;483751;84350;85113;85611.14388856156;89223.55388223018;89494.10505894858;984.1192133040888;996.9651062212932;1021.2493817044615;1041.8645576075214;1046.1317106801337;14860.777088343468;15560.92219538895;16318.020918744913;16505.752413714043;16660.66678666396;41247.793761031004;41295.7935210334;41741.1651766969;43639.12721745611;43951.78024109859;57383.713081434325;58332.70833645805;58407.415925841895;58885.41114588971;59370.40629593821;8055.597220138852;8261.83476330458;8278.130796266305;8405.663773448994;8497.660093596187;9448.149666530046;11256.268342557707;13001.284929328978;13080.28058456794;13326.533571324957;10969.945150274198;11116.499757511094;11246.381449020382;11266.718332041764;11908.702282442662;11014.724631883946;11063.170262230526;11389.601363952554;11394.430278486208;11459.541618335174;10948.233623646269;11144.55421783152;11215.551377945118;11695.649130526097;12171;7950.840983180419;8182.099969003286;8305;8461.788455288666;8524.616392262487;8225.629846656795;10643.201759867887;11519.424028798694;11564.248323858923;11611 18:41:12 INFO - PROCESS | 4636 | |3;mootools.html;1286.6526037969743;1289.8516670582885;1340.4772138865837;1405.8734713875751;1426.7931149983253;7296.525725827817;8199.262066414021;8486.575671216446;8498.745037648872;8654.653813847453;8475.194856488639;8524.701635442761;8548.358873084513;8554.101819308977;8556.486610803357;2271.659251112334;2299.1378233162563;2299.172297972737;2300.309907027885;2306.423394151462;2207.4591725027312;2253.0311965854676;2305.700258966332;2305.9192928247517;2308.73449553301;2212.070930209308;2228.92198773043;2236.5750507403623;2280.726312842453;2284.1091974130104;2192.408049826554;2210.491586935006;2210.6794514795347;2214.446388402899;2215.2025270902463;2165.3287480880977;2166.4150679316494;2167.349795061491;2173.7826217378292;2177.1182671018105;8292.460990035628;8373.16268373164;8421.957890210571;8460.492370457792;8506.106858779802;5106.208537676666;5303.655262407931;5316.9734151329385;5322.893542129135;5325.8934821303355;3133.5041614524343;3259.26666500038;3271;3274.887697435141;3286.09632351103;993.7018894331671;1000.5847573257163;1004.322082594246;1015.9339642923186;1034.4310629153936;11507.07943364529;11559.595414160549;11599.478023488879;11600;11635.883641163564;4252.659787217015;4340.761258130833;4368.449200533828;4493.6854420190275;4521.208788462006;9253.213476854407;9257.583408746623;9262.305327100494;9263.53682315895;9267.165955064076;5073.264376665363;5086.0845047891735;5088.491150884882;5090;5124.180131178993;3992.6007399260425;4122.072533679898;4614.630829533697;4716.080364328992;4768.070226305837;3605.7656252343513;3611.349957007764;3720.6465385788306;3734.3651579231596;3763.9059023524082;1049.0663309654415;1049.459528342904;1055.6410820321128;1059.7668512927144;1069.8662667166604;775.5656832173888;879.5690111845278;896.0412358776047;897.533282692996;924.3899026642383;5423.891522169613;5453.127499600124;5614.578906582023;5684.744186511617;5762.798302059407;404.423696232867;405.85794971760237;418.33693595650624;421.496311907274;422.5415424264707;3475.4786781982907;3567.94648080279;3639.836207370675;3728.422094575345;3728.8694895678523;9780.853287200698;10307.536160872778;10363.11913487347;10364.274500784873;10371.222158338154;10028.348157369894;10193.94903025495;10254;10257.897421025695;10258.17934565233;7735.381169506426;7766.37868970481;7825.491343062683;7841.059072911287;7848.372130229568;5415.323084614423;5466.34403871538;5501.449855014626;5505.532029777513;5641.12562552805;9478.905210947802;9563.904360956301;9602.471864047266;9630.422174669542;9723.9027609723;9500.287478439006;9846.409215447096;9932.404055756677;9942.403455792675;9948.850767238353;6770.830729231809;6970.268121847336;7309.342159205697;7509.51188172767;7646.885296720442;7965.1238363778775;8308.169183081643;8314;8324.334053275845;8366.288865446506;7261.709531618887;7392.630368481662;7408.185099639143;7456.366208872089;7527.774166775005;7172.748953786592;7179.425645948416;7291.927080729125;7328.926710733038;7544.207858174813;6660.467162627075;6662.167229096363;6665.100211471389;6730.192376914802;6732.865342693217;4690.859274221779;4761.9047619048115;4780.521947805191;5004.42449118356;5053.7220452875445;4827;4978.626603004862;4984.2025275955675;4999.200127979506;5161.406438259648 18:41:12 INFO - PROCESS | 4636 | |4;prototype.html;319.1705530472363;324.7888872233048;327.99796621321866;332.16460601587005;337.3522836154583;5303.575713942885;5308.548773354265;5340.118880384737;5349.22436246744;5475.972620136904;5072;5086.439492355347;5159.793608255674;5190.039842629116;5358.6516876403;4127.236461254677;4137.834486620532;4161.563035881234;4169.708120431571;4175.248455278049;4172.4965020987465;4173.08192197716;4180.874573762792;4181.728187667807;4194.706370554062;3894.9610503894883;3951;4123.484564429446;4131.294002428399;4134.131832315217;4146.3158578834755;4153.314703073989;4154.439150714645;4164.6251837334785;4168.6039826216465;3684.613115622875;3693.4275187346007;3700.3154416433044;3700.40793473043;3704.148045949447;5073.746312684388;5076.517730815566;5102.285680004802;5244.632875698702;5254.8686282842855;4672.182368085605;4674.602658774008;4700.224462963607;4718.457377401608;4718.693284936468;5497.422770609069;5522.641028333145;5522.751476183541;5576.275084239023;5587.273654424899;3899.5710471848074;3945.5857135000697;3967.7817720025387;4013.779242141681;4034.760707807166;5630.774769009276;5641.830745077655;5642.6614403135945;5655.3779084300695;5661.320641523002;4006.3790112532606;4023.436718859362;4041.2119636671155;4043.6158564936286;4048.2105989332385;4023.879283621496;4069.7354671946823;4122.670186385082;4138.8344466221015;4151.771652559139;4671.32265821454;4678.660669665167;4710.222813235778;4721.6458765592715;4734.7632618369635;5493.7802487900035;5550.195221692832;5551.777928882881;5587.273654424899;5597.692126932976;1391.7981892625714;1401.1312985948778;1419.3683810704345;1424.8432672406027;1438.3958737330347;892.161368313783;913.5614904845711;923.6028507741736;926.9165775080276;930.3719989007393;4691.319758635047;4700.271457924027;4735.881602959885;4745.738984355825;4761.285807128958;4554.4990051094355;4926.630502712311;4939.283803848493;4969.403301945686;4997.650164488451;4658.510856360102;4687.343771871941;4702.260163739438;4702.764861756898;4721.881952951135;2605.817592768488;2618.279973007438;2643.1409791817737;2669.9466010679675;2680.38351179226;4839.467658557556;5034.320366750442;5074.985002999415;5116.641835071509;5122.718250496184;5112.762429835595;5116.641835071509;5122.820701275437;5144.768485418165;5150.4849515048945;5072.442031376546;5075.487633621381;5077.441481437039;5126.9746050789245;5138.409082955433;4960.900781984268;4975.437649610526;5296.629235953446;5661.631993920382;5709;5575.693336866511;5593.188987596857;5613.101903695389;5665.773369065356;5665.886682266414;5649.774009039593;5822.796202132905;5872.559558033079;5915.190284614946;5935.376785437467;4023.3361495352938;4041.2218623805434;4052.675785937177;4061.1268577256033;4070.531888832822;5417.214503896834;5530.253415788817;5605.747741351567;5695.917775622618;5714.228579141929;5582.860428489158;5591.720413979366;5600;5604.915926261191;5622.128570071645;5327;5532.446755324436;5544.667319960815;5555.694436806015;5578;5453.400125986059;5517.779288828402;5519.696416696959;5520.227168196376;5529.806456773994;5603.97385411873;5620.241267428844;5640.943590564042;5656.151577263443;5709.800156994485;5197.636165468381;5363.19552067193;5422.403535611158;5530.446955304598;5561.4716601922755 18:41:12 INFO - PROCESS | 4636 | |5;yui.html;689.3279052923397;740.7111625771156;755.5051441305944;756.4251169111475;779.9103103143136;15955.36178552858;17067.231974561142;17103.657926841457;17108.74336884946;17394.391196308115;15121.84878151218;15161.166135862522;15258.465953691622;15284.312205950731;15289.770653440208;656.3305428462965;663.2538394306405;781.4061313401827;790.893229414029;808.454293351988;784.6429874407131;784.654751909161;792.9207079292082;793.0364607002491;793.833295008049;854.4531499840107;854.5769843927264;855.9015713193;856.1609622569886;856.4732689396039;697.4420463629127;710.1159056974049;854.7563944275882;855.5978690015713;856.1524091149779;608.3551435478363;618.2519151826295;622.632646738422;625.8654389306275;627.5168120547153;14400.351984160738;14467.855321446861;14502.419903203965;14524;14627.78058329126;13538.526151584647;13871.861281387159;14196.361163747657;14201.289935503184;14820.184889831055;12833.935830320883;12878.227306361649;12913.225206487641;13000.414981325768;13059.460533505595;4367.2575662137515;4377.5403582623685;4441.888952776174;4456.398386217884;4471.3293006049025;76084.61957690176;77941.83087253752;78188;79414;79594.6020269895;10729.248952573245;10783.83824242637;11059.391733454737;11168.822079694382;11558.248713833742;20787.272445464336;20965;20975.056122474525;20993;20994.47513812167;7791.376689864797;8005.079415867133;8170.632321545426;8186.877196842052;8466.74599762008;4601.9956261613015;4607.101615185007;4611.5619016193405;4695.178343789891;4712.31671407644;3296.159479332755;3421.555197824267;3430.3653824042135;3432.1248081738995;3435.2270739083506;569.2002736155708;582.3099626942087;583.7168973048068;583.8540364908773;585.5988647776287;554.7808615596816;564.4496615799644;613.355976224962;617.7528988404584;621.8911690454153;8380.371472139614;8939.865902011476;9130.908690913138;9168.312376571785;9183.65321636263;526.8498477933771;600.2227115884939;605.2736715940889;605.3130835797656;643.8454770854961;4322.286822674224;4373.825046998148;4399.912001759947;4688.367070445514;4689.577937985529;116121.41939290249;117245;119282;119780.40109799395;121495;94428.52785736027;98749.50625246827;99401;99836;104865.47567262268;87841;88138.11861881427;89157;89189.55405222933;94755.52622236844;8533;8651.48091114535;8671.089535598923;8705.695300664447;8721.956390218262;50201.74899125481;50842.74578627083;52336;52346.476535234164;52516.73741631267;67357.3264267351;68998.3100168992;73521.26478735358;74783.62608186924;77526;10799;10894.564217431214;10950.835737463785;10989.13184758609;11655.650330490098;12099.455524501242;12233.69415764613;12252.264864108181;12332.19840710351;12767.233965962072;10979.61571345031;11534.82697759551;12009.219400738924;12247.510099596273;12275.378763265036;11943.402829858645;12026.639200823989;12086.274823510617;12301;12559.763524907443;11078.501467434146;11095.167862410513;11471.770564588494;11577.710557236136;11832.34922079294;8831.337649676172;8883.911160888309;9043.457392556467;9083.772905677411;9201.527755559078;8300.2114799094;9009.189172974464;9771;9930.354526955725;9943.30396872212 18:41:12 INFO - PROCESS | 4636 | __end_tp_report 18:41:12 INFO - PROCESS | 4636 | __start_cc_report 18:41:12 INFO - PROCESS | 4636 | _x_x_mozilla_cycle_collect,2331 18:41:12 INFO - PROCESS | 4636 | __end_cc_report 18:41:12 INFO - PROCESS | 4636 | __startTimestamp1477100472244__endTimestamp 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | ------- Summary: start ------- 18:41:12 INFO - PROCESS | 4636 | Number of tests: 6 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#0] dojo.html Cycles:165 Average:5196.60 Median:5403.46 stddev:593.79 (11.0%) stddev-sans-first:570.30 18:41:12 INFO - PROCESS | 4636 | Values: 3010.0 3061.5 3075.5 3108.2 3111.3 5095.7 5158.5 5191.9 5269.8 5308.5 5208.4 5233.2 5284.6 5311.0 5388.7 4776.7 4813.3 4836.2 4845.9 4858.8 4753.6 4863.7 4887.6 4894.5 4894.6 4841.0 4891.1 4897.6 4898.1 4963.2 4703.1 4725.2 4727.7 4781.1 4880.0 4773.0 4796.5 4805.0 4858.0 4867.4 5249.8 5253.3 5284.7 5290.9 5355.7 5458.2 5510.9 5641.1 5643.9 5651.2 5646.4 5692.6 5750.1 5758.4 5760.3 5303.2 5304.9 5305.0 5306.1 5313.0 5599.5 5773.7 5774.3 5784.2 5849.0 5440.6 5477.4 5523.1 5531.6 5534.3 5530.7 5569.9 5586.7 5595.3 5689.3 5603.7 5614.1 5619.4 5655.1 5687.2 5537.9 5586.8 5600.9 5610.2 5618.9 4028.9 4048.9 4049.2 4071.5 4124.6 3686.4 3706.5 3722.4 3745.2 3751.2 5297.4 5311.7 5312.0 5314.6 5377.4 5350.6 5461.6 5463.2 5588.5 5594.9 5262.7 5306.6 5328.3 5407.5 5407.6 4670.1 4714.0 4717.3 4727.6 4775.8 5752.3 5760.8 5768.5 5768.8 5772.5 5393.4 5423.2 5423.5 5444.0 5586.4 5636.4 5639.9 5667.5 5713.6 5805.3 5469.5 5477.3 5481.0 5543.7 5547.4 5530.4 5530.8 5576.9 5577.4 5597.8 5333.1 5403.5 5405.1 5487.3 5552.4 5425.7 5431.8 5435.1 5437.9 5439.6 5346.6 5349.5 5351.5 5353.0 5380.6 5427.9 5451.9 5469.3 5474.3 5495.6 5378.1 5423.6 5477.8 5483.5 5489.0 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#1] ext.html Cycles:180 Average:54124.18 Median:35343.15 stddev:106119.83 (300.3%) stddev-sans-first:106341.44 18:41:12 INFO - PROCESS | 4636 | Values: 603.6 627.1 630.8 633.3 655.2 31764.2 43104.0 43560.1 43937.6 47287.8 44177.1 44301.9 44640.3 44722.8 44770.1 8809.8 8950.1 8952.2 8953.0 8963.0 8021.8 8202.3 8805.5 10362.2 10643.7 10860.6 10912.5 10923.6 10927.3 10932.6 10735.5 10780.7 10802.5 10806.9 10811.2 10495.2 10551.3 10553.5 10560.2 10561.6 42047.6 42226.4 42321.8 42341.8 42380.6 3523.3 3559.9 3617.9 3742.5 4009.8 30427.0 31430.5 31481.7 31558.4 31675.0 4136.6 4377.0 4392.0 4551.0 4573.4 325160.0 325566.0 327608.9 327834.0 329482.0 32765.3 34166.7 34175.7 56538.4 56949.7 35129.3 35340.5 35460.5 35473.0 35504.1 14810.6 14900.6 14970.8 14979.1 14987.4 11505.7 12626.2 12732.8 12734.4 12742.8 7699.4 7949.3 8167.2 8180.4 8293.4 3063.6 3065.5 3091.3 3105.0 3135.6 892.2 922.8 933.4 984.5 1049.0 27506.8 28827.0 30453.5 30630.2 30939.7 1038.2 1041.1 1043.3 1071.7 1097.7 15550.5 16196.6 16214.0 16406.4 16619.7 512738.0 512918.0 516093.0 517058.0 517406.0 56880.7 57358.1 57390.7 57439.4 57461.7 1213.7 1234.6 1263.2 1334.1 1344.9 28988.9 31367.0 31429.5 31529.5 32101.7 48509.5 49419.5 49421.8 49461.5 49491.8 324760.0 327634.0 328101.0 330977.4 331012.0 27059.5 27575.0 27838.2 27999.6 29635.3 45766.0 45909.0 46539.8 47010.0 47282.8 39387.2 39407.0 39609.4 41000.0 41501.8 39483.2 39921.0 40088.0 40919.4 41171.4 38997.4 39017.0 39442.8 40283.4 40389.0 33311.8 35345.8 35696.3 35735.5 36102.8 24405.5 24592.5 24890.5 25330.7 25386.5 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#2] jquery.html Cycles:180 Average:27860.72 Median:43795.45 stddev:78140.26 (178.4%) stddev-sans-first:78332.16 18:41:12 INFO - PROCESS | 4636 | Values: 349.5 367.8 381.2 384.7 403.9 14508.5 14832.6 15921.6 18313.7 19526.1 17866.9 18600.2 18933.9 19042.7 19224.6 5352.5 5601.5 5608.4 5609.4 5610.7 4553.4 5503.9 6148.3 6197.0 6274.6 5930.7 6212.7 6214.5 6216.7 6219.4 6041.6 6124.0 6133.3 6153.3 6163.8 4923.3 5175.1 5448.5 5449.3 5457.5 13134.3 13339.8 13358.5 13444.9 13487.7 2117.1 2289.3 2596.2 2773.5 2777.2 9526.0 9661.2 9899.6 10212.9 10228.0 1763.3 1860.8 1883.2 1892.1 1937.8 80132.0 81637.6 82207.6 82853.6 83212.0 10317.3 10530.3 10601.8 10628.3 10659.1 14620.9 14630.6 14637.9 14644.6 14662.2 7669.7 7835.1 7904.9 7991.5 8079.7 7445.3 7580.1 7720.5 7722.3 7731.1 4401.2 4456.8 4463.1 4621.4 4632.3 1776.0 1802.8 1805.5 1813.2 1814.6 901.6 915.0 919.1 935.2 980.8 17312.0 18305.4 18605.5 18736.4 19055.7 905.2 919.5 954.7 961.2 996.7 9950.2 10027.4 10449.1 10455.8 10463.2 462021.0 466209.0 472103.0 482041.0 483751.0 84350.0 85113.0 85611.1 89223.6 89494.1 984.1 997.0 1021.2 1041.9 1046.1 14860.8 15560.9 16318.0 16505.8 16660.7 41247.8 41295.8 41741.2 43639.1 43951.8 57383.7 58332.7 58407.4 58885.4 59370.4 8055.6 8261.8 8278.1 8405.7 8497.7 9448.1 11256.3 13001.3 13080.3 13326.5 10969.9 11116.5 11246.4 11266.7 11908.7 11014.7 11063.2 11389.6 11394.4 11459.5 10948.2 11144.6 11215.6 11695.6 12171.0 7950.8 8182.1 8305.0 8461.8 8524.6 8225.6 10643.2 11519.4 11564.2 11611.0 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#3] mootools.html Cycles:180 Average:5477.67 Median:5029.07 stddev:3144.15 (62.5%) stddev-sans-first:3137.20 18:41:12 INFO - PROCESS | 4636 | Values: 1286.7 1289.9 1340.5 1405.9 1426.8 7296.5 8199.3 8486.6 8498.7 8654.7 8475.2 8524.7 8548.4 8554.1 8556.5 2271.7 2299.1 2299.2 2300.3 2306.4 2207.5 2253.0 2305.7 2305.9 2308.7 2212.1 2228.9 2236.6 2280.7 2284.1 2192.4 2210.5 2210.7 2214.4 2215.2 2165.3 2166.4 2167.3 2173.8 2177.1 8292.5 8373.2 8422.0 8460.5 8506.1 5106.2 5303.7 5317.0 5322.9 5325.9 3133.5 3259.3 3271.0 3274.9 3286.1 993.7 1000.6 1004.3 1015.9 1034.4 11507.1 11559.6 11599.5 11600.0 11635.9 4252.7 4340.8 4368.4 4493.7 4521.2 9253.2 9257.6 9262.3 9263.5 9267.2 5073.3 5086.1 5088.5 5090.0 5124.2 3992.6 4122.1 4614.6 4716.1 4768.1 3605.8 3611.3 3720.6 3734.4 3763.9 1049.1 1049.5 1055.6 1059.8 1069.9 775.6 879.6 896.0 897.5 924.4 5423.9 5453.1 5614.6 5684.7 5762.8 404.4 405.9 418.3 421.5 422.5 3475.5 3567.9 3639.8 3728.4 3728.9 9780.9 10307.5 10363.1 10364.3 10371.2 10028.3 10193.9 10254.0 10257.9 10258.2 7735.4 7766.4 7825.5 7841.1 7848.4 5415.3 5466.3 5501.4 5505.5 5641.1 9478.9 9563.9 9602.5 9630.4 9723.9 9500.3 9846.4 9932.4 9942.4 9948.9 6770.8 6970.3 7309.3 7509.5 7646.9 7965.1 8308.2 8314.0 8324.3 8366.3 7261.7 7392.6 7408.2 7456.4 7527.8 7172.7 7179.4 7291.9 7328.9 7544.2 6660.5 6662.2 6665.1 6730.2 6732.9 4690.9 4761.9 4780.5 5004.4 5053.7 4827.0 4978.6 4984.2 4999.2 5161.4 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#4] prototype.html Cycles:180 Average:4531.56 Median:5073.09 stddev:1318.57 (26.0%) stddev-sans-first:1283.80 18:41:12 INFO - PROCESS | 4636 | Values: 319.2 324.8 328.0 332.2 337.4 5303.6 5308.5 5340.1 5349.2 5476.0 5072.0 5086.4 5159.8 5190.0 5358.7 4127.2 4137.8 4161.6 4169.7 4175.2 4172.5 4173.1 4180.9 4181.7 4194.7 3895.0 3951.0 4123.5 4131.3 4134.1 4146.3 4153.3 4154.4 4164.6 4168.6 3684.6 3693.4 3700.3 3700.4 3704.1 5073.7 5076.5 5102.3 5244.6 5254.9 4672.2 4674.6 4700.2 4718.5 4718.7 5497.4 5522.6 5522.8 5576.3 5587.3 3899.6 3945.6 3967.8 4013.8 4034.8 5630.8 5641.8 5642.7 5655.4 5661.3 4006.4 4023.4 4041.2 4043.6 4048.2 4023.9 4069.7 4122.7 4138.8 4151.8 4671.3 4678.7 4710.2 4721.6 4734.8 5493.8 5550.2 5551.8 5587.3 5597.7 1391.8 1401.1 1419.4 1424.8 1438.4 892.2 913.6 923.6 926.9 930.4 4691.3 4700.3 4735.9 4745.7 4761.3 4554.5 4926.6 4939.3 4969.4 4997.7 4658.5 4687.3 4702.3 4702.8 4721.9 2605.8 2618.3 2643.1 2669.9 2680.4 4839.5 5034.3 5075.0 5116.6 5122.7 5112.8 5116.6 5122.8 5144.8 5150.5 5072.4 5075.5 5077.4 5127.0 5138.4 4960.9 4975.4 5296.6 5661.6 5709.0 5575.7 5593.2 5613.1 5665.8 5665.9 5649.8 5822.8 5872.6 5915.2 5935.4 4023.3 4041.2 4052.7 4061.1 4070.5 5417.2 5530.3 5605.7 5695.9 5714.2 5582.9 5591.7 5600.0 5604.9 5622.1 5327.0 5532.4 5544.7 5555.7 5578.0 5453.4 5517.8 5519.7 5520.2 5529.8 5604.0 5620.2 5640.9 5656.2 5709.8 5197.6 5363.2 5422.4 5530.4 5561.5 18:41:12 INFO - PROCESS | 4636 | 18:41:12 INFO - PROCESS | 4636 | [#5] yui.html Cycles:180 Average:20620.27 Median:26521.79 stddev:30660.57 (115.6%) stddev-sans-first:30710.06 18:41:12 INFO - PROCESS | 4636 | Values: 689.3 740.7 755.5 756.4 779.9 15955.4 17067.2 17103.7 17108.7 17394.4 15121.8 15161.2 15258.5 15284.3 15289.8 656.3 663.3 781.4 790.9 808.5 784.6 784.7 792.9 793.0 793.8 854.5 854.6 855.9 856.2 856.5 697.4 710.1 854.8 855.6 856.2 608.4 618.3 622.6 625.9 627.5 14400.4 14467.9 14502.4 14524.0 14627.8 13538.5 13871.9 14196.4 14201.3 14820.2 12833.9 12878.2 12913.2 13000.4 13059.5 4367.3 4377.5 4441.9 4456.4 4471.3 76084.6 77941.8 78188.0 79414.0 79594.6 10729.2 10783.8 11059.4 11168.8 11558.2 20787.3 20965.0 20975.1 20993.0 20994.5 7791.4 8005.1 8170.6 8186.9 8466.7 4602.0 4607.1 4611.6 4695.2 4712.3 3296.2 3421.6 3430.4 3432.1 3435.2 569.2 582.3 583.7 583.9 585.6 554.8 564.4 613.4 617.8 621.9 8380.4 8939.9 9130.9 9168.3 9183.7 526.8 600.2 605.3 605.3 643.8 4322.3 4373.8 4399.9 4688.4 4689.6 116121.4 117245.0 119282.0 119780.4 121495.0 94428.5 98749.5 99401.0 99836.0 104865.5 87841.0 88138.1 89157.0 89189.6 94755.5 8533.0 8651.5 8671.1 8705.7 8722.0 50201.7 50842.7 52336.0 52346.5 52516.7 67357.3 68998.3 73521.3 74783.6 77526.0 10799.0 10894.6 10950.8 10989.1 11655.7 12099.5 12233.7 12252.3 12332.2 12767.2 10979.6 11534.8 12009.2 12247.5 12275.4 11943.4 12026.6 12086.3 12301.0 12559.8 11078.5 11095.2 11471.8 11577.7 11832.3 8831.3 8883.9 9043.5 9083.8 9201.5 8300.2 9009.2 9771.0 9930.4 9943.3 18:41:12 INFO - PROCESS | 4636 | -------- Summary: end -------- 18:41:12 INFO - PROCESS | 4636 | 18:41:13 INFO - TEST-INFO | 4636: exit 0 18:41:13 INFO - TEST-OK | dromaeo_css | took 1118178ms 18:41:13 INFO - TEST-START | kraken 18:41:13 INFO - operating with platform_type : linux_ 18:41:13 INFO - Initialising browser for kraken test... 18:41:13 INFO - TEST-INFO | started process 4942 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphJd2Eq/profile http://localhost:48857/getInfo.html) 18:41:17 INFO - PROCESS | 4942 | __metrics Screen width/height:1600/1200 18:41:17 INFO - PROCESS | 4942 | colorDepth:24 18:41:17 INFO - PROCESS | 4942 | Browser inner width/height: 1024/697 18:41:17 INFO - PROCESS | 4942 | __metrics 18:41:17 INFO - PROCESS | 4942 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:41:17 INFO - PROCESS | 4942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:18 INFO - PROCESS | 4942 | console.error: PushService: 18:41:18 INFO - PROCESS | 4942 | stateChangeProcessEnqueue: Error transitioning state 18:41:18 INFO - PROCESS | 4942 | UnknownError 18:41:19 INFO - TEST-INFO | 4942: exit 0 18:41:19 INFO - Browser initialized. 18:41:19 INFO - Running cycle 1/1 for kraken test... 18:41:19 INFO - TEST-INFO | started process 5026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphJd2Eq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:31 INFO - PROCESS | 5026 | RSS: Main: 171618304 18:41:31 INFO - PROCESS | 5026 | 18:41:33 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48857/tests/kraken/driver.html?testName=audio-beat-detection) 18:41:34 INFO - PROCESS | 5026 | RSS: Main: 228302848 18:41:34 INFO - PROCESS | 5026 | 18:41:37 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48857/tests/kraken/driver.html?testName=audio-dft) 18:41:37 INFO - PROCESS | 5026 | RSS: Main: 215416832 18:41:37 INFO - PROCESS | 5026 | 18:41:40 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48857/tests/kraken/driver.html?testName=audio-fft) 18:41:41 INFO - PROCESS | 5026 | RSS: Main: 297594880 18:41:41 INFO - PROCESS | 5026 | 18:41:43 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48857/tests/kraken/driver.html?testName=audio-oscillator) 18:41:44 INFO - PROCESS | 5026 | RSS: Main: 206274560 18:41:44 INFO - PROCESS | 5026 | 18:41:46 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48857/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:41:47 INFO - PROCESS | 5026 | RSS: Main: 211730432 18:41:47 INFO - PROCESS | 5026 | 18:41:50 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48857/tests/kraken/driver.html?testName=imaging-darkroom) 18:41:50 INFO - PROCESS | 5026 | RSS: Main: 247398400 18:41:50 INFO - PROCESS | 5026 | 18:41:53 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48857/tests/kraken/driver.html?testName=imaging-desaturate) 18:41:54 INFO - PROCESS | 5026 | RSS: Main: 246710272 18:41:54 INFO - PROCESS | 5026 | 18:41:57 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48857/tests/kraken/driver.html?testName=json-parse-financial) 18:41:57 INFO - PROCESS | 5026 | RSS: Main: 246419456 18:41:57 INFO - PROCESS | 5026 | 18:41:59 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48857/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:41:59 INFO - PROCESS | 5026 | RSS: Main: 208502784 18:41:59 INFO - PROCESS | 5026 | 18:42:01 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:02 INFO - PROCESS | 5026 | RSS: Main: 216215552 18:42:02 INFO - PROCESS | 5026 | 18:42:04 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:04 INFO - PROCESS | 5026 | RSS: Main: 225153024 18:42:04 INFO - PROCESS | 5026 | 18:42:07 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:08 INFO - PROCESS | 5026 | RSS: Main: 234311680 18:42:08 INFO - PROCESS | 5026 | 18:42:10 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:11 INFO - PROCESS | 5026 | RSS: Main: 230961152 18:42:11 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | Cycle 1(1): loaded http://localhost:48857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48857/tests/kraken/driver.html?testName=ai-astar) 18:42:13 INFO - PROCESS | 5026 | RSS: Main: 282849280 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | __start_tp_report 18:42:13 INFO - PROCESS | 5026 | _x_x_mozilla_page_load 18:42:13 INFO - PROCESS | 5026 | _x_x_mozilla_page_load_details 18:42:13 INFO - PROCESS | 5026 | |i|pagename|runs| 18:42:13 INFO - PROCESS | 5026 | |0;ai-astar;104;111;111;111;114;111;111;111;112;110 18:42:13 INFO - PROCESS | 5026 | |1;audio-beat-detection;149;128;124;163;123;130;130;124;163;127 18:42:13 INFO - PROCESS | 5026 | |2;audio-dft;162;162;162;161;160;163;163;163;161;162 18:42:13 INFO - PROCESS | 5026 | |3;audio-fft;76;75;76;107;79;76;77;76;104;75 18:42:13 INFO - PROCESS | 5026 | |4;audio-oscillator;82;82;117;83;82;82;108;82;82;113 18:42:13 INFO - PROCESS | 5026 | |5;imaging-gaussian-blur;116;117;116;116;117;116;116;117;116;117 18:42:13 INFO - PROCESS | 5026 | |6;imaging-darkroom;146;145;145;145;145;145;145;145;146;145 18:42:13 INFO - PROCESS | 5026 | |7;imaging-desaturate;84;84;84;85;84;84;85;84;85;85 18:42:13 INFO - PROCESS | 5026 | |8;json-parse-financial;52;51;52;50;51;52;53;52;52;52 18:42:13 INFO - PROCESS | 5026 | |9;json-stringify-tinderbox;60;59;58;59;60;59;59;59;64;60 18:42:13 INFO - PROCESS | 5026 | |10;stanford-crypto-aes;95;85;81;81;80;80;80;87;87;82 18:42:13 INFO - PROCESS | 5026 | |11;stanford-crypto-ccm;132;129;132;138;134;131;130;130;172;142 18:42:13 INFO - PROCESS | 5026 | |12;stanford-crypto-pbkdf2;168;166;167;170;164;167;167;167;168;166 18:42:13 INFO - PROCESS | 5026 | |13;stanford-crypto-sha256-iterative;68;70;69;65;67;71;64;64;64;72 18:42:13 INFO - PROCESS | 5026 | __end_tp_report 18:42:13 INFO - PROCESS | 5026 | __start_cc_report 18:42:13 INFO - PROCESS | 5026 | _x_x_mozilla_cycle_collect,1699 18:42:13 INFO - PROCESS | 5026 | __end_cc_report 18:42:13 INFO - PROCESS | 5026 | __startTimestamp1477100533568__endTimestamp 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | ------- Summary: start ------- 18:42:13 INFO - PROCESS | 5026 | Number of tests: 14 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.55 (2.3%) stddev-sans-first:1.12 18:42:13 INFO - PROCESS | 5026 | Values: 104.0 111.0 111.0 111.0 114.0 111.0 111.0 111.0 112.0 110.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:130.00 stddev:15.99 (12.3%) stddev-sans-first:16.26 18:42:13 INFO - PROCESS | 5026 | Values: 149.0 128.0 124.0 163.0 123.0 130.0 130.0 124.0 163.0 127.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 18:42:13 INFO - PROCESS | 5026 | Values: 162.0 162.0 162.0 161.0 160.0 163.0 163.0 163.0 161.0 162.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.00 stddev:12.40 (16.3%) stddev-sans-first:12.96 18:42:13 INFO - PROCESS | 5026 | Values: 76.0 75.0 76.0 107.0 79.0 76.0 77.0 76.0 104.0 75.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:82.00 stddev:14.90 (18.2%) stddev-sans-first:15.42 18:42:13 INFO - PROCESS | 5026 | Values: 82.0 82.0 117.0 83.0 82.0 82.0 108.0 82.0 82.0 113.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:42:13 INFO - PROCESS | 5026 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 116.0 117.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 18:42:13 INFO - PROCESS | 5026 | Values: 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 18:42:13 INFO - PROCESS | 5026 | Values: 84.0 84.0 84.0 85.0 84.0 84.0 85.0 84.0 85.0 85.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 18:42:13 INFO - PROCESS | 5026 | Values: 52.0 51.0 52.0 50.0 51.0 52.0 53.0 52.0 52.0 52.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:1.64 (2.8%) stddev-sans-first:1.73 18:42:13 INFO - PROCESS | 5026 | Values: 60.0 59.0 58.0 59.0 60.0 59.0 59.0 59.0 64.0 60.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.50 stddev:4.83 (5.8%) stddev-sans-first:2.96 18:42:13 INFO - PROCESS | 5026 | Values: 95.0 85.0 81.0 81.0 80.0 80.0 80.0 87.0 87.0 82.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:133.00 stddev:12.94 (9.7%) stddev-sans-first:13.60 18:42:13 INFO - PROCESS | 5026 | Values: 132.0 129.0 132.0 138.0 134.0 131.0 130.0 130.0 172.0 142.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:1.56 (0.9%) stddev-sans-first:1.62 18:42:13 INFO - PROCESS | 5026 | Values: 168.0 166.0 167.0 170.0 164.0 167.0 167.0 167.0 168.0 166.0 18:42:13 INFO - PROCESS | 5026 | 18:42:13 INFO - PROCESS | 5026 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.50 stddev:3.06 (4.5%) stddev-sans-first:3.24 18:42:13 INFO - PROCESS | 5026 | Values: 68.0 70.0 69.0 65.0 67.0 71.0 64.0 64.0 64.0 72.0 18:42:13 INFO - PROCESS | 5026 | -------- Summary: end -------- 18:42:13 INFO - PROCESS | 5026 | 18:42:14 INFO - TEST-INFO | 5026: exit 0 18:42:14 INFO - TEST-OK | kraken | took 61592ms 18:42:14 INFO - SUITE-END | took 1179s 18:42:15 INFO - Completed test suite (00:19:40) 18:42:15 INFO - javascript benchmark 18:42:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3010.0, 3061.5101583746605, 3075.492543730284, 3108.2074071111865, 3111.2688518198233, 5095.745212739367, 5158.509941555556, 5191.896162076761, 5269.8155564555245, 5308.517054545633, 5208.427073021964, 5233.162693968966, 5284.550813180885, 5311.044012077825, 5388.7305634718205, 4776.737279449629, 4813.326134341195, 4836.153673107192, 4845.903081938359, 4858.805647774085, 4753.595944344735, 4863.659543831933, 4887.608991280689, 4894.5105489451125, 4894.608431325503, 4840.975795121037, 4891.1195984722735, 4897.608191344684, 4898.06936682031, 4963.230699241623, 4703.129920964599, 4725.243960966229, 4727.739974301412, 4781.0676918001, 4879.975600122012, 4773.0, 4796.52034796521, 4805.0, 4858.028394321114, 4867.36724225852, 5249.816256431045, 5253.264542963988, 5284.6564973277, 5290.894182116336, 5355.678659280456, 5458.235846981426, 5510.91733623996, 5641.12562552805, 5643.887122257573, 5651.2370829937845, 5646.350669672999, 5692.573057020699, 5750.137479378085, 5758.42415758425, 5760.308762948473, 5303.151495760737, 5304.92042619361, 5304.9734751326, 5306.124489459235, 5313.043652142652, 5599.468050535193, 5773.711314434346, 5774.2782152230975, 5784.21913041734, 5849.0, 5440.646357986719, 5477.4248703886315, 5523.061079616395, 5531.640443371168, 5534.335879694462, 5530.695811730394, 5569.944300557024, 5586.748596313176, 5595.328560572757, 5689.260396148474, 5603.7478313475995, 5614.101743721067, 5619.381867994517, 5655.095184770417, 5687.175359572839, 5537.944620553823, 5586.826766379025, 5600.85997850057, 5610.214569960209, 5618.887622247614, 4028.9194216115516, 4048.8785336439955, 4049.1901619675596, 4071.491063617048, 4124.566920473367, 3686.3733165361955, 3706.5181526401393, 3722.3671975764187, 3745.157339598623, 3751.2122454284904, 5297.417284098746, 5311.7344132793205, 5311.973440132774, 5314.62797604164, 5377.3816011159215, 5350.571954243651, 5461.590380721462, 5463.153211252262, 5588.469095435927, 5594.888102238014, 5262.71055091966, 5306.575473962073, 5328.254044433782, 5407.45925407464, 5407.621466497386, 4670.136024835417, 4713.95286047142, 4717.268823332389, 4727.598154156866, 4775.832845850448, 5752.309722833287, 5760.8271751847515, 5768.480836724799, 5768.826935191951, 5772.451617096369, 5393.433689462707, 5423.159410291411, 5423.484768946943, 5444.047291723853, 5586.413426590149, 5636.351819540805, 5639.943600563942, 5667.4615911488345, 5713.628614140234, 5805.332386775575, 5469.535089517276, 5477.260569823023, 5481.040817856939, 5543.695096769717, 5547.41752116022, 5530.446955304437, 5530.7511161997, 5576.944230557805, 5577.44225577439, 5597.832065038056, 5333.066713325075, 5403.459654034722, 5405.108157154183, 5487.310780244122, 5552.3892371839875, 5425.674459532441, 5431.8098866539485, 5435.10320797064, 5437.891242175056, 5439.564834813126, 5346.572274217975, 5349.518543331119, 5351.545118664957, 5353.009693206769, 5380.596455265792, 5427.918581221206, 5451.945480545302, 5469.2616496772425, 5474.315710536183, 5495.587830912617, 5378.112611419072, 5423.620346575702, 5477.808276710296, 5483.5064844162625, 5488.9725551371985], "value": 5156.125569354324, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.6197195766667, 627.0594108836747, 630.8422894276431, 633.3381616211059, 655.1515787055765, 31764.20589485261, 43104.0, 43560.12879742407, 43937.56062439374, 47287.763561182146, 44177.116457670825, 44301.8924526887, 44640.3303950441, 44722.77638611802, 44770.10459790802, 8809.823803523941, 8950.149735775123, 8952.239059679903, 8952.955235223848, 8962.955185224066, 8021.799455013613, 8202.302804261644, 8805.471671699686, 10362.171026317914, 10643.733906652318, 10860.565577376896, 10912.50893709785, 10923.563057477691, 10927.344359338465, 10932.617358392457, 10735.51690173944, 10780.676579702622, 10802.51388687511, 10806.945965270203, 10811.243212975016, 10495.212859035526, 10551.261411701184, 10553.47232638365, 10560.2079844012, 10561.577536898514, 42047.57952420498, 42226.36660450096, 42321.788391057846, 42341.788291058656, 42380.57619423797, 3523.2953409317984, 3559.9110022249392, 3617.8733744318824, 3742.4947632069866, 4009.83960641574, 30427.0, 31430.528542071886, 31481.685183148104, 31558.36883262322, 31675.0, 4136.648384887289, 4376.956230437687, 4391.9780401098105, 4551.044280701057, 4573.382593349888, 325160.0, 325566.0, 327608.94452415325, 327834.0, 329481.9999999976, 32765.344693106006, 34166.658333416344, 34175.65824341775, 56538.43461565414, 56949.715251424306, 35129.29741405157, 35340.46989295162, 35460.46809297863, 35473.0, 35504.11239719028, 14810.555683329518, 14900.552983410515, 14970.775438368215, 14979.101253924799, 14987.400503979938, 11505.654830355103, 12626.179298345578, 12732.80900786489, 12734.363281835871, 12742.80885786714, 7699.422543309274, 7949.3243074339325, 8167.224113709188, 8180.386471014698, 8293.377996650155, 3063.6170478690165, 3065.509518477033, 3091.30445649727, 3104.98447507761, 3135.576697145856, 892.2237653241722, 922.801597656507, 933.4021068502396, 984.542187882638, 1048.9982067125877, 27506.76219570124, 28827.0, 30453.543196852064, 30630.234244144074, 30939.69060309413, 1038.2213339995003, 1041.0838462153408, 1043.3202543904308, 1071.6624263357019, 1097.6761855252719, 15550.533483995498, 16196.595085122966, 16214.0, 16406.425775097814, 16619.66760664804, 512738.0, 512918.0, 516093.0, 517058.0, 517406.0, 56880.71559642175, 57358.13962790561, 57390.7130464345, 57439.42560574424, 57461.712691436274, 1213.660175150959, 1234.6357824441811, 1263.2420547671325, 1334.0594880609146, 1344.9058565900293, 28988.855055724587, 31367.04026235008, 31429.52855707212, 31529.527057093703, 32101.67898320987, 48509.51490485191, 49419.50580494149, 49421.752891235315, 49461.50538494569, 49491.75254123707, 324760.0, 327634.0, 328101.0, 330977.3804523944, 331012.0, 27059.458810824068, 27575.0, 27838.16485505438, 27999.58000630033, 29635.259118522208, 45766.0, 45909.0, 46539.76730116463, 47010.0, 47282.76358618185, 39387.21225575415, 39407.0, 39609.40585891157, 41000.0, 41501.792491037355, 39483.21033579255, 39921.0, 40088.0, 40919.38620920629, 41171.38242926419, 38997.41503877387, 39017.0, 39442.802785985885, 40283.39574906437, 40389.0, 33311.83344083264, 35345.823270883484, 35696.28607427785, 35735.533058367706, 36102.8194859024, 24405.51188976175, 24592.508149836543, 24890.50218995646, 25330.733463327128, 25386.492270154864], "value": 18487.711265882623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.51766562144235, 367.75331625788596, 381.15193694030785, 384.7422227107838, 403.8970062634028, 14508.492202772904, 14832.555023349303, 15921.601959951007, 18313.7252941206, 19526.121324540396, 17866.910665446656, 18600.16299266533, 18933.905330473328, 19042.714359284622, 19224.61550768984, 5352.545033672132, 5601.523870470995, 5608.383077861432, 5609.439056094378, 5610.69141197234, 4553.385292985437, 5503.944960550383, 6148.323684394713, 6197.0, 6274.623522588637, 5930.673812940287, 6212.720427580747, 6214.471769899564, 6216.689165541728, 6219.40915613016, 6041.637501749887, 6123.969380153093, 6133.325334213256, 6153.261608606951, 6163.8150855474405, 4923.335349727777, 5175.094358487249, 5448.45515448456, 5449.264349312832, 5457.536109430703, 13134.34328283582, 13339.799903001365, 13358.532451364155, 13444.932775336061, 13487.66280842977, 2117.110813458351, 2289.278877153691, 2596.234110937278, 2773.486904922596, 2777.180731684158, 9526.0, 9661.227101831837, 9899.554520046615, 10212.948935255276, 10228.028337307944, 1763.3387479695114, 1860.83252507275, 1883.2467013194694, 1892.0539730134933, 1937.8159074887863, 80132.0, 81637.59181204115, 82207.58896205481, 82853.58573207095, 83212.0, 10317.27779055474, 10530.262881598368, 10601.787964240671, 10628.25602207838, 10659.147268218523, 14620.853791462161, 14630.561083167522, 14637.92681036588, 14644.633884152981, 14662.193579353025, 7669.693212271558, 7835.138134805167, 7904.881426778603, 7991.520508769492, 8079.676812927417, 7445.329920307198, 7580.128285247157, 7720.459567830198, 7722.304992550585, 7731.072271327476, 4401.22978478765, 4456.8133784344345, 4463.062756821104, 4621.376114224604, 4632.328312394751, 1776.0108678280408, 1802.8287312705272, 1805.4673871208024, 1813.165943665902, 1814.637072585488, 901.5672477210976, 914.977125571866, 919.0763282900642, 935.2331088507494, 980.8185485685171, 17312.0, 18305.359312424, 18605.534861628566, 18736.437906862815, 19055.714164287547, 905.1898550797, 919.5494207838245, 954.651552183462, 961.1637875048752, 996.725900377402, 9950.164203202052, 10027.448490333101, 10449.111825494767, 10455.84316235257, 10463.162946964225, 462021.0, 466209.0, 472103.0, 482041.0, 483751.0, 84350.0, 85113.0, 85611.14388856156, 89223.55388223018, 89494.10505894858, 984.1192133040888, 996.9651062212932, 1021.2493817044615, 1041.8645576075214, 1046.1317106801337, 14860.777088343468, 15560.92219538895, 16318.020918744913, 16505.752413714043, 16660.66678666396, 41247.793761031004, 41295.7935210334, 41741.1651766969, 43639.12721745611, 43951.78024109859, 57383.713081434325, 58332.70833645805, 58407.415925841895, 58885.41114588971, 59370.40629593821, 8055.597220138852, 8261.83476330458, 8278.130796266305, 8405.663773448994, 8497.660093596187, 9448.149666530046, 11256.268342557707, 13001.284929328978, 13080.28058456794, 13326.533571324957, 10969.945150274198, 11116.499757511094, 11246.381449020382, 11266.718332041764, 11908.702282442662, 11014.724631883946, 11063.170262230526, 11389.601363952554, 11394.430278486208, 11459.541618335174, 10948.233623646269, 11144.55421783152, 11215.551377945118, 11695.649130526097, 12171.0, 7950.840983180419, 8182.099969003286, 8305.0, 8461.788455288666, 8524.616392262487, 8225.629846656795, 10643.201759867887, 11519.424028798694, 11564.248323858923, 11611.0], "value": 8806.460076959533, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.6526037969743, 1289.8516670582885, 1340.4772138865837, 1405.8734713875751, 1426.7931149983253, 7296.525725827817, 8199.262066414021, 8486.575671216446, 8498.745037648872, 8654.653813847453, 8475.194856488639, 8524.701635442761, 8548.358873084513, 8554.101819308977, 8556.486610803357, 2271.659251112334, 2299.1378233162563, 2299.172297972737, 2300.309907027885, 2306.423394151462, 2207.4591725027312, 2253.0311965854676, 2305.700258966332, 2305.9192928247517, 2308.73449553301, 2212.070930209308, 2228.92198773043, 2236.5750507403623, 2280.726312842453, 2284.1091974130104, 2192.408049826554, 2210.491586935006, 2210.6794514795347, 2214.446388402899, 2215.2025270902463, 2165.3287480880977, 2166.4150679316494, 2167.349795061491, 2173.7826217378292, 2177.1182671018105, 8292.460990035628, 8373.16268373164, 8421.957890210571, 8460.492370457792, 8506.106858779802, 5106.208537676666, 5303.655262407931, 5316.9734151329385, 5322.893542129135, 5325.8934821303355, 3133.5041614524343, 3259.26666500038, 3271.0, 3274.887697435141, 3286.09632351103, 993.7018894331671, 1000.5847573257163, 1004.322082594246, 1015.9339642923186, 1034.4310629153936, 11507.07943364529, 11559.595414160549, 11599.478023488879, 11600.0, 11635.883641163564, 4252.659787217015, 4340.761258130833, 4368.449200533828, 4493.6854420190275, 4521.208788462006, 9253.213476854407, 9257.583408746623, 9262.305327100494, 9263.53682315895, 9267.165955064076, 5073.264376665363, 5086.0845047891735, 5088.491150884882, 5090.0, 5124.180131178993, 3992.6007399260425, 4122.072533679898, 4614.630829533697, 4716.080364328992, 4768.070226305837, 3605.7656252343513, 3611.349957007764, 3720.6465385788306, 3734.3651579231596, 3763.9059023524082, 1049.0663309654415, 1049.459528342904, 1055.6410820321128, 1059.7668512927144, 1069.8662667166604, 775.5656832173888, 879.5690111845278, 896.0412358776047, 897.533282692996, 924.3899026642383, 5423.891522169613, 5453.127499600124, 5614.578906582023, 5684.744186511617, 5762.798302059407, 404.423696232867, 405.85794971760237, 418.33693595650624, 421.496311907274, 422.5415424264707, 3475.4786781982907, 3567.94648080279, 3639.836207370675, 3728.422094575345, 3728.8694895678523, 9780.853287200698, 10307.536160872778, 10363.11913487347, 10364.274500784873, 10371.222158338154, 10028.348157369894, 10193.94903025495, 10254.0, 10257.897421025695, 10258.17934565233, 7735.381169506426, 7766.37868970481, 7825.491343062683, 7841.059072911287, 7848.372130229568, 5415.323084614423, 5466.34403871538, 5501.449855014626, 5505.532029777513, 5641.12562552805, 9478.905210947802, 9563.904360956301, 9602.471864047266, 9630.422174669542, 9723.9027609723, 9500.287478439006, 9846.409215447096, 9932.404055756677, 9942.403455792675, 9948.850767238353, 6770.830729231809, 6970.268121847336, 7309.342159205697, 7509.51188172767, 7646.885296720442, 7965.1238363778775, 8308.169183081643, 8314.0, 8324.334053275845, 8366.288865446506, 7261.709531618887, 7392.630368481662, 7408.185099639143, 7456.366208872089, 7527.774166775005, 7172.748953786592, 7179.425645948416, 7291.927080729125, 7328.926710733038, 7544.207858174813, 6660.467162627075, 6662.167229096363, 6665.100211471389, 6730.192376914802, 6732.865342693217, 4690.859274221779, 4761.9047619048115, 4780.521947805191, 5004.42449118356, 5053.7220452875445, 4827.0, 4978.626603004862, 4984.2025275955675, 4999.200127979506, 5161.406438259648], "value": 4277.582503772722, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.1705530472363, 324.7888872233048, 327.99796621321866, 332.16460601587005, 337.3522836154583, 5303.575713942885, 5308.548773354265, 5340.118880384737, 5349.22436246744, 5475.972620136904, 5072.0, 5086.439492355347, 5159.793608255674, 5190.039842629116, 5358.6516876403, 4127.236461254677, 4137.834486620532, 4161.563035881234, 4169.708120431571, 4175.248455278049, 4172.4965020987465, 4173.08192197716, 4180.874573762792, 4181.728187667807, 4194.706370554062, 3894.9610503894883, 3951.0, 4123.484564429446, 4131.294002428399, 4134.131832315217, 4146.3158578834755, 4153.314703073989, 4154.439150714645, 4164.6251837334785, 4168.6039826216465, 3684.613115622875, 3693.4275187346007, 3700.3154416433044, 3700.40793473043, 3704.148045949447, 5073.746312684388, 5076.517730815566, 5102.285680004802, 5244.632875698702, 5254.8686282842855, 4672.182368085605, 4674.602658774008, 4700.224462963607, 4718.457377401608, 4718.693284936468, 5497.422770609069, 5522.641028333145, 5522.751476183541, 5576.275084239023, 5587.273654424899, 3899.5710471848074, 3945.5857135000697, 3967.7817720025387, 4013.779242141681, 4034.760707807166, 5630.774769009276, 5641.830745077655, 5642.6614403135945, 5655.3779084300695, 5661.320641523002, 4006.3790112532606, 4023.436718859362, 4041.2119636671155, 4043.6158564936286, 4048.2105989332385, 4023.879283621496, 4069.7354671946823, 4122.670186385082, 4138.8344466221015, 4151.771652559139, 4671.32265821454, 4678.660669665167, 4710.222813235778, 4721.6458765592715, 4734.7632618369635, 5493.7802487900035, 5550.195221692832, 5551.777928882881, 5587.273654424899, 5597.692126932976, 1391.7981892625714, 1401.1312985948778, 1419.3683810704345, 1424.8432672406027, 1438.3958737330347, 892.161368313783, 913.5614904845711, 923.6028507741736, 926.9165775080276, 930.3719989007393, 4691.319758635047, 4700.271457924027, 4735.881602959885, 4745.738984355825, 4761.285807128958, 4554.4990051094355, 4926.630502712311, 4939.283803848493, 4969.403301945686, 4997.650164488451, 4658.510856360102, 4687.343771871941, 4702.260163739438, 4702.764861756898, 4721.881952951135, 2605.817592768488, 2618.279973007438, 2643.1409791817737, 2669.9466010679675, 2680.38351179226, 4839.467658557556, 5034.320366750442, 5074.985002999415, 5116.641835071509, 5122.718250496184, 5112.762429835595, 5116.641835071509, 5122.820701275437, 5144.768485418165, 5150.4849515048945, 5072.442031376546, 5075.487633621381, 5077.441481437039, 5126.9746050789245, 5138.409082955433, 4960.900781984268, 4975.437649610526, 5296.629235953446, 5661.631993920382, 5709.0, 5575.693336866511, 5593.188987596857, 5613.101903695389, 5665.773369065356, 5665.886682266414, 5649.774009039593, 5822.796202132905, 5872.559558033079, 5915.190284614946, 5935.376785437467, 4023.3361495352938, 4041.2218623805434, 4052.675785937177, 4061.1268577256033, 4070.531888832822, 5417.214503896834, 5530.253415788817, 5605.747741351567, 5695.917775622618, 5714.228579141929, 5582.860428489158, 5591.720413979366, 5600.0, 5604.915926261191, 5622.128570071645, 5327.0, 5532.446755324436, 5544.667319960815, 5555.694436806015, 5578.0, 5453.400125986059, 5517.779288828402, 5519.696416696959, 5520.227168196376, 5529.806456773994, 5603.97385411873, 5620.241267428844, 5640.943590564042, 5656.151577263443, 5709.800156994485, 5197.636165468381, 5363.19552067193, 5422.403535611158, 5530.446955304598, 5561.4716601922755], "value": 4113.037169971383, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.3279052923397, 740.7111625771156, 755.5051441305944, 756.4251169111475, 779.9103103143136, 15955.36178552858, 17067.231974561142, 17103.657926841457, 17108.74336884946, 17394.391196308115, 15121.84878151218, 15161.166135862522, 15258.465953691622, 15284.312205950731, 15289.770653440208, 656.3305428462965, 663.2538394306405, 781.4061313401827, 790.893229414029, 808.454293351988, 784.6429874407131, 784.654751909161, 792.9207079292082, 793.0364607002491, 793.833295008049, 854.4531499840107, 854.5769843927264, 855.9015713193, 856.1609622569886, 856.4732689396039, 697.4420463629127, 710.1159056974049, 854.7563944275882, 855.5978690015713, 856.1524091149779, 608.3551435478363, 618.2519151826295, 622.632646738422, 625.8654389306275, 627.5168120547153, 14400.351984160738, 14467.855321446861, 14502.419903203965, 14524.0, 14627.78058329126, 13538.526151584647, 13871.861281387159, 14196.361163747657, 14201.289935503184, 14820.184889831055, 12833.935830320883, 12878.227306361649, 12913.225206487641, 13000.414981325768, 13059.460533505595, 4367.2575662137515, 4377.5403582623685, 4441.888952776174, 4456.398386217884, 4471.3293006049025, 76084.61957690176, 77941.83087253752, 78188.0, 79414.0, 79594.6020269895, 10729.248952573245, 10783.83824242637, 11059.391733454737, 11168.822079694382, 11558.248713833742, 20787.272445464336, 20965.0, 20975.056122474525, 20993.0, 20994.47513812167, 7791.376689864797, 8005.079415867133, 8170.632321545426, 8186.877196842052, 8466.74599762008, 4601.9956261613015, 4607.101615185007, 4611.5619016193405, 4695.178343789891, 4712.31671407644, 3296.159479332755, 3421.555197824267, 3430.3653824042135, 3432.1248081738995, 3435.2270739083506, 569.2002736155708, 582.3099626942087, 583.7168973048068, 583.8540364908773, 585.5988647776287, 554.7808615596816, 564.4496615799644, 613.355976224962, 617.7528988404584, 621.8911690454153, 8380.371472139614, 8939.865902011476, 9130.908690913138, 9168.312376571785, 9183.65321636263, 526.8498477933771, 600.2227115884939, 605.2736715940889, 605.3130835797656, 643.8454770854961, 4322.286822674224, 4373.825046998148, 4399.912001759947, 4688.367070445514, 4689.577937985529, 116121.41939290249, 117245.0, 119282.0, 119780.40109799395, 121495.0, 94428.52785736027, 98749.50625246827, 99401.0, 99836.0, 104865.47567262268, 87841.0, 88138.11861881427, 89157.0, 89189.55405222933, 94755.52622236844, 8533.0, 8651.48091114535, 8671.089535598923, 8705.695300664447, 8721.956390218262, 50201.74899125481, 50842.74578627083, 52336.0, 52346.476535234164, 52516.73741631267, 67357.3264267351, 68998.3100168992, 73521.26478735358, 74783.62608186924, 77526.0, 10799.0, 10894.564217431214, 10950.835737463785, 10989.13184758609, 11655.650330490098, 12099.455524501242, 12233.69415764613, 12252.264864108181, 12332.19840710351, 12767.233965962072, 10979.61571345031, 11534.82697759551, 12009.219400738924, 12247.510099596273, 12275.378763265036, 11943.402829858645, 12026.639200823989, 12086.274823510617, 12301.0, 12559.763524907443, 11078.501467434146, 11095.167862410513, 11471.770564588494, 11577.710557236136, 11832.34922079294, 8831.337649676172, 8883.911160888309, 9043.457392556467, 9083.772905677411, 9201.527755559078, 8300.2114799094, 9009.189172974464, 9771.0, 9930.354526955725, 9943.30396872212], "value": 7192.612764067791, "unit": "score"}], "name": "dromaeo_css", "value": 6882.035897648261, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 111.0, 111.0, 114.0, 111.0, 111.0, 111.0, 112.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 128.0, 124.0, 163.0, 123.0, 130.0, 130.0, 124.0, 163.0, 127.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 160.0, 163.0, 163.0, 163.0, 161.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 107.0, 79.0, 76.0, 77.0, 76.0, 104.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 117.0, 83.0, 82.0, 82.0, 108.0, 82.0, 82.0, 113.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0, 85.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 50.0, 51.0, 52.0, 53.0, 52.0, 52.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 59.0, 60.0, 59.0, 59.0, 59.0, 64.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 85.0, 81.0, 81.0, 80.0, 80.0, 80.0, 87.0, 87.0, 82.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 129.0, 132.0, 138.0, 134.0, 131.0, 130.0, 130.0, 172.0, 142.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 167.0, 170.0, 164.0, 167.0, 167.0, 167.0, 168.0, 166.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 65.0, 67.0, 71.0, 64.0, 64.0, 64.0, 72.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1494.6, "alertThreshold": 2.0}]} 18:42:15 INFO - Return code: 0 18:42:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:15 INFO - # TBPL SUCCESS # 18:42:15 INFO - Running post-action listener: _resource_record_post_action 18:42:15 INFO - [mozharness: 2016-10-22 01:42:15.377794Z] Finished run-tests step (success) 18:42:15 INFO - Running post-run listener: _resource_record_post_run 18:42:15 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 8192; Write bytes: 392757248; Read time: 192; Write time: 285668 18:42:15 INFO - TinderboxPrint: CPU usage
13.1% 18:42:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 192 18:42:15 INFO - TinderboxPrint: I/O write bytes / time
392,757,248 / 285,668 18:42:15 INFO - TinderboxPrint: CPU idle
8,299.4 (86.9%) 18:42:15 INFO - TinderboxPrint: CPU user
1,201.8 (12.6%) 18:42:15 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102674432; Read time: 0; Write time: 65640 18:42:15 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287195136; Read time: 188; Write time: 219576 18:42:16 INFO - Running post-run listener: _upload_blobber_files 18:42:16 INFO - Blob upload gear active. 18:42:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:16 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:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:42:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:42:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:42:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:17 INFO - (blobuploader) - INFO - Done attempting. 18:42:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:42:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:20 INFO - (blobuploader) - INFO - Done attempting. 18:42:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:42:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:42:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:21 INFO - (blobuploader) - INFO - Done attempting. 18:42:21 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:21 INFO - Return code: 0 18:42:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d2c0285779e2ce26ee12abca9e9c24cb38bbb9ef37d29c0b4397bab510c2a1aaf81765cef36424e6f1e41bc8d5d58a439ffc96513bfc9ce41f7c80b21b7ea91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6a9cdd959e8fd6abe19192f5462e0cbe121a20d7a2d28f28702d4424088c67e739dc708df9dd9eca2b87e3df06fc7914f6f544e8adb2cbae7825994027d52f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b531ce669c1f4929741cb795ecd34a95136a59f1986021f9bdae0e1d22c5c8bfa3275c091d4b7c48a61a38fcef86cf41cdfe5b6afcccaf87bf7374c835cc74a0"} 18:42:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:21 INFO - Contents: 18:42:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d2c0285779e2ce26ee12abca9e9c24cb38bbb9ef37d29c0b4397bab510c2a1aaf81765cef36424e6f1e41bc8d5d58a439ffc96513bfc9ce41f7c80b21b7ea91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6a9cdd959e8fd6abe19192f5462e0cbe121a20d7a2d28f28702d4424088c67e739dc708df9dd9eca2b87e3df06fc7914f6f544e8adb2cbae7825994027d52f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b531ce669c1f4929741cb795ecd34a95136a59f1986021f9bdae0e1d22c5c8bfa3275c091d4b7c48a61a38fcef86cf41cdfe5b6afcccaf87bf7374c835cc74a0"} 18:42:21 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:21 INFO - Copying logs to upload dir... 18:42:21 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.037891 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-21 18:42:21.318969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 18:42:21.322686) ========= 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=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d2c0285779e2ce26ee12abca9e9c24cb38bbb9ef37d29c0b4397bab510c2a1aaf81765cef36424e6f1e41bc8d5d58a439ffc96513bfc9ce41f7c80b21b7ea91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6a9cdd959e8fd6abe19192f5462e0cbe121a20d7a2d28f28702d4424088c67e739dc708df9dd9eca2b87e3df06fc7914f6f544e8adb2cbae7825994027d52f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b531ce669c1f4929741cb795ecd34a95136a59f1986021f9bdae0e1d22c5c8bfa3275c091d4b7c48a61a38fcef86cf41cdfe5b6afcccaf87bf7374c835cc74a0"} build_url:https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/fhs2EOTeQ1a9_lipRJxBTQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d2c0285779e2ce26ee12abca9e9c24cb38bbb9ef37d29c0b4397bab510c2a1aaf81765cef36424e6f1e41bc8d5d58a439ffc96513bfc9ce41f7c80b21b7ea91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6a9cdd959e8fd6abe19192f5462e0cbe121a20d7a2d28f28702d4424088c67e739dc708df9dd9eca2b87e3df06fc7914f6f544e8adb2cbae7825994027d52f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b531ce669c1f4929741cb795ecd34a95136a59f1986021f9bdae0e1d22c5c8bfa3275c091d4b7c48a61a38fcef86cf41cdfe5b6afcccaf87bf7374c835cc74a0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 18:42:21.348237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:42:21.348583) ========= 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=31acecf42b6a79ec49471d4a000001bb-1477099299.159752-1613062073 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:42:21.435386) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-21 18:42:21.435696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-21 18:42:21.435990) ========= ========= Total master_lag: 0.05 =========