builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1480398904.12 results: success (0) buildid: 20161128073209 builduid: 37d182a2d7664150a3249bb0ca8059a8 revision: bc4ed3faa33e589fcc1b86221d837b98af3551b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.117914) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.118362) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.118653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.134628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.134964) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-28 21:55:04-- 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% 103M=0s 2016-11-28 21:55:04 (103 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.281334) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.281828) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018044 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.332587) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:04.332891) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bc4ed3faa33e589fcc1b86221d837b98af3551b7 --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 bc4ed3faa33e589fcc1b86221d837b98af3551b7 --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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-28 21:55:04,341 truncating revision to first 12 chars 2016-11-28 21:55:04,341 Setting DEBUG logging. 2016-11-28 21:55:04,341 attempt 1/10 2016-11-28 21:55:04,341 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bc4ed3faa33e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 21:55:04,662 unpacking tar archive at: mozilla-central-bc4ed3faa33e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676744 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:05.022459) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:05.022977) ========= script_repo_revision: bc4ed3faa33e589fcc1b86221d837b98af3551b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:05.023967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:05.024440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 21:55:05.039658) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-11-28 21:55:05.039949) ========= /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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:55:05 INFO - MultiFileLogger online at 20161128 21:55:05 in /builds/slave/test 21:55:05 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 21:55:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:55:05 INFO - {'append_to_log': False, 21:55:05 INFO - 'base_work_dir': '/builds/slave/test', 21:55:05 INFO - 'blob_upload_branch': 'Firefox', 21:55:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:55:05 INFO - 'branch': 'Firefox', 21:55:05 INFO - 'buildbot_json_path': 'buildprops.json', 21:55:05 INFO - 'config_files': ('talos/linux_config.py',), 21:55:05 INFO - 'default_actions': ('clobber', 21:55:05 INFO - 'read-buildbot-config', 21:55:05 INFO - 'download-and-extract', 21:55:05 INFO - 'populate-webroot', 21:55:05 INFO - 'create-virtualenv', 21:55:05 INFO - 'install', 21:55:05 INFO - 'run-tests'), 21:55:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:55:05 INFO - 'download_minidump_stackwalk': True, 21:55:05 INFO - 'download_symbols': 'ondemand', 21:55:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:55:05 INFO - 'tooltool.py': '/tools/tooltool.py', 21:55:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:55:05 INFO - '/tools/misc-python/virtualenv.py')}, 21:55:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:55:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:55:05 INFO - 'installer_path': 'installer.exe', 21:55:05 INFO - 'log_level': 'info', 21:55:05 INFO - 'log_name': 'talos', 21:55:05 INFO - 'log_to_console': True, 21:55:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:55:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:55:05 INFO - 'opt_config_files': (), 21:55:05 INFO - 'pip_index': False, 21:55:05 INFO - 'sps_profile': False, 21:55:05 INFO - 'sps_profile_interval': 0, 21:55:05 INFO - 'suite': 'dromaeojs', 21:55:05 INFO - 'system_bits': '32', 21:55:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:55:05 INFO - 'title': 'talos-linux64-ix-047', 21:55:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:55:05 INFO - 'use_talos_json': True, 21:55:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:55:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:55:05 INFO - 'work_dir': 'build'} 21:55:05 INFO - [mozharness: 2016-11-29 05:55:05.151140Z] Running clobber step. 21:55:05 INFO - Running pre-action listener: _resource_record_pre_action 21:55:05 INFO - Running main action method: clobber 21:55:05 INFO - rmtree: /builds/slave/test/build 21:55:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:55:05 INFO - Running post-action listener: _resource_record_post_action 21:55:05 INFO - [mozharness: 2016-11-29 05:55:05.602900Z] Finished clobber step (success) 21:55:05 INFO - [mozharness: 2016-11-29 05:55:05.602999Z] Running read-buildbot-config step. 21:55:05 INFO - Running pre-action listener: _resource_record_pre_action 21:55:05 INFO - Running main action method: read_buildbot_config 21:55:05 INFO - Using buildbot properties: 21:55:05 INFO - { 21:55:05 INFO - "project": "", 21:55:05 INFO - "product": "firefox", 21:55:05 INFO - "script_repo_revision": "production", 21:55:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:55:05 INFO - "repository": "", 21:55:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:55:05 INFO - "buildid": "20161128073209", 21:55:05 INFO - "pgo_build": "True", 21:55:05 INFO - "basedir": "/builds/slave/test", 21:55:05 INFO - "buildnumber": 108, 21:55:05 INFO - "slavename": "talos-linux64-ix-047", 21:55:05 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:55:05 INFO - "platform": "ubuntu64_hw", 21:55:05 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:55:05 INFO - "branch": "mozilla-central", 21:55:05 INFO - "repo_path": "mozilla-central", 21:55:05 INFO - "revision": "bc4ed3faa33e589fcc1b86221d837b98af3551b7", 21:55:05 INFO - "stage_platform": "linux64-pgo", 21:55:05 INFO - "builduid": "37d182a2d7664150a3249bb0ca8059a8", 21:55:05 INFO - "slavebuilddir": "test" 21:55:05 INFO - } 21:55:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:55:05 INFO - Running post-action listener: _resource_record_post_action 21:55:05 INFO - [mozharness: 2016-11-29 05:55:05.605381Z] Finished read-buildbot-config step (success) 21:55:05 INFO - [mozharness: 2016-11-29 05:55:05.605467Z] Running download-and-extract step. 21:55:05 INFO - Running pre-action listener: _resource_record_pre_action 21:55:05 INFO - Running main action method: download_and_extract 21:55:05 INFO - mkdir: /builds/slave/test/build/tests 21:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:05 INFO - trying https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:55:05 INFO - Downloading https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:55:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:55:06 INFO - Downloaded 1361 bytes. 21:55:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:55:06 INFO - Using the following test package requirements: 21:55:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:55:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:55:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:55:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'jsshell-linux-x86_64.zip'], 21:55:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:55:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:55:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:55:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:55:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:55:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:55:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:55:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:55:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:55:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:55:06 INFO - Fetch https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:55:08 INFO - Http code: 200 21:55:08 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 21:55:08 INFO - x-amz-cf-id: GMoaSafggvW3Ar4HtG6fYS3YLFjOGcWxAK0h__ylAbcT5DmUn7PJbA== 21:55:08 INFO - x-amz-version-id: ZznZpoOJ_tmSoZt396qxWKfpb7YBWPlo 21:55:08 INFO - Expected file size: 21780746 21:55:08 INFO - Obtained file size: 21780746 21:55:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:55:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:55:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:55:12 INFO - Fetch https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:55:13 INFO - Http code: 200 21:55:13 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 21:55:13 INFO - x-amz-cf-id: WBJHcuyStVA0MStKTqdLEbOENKE5ISAC03xgrdup7diZqNi1KVaJ0w== 21:55:13 INFO - x-amz-version-id: ZznZpoOJ_tmSoZt396qxWKfpb7YBWPlo 21:55:13 INFO - Expected file size: 21780746 21:55:13 INFO - Obtained file size: 21780746 21:55:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:55:17 INFO - Fetch https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:55:18 INFO - Http code: 200 21:55:18 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 21:55:18 INFO - x-amz-cf-id: 4eZ9bc0GAUGbpD4Q_YvwNWlFD7zaE4MwhNLV2C_qciOEDaCVsRlM0w== 21:55:18 INFO - x-amz-version-id: JNNa6Px3XxUubQOhpsKaF5QntGc8xaEM 21:55:18 INFO - Expected file size: 14409702 21:55:18 INFO - Obtained file size: 14409702 21:55:18 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')]} 21:55:18 INFO - trying https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:55:18 INFO - Downloading https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:55:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:55:20 INFO - Downloaded 59817444 bytes. 21:55:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:55:20 INFO - mkdir: /builds/slave/test/properties 21:55:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:55:20 INFO - Writing to file /builds/slave/test/properties/build_url 21:55:20 INFO - Contents: 21:55:20 INFO - build_url:https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:55:20 INFO - Running post-action listener: _resource_record_post_action 21:55:20 INFO - Running post-action listener: set_extra_try_arguments 21:55:20 INFO - [mozharness: 2016-11-29 05:55:20.711296Z] Finished download-and-extract step (success) 21:55:20 INFO - [mozharness: 2016-11-29 05:55:20.711521Z] Running populate-webroot step. 21:55:20 INFO - Running pre-action listener: _resource_record_pre_action 21:55:20 INFO - Running main action method: populate_webroot 21:55:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:20 INFO - '--tppagecycles', 21:55:20 INFO - '7'], 21:55:20 INFO - 'tests': ['tsvgm']}}, 21:55:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:55:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:55:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:55:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['damp', 'tps']}, 21:55:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'tests': ['damp', 'tps']}, 21:55:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['dromaeo_dom']}, 21:55:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['basic_compositor_video']}, 21:55:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:55:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'other-e10s': {'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['a11yr', 21:55:20 INFO - 'ts_paint', 21:55:20 INFO - 'tpaint', 21:55:20 INFO - 'sessionrestore', 21:55:20 INFO - 'sessionrestore_no_auto_restore', 21:55:20 INFO - 'tabpaint']}, 21:55:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['tsvgx', 21:55:20 INFO - 'tsvgr_opacity', 21:55:20 INFO - 'tart', 21:55:20 INFO - 'tscrollx', 21:55:20 INFO - 'cart', 21:55:20 INFO - 'tsvg_static']}, 21:55:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:55:20 INFO - 'tsvgr_opacity', 21:55:20 INFO - 'tart', 21:55:20 INFO - 'tscrollx', 21:55:20 INFO - 'cart', 21:55:20 INFO - 'tsvg_static']}, 21:55:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'talos_options': ['--disable-e10s'], 21:55:20 INFO - 'tests': ['tp5o']}, 21:55:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:55:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'talos_options': ['--disable-e10s', 21:55:20 INFO - '--xperf_path', 21:55:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:20 INFO - 'tests': ['tp5n']}, 21:55:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:55:20 INFO - 'talos_options': ['--xperf_path', 21:55:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:20 INFO - 'tests': ['tp5n']}}, 21:55:20 INFO - 'talos.zip': {'path': '', 21:55:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:20 INFO - Running post-action listener: _resource_record_post_action 21:55:20 INFO - [mozharness: 2016-11-29 05:55:20.730418Z] Finished populate-webroot step (success) 21:55:20 INFO - [mozharness: 2016-11-29 05:55:20.730504Z] Running create-virtualenv step. 21:55:20 INFO - Running pre-action listener: _resource_record_pre_action 21:55:20 INFO - Running main action method: create_virtualenv 21:55:20 INFO - Creating virtualenv /builds/slave/test/build/venv 21:55:20 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 21:55:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:55:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:20 INFO - Using real prefix '/usr' 21:55:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:55:21 INFO - Installing distribute.............................................................................................................................................................................................done. 21:55:23 INFO - Installing pip.................done. 21:55:23 INFO - Return code: 0 21:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:55:23 INFO - Reading from file tmpfile_stdout 21:55:23 INFO - Output received: 21:55:23 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) 21:55:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:55:23 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')]} 21:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:23 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')]} 21:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:23 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:23 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 21:55:23 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 21:55:23 INFO - Using env: {'DISPLAY': ':0', 21:55:23 INFO - 'HOME': '/home/cltbld', 21:55:23 INFO - 'LANG': 'en_US.UTF-8', 21:55:23 INFO - 'LANGUAGE': 'en_US:en', 21:55:23 INFO - 'LOGNAME': 'cltbld', 21:55:23 INFO - 'MAIL': '/var/mail/cltbld', 21:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:23 INFO - 'MOZ_NO_REMOTE': '1', 21:55:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:23 INFO - 'NO_EM_RESTART': '1', 21:55:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:23 INFO - 'PWD': '/builds/slave/test', 21:55:23 INFO - 'SHELL': '/bin/bash', 21:55:23 INFO - 'SHLVL': '1', 21:55:23 INFO - 'TERM': 'linux', 21:55:23 INFO - 'TMOUT': '86400', 21:55:23 INFO - 'USER': 'cltbld', 21:55:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:23 INFO - '_': '/tools/buildbot/bin/python'} 21:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:23 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 21:55:23 INFO - Cleaning up... 21:55:23 INFO - Return code: 0 21:55:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:55:23 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')]} 21:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:23 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')]} 21:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:23 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:23 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 21:55:23 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 21:55:23 INFO - Using env: {'DISPLAY': ':0', 21:55:23 INFO - 'HOME': '/home/cltbld', 21:55:23 INFO - 'LANG': 'en_US.UTF-8', 21:55:23 INFO - 'LANGUAGE': 'en_US:en', 21:55:23 INFO - 'LOGNAME': 'cltbld', 21:55:23 INFO - 'MAIL': '/var/mail/cltbld', 21:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:23 INFO - 'MOZ_NO_REMOTE': '1', 21:55:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:23 INFO - 'NO_EM_RESTART': '1', 21:55:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:23 INFO - 'PWD': '/builds/slave/test', 21:55:23 INFO - 'SHELL': '/bin/bash', 21:55:23 INFO - 'SHLVL': '1', 21:55:23 INFO - 'TERM': 'linux', 21:55:23 INFO - 'TMOUT': '86400', 21:55:23 INFO - 'USER': 'cltbld', 21:55:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:23 INFO - '_': '/tools/buildbot/bin/python'} 21:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:23 INFO - Downloading/unpacking psutil>=3.1.1 21:55:23 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 21:55:23 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 21:55:23 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 21:55:23 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 21:55:23 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 21:55:23 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 21:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:55:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:26 INFO - Installing collected packages: psutil 21:55:26 INFO - Running setup.py install for psutil 21:55:26 INFO - building 'psutil._psutil_linux' extension 21:55:26 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 21:55:26 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 21:55:26 INFO - building 'psutil._psutil_posix' extension 21:55:26 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 21:55:26 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 21:55:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:26 INFO - Successfully installed psutil 21:55:26 INFO - Cleaning up... 21:55:26 INFO - Return code: 0 21:55:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:55:26 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')]} 21:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:26 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')]} 21:55:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:26 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:26 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 21:55:26 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 21:55:26 INFO - Using env: {'DISPLAY': ':0', 21:55:26 INFO - 'HOME': '/home/cltbld', 21:55:26 INFO - 'LANG': 'en_US.UTF-8', 21:55:26 INFO - 'LANGUAGE': 'en_US:en', 21:55:26 INFO - 'LOGNAME': 'cltbld', 21:55:26 INFO - 'MAIL': '/var/mail/cltbld', 21:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:26 INFO - 'MOZ_NO_REMOTE': '1', 21:55:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:26 INFO - 'NO_EM_RESTART': '1', 21:55:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:26 INFO - 'PWD': '/builds/slave/test', 21:55:26 INFO - 'SHELL': '/bin/bash', 21:55:26 INFO - 'SHLVL': '1', 21:55:26 INFO - 'TERM': 'linux', 21:55:26 INFO - 'TMOUT': '86400', 21:55:26 INFO - 'USER': 'cltbld', 21:55:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:26 INFO - '_': '/tools/buildbot/bin/python'} 21:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:55:26 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 21:55:26 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 21:55:26 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 21:55:26 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 21:55:26 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 21:55:26 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 21:55:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:55:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:55:28 INFO - Installing collected packages: mozsystemmonitor 21:55:28 INFO - Running setup.py install for mozsystemmonitor 21:55:28 INFO - Successfully installed mozsystemmonitor 21:55:28 INFO - Cleaning up... 21:55:28 INFO - Return code: 0 21:55:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:55:28 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')]} 21:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:28 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')]} 21:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:55:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:55:29 INFO - Using env: {'DISPLAY': ':0', 21:55:29 INFO - 'HOME': '/home/cltbld', 21:55:29 INFO - 'LANG': 'en_US.UTF-8', 21:55:29 INFO - 'LANGUAGE': 'en_US:en', 21:55:29 INFO - 'LOGNAME': 'cltbld', 21:55:29 INFO - 'MAIL': '/var/mail/cltbld', 21:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:29 INFO - 'MOZ_NO_REMOTE': '1', 21:55:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:29 INFO - 'NO_EM_RESTART': '1', 21:55:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:29 INFO - 'PWD': '/builds/slave/test', 21:55:29 INFO - 'SHELL': '/bin/bash', 21:55:29 INFO - 'SHLVL': '1', 21:55:29 INFO - 'TERM': 'linux', 21:55:29 INFO - 'TMOUT': '86400', 21:55:29 INFO - 'USER': 'cltbld', 21:55:29 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:29 INFO - '_': '/tools/buildbot/bin/python'} 21:55:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:29 INFO - Downloading/unpacking jsonschema==2.5.1 21:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:31 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:31 INFO - warning: no files found matching '*.txt' 21:55:31 INFO - no previously-included directories found matching 'build' 21:55:31 INFO - no previously-included directories found matching 'dist' 21:55:31 INFO - no previously-included directories found matching '.git*' 21:55:31 INFO - Installing collected packages: jsonschema, functools32 21:55:31 INFO - Running setup.py install for functools32 21:55:31 INFO - warning: no files found matching '*.txt' 21:55:31 INFO - no previously-included directories found matching 'build' 21:55:31 INFO - no previously-included directories found matching 'dist' 21:55:31 INFO - no previously-included directories found matching '.git*' 21:55:31 INFO - Successfully installed jsonschema functools32 21:55:31 INFO - Cleaning up... 21:55:31 INFO - Return code: 0 21:55:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:55:31 INFO - Using env: {'DISPLAY': ':0', 21:55:31 INFO - 'HOME': '/home/cltbld', 21:55:31 INFO - 'LANG': 'en_US.UTF-8', 21:55:31 INFO - 'LANGUAGE': 'en_US:en', 21:55:31 INFO - 'LOGNAME': 'cltbld', 21:55:31 INFO - 'MAIL': '/var/mail/cltbld', 21:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:31 INFO - 'MOZ_NO_REMOTE': '1', 21:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:31 INFO - 'NO_EM_RESTART': '1', 21:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:31 INFO - 'PWD': '/builds/slave/test', 21:55:31 INFO - 'SHELL': '/bin/bash', 21:55:31 INFO - 'SHLVL': '1', 21:55:31 INFO - 'TERM': 'linux', 21:55:31 INFO - 'TMOUT': '86400', 21:55:31 INFO - 'USER': 'cltbld', 21:55:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:31 INFO - '_': '/tools/buildbot/bin/python'} 21:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:55:31 INFO - Cleaning up... 21:55:31 INFO - Return code: 0 21:55:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:55:31 INFO - Using env: {'DISPLAY': ':0', 21:55:31 INFO - 'HOME': '/home/cltbld', 21:55:31 INFO - 'LANG': 'en_US.UTF-8', 21:55:31 INFO - 'LANGUAGE': 'en_US:en', 21:55:31 INFO - 'LOGNAME': 'cltbld', 21:55:31 INFO - 'MAIL': '/var/mail/cltbld', 21:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:31 INFO - 'MOZ_NO_REMOTE': '1', 21:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:31 INFO - 'NO_EM_RESTART': '1', 21:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:31 INFO - 'PWD': '/builds/slave/test', 21:55:31 INFO - 'SHELL': '/bin/bash', 21:55:31 INFO - 'SHLVL': '1', 21:55:31 INFO - 'TERM': 'linux', 21:55:31 INFO - 'TMOUT': '86400', 21:55:31 INFO - 'USER': 'cltbld', 21:55:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:31 INFO - '_': '/tools/buildbot/bin/python'} 21:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:32 INFO - Downloading/unpacking blobuploader==1.2.4 21:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - Downloading blobuploader-1.2.4.tar.gz 21:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:55:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:55:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - Downloading docopt-0.6.1.tar.gz 21:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:34 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:34 INFO - Running setup.py install for blobuploader 21:55:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:55:35 INFO - Running setup.py install for requests 21:55:35 INFO - Running setup.py install for docopt 21:55:35 INFO - Successfully installed blobuploader requests docopt 21:55:35 INFO - Cleaning up... 21:55:35 INFO - Return code: 0 21:55:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:35 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:35 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 21:55:35 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 21:55:35 INFO - Using env: {'DISPLAY': ':0', 21:55:35 INFO - 'HOME': '/home/cltbld', 21:55:35 INFO - 'LANG': 'en_US.UTF-8', 21:55:35 INFO - 'LANGUAGE': 'en_US:en', 21:55:35 INFO - 'LOGNAME': 'cltbld', 21:55:35 INFO - 'MAIL': '/var/mail/cltbld', 21:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:35 INFO - 'MOZ_NO_REMOTE': '1', 21:55:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:35 INFO - 'NO_EM_RESTART': '1', 21:55:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:35 INFO - 'PWD': '/builds/slave/test', 21:55:35 INFO - 'SHELL': '/bin/bash', 21:55:35 INFO - 'SHLVL': '1', 21:55:35 INFO - 'TERM': 'linux', 21:55:35 INFO - 'TMOUT': '86400', 21:55:35 INFO - 'USER': 'cltbld', 21:55:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:35 INFO - '_': '/tools/buildbot/bin/python'} 21:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:55:35 INFO - Running setup.py (path:/tmp/pip-J_up34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:55:35 INFO - Running setup.py (path:/tmp/pip-BHT0z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:55:35 INFO - Running setup.py (path:/tmp/pip-9l_kLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:55:35 INFO - Running setup.py (path:/tmp/pip-fYSSnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:55:36 INFO - Running setup.py (path:/tmp/pip-47sV01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:55:36 INFO - Running setup.py (path:/tmp/pip-kcb6ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:55:36 INFO - Running setup.py (path:/tmp/pip-e_rhOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:55:36 INFO - Running setup.py (path:/tmp/pip-U7tPSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:55:36 INFO - Running setup.py (path:/tmp/pip-RMQxwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:55:36 INFO - Running setup.py (path:/tmp/pip-8IKvHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:55:36 INFO - Running setup.py (path:/tmp/pip-PPQfhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:55:36 INFO - Running setup.py (path:/tmp/pip-e9HF5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:55:36 INFO - Running setup.py (path:/tmp/pip-JVJFrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:55:36 INFO - Running setup.py (path:/tmp/pip-TnoF2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:36 INFO - Running setup.py (path:/tmp/pip-OSW18U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:55:36 INFO - Running setup.py (path:/tmp/pip-PB3Lfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:55:36 INFO - Running setup.py (path:/tmp/pip-0RTBUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:55:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:55:36 INFO - Running setup.py install for manifestparser 21:55:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for mozcrash 21:55:37 INFO - Running setup.py install for mozdebug 21:55:37 INFO - Running setup.py install for mozdevice 21:55:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for mozfile 21:55:37 INFO - Running setup.py install for mozhttpd 21:55:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for mozinfo 21:55:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for mozInstall 21:55:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for mozleak 21:55:37 INFO - Running setup.py install for mozlog 21:55:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:55:37 INFO - Running setup.py install for moznetwork 21:55:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Running setup.py install for mozprocess 21:55:38 INFO - Running setup.py install for mozprofile 21:55:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Running setup.py install for mozrunner 21:55:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Running setup.py install for mozscreenshot 21:55:38 INFO - Running setup.py install for moztest 21:55:38 INFO - Running setup.py install for mozversion 21:55:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:55:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:55:38 INFO - Cleaning up... 21:55:38 INFO - Return code: 0 21:55:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:38 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:38 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 21:55:38 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 21:55:38 INFO - Using env: {'DISPLAY': ':0', 21:55:38 INFO - 'HOME': '/home/cltbld', 21:55:38 INFO - 'LANG': 'en_US.UTF-8', 21:55:38 INFO - 'LANGUAGE': 'en_US:en', 21:55:38 INFO - 'LOGNAME': 'cltbld', 21:55:38 INFO - 'MAIL': '/var/mail/cltbld', 21:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:38 INFO - 'MOZ_NO_REMOTE': '1', 21:55:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:38 INFO - 'NO_EM_RESTART': '1', 21:55:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:38 INFO - 'PWD': '/builds/slave/test', 21:55:38 INFO - 'SHELL': '/bin/bash', 21:55:38 INFO - 'SHLVL': '1', 21:55:38 INFO - 'TERM': 'linux', 21:55:38 INFO - 'TMOUT': '86400', 21:55:38 INFO - 'USER': 'cltbld', 21:55:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:38 INFO - '_': '/tools/buildbot/bin/python'} 21:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:55:38 INFO - Running setup.py (path:/tmp/pip-fxZajt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:55:39 INFO - Running setup.py (path:/tmp/pip-4D8nw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:55:39 INFO - Running setup.py (path:/tmp/pip-GGXU9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:55:39 INFO - Running setup.py (path:/tmp/pip-ATGRWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:55:39 INFO - Running setup.py (path:/tmp/pip-LjPDNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:55:39 INFO - Running setup.py (path:/tmp/pip-CqpGab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:55:39 INFO - Running setup.py (path:/tmp/pip-JBaeat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:55:39 INFO - Running setup.py (path:/tmp/pip-q4Q25c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:55:39 INFO - Running setup.py (path:/tmp/pip-Aw01vM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:55:39 INFO - Running setup.py (path:/tmp/pip-k4tLlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:55:39 INFO - Running setup.py (path:/tmp/pip-fa55SQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:55:39 INFO - Running setup.py (path:/tmp/pip-kRx4u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:55:39 INFO - Running setup.py (path:/tmp/pip-3EBJ8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:55:39 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)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:55:39 INFO - Running setup.py (path:/tmp/pip-wKhfsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:39 INFO - Running setup.py (path:/tmp/pip-d6J3ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:40 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)) 21:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:55:40 INFO - Running setup.py (path:/tmp/pip-G5iCL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:55:40 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)) 21:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:55:40 INFO - Running setup.py (path:/tmp/pip-UuGw4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:55:40 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)) 21:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:55:40 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)) 21:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:42 INFO - Downloading blessings-1.6.tar.gz 21:55:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:55:42 INFO - Installing collected packages: blessings 21:55:42 INFO - Running setup.py install for blessings 21:55:42 INFO - Successfully installed blessings 21:55:42 INFO - Cleaning up... 21:55:42 INFO - Return code: 0 21:55:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:55:42 INFO - Reading from file tmpfile_stdout 21:55:42 INFO - Current package versions: 21:55:42 INFO - argparse == 1.2.1 21:55:42 INFO - blessings == 1.6 21:55:42 INFO - blobuploader == 1.2.4 21:55:42 INFO - docopt == 0.6.1 21:55:42 INFO - functools32 == 3.2.3-2 21:55:42 INFO - jsonschema == 2.5.1 21:55:42 INFO - manifestparser == 1.1 21:55:42 INFO - mozInstall == 1.12 21:55:42 INFO - mozcrash == 1.0 21:55:42 INFO - mozdebug == 0.1 21:55:42 INFO - mozdevice == 0.49 21:55:42 INFO - mozfile == 1.2 21:55:42 INFO - mozhttpd == 0.7 21:55:42 INFO - mozinfo == 0.9 21:55:42 INFO - mozleak == 0.1 21:55:42 INFO - mozlog == 3.4 21:55:42 INFO - moznetwork == 0.27 21:55:42 INFO - mozprocess == 0.23 21:55:42 INFO - mozprofile == 0.28 21:55:42 INFO - mozrunner == 6.13 21:55:42 INFO - mozscreenshot == 0.1 21:55:42 INFO - mozsystemmonitor == 0.3 21:55:42 INFO - moztest == 0.7 21:55:42 INFO - mozversion == 1.4 21:55:42 INFO - psutil == 3.1.1 21:55:42 INFO - requests == 1.2.3 21:55:42 INFO - wsgiref == 0.1.2 21:55:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 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 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:42 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 21:55:42 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 21:55:42 INFO - Using env: {'DISPLAY': ':0', 21:55:42 INFO - 'HOME': '/home/cltbld', 21:55:42 INFO - 'LANG': 'en_US.UTF-8', 21:55:42 INFO - 'LANGUAGE': 'en_US:en', 21:55:42 INFO - 'LOGNAME': 'cltbld', 21:55:42 INFO - 'MAIL': '/var/mail/cltbld', 21:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:42 INFO - 'MOZ_NO_REMOTE': '1', 21:55:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:42 INFO - 'NO_EM_RESTART': '1', 21:55:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:42 INFO - 'PWD': '/builds/slave/test', 21:55:42 INFO - 'SHELL': '/bin/bash', 21:55:42 INFO - 'SHLVL': '1', 21:55:42 INFO - 'TERM': 'linux', 21:55:42 INFO - 'TMOUT': '86400', 21:55:42 INFO - 'USER': 'cltbld', 21:55:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:42 INFO - '_': '/tools/buildbot/bin/python'} 21:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 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)) 21:55:42 INFO - Cleaning up... 21:55:42 INFO - Return code: 0 21:55:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f202b563e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b97b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, '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': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', '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 21:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:55:42 INFO - Using env: {'DISPLAY': ':0', 21:55:42 INFO - 'HOME': '/home/cltbld', 21:55:42 INFO - 'LANG': 'en_US.UTF-8', 21:55:42 INFO - 'LANGUAGE': 'en_US:en', 21:55:42 INFO - 'LOGNAME': 'cltbld', 21:55:42 INFO - 'MAIL': '/var/mail/cltbld', 21:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:42 INFO - 'MOZ_NO_REMOTE': '1', 21:55:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:42 INFO - 'NO_EM_RESTART': '1', 21:55:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:42 INFO - 'PWD': '/builds/slave/test', 21:55:42 INFO - 'SHELL': '/bin/bash', 21:55:42 INFO - 'SHLVL': '1', 21:55:42 INFO - 'TERM': 'linux', 21:55:42 INFO - 'TMOUT': '86400', 21:55:42 INFO - 'USER': 'cltbld', 21:55:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:42 INFO - '_': '/tools/buildbot/bin/python'} 21:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:55:43 INFO - Cleaning up... 21:55:43 INFO - Return code: 0 21:55:43 INFO - Running post-action listener: _resource_record_post_action 21:55:43 INFO - Running post-action listener: _start_resource_monitoring 21:55:43 INFO - Starting resource monitoring. 21:55:43 INFO - [mozharness: 2016-11-29 05:55:43.151918Z] Finished create-virtualenv step (success) 21:55:43 INFO - [mozharness: 2016-11-29 05:55:43.152395Z] Running install step. 21:55:43 INFO - Running pre-action listener: _resource_record_pre_action 21:55:43 INFO - Running main action method: install 21:55:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:55:43 INFO - Reading from file tmpfile_stdout 21:55:43 INFO - Detecting whether we're running mozinstall >=1.0... 21:55:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:55:43 INFO - Reading from file tmpfile_stdout 21:55:43 INFO - Output received: 21:55:43 INFO - Usage: mozinstall [options] installer 21:55:43 INFO - Options: 21:55:43 INFO - -h, --help show this help message and exit 21:55:43 INFO - -d DEST, --destination=DEST 21:55:43 INFO - Directory to install application into. [default: 21:55:43 INFO - "/builds/slave/test"] 21:55:43 INFO - --app=APP Application being installed. [default: firefox] 21:55:43 INFO - mkdir: /builds/slave/test/build/application 21:55:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:56:00 INFO - Reading from file tmpfile_stdout 21:56:00 INFO - Output received: 21:56:00 INFO - /builds/slave/test/build/application/firefox/firefox 21:56:00 INFO - Running post-action listener: _resource_record_post_action 21:56:00 INFO - [mozharness: 2016-11-29 05:56:00.446807Z] Finished install step (success) 21:56:00 INFO - [mozharness: 2016-11-29 05:56:00.447047Z] Running run-tests step. 21:56:00 INFO - Running pre-action listener: _resource_record_pre_action 21:56:00 INFO - Running main action method: run_tests 21:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:56:00 INFO - Python 2.7.3 21:56:00 INFO - Return code: 0 21:56:00 INFO - grabbing minidump binary from tooltool 21:56:00 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')]} 21:56:00 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 0x1dc1fc0>, '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 0x1dc2ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:56:00 INFO - Return code: 0 21:56:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:56:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:56:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:56:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:56:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:56:00 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/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 21:56:00 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/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 21:56:00 INFO - Using env: {'DISPLAY': ':0', 21:56:00 INFO - 'HOME': '/home/cltbld', 21:56:00 INFO - 'LANG': 'en_US.UTF-8', 21:56:00 INFO - 'LANGUAGE': 'en_US:en', 21:56:00 INFO - 'LOGNAME': 'cltbld', 21:56:00 INFO - 'MAIL': '/var/mail/cltbld', 21:56:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:56:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:00 INFO - 'MOZ_NO_REMOTE': '1', 21:56:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:00 INFO - 'NO_EM_RESTART': '1', 21:56:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:00 INFO - 'PWD': '/builds/slave/test', 21:56:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:56:00 INFO - 'SHELL': '/bin/bash', 21:56:00 INFO - 'SHLVL': '1', 21:56:00 INFO - 'TERM': 'linux', 21:56:00 INFO - 'TMOUT': '86400', 21:56:00 INFO - 'USER': 'cltbld', 21:56:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363', 21:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:00 INFO - '_': '/tools/buildbot/bin/python'} 21:56:00 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/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 21:56:00 INFO - mozversion application_buildid: 20161128073209 21:56:00 INFO - mozversion application_changeset: bc4ed3faa33e589fcc1b86221d837b98af3551b7 21:56:00 INFO - mozversion application_display_name: Nightly 21:56:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:56:00 INFO - mozversion application_name: Firefox 21:56:00 INFO - mozversion application_remotingname: firefox 21:56:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:56:00 INFO - mozversion application_vendor: Mozilla 21:56:00 INFO - mozversion application_version: 53.0a1 21:56:00 INFO - mozversion platform_buildid: 20161128073209 21:56:00 INFO - mozversion platform_changeset: bc4ed3faa33e589fcc1b86221d837b98af3551b7 21:56:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:56:00 INFO - mozversion platform_version: 53.0a1 21:56:00 INFO - using testdate: 1480398960 21:56:00 INFO - actual date: 1480398960 21:56:00 INFO - starting webserver on 'localhost:44065' 21:56:00 INFO - SUITE-START | Running 2 tests 21:56:00 INFO - TEST-START | dromaeo_css 21:56:00 INFO - operating with platform_type : linux_ 21:56:00 INFO - Initialising browser for dromaeo_css test... 21:56:00 INFO - TEST-INFO | started process 8596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2TrLS7/profile http://localhost:44065/getInfo.html) 21:56:04 INFO - PROCESS | 8596 | __metrics Screen width/height:1600/1200 21:56:04 INFO - PROCESS | 8596 | colorDepth:24 21:56:04 INFO - PROCESS | 8596 | Browser inner width/height: 1024/697 21:56:04 INFO - PROCESS | 8596 | __metrics 21:56:05 INFO - PROCESS | 8596 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:56:05 INFO - PROCESS | 8596 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:56:06 INFO - PROCESS | 8596 | console.error: PushService: 21:56:06 INFO - PROCESS | 8596 | stateChangeProcessEnqueue: Error transitioning state 21:56:06 INFO - PROCESS | 8596 | UnknownError 21:56:06 INFO - TEST-INFO | 8596: exit 0 21:56:06 INFO - Browser initialized. 21:56:06 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:06 INFO - TEST-INFO | started process 8679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2TrLS7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:18 INFO - PROCESS | 8679 | RSS: Main: 173076480 21:56:18 INFO - PROCESS | 8679 | 21:57:10 INFO - PROCESS | 8679 | 1480399030084 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] 21:59:21 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44065/tests/dromaeo/cssquery-ext.html) 21:59:23 INFO - PROCESS | 8679 | RSS: Main: 357306368 21:59:23 INFO - PROCESS | 8679 | 22:02:26 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-ext.html (next: http://localhost:44065/tests/dromaeo/cssquery-jquery.html) 22:02:26 INFO - PROCESS | 8679 | RSS: Main: 282476544 22:02:26 INFO - PROCESS | 8679 | 22:05:28 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44065/tests/dromaeo/cssquery-mootools.html) 22:05:29 INFO - PROCESS | 8679 | RSS: Main: 221118464 22:05:29 INFO - PROCESS | 8679 | 22:08:31 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44065/tests/dromaeo/cssquery-prototype.html) 22:08:31 INFO - PROCESS | 8679 | RSS: Main: 250429440 22:08:31 INFO - PROCESS | 8679 | 22:11:34 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44065/tests/dromaeo/cssquery-yui.html) 22:11:35 INFO - PROCESS | 8679 | RSS: Main: 224489472 22:11:35 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | Cycle 1(1): loaded http://localhost:44065/tests/dromaeo/cssquery-yui.html (next: http://localhost:44065/tests/dromaeo/cssquery-dojo.html) 22:14:38 INFO - PROCESS | 8679 | RSS: Main: 309825536 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | __start_tp_report 22:14:38 INFO - PROCESS | 8679 | _x_x_mozilla_page_load 22:14:38 INFO - PROCESS | 8679 | _x_x_mozilla_page_load_details 22:14:38 INFO - PROCESS | 8679 | |i|pagename|runs| 22:14:38 INFO - PROCESS | 8679 | |0;dojo.html;3069.4935335669616;3073.569700241965;3110.8444577771106;3125.8590614425743;3133.420317241309;5204.635675502716;5256.1590145576665;5259.631825772198;5318.282031925689;5328.786848526059;5311.73441327934;5314.734263286839;5330.946690533103;5338.172583249593;5345.518903298703;4803.327534145214;4820.39745031871;4831.347768051303;4856.830010949618;4907.509249075099;4899.363082799231;4908.680935739166;4929.162042452792;4936.136176169174;4944.332515110468;4872.196087645534;4922.433920099181;4922.8523144305545;4934.851954441372;4937.7777999990085;4951.5791157751455;4956.355673762424;4961.975190124026;4968.080905032581;5013.47358527353;4701.952980470186;4756.6432517561325;4827.420709514846;4843.443004054544;4864.513548645107;5305.442928492491;5316.415194328621;5322.494363035506;5343.145096784496;5407.161889907032;5677.432256774331;5722.113072473773;5725.885482290331;5729.856753581152;5740.913886291709;5533.08704063829;5567.610267281292;5659.773609055633;5749;5752.942470575283;5312.734363281859;5345.305110335631;5346.144616861361;5388.703621300788;5389.272448219479;5805.187273781674;5809.448102430262;5813.128030795415;5814.156947242711;5818.650880947157;5377.327834020747;5402.405735369185;5603.495685388335;5608.4111168326735;5609.4390560943575;5657.858553536194;5689.203511508392;5690.829275121753;5718.79984200551;5772.422757724194;5644.74598643062;5696.572757043155;5699.373068962411;5703.629264097904;5709.257796486513;5504.6421982571;5716.399778023331;5726.713664316768;5727.656340619576;5729.398413166641;4295.871123866289;4347.500037495728;4394.2749446340995;4438.511763705989;4441.755703436277;3956.9802150989062;3966.781826999543;3994.500687414073;4018.33697439925;4019.31671615826;5430.891382172414;5453.400125986138;5468.7539060742365;5472.972635136878;5479.589030822704;5538.556915446755;5575.498205161555;5672.602917795714;5756.107803290497;5805.448482394166;5407.567394608422;5432.02223599748;5439.864003399946;5476.7535460904355;5482.369527504388;4941.407031156213;4982.402111746614;4991.126552853236;5003.849884503471;5039.26930595069;5790.218320526675;5836.241288632451;5907.4387933148;5960.64236145824;5970.283565972111;5656.2929633795775;5659.03796354629;5678.290213723285;5710.600257981901;5771.422857714363;5866;5866.882662346644;5871.970640146772;5882.558808089496;5889.234399528034;5444.918326225031;5580.1629755537;5584.078627026495;5590.8043218487155;5593.524550413261;5565;5599.832005039692;5641.605087643826;5658.519025882846;5667.319921609268;5467.291575644507;5506.807261745819;5552.805651802168;5556.333240011225;5575.553955683617;5611.579131565068;5620.718964051863;5627.437256274504;5666.660000399989;5669.801556945487;5608.186812912187;5612.438756124355;5614.578906581941;5621.409751975983;5622.85942851432;5524.4199359066715;5572.777088916398;5578.86052848682;5657.688827114548;5661.518770904355;5380.139177731544;5413.1880217966045;5518.834434966797;5583.050881350261;5600.635958662674 22:14:38 INFO - PROCESS | 8679 | |1;ext.html;529.5469232426224;578.5227187570255;595.553334998751;595.5801160182073;595.8957182493064;31792.36415271696;41128.794356028215;41863.162736745246;41917.161656766846;41947.37078943818;42949.35575966363;43085.353719694154;43307.35038974418;43422.7828860856;43475.3478697819;8011.839763204733;8191.058028326729;8483.600205966008;8716.520591367473;8722.433041852291;10072.798544029116;10354.171666266719;10356.792864142713;10645.52095155716;10656.840147397796;10381.325213861075;10474.842877356808;10477.947610261937;10480.213983951196;10487.528061237263;10371.429571373572;10413.53139108742;10414.062734353869;10417;10429.582816687323;10274.845877311845;10311.123554497877;10349.327293725884;10353.327033742782;10363.067323940806;39682.40476392827;39901.40147897784;39940;39961;39982.40026399606;3386.136535183537;3624.1120925373452;3812.390017597199;3885.689144868432;4022.275990321741;30774.84612576945;31021.689783102105;31027.844860775553;31060.223494412596;32791.18022049444;4110.951707314645;4165.500139983191;4210.115875666113;4341.261985462479;4359.738415695068;342404;342434;342730;343481;343984;55281;55336.72331638316;55750.442495575335;55753.72123139358;55754.442455575736;57048.71475642595;57360;57415.42584574184;57576;57585.42414575801;16204.513864584082;16390.754138687927;16401.83598164027;16410;16431;11871.940640296743;12129.21160124589;12340.136190466581;13051.086423950232;13123;14872.405103795727;15586;15594.454194103379;15613.921930390276;15626.45307414235;5937.406259374028;6428.967855160788;6431.13179720741;6469.353064693587;6782.796516104525;923.5382308845577;998.2463240253562;1013.9493025348703;1067;1144.6909334479644;31536.684633153833;32011;32141.196470088435;32513.837430813168;32557.67442325594;1055.2507719519074;1061.9628313009007;1067.3702515515884;1079.6221322537049;1087.7389426537575;16088.678226435406;16768.245428955724;16801.24394402255;16886.155692215587;16923.238454269587;490132;495880;503885;505188;506527;51017.48982510201;51590.48409515932;53551.73224133854;54144.18783718247;54609.45390546123;1338.136901698399;1354.4717560151548;1376.0367742580434;1376.986230137706;1380.1856904426436;30163.396732065674;33036.33927321488;33066.66933330636;33178;33263;49811.50188498069;50314.74842625763;50439.24341134813;50484.49515504798;50498.24252636287;334839;335461;335479;335766;336904;38269;39179.41230881596;39461.40807887827;39462.21075578415;39492.60507394889;44830.55169448394;45185.548144518136;45435.31847022363;45692.77153614211;46320;38804.80597596994;39235.41146882856;39675.404868927566;39847.60152398439;40608.59391406048;38319.42520862134;38972;39150.60849391584;39687;41149.79425102855;38461.80769096137;38725.41911871268;39288.803555982035;39312.2137557253;39700.80149599234;33274.33451331008;34723.13192170216;35503.28909635905;35795.463068053476;35873.82063089668;26899.86550067237;27351.452970940867;27390.726092738816;27430.725692743614;27500.312492187855 22:14:38 INFO - PROCESS | 8679 | |2;jquery.html;378.15482396843066;392.1959982036824;405.4000079881775;408.5893676854761;418.62114786118553;13297.933510332447;15246;18692.99999999998;19127.61744765104;19369;18736.531586710338;19161.712574311397;19195.040247987614;19386.51533711658;19413.611727765438;4525.660575456837;5472.370677372103;5716.742746576414;5719.342275638292;5724.513416359594;5590.580706447013;5628.634138780986;5674.829755107354;5679.772809087653;5687.090065589507;5450.04624190767;5549.889002219953;5639.097744360903;5644.294463192101;5648.181013753024;5523.447655234465;5534;5560.388357280696;5564.972175139139;5566.972165139168;4945;4973;5000.899982000376;5002.799888004475;5174.689518628894;13896.513622023282;14122.364493597814;14138.363773630212;14150.221737804417;21831;2073.7615174255;2083.864548804323;2112.36629011296;2151.612709712251;2165.6101901657694;9501.42991420517;10092.798144037079;10440.32137911041;10547.99794019567;10721.30311529748;1827.232562323827;1901.5911579010578;1903.2101677803694;1924.0668275886185;1927.152053096647;72750.63624681896;85587.57206213991;86345;86375.56812215962;87083.84172912684;10805.459727013618;10904.890951090547;10974.798096695064;10982.505787239434;11334.773304533863;15412.768808467657;15419.537413877602;15455.53633391;15462.84537154614;15474.613134671723;7977.680892764224;8102.06826214981;8204.8359032819;8369.455985361054;8391.202835730595;7710.537367757953;7759.844803103906;7788.805279868048;7989.2809647132035;7991.960040199761;4667.62658987287;4783.282507623815;4816.494268101869;4885.706857588556;4902.2156454967035;1915.7796853362042;1984.6328429240348;1991.9601607967759;2008.2870580943847;2065.7417822772154;849.3460035772545;989.248171389749;1025.9640912568025;1036.6475398364441;1068.7274744939991;18772.24911003572;18818;19048.80951190498;19049.428517144508;19297.42107736742;933.5565606337071;945.9858102128474;991.6628346362271;992.5136683025265;1025.7692019295748;9818.950905245429;10374.792504149875;11039.227254092137;11276.605318813803;11387.430628468543;226435;333177;429910;433073;441817;93848.53075734578;94149;94628;95441;95641;1009.2077718990557;1011.9949400252951;1033.8862725100232;1037.2479952034864;1065.5737704918095;16664.50006499807;16755.32978680839;16881;17705.203265853324;17960.371387001393;41841.58158418377;43338.349924750524;43544;43831.78084109559;43866.56133438625;53579.73210133924;54015.45984540261;58281.70859145677;58299;59444.405555943886;8209.835803283782;8360.28937540316;8397.202265784741;8441.746747597583;8580.0561938188;11903.404829758305;12839.871601283867;12932.935335323264;12951.417186226454;13265.403056862271;10839.295445795999;11176.27354221973;11369.43152842338;11724.94137529341;11904.940475297568;11015.944920275348;11229.494672739584;11331.830022549504;11686;11909.940450297692;11241.156913231376;11344.886551134383;11395.544178233109;11410.201285909907;12080.939595301967;8132.105468398353;8494.1081186477;8670.393072484865;8736.301095912439;8884.644614215358;9962.750931226778;11252;11704.297742135499;11752.588659397223;11829.231099978819 22:14:38 INFO - PROCESS | 8679 | |3;mootools.html;1334.7509446582503;1426.0445501513984;1474.498670452046;1541.868941140003;1701.5916180116776;7265;8344.95827520863;8506.447080939735;8513.531755753438;8760.649574017032;8517.23344898959;8518.659253629863;8589.91410085899;8595.31237501;8609.870851937227;2053.5995480881234;2086.3636590839787;2348.260298006131;2382.9038642224596;2384.332386931662;2291.9312420627407;2359.4219416243045;2366.4202270443766;2370.7747763962398;2372.7271363793125;2294.724633044029;2335.6730057791924;2343.297010896733;2348.6007378745567;2350.330155905567;2350.9177178798745;2363.550925324183;2368.218487898989;2371.430856594422;2374.608189648706;2273.9658905116435;2304.8847557622053;2308.8845557722216;2310.87290199039;2312.456572705413;8702.825943481095;8749.168828961303;8752.212300892887;8763.868541971875;8769.517676527788;5237.711925844077;5333.173358129496;5349.625526213166;5356.464353564651;5398.78404863805;3205.4871220604823;3309.3050459403553;3318.3363327334628;3468.2023134679166;3479.5824501060033;1114.5430373546887;1131.5247596009613;1137.9089672826153;1170.356304032787;1182.6688527212393;11899.881001189964;11964.04287656994;11986.400679965967;12008;12015.459304331325;4557.886052848706;4607.608353289941;4706.058788242365;4714.104320179224;4828.227483602606;10106.747331316776;10111.443870587042;10116.190704743603;10122.949385253027;10129.544170512345;5376.220448035091;5386.703731294817;5390.9191362129595;5392.191171324333;5394.730263486888;4735.881602959954;4872.756362181876;4906.582940450102;4909.754512274372;4916.729579873141;3807.2575847709986;3901.180752042103;3914.549826769901;3945.684345252373;3999.300122478555;1250.6748245456065;1265.791144461172;1308.2346827106248;1319.8152258683792;1331.0483004651528;869.7869022089502;873.5413907698382;875.9562021898879;917.6788124156492;949.2548349545574;5550.278463799761;5639.154126881001;5690.715464226772;5697.800576979786;5854.619449735754;890.2877697841831;908.3732224764891;922.9400088994328;926.6941909169958;983.6360546597805;3591.8383672734267;3660.103274697663;3740.158464345555;3749.49381833449;3763.0780458787226;10496.842547361795;10697.197710171768;10779.622713205;10780.568777248978;10795.136389088853;10669.73325666849;10670.57317707283;10682.51928663212;10723.46382680863;10762;8050.355971522265;8150.592470376458;8166.265036146657;8167.714130005421;8178;5382.650127741685;5484.287042684426;5558.3607885092115;5636.323641163087;5704.229928959703;9695.903040969783;9744.220462362851;9814.75463113428;9898.653547125816;9923.354981926152;8560.785980350292;9383.530823458936;9689.854652180082;9703.02969703024;9938.204943604349;6527.314631963575;7010.263922288086;7086.326798954091;7126.465515086285;7140.9285907140265;7997.200279971957;8114.513129212266;8208.507489550408;8285.25432711059;8361.790955226168;7299.270072992871;7471.551706897603;7475.140358858584;7549;7553.697852085855;6901.965490172718;6960.129983752031;6994.545354551938;7020.473464490082;7104.786856394317;6664.266930637723;6671.299513551007;6714.093597364294;6730.158730158731;6747.392734653708;5049.974750126225;5080.745962701776;5151.484851514819;5208.374995000624;5276.07668657991;4807.687500312469;4923.212286034217;5081.669691470042;5106.4893510648635;5319.574434045345 22:14:38 INFO - PROCESS | 8679 | |4;prototype.html;316.4414807864119;325.7426632959962;327.1412542077047;327.4008564327281;332.19608547315494;5162.638615296931;5234.581233501321;5273.129933560967;5310.973445132779;5322.467753224676;5123.1546794778815;5163.767630456629;5219.217117432387;5227.503387178212;5243.4232234454175;4103.958960410388;4104.399284279119;4108.917821643566;4114.382842573608;4123.587641235875;4054.756714597119;4122.278601244785;4149.751014939114;4163.521195062576;4176.728512646683;4073.205724883649;4082.8775136745944;4084.7957602119923;4093.713440059197;4121.381792731099;4038.676905847525;4055.695822813286;4057.411675307064;4058.2346679194497;4087.7532352632434;3598.47822065799;3616.692581130607;3618.149734812317;3631;3669.412893936957;4936.531029552187;5076.263941728429;5091.490850914916;5096.362954630671;5135.974320128413;4657.464391594942;4659.976700116477;4668.6731928765;4677.438707355105;4681.719096854165;5419.34967803866;5427.9185812212845;5429.592780541475;5432.864178395532;5442.863928401822;3782.7730336179916;3919.4708714323488;3946.9013274668077;3963.9009024774323;4011.5186177658866;5631.8028868989795;5640.600331741249;5661.7735290588325;5663.603547751659;5666.801661941812;4001.8999525011527;4007.054814711185;4020.8592699255387;4050.8784736457364;4072.045418199281;3964.246793109358;4051.9942628398544;4055.6552693020835;4107.876763697094;4110.280700877334;4644.256918892961;4647.697899636512;4653.604443622262;4662.813487460531;4672.696274742131;5493.450654934474;5498.532624726863;5508.512701570634;5537.141743029837;5538.667679939216;1295.4494339905502;1320.518010926005;1328.9468421263234;1354.73582651384;1365.3855764905832;942.4977181634268;946.8059047895165;953.823542644613;962.4562122400961;963.7012526116786;5115.97442012795;5117.669405954479;5130.48695130484;5145.787311456035;5152.896942061137;5426.399212232401;5443.673379597237;5444.237806707064;5448.237246785374;5486.588505862076;4865.270209468608;5097.515736005073;5124;5173.844784656466;5194.909069095456;2873.109336105814;2879.452903948243;2880.3663194097267;2880.4671135839517;2886.5670149477746;5528.585356098309;5537.7784888604;5614.568285087358;5623.943760562423;5697.971510142423;5197.558207552324;5433.429489903582;5496.196939560773;5498.670079795225;5500.50495455403;5546.334439867242;5581.855719577558;5594.552435805207;5628;5653.321601407857;5195.636305458582;5387.784488620411;5408.78364865401;5431.429699881455;5442.700651464207;5334.333208348957;5343.866403339792;5349.8230389314285;5378.247045413567;5407;5551.555875529867;5570.688164411724;5577.247071645276;5598.124628249582;5672.290963629546;3759.4924685167152;3862.407960596672;3885.8445662173194;3891.01558205323;3911.125322887732;5358.97320513395;5404.919196058209;5415.620906536662;5425.023495770799;5436.3476382834315;5352.143657014859;5413.512783849473;5486.780528778964;5502.724863756716;5535.557155427637;5451.400345962021;5474.425185355481;5494.890102197854;5497.8075767347955;5505.504504594605;5255.921161182662;5441.7279136043835;5470;5473;5482.972585137049;5515.841673248502;5516.448355164452;5558.97220513911;5565.916511252253;5577.44225577439;5462.9726851365485;5505.284313039279;5506.834794956158;5511.063119269654;5527.944720552904 22:14:38 INFO - PROCESS | 8679 | |5;yui.html;699.9230084690686;709.948249558775;715.8568286342733;721.4516967105;723.4176487927219;14011.579652610413;14899.25503724815;14931;17195.312187512514;17962.910185449055;16833.663326733487;16858.072805995664;16969.74545381817;16992.660146797058;17026.74459883103;666.2105405094953;782.7143092771126;801.9198080191964;802.9317508011826;803.2088392932957;678.630146570118;785.0814546980047;787.7518581646791;791.6754130806371;805.9194080591923;823.7899335669426;826.6321486938315;826.7726375246795;829.8008477965305;832.675256649907;692.1763101908713;821.1501096365233;823.6293667849492;823.7693445835175;824.4558591329724;513.1609817947646;596.9701514924236;598.512212546773;607.4927435591287;615.4399496458202;15275.541733748005;15455.690886182214;15506.61233469161;15572.221388930508;15582.298796554069;13891.374888130058;13928.442862285598;14169.645758856008;14255.643608909757;14264.429422823074;12493.687657808536;12661.177023493443;12767.680807979781;13114.606561803066;13188;4275.572442755731;4275.8075886585175;4357.046846218954;4487.573680500347;4505.932411013838;86602.13397866004;87327.56336218279;87452.5627371859;89244.5537772307;90742.54628726881;10838.07876330505;10967.670969870916;11293.887061129284;11308.943455282835;11328.09375249978;19298.517537061685;19403.320883769;19427.51431214231;19439;19450.221991120197;7759.72840950564;7900.565468899151;7968.681252749941;8106.675732970615;8194.754157375288;4335.393044973706;4379.4525684289465;4513.54864513546;4537.3647689323525;4662.790174442158;3323.152596087982;3370.5449764282;3409.130671678756;3410.6418826023405;3413.0272872231294;568.567339075141;569.3509399284818;570.4352690836042;573.1488739222251;579.623244890816;556.2907293201201;557.2783245696833;627.6892937995722;659.4724220623577;665.7070888808909;9009.819803603892;9312.90687093134;9547.61809527625;9653.71038868835;10090.69727908164;623.9001759718514;688.3942130924754;718.7585080315839;718.9784306470814;722.9024081748896;3930.5872883346838;4205.2640787862;4244.808983595745;4300.247456695129;4326.437563116775;68539.65730171317;70007;71450.2854971454;77760.61119694365;82753.58623206845;67756.32243677501;68112.21046211128;68619.65690171518;69179.3082069183;69757.65121174463;64608.571186330904;65035.14683678397;65979.34020659924;66201.6689916557;67436.6628166856;9212.95393523055;9304.16262536375;9486.95256523713;9567.282453816131;9666;44041.559584405026;44875.77562112299;45592.54407455883;46327.073458529965;46695.533044670476;51886.22170667368;53724.46275537195;55151.07280689514;61566.384336157855;62757.37242627515;13101.279429631462;13120.212787232796;13555.932220338835;13654.931725341707;14058.156510609397;14738.55784326472;14948.925255374088;15197.240137992834;15210.631043206164;15539.844601554292;12610.36948152607;12639.810402844148;12708.173968692005;13024;13158.60524184276;12791.232526048467;12925.482980681043;13375.464981400635;13580.592582222549;14039.649008774453;13017.153884997444;13111.541096061974;13606.455741770218;13965.930170349084;14054.929725351309;10862.619808306672;10971.74335195966;10979;11273.830892536782;11392.54429822831;10938.453077345941;11074.280171788809;11128.38793866345;11324.547018119183;11381.317120972768 22:14:38 INFO - PROCESS | 8679 | __end_tp_report 22:14:38 INFO - PROCESS | 8679 | __start_cc_report 22:14:38 INFO - PROCESS | 8679 | _x_x_mozilla_cycle_collect,1768 22:14:38 INFO - PROCESS | 8679 | __end_cc_report 22:14:38 INFO - PROCESS | 8679 | __startTimestamp1480400078636__endTimestamp 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | ------- Summary: start ------- 22:14:38 INFO - PROCESS | 8679 | Number of tests: 6 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#0] dojo.html Cycles:165 Average:5306.50 Median:5506.81 stddev:581.37 (10.6%) stddev-sans-first:556.04 22:14:38 INFO - PROCESS | 8679 | Values: 3069.5 3073.6 3110.8 3125.9 3133.4 5204.6 5256.2 5259.6 5318.3 5328.8 5311.7 5314.7 5330.9 5338.2 5345.5 4803.3 4820.4 4831.3 4856.8 4907.5 4899.4 4908.7 4929.2 4936.1 4944.3 4872.2 4922.4 4922.9 4934.9 4937.8 4951.6 4956.4 4962.0 4968.1 5013.5 4702.0 4756.6 4827.4 4843.4 4864.5 5305.4 5316.4 5322.5 5343.1 5407.2 5677.4 5722.1 5725.9 5729.9 5740.9 5533.1 5567.6 5659.8 5749.0 5752.9 5312.7 5345.3 5346.1 5388.7 5389.3 5805.2 5809.4 5813.1 5814.2 5818.7 5377.3 5402.4 5603.5 5608.4 5609.4 5657.9 5689.2 5690.8 5718.8 5772.4 5644.7 5696.6 5699.4 5703.6 5709.3 5504.6 5716.4 5726.7 5727.7 5729.4 4295.9 4347.5 4394.3 4438.5 4441.8 3957.0 3966.8 3994.5 4018.3 4019.3 5430.9 5453.4 5468.8 5473.0 5479.6 5538.6 5575.5 5672.6 5756.1 5805.4 5407.6 5432.0 5439.9 5476.8 5482.4 4941.4 4982.4 4991.1 5003.8 5039.3 5790.2 5836.2 5907.4 5960.6 5970.3 5656.3 5659.0 5678.3 5710.6 5771.4 5866.0 5866.9 5872.0 5882.6 5889.2 5444.9 5580.2 5584.1 5590.8 5593.5 5565.0 5599.8 5641.6 5658.5 5667.3 5467.3 5506.8 5552.8 5556.3 5575.6 5611.6 5620.7 5627.4 5666.7 5669.8 5608.2 5612.4 5614.6 5621.4 5622.9 5524.4 5572.8 5578.9 5657.7 5661.5 5380.1 5413.2 5518.8 5583.1 5600.6 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#1] ext.html Cycles:180 Average:55802.46 Median:3718.25 stddev:105732.42 (2843.6%) stddev-sans-first:105947.58 22:14:38 INFO - PROCESS | 8679 | Values: 529.5 578.5 595.6 595.6 595.9 31792.4 41128.8 41863.2 41917.2 41947.4 42949.4 43085.4 43307.4 43422.8 43475.3 8011.8 8191.1 8483.6 8716.5 8722.4 10072.8 10354.2 10356.8 10645.5 10656.8 10381.3 10474.8 10477.9 10480.2 10487.5 10371.4 10413.5 10414.1 10417.0 10429.6 10274.8 10311.1 10349.3 10353.3 10363.1 39682.4 39901.4 39940.0 39961.0 39982.4 3386.1 3624.1 3812.4 3885.7 4022.3 30774.8 31021.7 31027.8 31060.2 32791.2 4111.0 4165.5 4210.1 4341.3 4359.7 342404.0 342434.0 342730.0 343481.0 343984.0 55281.0 55336.7 55750.4 55753.7 55754.4 57048.7 57360.0 57415.4 57576.0 57585.4 16204.5 16390.8 16401.8 16410.0 16431.0 11871.9 12129.2 12340.1 13051.1 13123.0 14872.4 15586.0 15594.5 15613.9 15626.5 5937.4 6429.0 6431.1 6469.4 6782.8 923.5 998.2 1013.9 1067.0 1144.7 31536.7 32011.0 32141.2 32513.8 32557.7 1055.3 1062.0 1067.4 1079.6 1087.7 16088.7 16768.2 16801.2 16886.2 16923.2 490132.0 495880.0 503885.0 505188.0 506527.0 51017.5 51590.5 53551.7 54144.2 54609.5 1338.1 1354.5 1376.0 1377.0 1380.2 30163.4 33036.3 33066.7 33178.0 33263.0 49811.5 50314.7 50439.2 50484.5 50498.2 334839.0 335461.0 335479.0 335766.0 336904.0 38269.0 39179.4 39461.4 39462.2 39492.6 44830.6 45185.5 45435.3 45692.8 46320.0 38804.8 39235.4 39675.4 39847.6 40608.6 38319.4 38972.0 39150.6 39687.0 41149.8 38461.8 38725.4 39288.8 39312.2 39700.8 33274.3 34723.1 35503.3 35795.5 35873.8 26899.9 27351.5 27390.7 27430.7 27500.3 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#2] jquery.html Cycles:180 Average:25550.04 Median:124133.00 stddev:64051.66 (51.6%) stddev-sans-first:64203.46 22:14:38 INFO - PROCESS | 8679 | Values: 378.2 392.2 405.4 408.6 418.6 13297.9 15246.0 18693.0 19127.6 19369.0 18736.5 19161.7 19195.0 19386.5 19413.6 4525.7 5472.4 5716.7 5719.3 5724.5 5590.6 5628.6 5674.8 5679.8 5687.1 5450.0 5549.9 5639.1 5644.3 5648.2 5523.4 5534.0 5560.4 5565.0 5567.0 4945.0 4973.0 5000.9 5002.8 5174.7 13896.5 14122.4 14138.4 14150.2 21831.0 2073.8 2083.9 2112.4 2151.6 2165.6 9501.4 10092.8 10440.3 10548.0 10721.3 1827.2 1901.6 1903.2 1924.1 1927.2 72750.6 85587.6 86345.0 86375.6 87083.8 10805.5 10904.9 10974.8 10982.5 11334.8 15412.8 15419.5 15455.5 15462.8 15474.6 7977.7 8102.1 8204.8 8369.5 8391.2 7710.5 7759.8 7788.8 7989.3 7992.0 4667.6 4783.3 4816.5 4885.7 4902.2 1915.8 1984.6 1992.0 2008.3 2065.7 849.3 989.2 1026.0 1036.6 1068.7 18772.2 18818.0 19048.8 19049.4 19297.4 933.6 946.0 991.7 992.5 1025.8 9819.0 10374.8 11039.2 11276.6 11387.4 226435.0 333177.0 429910.0 433073.0 441817.0 93848.5 94149.0 94628.0 95441.0 95641.0 1009.2 1012.0 1033.9 1037.2 1065.6 16664.5 16755.3 16881.0 17705.2 17960.4 41841.6 43338.3 43544.0 43831.8 43866.6 53579.7 54015.5 58281.7 58299.0 59444.4 8209.8 8360.3 8397.2 8441.7 8580.1 11903.4 12839.9 12932.9 12951.4 13265.4 10839.3 11176.3 11369.4 11724.9 11904.9 11015.9 11229.5 11331.8 11686.0 11909.9 11241.2 11344.9 11395.5 11410.2 12080.9 8132.1 8494.1 8670.4 8736.3 8884.6 9962.8 11252.0 11704.3 11752.6 11829.2 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#3] mootools.html Cycles:180 Average:5636.26 Median:5128.99 stddev:3173.60 (61.9%) stddev-sans-first:3166.04 22:14:38 INFO - PROCESS | 8679 | Values: 1334.8 1426.0 1474.5 1541.9 1701.6 7265.0 8345.0 8506.4 8513.5 8760.6 8517.2 8518.7 8589.9 8595.3 8609.9 2053.6 2086.4 2348.3 2382.9 2384.3 2291.9 2359.4 2366.4 2370.8 2372.7 2294.7 2335.7 2343.3 2348.6 2350.3 2350.9 2363.6 2368.2 2371.4 2374.6 2274.0 2304.9 2308.9 2310.9 2312.5 8702.8 8749.2 8752.2 8763.9 8769.5 5237.7 5333.2 5349.6 5356.5 5398.8 3205.5 3309.3 3318.3 3468.2 3479.6 1114.5 1131.5 1137.9 1170.4 1182.7 11899.9 11964.0 11986.4 12008.0 12015.5 4557.9 4607.6 4706.1 4714.1 4828.2 10106.7 10111.4 10116.2 10122.9 10129.5 5376.2 5386.7 5390.9 5392.2 5394.7 4735.9 4872.8 4906.6 4909.8 4916.7 3807.3 3901.2 3914.5 3945.7 3999.3 1250.7 1265.8 1308.2 1319.8 1331.0 869.8 873.5 876.0 917.7 949.3 5550.3 5639.2 5690.7 5697.8 5854.6 890.3 908.4 922.9 926.7 983.6 3591.8 3660.1 3740.2 3749.5 3763.1 10496.8 10697.2 10779.6 10780.6 10795.1 10669.7 10670.6 10682.5 10723.5 10762.0 8050.4 8150.6 8166.3 8167.7 8178.0 5382.7 5484.3 5558.4 5636.3 5704.2 9695.9 9744.2 9814.8 9898.7 9923.4 8560.8 9383.5 9689.9 9703.0 9938.2 6527.3 7010.3 7086.3 7126.5 7140.9 7997.2 8114.5 8208.5 8285.3 8361.8 7299.3 7471.6 7475.1 7549.0 7553.7 6902.0 6960.1 6994.5 7020.5 7104.8 6664.3 6671.3 6714.1 6730.2 6747.4 5050.0 5080.7 5151.5 5208.4 5276.1 4807.7 4923.2 5081.7 5106.5 5319.6 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#4] prototype.html Cycles:180 Average:4563.70 Median:5223.36 stddev:1327.85 (25.4%) stddev-sans-first:1292.74 22:14:38 INFO - PROCESS | 8679 | Values: 316.4 325.7 327.1 327.4 332.2 5162.6 5234.6 5273.1 5311.0 5322.5 5123.2 5163.8 5219.2 5227.5 5243.4 4104.0 4104.4 4108.9 4114.4 4123.6 4054.8 4122.3 4149.8 4163.5 4176.7 4073.2 4082.9 4084.8 4093.7 4121.4 4038.7 4055.7 4057.4 4058.2 4087.8 3598.5 3616.7 3618.1 3631.0 3669.4 4936.5 5076.3 5091.5 5096.4 5136.0 4657.5 4660.0 4668.7 4677.4 4681.7 5419.3 5427.9 5429.6 5432.9 5442.9 3782.8 3919.5 3946.9 3963.9 4011.5 5631.8 5640.6 5661.8 5663.6 5666.8 4001.9 4007.1 4020.9 4050.9 4072.0 3964.2 4052.0 4055.7 4107.9 4110.3 4644.3 4647.7 4653.6 4662.8 4672.7 5493.5 5498.5 5508.5 5537.1 5538.7 1295.4 1320.5 1328.9 1354.7 1365.4 942.5 946.8 953.8 962.5 963.7 5116.0 5117.7 5130.5 5145.8 5152.9 5426.4 5443.7 5444.2 5448.2 5486.6 4865.3 5097.5 5124.0 5173.8 5194.9 2873.1 2879.5 2880.4 2880.5 2886.6 5528.6 5537.8 5614.6 5623.9 5698.0 5197.6 5433.4 5496.2 5498.7 5500.5 5546.3 5581.9 5594.6 5628.0 5653.3 5195.6 5387.8 5408.8 5431.4 5442.7 5334.3 5343.9 5349.8 5378.2 5407.0 5551.6 5570.7 5577.2 5598.1 5672.3 3759.5 3862.4 3885.8 3891.0 3911.1 5359.0 5404.9 5415.6 5425.0 5436.3 5352.1 5413.5 5486.8 5502.7 5535.6 5451.4 5474.4 5494.9 5497.8 5505.5 5255.9 5441.7 5470.0 5473.0 5483.0 5515.8 5516.4 5559.0 5565.9 5577.4 5463.0 5505.3 5506.8 5511.1 5527.9 22:14:38 INFO - PROCESS | 8679 | 22:14:38 INFO - PROCESS | 8679 | [#5] yui.html Cycles:180 Average:17931.06 Median:4525.46 stddev:23208.17 (512.8%) stddev-sans-first:23237.21 22:14:38 INFO - PROCESS | 8679 | Values: 699.9 709.9 715.9 721.5 723.4 14011.6 14899.3 14931.0 17195.3 17962.9 16833.7 16858.1 16969.7 16992.7 17026.7 666.2 782.7 801.9 802.9 803.2 678.6 785.1 787.8 791.7 805.9 823.8 826.6 826.8 829.8 832.7 692.2 821.2 823.6 823.8 824.5 513.2 597.0 598.5 607.5 615.4 15275.5 15455.7 15506.6 15572.2 15582.3 13891.4 13928.4 14169.6 14255.6 14264.4 12493.7 12661.2 12767.7 13114.6 13188.0 4275.6 4275.8 4357.0 4487.6 4505.9 86602.1 87327.6 87452.6 89244.6 90742.5 10838.1 10967.7 11293.9 11308.9 11328.1 19298.5 19403.3 19427.5 19439.0 19450.2 7759.7 7900.6 7968.7 8106.7 8194.8 4335.4 4379.5 4513.5 4537.4 4662.8 3323.2 3370.5 3409.1 3410.6 3413.0 568.6 569.4 570.4 573.1 579.6 556.3 557.3 627.7 659.5 665.7 9009.8 9312.9 9547.6 9653.7 10090.7 623.9 688.4 718.8 719.0 722.9 3930.6 4205.3 4244.8 4300.2 4326.4 68539.7 70007.0 71450.3 77760.6 82753.6 67756.3 68112.2 68619.7 69179.3 69757.7 64608.6 65035.1 65979.3 66201.7 67436.7 9213.0 9304.2 9487.0 9567.3 9666.0 44041.6 44875.8 45592.5 46327.1 46695.5 51886.2 53724.5 55151.1 61566.4 62757.4 13101.3 13120.2 13555.9 13654.9 14058.2 14738.6 14948.9 15197.2 15210.6 15539.8 12610.4 12639.8 12708.2 13024.0 13158.6 12791.2 12925.5 13375.5 13580.6 14039.6 13017.2 13111.5 13606.5 13965.9 14054.9 10862.6 10971.7 10979.0 11273.8 11392.5 10938.5 11074.3 11128.4 11324.5 11381.3 22:14:38 INFO - PROCESS | 8679 | -------- Summary: end -------- 22:14:38 INFO - PROCESS | 8679 | 22:14:39 INFO - TEST-INFO | 8679: exit 0 22:14:39 INFO - TEST-OK | dromaeo_css | took 1118872ms 22:14:39 INFO - TEST-START | kraken 22:14:39 INFO - operating with platform_type : linux_ 22:14:39 INFO - Initialising browser for kraken test... 22:14:39 INFO - TEST-INFO | started process 8987 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3mGcgF/profile http://localhost:44065/getInfo.html) 22:14:43 INFO - PROCESS | 8987 | __metrics Screen width/height:1600/1200 22:14:43 INFO - PROCESS | 8987 | colorDepth:24 22:14:43 INFO - PROCESS | 8987 | Browser inner width/height: 1024/697 22:14:43 INFO - PROCESS | 8987 | __metrics 22:14:44 INFO - PROCESS | 8987 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:14:44 INFO - PROCESS | 8987 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:14:45 INFO - TEST-INFO | 8987: exit 0 22:14:45 INFO - Browser initialized. 22:14:45 INFO - Running cycle 1/1 for kraken test... 22:14:45 INFO - TEST-INFO | started process 9071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3mGcgF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:57 INFO - PROCESS | 9071 | RSS: Main: 173318144 22:14:57 INFO - PROCESS | 9071 | 22:15:00 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44065/tests/kraken/driver.html?testName=audio-beat-detection) 22:15:01 INFO - PROCESS | 9071 | RSS: Main: 230866944 22:15:01 INFO - PROCESS | 9071 | 22:15:03 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44065/tests/kraken/driver.html?testName=audio-dft) 22:15:04 INFO - PROCESS | 9071 | RSS: Main: 217239552 22:15:04 INFO - PROCESS | 9071 | 22:15:07 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44065/tests/kraken/driver.html?testName=audio-fft) 22:15:08 INFO - PROCESS | 9071 | RSS: Main: 298803200 22:15:08 INFO - PROCESS | 9071 | 22:15:10 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44065/tests/kraken/driver.html?testName=audio-oscillator) 22:15:10 INFO - PROCESS | 9071 | RSS: Main: 209997824 22:15:10 INFO - PROCESS | 9071 | 22:15:12 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44065/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:13 INFO - PROCESS | 9071 | RSS: Main: 213397504 22:15:13 INFO - PROCESS | 9071 | 22:15:16 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44065/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:17 INFO - PROCESS | 9071 | RSS: Main: 252833792 22:15:17 INFO - PROCESS | 9071 | 22:15:20 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44065/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:21 INFO - PROCESS | 9071 | RSS: Main: 252657664 22:15:21 INFO - PROCESS | 9071 | 22:15:23 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44065/tests/kraken/driver.html?testName=json-parse-financial) 22:15:24 INFO - PROCESS | 9071 | RSS: Main: 251342848 22:15:24 INFO - PROCESS | 9071 | 22:15:25 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44065/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:26 INFO - PROCESS | 9071 | RSS: Main: 213561344 22:15:26 INFO - PROCESS | 9071 | 22:15:28 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:28 INFO - PROCESS | 9071 | RSS: Main: 221229056 22:15:28 INFO - PROCESS | 9071 | 22:15:31 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:31 INFO - PROCESS | 9071 | RSS: Main: 229486592 22:15:31 INFO - PROCESS | 9071 | 22:15:34 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:15:35 INFO - PROCESS | 9071 | RSS: Main: 238686208 22:15:35 INFO - PROCESS | 9071 | 22:15:37 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:15:38 INFO - PROCESS | 9071 | RSS: Main: 235487232 22:15:38 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | Cycle 1(1): loaded http://localhost:44065/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44065/tests/kraken/driver.html?testName=ai-astar) 22:15:40 INFO - PROCESS | 9071 | RSS: Main: 285822976 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | __start_tp_report 22:15:40 INFO - PROCESS | 9071 | _x_x_mozilla_page_load 22:15:40 INFO - PROCESS | 9071 | _x_x_mozilla_page_load_details 22:15:40 INFO - PROCESS | 9071 | |i|pagename|runs| 22:15:40 INFO - PROCESS | 9071 | |0;ai-astar;104;112;113;111;113;112;111;114;111;113 22:15:40 INFO - PROCESS | 9071 | |1;audio-beat-detection;151;123;127;171;127;124;127;124;148;130 22:15:40 INFO - PROCESS | 9071 | |2;audio-dft;159;160;160;163;161;162;163;161;161;161 22:15:40 INFO - PROCESS | 9071 | |3;audio-fft;75;75;83;104;76;77;85;75;96;76 22:15:40 INFO - PROCESS | 9071 | |4;audio-oscillator;82;82;109;82;82;83;110;83;83;111 22:15:40 INFO - PROCESS | 9071 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;116;116 22:15:40 INFO - PROCESS | 9071 | |6;imaging-darkroom;146;146;146;145;145;146;145;148;148;148 22:15:40 INFO - PROCESS | 9071 | |7;imaging-desaturate;95;95;95;95;96;95;96;95;96;95 22:15:40 INFO - PROCESS | 9071 | |8;json-parse-financial;53;54;52;53;54;54;52;53;53;62 22:15:40 INFO - PROCESS | 9071 | |9;json-stringify-tinderbox;62;62;62;61;62;63;62;62;62;63 22:15:40 INFO - PROCESS | 9071 | |10;stanford-crypto-aes;89;84;86;80;87;82;86;80;80;80 22:15:40 INFO - PROCESS | 9071 | |11;stanford-crypto-ccm;141;131;132;132;131;132;138;143;171;144 22:15:40 INFO - PROCESS | 9071 | |12;stanford-crypto-pbkdf2;172;169;170;167;168;171;167;168;166;165 22:15:40 INFO - PROCESS | 9071 | |13;stanford-crypto-sha256-iterative;67;72;66;71;66;67;71;67;66;70 22:15:40 INFO - PROCESS | 9071 | __end_tp_report 22:15:40 INFO - PROCESS | 9071 | __start_cc_report 22:15:40 INFO - PROCESS | 9071 | _x_x_mozilla_cycle_collect,1696 22:15:40 INFO - PROCESS | 9071 | __end_cc_report 22:15:40 INFO - PROCESS | 9071 | __startTimestamp1480400140394__endTimestamp 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | ------- Summary: start ------- 22:15:40 INFO - PROCESS | 9071 | Number of tests: 14 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:2.80 (2.5%) stddev-sans-first:1.09 22:15:40 INFO - PROCESS | 9071 | Values: 104.0 112.0 113.0 111.0 113.0 112.0 111.0 114.0 111.0 113.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:128.50 stddev:16.07 (12.5%) stddev-sans-first:15.99 22:15:40 INFO - PROCESS | 9071 | Values: 151.0 123.0 127.0 171.0 127.0 124.0 127.0 124.0 148.0 130.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.29 (0.8%) stddev-sans-first:1.12 22:15:40 INFO - PROCESS | 9071 | Values: 159.0 160.0 160.0 163.0 161.0 162.0 163.0 161.0 161.0 161.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.50 stddev:10.18 (13.3%) stddev-sans-first:10.46 22:15:40 INFO - PROCESS | 9071 | Values: 75.0 75.0 83.0 104.0 76.0 77.0 85.0 75.0 96.0 76.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:82.00 stddev:13.33 (16.3%) stddev-sans-first:13.77 22:15:40 INFO - PROCESS | 9071 | Values: 82.0 82.0 109.0 82.0 82.0 83.0 110.0 83.0 83.0 111.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:15:40 INFO - PROCESS | 9071 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.25 (0.9%) stddev-sans-first:1.32 22:15:40 INFO - PROCESS | 9071 | Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 148.0 148.0 148.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:15:40 INFO - PROCESS | 9071 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:53.50 stddev:2.91 (5.4%) stddev-sans-first:3.06 22:15:40 INFO - PROCESS | 9071 | Values: 53.0 54.0 52.0 53.0 54.0 54.0 52.0 53.0 53.0 62.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 22:15:40 INFO - PROCESS | 9071 | Values: 62.0 62.0 62.0 61.0 62.0 63.0 62.0 62.0 62.0 63.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:85.00 stddev:3.44 (4.0%) stddev-sans-first:2.99 22:15:40 INFO - PROCESS | 9071 | Values: 89.0 84.0 86.0 80.0 87.0 82.0 86.0 80.0 80.0 80.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:139.50 stddev:12.21 (8.8%) stddev-sans-first:12.94 22:15:40 INFO - PROCESS | 9071 | Values: 141.0 131.0 132.0 132.0 131.0 132.0 138.0 143.0 171.0 144.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.50 stddev:2.21 (1.3%) stddev-sans-first:1.90 22:15:40 INFO - PROCESS | 9071 | Values: 172.0 169.0 170.0 167.0 168.0 171.0 167.0 168.0 166.0 165.0 22:15:40 INFO - PROCESS | 9071 | 22:15:40 INFO - PROCESS | 9071 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:2.41 (3.5%) stddev-sans-first:2.51 22:15:40 INFO - PROCESS | 9071 | Values: 67.0 72.0 66.0 71.0 66.0 67.0 71.0 67.0 66.0 70.0 22:15:40 INFO - PROCESS | 9071 | -------- Summary: end -------- 22:15:40 INFO - PROCESS | 9071 | 22:15:41 INFO - TEST-INFO | 9071: exit 0 22:15:41 INFO - TEST-OK | kraken | took 62051ms 22:15:41 INFO - SUITE-END | took 1180s 22:15:42 INFO - Completed test suite (00:19:41) 22:15:42 INFO - javascript benchmark 22:15:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3069.4935335669616, 3073.569700241965, 3110.8444577771106, 3125.8590614425743, 3133.420317241309, 5204.635675502716, 5256.1590145576665, 5259.631825772198, 5318.282031925689, 5328.786848526059, 5311.73441327934, 5314.734263286839, 5330.946690533103, 5338.172583249593, 5345.518903298703, 4803.327534145214, 4820.39745031871, 4831.347768051303, 4856.830010949618, 4907.509249075099, 4899.363082799231, 4908.680935739166, 4929.162042452792, 4936.136176169174, 4944.332515110468, 4872.196087645534, 4922.433920099181, 4922.8523144305545, 4934.851954441372, 4937.7777999990085, 4951.5791157751455, 4956.355673762424, 4961.975190124026, 4968.080905032581, 5013.47358527353, 4701.952980470186, 4756.6432517561325, 4827.420709514846, 4843.443004054544, 4864.513548645107, 5305.442928492491, 5316.415194328621, 5322.494363035506, 5343.145096784496, 5407.161889907032, 5677.432256774331, 5722.113072473773, 5725.885482290331, 5729.856753581152, 5740.913886291709, 5533.08704063829, 5567.610267281292, 5659.773609055633, 5749.0, 5752.942470575283, 5312.734363281859, 5345.305110335631, 5346.144616861361, 5388.703621300788, 5389.272448219479, 5805.187273781674, 5809.448102430262, 5813.128030795415, 5814.156947242711, 5818.650880947157, 5377.327834020747, 5402.405735369185, 5603.495685388335, 5608.4111168326735, 5609.4390560943575, 5657.858553536194, 5689.203511508392, 5690.829275121753, 5718.79984200551, 5772.422757724194, 5644.74598643062, 5696.572757043155, 5699.373068962411, 5703.629264097904, 5709.257796486513, 5504.6421982571, 5716.399778023331, 5726.713664316768, 5727.656340619576, 5729.398413166641, 4295.871123866289, 4347.500037495728, 4394.2749446340995, 4438.511763705989, 4441.755703436277, 3956.9802150989062, 3966.781826999543, 3994.500687414073, 4018.33697439925, 4019.31671615826, 5430.891382172414, 5453.400125986138, 5468.7539060742365, 5472.972635136878, 5479.589030822704, 5538.556915446755, 5575.498205161555, 5672.602917795714, 5756.107803290497, 5805.448482394166, 5407.567394608422, 5432.02223599748, 5439.864003399946, 5476.7535460904355, 5482.369527504388, 4941.407031156213, 4982.402111746614, 4991.126552853236, 5003.849884503471, 5039.26930595069, 5790.218320526675, 5836.241288632451, 5907.4387933148, 5960.64236145824, 5970.283565972111, 5656.2929633795775, 5659.03796354629, 5678.290213723285, 5710.600257981901, 5771.422857714363, 5866.0, 5866.882662346644, 5871.970640146772, 5882.558808089496, 5889.234399528034, 5444.918326225031, 5580.1629755537, 5584.078627026495, 5590.8043218487155, 5593.524550413261, 5565.0, 5599.832005039692, 5641.605087643826, 5658.519025882846, 5667.319921609268, 5467.291575644507, 5506.807261745819, 5552.805651802168, 5556.333240011225, 5575.553955683617, 5611.579131565068, 5620.718964051863, 5627.437256274504, 5666.660000399989, 5669.801556945487, 5608.186812912187, 5612.438756124355, 5614.578906581941, 5621.409751975983, 5622.85942851432, 5524.4199359066715, 5572.777088916398, 5578.86052848682, 5657.688827114548, 5661.518770904355, 5380.139177731544, 5413.1880217966045, 5518.834434966797, 5583.050881350261, 5600.635958662674], "value": 5268.449138474119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.5469232426224, 578.5227187570255, 595.553334998751, 595.5801160182073, 595.8957182493064, 31792.36415271696, 41128.794356028215, 41863.162736745246, 41917.161656766846, 41947.37078943818, 42949.35575966363, 43085.353719694154, 43307.35038974418, 43422.7828860856, 43475.3478697819, 8011.839763204733, 8191.058028326729, 8483.600205966008, 8716.520591367473, 8722.433041852291, 10072.798544029116, 10354.171666266719, 10356.792864142713, 10645.52095155716, 10656.840147397796, 10381.325213861075, 10474.842877356808, 10477.947610261937, 10480.213983951196, 10487.528061237263, 10371.429571373572, 10413.53139108742, 10414.062734353869, 10417.0, 10429.582816687323, 10274.845877311845, 10311.123554497877, 10349.327293725884, 10353.327033742782, 10363.067323940806, 39682.40476392827, 39901.40147897784, 39940.0, 39961.0, 39982.40026399606, 3386.136535183537, 3624.1120925373452, 3812.390017597199, 3885.689144868432, 4022.275990321741, 30774.84612576945, 31021.689783102105, 31027.844860775553, 31060.223494412596, 32791.18022049444, 4110.951707314645, 4165.500139983191, 4210.115875666113, 4341.261985462479, 4359.738415695068, 342404.0, 342434.0, 342730.0, 343481.0, 343984.0, 55281.0, 55336.72331638316, 55750.442495575335, 55753.72123139358, 55754.442455575736, 57048.71475642595, 57360.0, 57415.42584574184, 57576.0, 57585.42414575801, 16204.513864584082, 16390.754138687927, 16401.83598164027, 16410.0, 16431.0, 11871.940640296743, 12129.21160124589, 12340.136190466581, 13051.086423950232, 13123.0, 14872.405103795727, 15586.0, 15594.454194103379, 15613.921930390276, 15626.45307414235, 5937.406259374028, 6428.967855160788, 6431.13179720741, 6469.353064693587, 6782.796516104525, 923.5382308845577, 998.2463240253562, 1013.9493025348703, 1067.0, 1144.6909334479644, 31536.684633153833, 32011.0, 32141.196470088435, 32513.837430813168, 32557.67442325594, 1055.2507719519074, 1061.9628313009007, 1067.3702515515884, 1079.6221322537049, 1087.7389426537575, 16088.678226435406, 16768.245428955724, 16801.24394402255, 16886.155692215587, 16923.238454269587, 490132.0, 495880.0, 503885.0, 505188.0, 506527.0, 51017.48982510201, 51590.48409515932, 53551.73224133854, 54144.18783718247, 54609.45390546123, 1338.136901698399, 1354.4717560151548, 1376.0367742580434, 1376.986230137706, 1380.1856904426436, 30163.396732065674, 33036.33927321488, 33066.66933330636, 33178.0, 33263.0, 49811.50188498069, 50314.74842625763, 50439.24341134813, 50484.49515504798, 50498.24252636287, 334839.0, 335461.0, 335479.0, 335766.0, 336904.0, 38269.0, 39179.41230881596, 39461.40807887827, 39462.21075578415, 39492.60507394889, 44830.55169448394, 45185.548144518136, 45435.31847022363, 45692.77153614211, 46320.0, 38804.80597596994, 39235.41146882856, 39675.404868927566, 39847.60152398439, 40608.59391406048, 38319.42520862134, 38972.0, 39150.60849391584, 39687.0, 41149.79425102855, 38461.80769096137, 38725.41911871268, 39288.803555982035, 39312.2137557253, 39700.80149599234, 33274.33451331008, 34723.13192170216, 35503.28909635905, 35795.463068053476, 35873.82063089668, 26899.86550067237, 27351.452970940867, 27390.726092738816, 27430.725692743614, 27500.312492187855], "value": 19859.02559170941, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.15482396843066, 392.1959982036824, 405.4000079881775, 408.5893676854761, 418.62114786118553, 13297.933510332447, 15246.0, 18692.99999999998, 19127.61744765104, 19369.0, 18736.531586710338, 19161.712574311397, 19195.040247987614, 19386.51533711658, 19413.611727765438, 4525.660575456837, 5472.370677372103, 5716.742746576414, 5719.342275638292, 5724.513416359594, 5590.580706447013, 5628.634138780986, 5674.829755107354, 5679.772809087653, 5687.090065589507, 5450.04624190767, 5549.889002219953, 5639.097744360903, 5644.294463192101, 5648.181013753024, 5523.447655234465, 5534.0, 5560.388357280696, 5564.972175139139, 5566.972165139168, 4945.0, 4973.0, 5000.899982000376, 5002.799888004475, 5174.689518628894, 13896.513622023282, 14122.364493597814, 14138.363773630212, 14150.221737804417, 21831.0, 2073.7615174255, 2083.864548804323, 2112.36629011296, 2151.612709712251, 2165.6101901657694, 9501.42991420517, 10092.798144037079, 10440.32137911041, 10547.99794019567, 10721.30311529748, 1827.232562323827, 1901.5911579010578, 1903.2101677803694, 1924.0668275886185, 1927.152053096647, 72750.63624681896, 85587.57206213991, 86345.0, 86375.56812215962, 87083.84172912684, 10805.459727013618, 10904.890951090547, 10974.798096695064, 10982.505787239434, 11334.773304533863, 15412.768808467657, 15419.537413877602, 15455.53633391, 15462.84537154614, 15474.613134671723, 7977.680892764224, 8102.06826214981, 8204.8359032819, 8369.455985361054, 8391.202835730595, 7710.537367757953, 7759.844803103906, 7788.805279868048, 7989.2809647132035, 7991.960040199761, 4667.62658987287, 4783.282507623815, 4816.494268101869, 4885.706857588556, 4902.2156454967035, 1915.7796853362042, 1984.6328429240348, 1991.9601607967759, 2008.2870580943847, 2065.7417822772154, 849.3460035772545, 989.248171389749, 1025.9640912568025, 1036.6475398364441, 1068.7274744939991, 18772.24911003572, 18818.0, 19048.80951190498, 19049.428517144508, 19297.42107736742, 933.5565606337071, 945.9858102128474, 991.6628346362271, 992.5136683025265, 1025.7692019295748, 9818.950905245429, 10374.792504149875, 11039.227254092137, 11276.605318813803, 11387.430628468543, 226435.0, 333177.0, 429910.0, 433073.0, 441817.0, 93848.53075734578, 94149.0, 94628.0, 95441.0, 95641.0, 1009.2077718990557, 1011.9949400252951, 1033.8862725100232, 1037.2479952034864, 1065.5737704918095, 16664.50006499807, 16755.32978680839, 16881.0, 17705.203265853324, 17960.371387001393, 41841.58158418377, 43338.349924750524, 43544.0, 43831.78084109559, 43866.56133438625, 53579.73210133924, 54015.45984540261, 58281.70859145677, 58299.0, 59444.405555943886, 8209.835803283782, 8360.28937540316, 8397.202265784741, 8441.746747597583, 8580.0561938188, 11903.404829758305, 12839.871601283867, 12932.935335323264, 12951.417186226454, 13265.403056862271, 10839.295445795999, 11176.27354221973, 11369.43152842338, 11724.94137529341, 11904.940475297568, 11015.944920275348, 11229.494672739584, 11331.830022549504, 11686.0, 11909.940450297692, 11241.156913231376, 11344.886551134383, 11395.544178233109, 11410.201285909907, 12080.939595301967, 8132.105468398353, 8494.1081186477, 8670.393072484865, 8736.301095912439, 8884.644614215358, 9962.750931226778, 11252.0, 11704.297742135499, 11752.588659397223, 11829.231099978819], "value": 8931.253274927487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.7509446582503, 1426.0445501513984, 1474.498670452046, 1541.868941140003, 1701.5916180116776, 7265.0, 8344.95827520863, 8506.447080939735, 8513.531755753438, 8760.649574017032, 8517.23344898959, 8518.659253629863, 8589.91410085899, 8595.31237501, 8609.870851937227, 2053.5995480881234, 2086.3636590839787, 2348.260298006131, 2382.9038642224596, 2384.332386931662, 2291.9312420627407, 2359.4219416243045, 2366.4202270443766, 2370.7747763962398, 2372.7271363793125, 2294.724633044029, 2335.6730057791924, 2343.297010896733, 2348.6007378745567, 2350.330155905567, 2350.9177178798745, 2363.550925324183, 2368.218487898989, 2371.430856594422, 2374.608189648706, 2273.9658905116435, 2304.8847557622053, 2308.8845557722216, 2310.87290199039, 2312.456572705413, 8702.825943481095, 8749.168828961303, 8752.212300892887, 8763.868541971875, 8769.517676527788, 5237.711925844077, 5333.173358129496, 5349.625526213166, 5356.464353564651, 5398.78404863805, 3205.4871220604823, 3309.3050459403553, 3318.3363327334628, 3468.2023134679166, 3479.5824501060033, 1114.5430373546887, 1131.5247596009613, 1137.9089672826153, 1170.356304032787, 1182.6688527212393, 11899.881001189964, 11964.04287656994, 11986.400679965967, 12008.0, 12015.459304331325, 4557.886052848706, 4607.608353289941, 4706.058788242365, 4714.104320179224, 4828.227483602606, 10106.747331316776, 10111.443870587042, 10116.190704743603, 10122.949385253027, 10129.544170512345, 5376.220448035091, 5386.703731294817, 5390.9191362129595, 5392.191171324333, 5394.730263486888, 4735.881602959954, 4872.756362181876, 4906.582940450102, 4909.754512274372, 4916.729579873141, 3807.2575847709986, 3901.180752042103, 3914.549826769901, 3945.684345252373, 3999.300122478555, 1250.6748245456065, 1265.791144461172, 1308.2346827106248, 1319.8152258683792, 1331.0483004651528, 869.7869022089502, 873.5413907698382, 875.9562021898879, 917.6788124156492, 949.2548349545574, 5550.278463799761, 5639.154126881001, 5690.715464226772, 5697.800576979786, 5854.619449735754, 890.2877697841831, 908.3732224764891, 922.9400088994328, 926.6941909169958, 983.6360546597805, 3591.8383672734267, 3660.103274697663, 3740.158464345555, 3749.49381833449, 3763.0780458787226, 10496.842547361795, 10697.197710171768, 10779.622713205, 10780.568777248978, 10795.136389088853, 10669.73325666849, 10670.57317707283, 10682.51928663212, 10723.46382680863, 10762.0, 8050.355971522265, 8150.592470376458, 8166.265036146657, 8167.714130005421, 8178.0, 5382.650127741685, 5484.287042684426, 5558.3607885092115, 5636.323641163087, 5704.229928959703, 9695.903040969783, 9744.220462362851, 9814.75463113428, 9898.653547125816, 9923.354981926152, 8560.785980350292, 9383.530823458936, 9689.854652180082, 9703.02969703024, 9938.204943604349, 6527.314631963575, 7010.263922288086, 7086.326798954091, 7126.465515086285, 7140.9285907140265, 7997.200279971957, 8114.513129212266, 8208.507489550408, 8285.25432711059, 8361.790955226168, 7299.270072992871, 7471.551706897603, 7475.140358858584, 7549.0, 7553.697852085855, 6901.965490172718, 6960.129983752031, 6994.545354551938, 7020.473464490082, 7104.786856394317, 6664.266930637723, 6671.299513551007, 6714.093597364294, 6730.158730158731, 6747.392734653708, 5049.974750126225, 5080.745962701776, 5151.484851514819, 5208.374995000624, 5276.07668657991, 4807.687500312469, 4923.212286034217, 5081.669691470042, 5106.4893510648635, 5319.574434045345], "value": 4532.773371416532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.4414807864119, 325.7426632959962, 327.1412542077047, 327.4008564327281, 332.19608547315494, 5162.638615296931, 5234.581233501321, 5273.129933560967, 5310.973445132779, 5322.467753224676, 5123.1546794778815, 5163.767630456629, 5219.217117432387, 5227.503387178212, 5243.4232234454175, 4103.958960410388, 4104.399284279119, 4108.917821643566, 4114.382842573608, 4123.587641235875, 4054.756714597119, 4122.278601244785, 4149.751014939114, 4163.521195062576, 4176.728512646683, 4073.205724883649, 4082.8775136745944, 4084.7957602119923, 4093.713440059197, 4121.381792731099, 4038.676905847525, 4055.695822813286, 4057.411675307064, 4058.2346679194497, 4087.7532352632434, 3598.47822065799, 3616.692581130607, 3618.149734812317, 3631.0, 3669.412893936957, 4936.531029552187, 5076.263941728429, 5091.490850914916, 5096.362954630671, 5135.974320128413, 4657.464391594942, 4659.976700116477, 4668.6731928765, 4677.438707355105, 4681.719096854165, 5419.34967803866, 5427.9185812212845, 5429.592780541475, 5432.864178395532, 5442.863928401822, 3782.7730336179916, 3919.4708714323488, 3946.9013274668077, 3963.9009024774323, 4011.5186177658866, 5631.8028868989795, 5640.600331741249, 5661.7735290588325, 5663.603547751659, 5666.801661941812, 4001.8999525011527, 4007.054814711185, 4020.8592699255387, 4050.8784736457364, 4072.045418199281, 3964.246793109358, 4051.9942628398544, 4055.6552693020835, 4107.876763697094, 4110.280700877334, 4644.256918892961, 4647.697899636512, 4653.604443622262, 4662.813487460531, 4672.696274742131, 5493.450654934474, 5498.532624726863, 5508.512701570634, 5537.141743029837, 5538.667679939216, 1295.4494339905502, 1320.518010926005, 1328.9468421263234, 1354.73582651384, 1365.3855764905832, 942.4977181634268, 946.8059047895165, 953.823542644613, 962.4562122400961, 963.7012526116786, 5115.97442012795, 5117.669405954479, 5130.48695130484, 5145.787311456035, 5152.896942061137, 5426.399212232401, 5443.673379597237, 5444.237806707064, 5448.237246785374, 5486.588505862076, 4865.270209468608, 5097.515736005073, 5124.0, 5173.844784656466, 5194.909069095456, 2873.109336105814, 2879.452903948243, 2880.3663194097267, 2880.4671135839517, 2886.5670149477746, 5528.585356098309, 5537.7784888604, 5614.568285087358, 5623.943760562423, 5697.971510142423, 5197.558207552324, 5433.429489903582, 5496.196939560773, 5498.670079795225, 5500.50495455403, 5546.334439867242, 5581.855719577558, 5594.552435805207, 5628.0, 5653.321601407857, 5195.636305458582, 5387.784488620411, 5408.78364865401, 5431.429699881455, 5442.700651464207, 5334.333208348957, 5343.866403339792, 5349.8230389314285, 5378.247045413567, 5407.0, 5551.555875529867, 5570.688164411724, 5577.247071645276, 5598.124628249582, 5672.290963629546, 3759.4924685167152, 3862.407960596672, 3885.8445662173194, 3891.01558205323, 3911.125322887732, 5358.97320513395, 5404.919196058209, 5415.620906536662, 5425.023495770799, 5436.3476382834315, 5352.143657014859, 5413.512783849473, 5486.780528778964, 5502.724863756716, 5535.557155427637, 5451.400345962021, 5474.425185355481, 5494.890102197854, 5497.8075767347955, 5505.504504594605, 5255.921161182662, 5441.7279136043835, 5470.0, 5473.0, 5482.972585137049, 5515.841673248502, 5516.448355164452, 5558.97220513911, 5565.916511252253, 5577.44225577439, 5462.9726851365485, 5505.284313039279, 5506.834794956158, 5511.063119269654, 5527.944720552904], "value": 4139.424013212859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9230084690686, 709.948249558775, 715.8568286342733, 721.4516967105, 723.4176487927219, 14011.579652610413, 14899.25503724815, 14931.0, 17195.312187512514, 17962.910185449055, 16833.663326733487, 16858.072805995664, 16969.74545381817, 16992.660146797058, 17026.74459883103, 666.2105405094953, 782.7143092771126, 801.9198080191964, 802.9317508011826, 803.2088392932957, 678.630146570118, 785.0814546980047, 787.7518581646791, 791.6754130806371, 805.9194080591923, 823.7899335669426, 826.6321486938315, 826.7726375246795, 829.8008477965305, 832.675256649907, 692.1763101908713, 821.1501096365233, 823.6293667849492, 823.7693445835175, 824.4558591329724, 513.1609817947646, 596.9701514924236, 598.512212546773, 607.4927435591287, 615.4399496458202, 15275.541733748005, 15455.690886182214, 15506.61233469161, 15572.221388930508, 15582.298796554069, 13891.374888130058, 13928.442862285598, 14169.645758856008, 14255.643608909757, 14264.429422823074, 12493.687657808536, 12661.177023493443, 12767.680807979781, 13114.606561803066, 13188.0, 4275.572442755731, 4275.8075886585175, 4357.046846218954, 4487.573680500347, 4505.932411013838, 86602.13397866004, 87327.56336218279, 87452.5627371859, 89244.5537772307, 90742.54628726881, 10838.07876330505, 10967.670969870916, 11293.887061129284, 11308.943455282835, 11328.09375249978, 19298.517537061685, 19403.320883769, 19427.51431214231, 19439.0, 19450.221991120197, 7759.72840950564, 7900.565468899151, 7968.681252749941, 8106.675732970615, 8194.754157375288, 4335.393044973706, 4379.4525684289465, 4513.54864513546, 4537.3647689323525, 4662.790174442158, 3323.152596087982, 3370.5449764282, 3409.130671678756, 3410.6418826023405, 3413.0272872231294, 568.567339075141, 569.3509399284818, 570.4352690836042, 573.1488739222251, 579.623244890816, 556.2907293201201, 557.2783245696833, 627.6892937995722, 659.4724220623577, 665.7070888808909, 9009.819803603892, 9312.90687093134, 9547.61809527625, 9653.71038868835, 10090.69727908164, 623.9001759718514, 688.3942130924754, 718.7585080315839, 718.9784306470814, 722.9024081748896, 3930.5872883346838, 4205.2640787862, 4244.808983595745, 4300.247456695129, 4326.437563116775, 68539.65730171317, 70007.0, 71450.2854971454, 77760.61119694365, 82753.58623206845, 67756.32243677501, 68112.21046211128, 68619.65690171518, 69179.3082069183, 69757.65121174463, 64608.571186330904, 65035.14683678397, 65979.34020659924, 66201.6689916557, 67436.6628166856, 9212.95393523055, 9304.16262536375, 9486.95256523713, 9567.282453816131, 9666.0, 44041.559584405026, 44875.77562112299, 45592.54407455883, 46327.073458529965, 46695.533044670476, 51886.22170667368, 53724.46275537195, 55151.07280689514, 61566.384336157855, 62757.37242627515, 13101.279429631462, 13120.212787232796, 13555.932220338835, 13654.931725341707, 14058.156510609397, 14738.55784326472, 14948.925255374088, 15197.240137992834, 15210.631043206164, 15539.844601554292, 12610.36948152607, 12639.810402844148, 12708.173968692005, 13024.0, 13158.60524184276, 12791.232526048467, 12925.482980681043, 13375.464981400635, 13580.592582222549, 14039.649008774453, 13017.153884997444, 13111.541096061974, 13606.455741770218, 13965.930170349084, 14054.929725351309, 10862.619808306672, 10971.74335195966, 10979.0, 11273.830892536782, 11392.54429822831, 10938.453077345941, 11074.280171788809, 11128.38793866345, 11324.547018119183, 11381.317120972768], "value": 7158.371626244502, "unit": "score"}], "name": "dromaeo_css", "value": 7075.973950412339, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 113.0, 111.0, 113.0, 112.0, 111.0, 114.0, 111.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 127.0, 171.0, 127.0, 124.0, 127.0, 124.0, 148.0, 130.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 163.0, 161.0, 162.0, 163.0, 161.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 83.0, 104.0, 76.0, 77.0, 85.0, 75.0, 96.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 82.0, 83.0, 110.0, 83.0, 83.0, 111.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 148.0, 148.0, 148.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 52.0, 53.0, 54.0, 54.0, 52.0, 53.0, 53.0, 62.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 86.0, 80.0, 87.0, 82.0, 86.0, 80.0, 80.0, 80.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 131.0, 132.0, 132.0, 131.0, 132.0, 138.0, 143.0, 171.0, 144.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 170.0, 167.0, 168.0, 171.0, 167.0, 168.0, 166.0, 165.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 66.0, 71.0, 66.0, 67.0, 71.0, 67.0, 66.0, 70.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1514.5, "alertThreshold": 2.0}]} 22:15:42 INFO - Return code: 0 22:15:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:15:42 INFO - # TBPL SUCCESS # 22:15:42 INFO - Running post-action listener: _resource_record_post_action 22:15:42 INFO - [mozharness: 2016-11-29 06:15:42.191299Z] Finished run-tests step (success) 22:15:42 INFO - Running post-run listener: _resource_record_post_run 22:15:42 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 495616; Write bytes: 404004864; Read time: 60; Write time: 226744 22:15:42 INFO - TinderboxPrint: CPU usage
13.1% 22:15:42 INFO - TinderboxPrint: I/O read bytes / time
495,616 / 60 22:15:42 INFO - TinderboxPrint: I/O write bytes / time
404,004,864 / 226,744 22:15:42 INFO - TinderboxPrint: CPU idle
8,303.4 (86.8%) 22:15:42 INFO - TinderboxPrint: CPU user
1,205.9 (12.6%) 22:15:42 INFO - TinderboxPrint: Swap in / out
0 / 0 22:15:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106004480; Read time: 0; Write time: 75724 22:15:42 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 491520; Write bytes: 288608256; Read time: 44; Write time: 150372 22:15:42 INFO - Running post-run listener: _upload_blobber_files 22:15:42 INFO - Blob upload gear active. 22:15:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:15:42 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 22:15:42 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'] 22:15:42 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 22:15:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:15:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:46 INFO - (blobuploader) - INFO - Done attempting. 22:15:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:15:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:15:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:48 INFO - (blobuploader) - INFO - Done attempting. 22:15:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:15:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:15:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:48 INFO - (blobuploader) - INFO - Done attempting. 22:15:48 INFO - (blobuploader) - INFO - Iteration through files over. 22:15:48 INFO - Return code: 0 22:15:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:15:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:15:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201474881ae48f37ddda3039a0ce3abddb6b1ee5011247e01f2623949d6e6f319fd486493e528dc22f799931460b43f2e2740e44a9c9b352f77f4c54ab8d70a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8399a29963394f66a764834bb12e8195a6454e5be979b218e1fb52353e48166ec8fa7a44f770ce4c8f5a1e793d5529e9ef7e9977b373a306b24202fe6d2041", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf5313499115f2cfb44bdefe866be0fb2a7862a12dc11de6ab124e920d573f69e960e21744d0a6db1ea4e30a0b8ca1748cf67ef70851035d41b9cf26e6eb026"} 22:15:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:15:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:15:48 INFO - Contents: 22:15:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201474881ae48f37ddda3039a0ce3abddb6b1ee5011247e01f2623949d6e6f319fd486493e528dc22f799931460b43f2e2740e44a9c9b352f77f4c54ab8d70a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8399a29963394f66a764834bb12e8195a6454e5be979b218e1fb52353e48166ec8fa7a44f770ce4c8f5a1e793d5529e9ef7e9977b373a306b24202fe6d2041", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf5313499115f2cfb44bdefe866be0fb2a7862a12dc11de6ab124e920d573f69e960e21744d0a6db1ea4e30a0b8ca1748cf67ef70851035d41b9cf26e6eb026"} 22:15:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:15:48 INFO - Copying logs to upload dir... 22:15:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:15:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.017005 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-11-28 22:15:49.083309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 22:15:49.089117) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 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/201474881ae48f37ddda3039a0ce3abddb6b1ee5011247e01f2623949d6e6f319fd486493e528dc22f799931460b43f2e2740e44a9c9b352f77f4c54ab8d70a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8399a29963394f66a764834bb12e8195a6454e5be979b218e1fb52353e48166ec8fa7a44f770ce4c8f5a1e793d5529e9ef7e9977b373a306b24202fe6d2041", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf5313499115f2cfb44bdefe866be0fb2a7862a12dc11de6ab124e920d573f69e960e21744d0a6db1ea4e30a0b8ca1748cf67ef70851035d41b9cf26e6eb026"} build_url:https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011876 build_url: 'https://queue.taskcluster.net/v1/task/StC9WcOnQgiMakqHglPdWw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/201474881ae48f37ddda3039a0ce3abddb6b1ee5011247e01f2623949d6e6f319fd486493e528dc22f799931460b43f2e2740e44a9c9b352f77f4c54ab8d70a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d8399a29963394f66a764834bb12e8195a6454e5be979b218e1fb52353e48166ec8fa7a44f770ce4c8f5a1e793d5529e9ef7e9977b373a306b24202fe6d2041", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf5313499115f2cfb44bdefe866be0fb2a7862a12dc11de6ab124e920d573f69e960e21744d0a6db1ea4e30a0b8ca1748cf67ef70851035d41b9cf26e6eb026"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 22:15:49.115929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 22:15:49.116321) ========= 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=03627c79f6bb3d1578c4a402000001ba-1480398731.576296-266031363 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 22:15:49.199456) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 22:15:49.199854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 22:15:49.200214) ========= ========= Total master_lag: 0.18 =========