builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1477028949.95 results: success (0) buildid: 20161020224155 builduid: 84a34e2a6f1c44a2a31d13f9a49b8382 revision: 1171f3ae792b91d7ca49ef0907b251e381f1df56 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:09.950892) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:09.951320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:09.951707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:09.966163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:09.966541) ========= 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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-20 22:49:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-10-20 22:49:10 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117092 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:10.097379) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:10.097720) ========= 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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019992 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:10.147467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 22:49:10.147843) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1171f3ae792b91d7ca49ef0907b251e381f1df56 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1171f3ae792b91d7ca49ef0907b251e381f1df56 --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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-20 22:49:10,440 truncating revision to first 12 chars 2016-10-20 22:49:10,440 Setting DEBUG logging. 2016-10-20 22:49:10,440 attempt 1/10 2016-10-20 22:49:10,440 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1171f3ae792b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 22:49:11,089 unpacking tar archive at: mozilla-inbound-1171f3ae792b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.987247 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 22:49:11.148390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:11.148740) ========= script_repo_revision: 1171f3ae792b91d7ca49ef0907b251e381f1df56 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:11.149135) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:11.149400) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 22:49:11.159690) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-20 22:49:11.159963) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:49:11 INFO - MultiFileLogger online at 20161020 22:49:11 in /builds/slave/test 22:49:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:49:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:49:11 INFO - {'append_to_log': False, 22:49:11 INFO - 'base_work_dir': '/builds/slave/test', 22:49:11 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:49:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:49:11 INFO - 'branch': 'Mozilla-Inbound', 22:49:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:49:11 INFO - 'config_files': ('talos/linux_config.py',), 22:49:11 INFO - 'default_actions': ('clobber', 22:49:11 INFO - 'read-buildbot-config', 22:49:11 INFO - 'download-and-extract', 22:49:11 INFO - 'populate-webroot', 22:49:11 INFO - 'create-virtualenv', 22:49:11 INFO - 'install', 22:49:11 INFO - 'run-tests'), 22:49:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:49:11 INFO - 'download_minidump_stackwalk': True, 22:49:11 INFO - 'download_symbols': 'ondemand', 22:49:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:49:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:49:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:49:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:49:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:49:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:49:11 INFO - 'installer_path': 'installer.exe', 22:49:11 INFO - 'log_level': 'info', 22:49:11 INFO - 'log_name': 'talos', 22:49:11 INFO - 'log_to_console': True, 22:49:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:49:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:49:11 INFO - 'opt_config_files': (), 22:49:11 INFO - 'pip_index': False, 22:49:11 INFO - 'sps_profile': False, 22:49:11 INFO - 'sps_profile_interval': 0, 22:49:11 INFO - 'suite': 'dromaeojs', 22:49:11 INFO - 'system_bits': '32', 22:49:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:49:11 INFO - 'title': 'talos-linux64-ix-052', 22:49:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:49:11 INFO - 'use_talos_json': True, 22:49:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:49:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:49:11 INFO - 'work_dir': 'build'} 22:49:11 INFO - [mozharness: 2016-10-21 05:49:11.548985Z] Running clobber step. 22:49:11 INFO - Running pre-action listener: _resource_record_pre_action 22:49:11 INFO - Running main action method: clobber 22:49:11 INFO - rmtree: /builds/slave/test/build 22:49:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:49:12 INFO - Running post-action listener: _resource_record_post_action 22:49:12 INFO - [mozharness: 2016-10-21 05:49:12.003133Z] Finished clobber step (success) 22:49:12 INFO - [mozharness: 2016-10-21 05:49:12.003233Z] Running read-buildbot-config step. 22:49:12 INFO - Running pre-action listener: _resource_record_pre_action 22:49:12 INFO - Running main action method: read_buildbot_config 22:49:12 INFO - Using buildbot properties: 22:49:12 INFO - { 22:49:12 INFO - "project": "", 22:49:12 INFO - "product": "firefox", 22:49:12 INFO - "script_repo_revision": "production", 22:49:12 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:49:12 INFO - "repository": "", 22:49:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:49:12 INFO - "buildid": "20161020224155", 22:49:12 INFO - "stage_platform": "linux64-pgo", 22:49:12 INFO - "basedir": "/builds/slave/test", 22:49:12 INFO - "buildnumber": 1877, 22:49:12 INFO - "platform": "ubuntu64_hw", 22:49:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:49:12 INFO - "slavebuilddir": "test", 22:49:12 INFO - "branch": "mozilla-inbound", 22:49:12 INFO - "revision": "1171f3ae792b91d7ca49ef0907b251e381f1df56", 22:49:12 INFO - "slavename": "talos-linux64-ix-052", 22:49:12 INFO - "builduid": "84a34e2a6f1c44a2a31d13f9a49b8382", 22:49:12 INFO - "repo_path": "integration/mozilla-inbound" 22:49:12 INFO - } 22:49:12 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 22:49:12 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 22:49:12 INFO - Running post-action listener: _resource_record_post_action 22:49:12 INFO - [mozharness: 2016-10-21 05:49:12.005593Z] Finished read-buildbot-config step (success) 22:49:12 INFO - [mozharness: 2016-10-21 05:49:12.005680Z] Running download-and-extract step. 22:49:12 INFO - Running pre-action listener: _resource_record_pre_action 22:49:12 INFO - Running main action method: download_and_extract 22:49:12 INFO - mkdir: /builds/slave/test/build/tests 22:49:12 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')]} 22:49:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:49:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:49:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:49:12 INFO - Downloaded 1361 bytes. 22:49:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:49:12 INFO - Using the following test package requirements: 22:49:12 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:49:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:49:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'jsshell-linux-x86_64.zip'], 22:49:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:49:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:49:12 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:49:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:49:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:49:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:49:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:49:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:49:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:49:12 INFO - Http code: 200 22:49:12 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:49:12 INFO - x-amz-cf-id: gEOBkcIiQj8gjAmC2mQbv-e-QZDyLbGoJ6Gl072M4BeJ4n5xCtQpbQ== 22:49:12 INFO - x-amz-replication-status: COMPLETED 22:49:12 INFO - x-amz-version-id: qgar26m6taTPFsnE1hbyBpunsmMZykDF 22:49:12 INFO - Expected file size: 22120017 22:49:12 INFO - Obtained file size: 22120017 22:49:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:49:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:49:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:49:16 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:49:16 INFO - Http code: 200 22:49:16 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 22:49:16 INFO - x-amz-cf-id: XjnmIxOA7twVgwmp-_R_18sEyH_aPsgYOGZplGCOs9ytva-3RDO9vw== 22:49:16 INFO - x-amz-replication-status: COMPLETED 22:49:16 INFO - x-amz-version-id: qgar26m6taTPFsnE1hbyBpunsmMZykDF 22:49:16 INFO - Expected file size: 22120017 22:49:16 INFO - Obtained file size: 22120017 22:49:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 22:49:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:49:21 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:49:21 INFO - Http code: 200 22:49:21 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 22:49:21 INFO - x-amz-cf-id: dEUvyZHyZ7vGcfbeR8rXQ5p6uZwG2Ciz60SAl_wxv67cwaaLWDGiwA== 22:49:21 INFO - x-amz-replication-status: COMPLETED 22:49:21 INFO - x-amz-version-id: fqM41GWwnDxTQo8GN7s3EmLKcLJqa74. 22:49:21 INFO - Expected file size: 14409028 22:49:21 INFO - Obtained file size: 14409028 22:49:21 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')]} 22:49:21 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:49:21 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:49:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:49:22 INFO - Downloaded 59464235 bytes. 22:49:22 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:49:22 INFO - mkdir: /builds/slave/test/properties 22:49:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:49:22 INFO - Writing to file /builds/slave/test/properties/build_url 22:49:22 INFO - Contents: 22:49:22 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:49:22 INFO - Running post-action listener: _resource_record_post_action 22:49:22 INFO - Running post-action listener: set_extra_try_arguments 22:49:22 INFO - [mozharness: 2016-10-21 05:49:22.774029Z] Finished download-and-extract step (success) 22:49:22 INFO - [mozharness: 2016-10-21 05:49:22.774256Z] Running populate-webroot step. 22:49:22 INFO - Running pre-action listener: _resource_record_pre_action 22:49:22 INFO - Running main action method: populate_webroot 22:49:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:49:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:49:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:49:22 INFO - '--tppagecycles', 22:49:22 INFO - '7'], 22:49:22 INFO - 'tests': ['tsvgm']}}, 22:49:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:49:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:49:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:49:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:49:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['damp', 'tps']}, 22:49:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'tests': ['damp', 'tps']}, 22:49:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['dromaeo_dom']}, 22:49:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:49:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['basic_compositor_video']}, 22:49:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:49:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'other-e10s': {'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['a11yr', 22:49:22 INFO - 'ts_paint', 22:49:22 INFO - 'tpaint', 22:49:22 INFO - 'sessionrestore', 22:49:22 INFO - 'sessionrestore_no_auto_restore', 22:49:22 INFO - 'tabpaint']}, 22:49:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['tsvgx', 22:49:22 INFO - 'tsvgr_opacity', 22:49:22 INFO - 'tart', 22:49:22 INFO - 'tscrollx', 22:49:22 INFO - 'cart']}, 22:49:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:49:22 INFO - 'tsvgr_opacity', 22:49:22 INFO - 'tart', 22:49:22 INFO - 'tscrollx', 22:49:22 INFO - 'cart']}, 22:49:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'talos_options': ['--disable-e10s'], 22:49:22 INFO - 'tests': ['tp5o']}, 22:49:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'tests': ['tp5o']}, 22:49:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'talos_options': ['--disable-e10s', 22:49:22 INFO - '--xperf_path', 22:49:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:22 INFO - 'tests': ['tp5n']}, 22:49:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:22 INFO - 'talos_options': ['--xperf_path', 22:49:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:22 INFO - 'tests': ['tp5n']}}, 22:49:22 INFO - 'talos.zip': {'path': '', 22:49:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:49:22 INFO - Running post-action listener: _resource_record_post_action 22:49:22 INFO - [mozharness: 2016-10-21 05:49:22.789509Z] Finished populate-webroot step (success) 22:49:22 INFO - [mozharness: 2016-10-21 05:49:22.789597Z] Running create-virtualenv step. 22:49:22 INFO - Running pre-action listener: _resource_record_pre_action 22:49:22 INFO - Running main action method: create_virtualenv 22:49:22 INFO - Creating virtualenv /builds/slave/test/build/venv 22:49:22 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 22:49:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:49:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:49:22 INFO - Using real prefix '/usr' 22:49:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:49:23 INFO - Installing distribute.............................................................................................................................................................................................done. 22:49:25 INFO - Installing pip.................done. 22:49:25 INFO - Return code: 0 22:49:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:49:25 INFO - Reading from file tmpfile_stdout 22:49:25 INFO - Output received: 22:49:25 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) 22:49:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:25 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 22:49:25 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 22:49:25 INFO - Using env: {'DISPLAY': ':0', 22:49:25 INFO - 'HOME': '/home/cltbld', 22:49:25 INFO - 'LANG': 'en_US.UTF-8', 22:49:25 INFO - 'LANGUAGE': 'en_US:en', 22:49:25 INFO - 'LOGNAME': 'cltbld', 22:49:25 INFO - 'MAIL': '/var/mail/cltbld', 22:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:25 INFO - 'MOZ_NO_REMOTE': '1', 22:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:25 INFO - 'NO_EM_RESTART': '1', 22:49:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:25 INFO - 'PWD': '/builds/slave/test', 22:49:25 INFO - 'SHELL': '/bin/bash', 22:49:25 INFO - 'SHLVL': '1', 22:49:25 INFO - 'TERM': 'linux', 22:49:25 INFO - 'TMOUT': '86400', 22:49:25 INFO - 'USER': 'cltbld', 22:49:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:25 INFO - '_': '/tools/buildbot/bin/python'} 22:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:25 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 22:49:25 INFO - Cleaning up... 22:49:25 INFO - Return code: 0 22:49:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:25 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 22:49:25 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 22:49:25 INFO - Using env: {'DISPLAY': ':0', 22:49:25 INFO - 'HOME': '/home/cltbld', 22:49:25 INFO - 'LANG': 'en_US.UTF-8', 22:49:25 INFO - 'LANGUAGE': 'en_US:en', 22:49:25 INFO - 'LOGNAME': 'cltbld', 22:49:25 INFO - 'MAIL': '/var/mail/cltbld', 22:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:25 INFO - 'MOZ_NO_REMOTE': '1', 22:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:25 INFO - 'NO_EM_RESTART': '1', 22:49:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:25 INFO - 'PWD': '/builds/slave/test', 22:49:25 INFO - 'SHELL': '/bin/bash', 22:49:25 INFO - 'SHLVL': '1', 22:49:25 INFO - 'TERM': 'linux', 22:49:25 INFO - 'TMOUT': '86400', 22:49:25 INFO - 'USER': 'cltbld', 22:49:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:25 INFO - '_': '/tools/buildbot/bin/python'} 22:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:25 INFO - Downloading/unpacking psutil>=3.1.1 22:49:25 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 22:49:25 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 22:49:25 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 22:49:25 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 22:49:25 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 22:49:25 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 22:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:49:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:28 INFO - Installing collected packages: psutil 22:49:28 INFO - Running setup.py install for psutil 22:49:28 INFO - building 'psutil._psutil_linux' extension 22:49:28 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 22:49:28 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 22:49:28 INFO - building 'psutil._psutil_posix' extension 22:49:28 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 22:49:28 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 22:49:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:28 INFO - Successfully installed psutil 22:49:28 INFO - Cleaning up... 22:49:28 INFO - Return code: 0 22:49:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:49: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')]} 22:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49: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')]} 22:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:28 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:28 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 22:49:28 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 22:49:28 INFO - Using env: {'DISPLAY': ':0', 22:49:28 INFO - 'HOME': '/home/cltbld', 22:49:28 INFO - 'LANG': 'en_US.UTF-8', 22:49:28 INFO - 'LANGUAGE': 'en_US:en', 22:49:28 INFO - 'LOGNAME': 'cltbld', 22:49:28 INFO - 'MAIL': '/var/mail/cltbld', 22:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:28 INFO - 'MOZ_NO_REMOTE': '1', 22:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:28 INFO - 'NO_EM_RESTART': '1', 22:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:28 INFO - 'PWD': '/builds/slave/test', 22:49:28 INFO - 'SHELL': '/bin/bash', 22:49:28 INFO - 'SHLVL': '1', 22:49:28 INFO - 'TERM': 'linux', 22:49:28 INFO - 'TMOUT': '86400', 22:49:28 INFO - 'USER': 'cltbld', 22:49:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:28 INFO - '_': '/tools/buildbot/bin/python'} 22:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:49:28 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 22:49:28 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 22:49:28 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 22:49:28 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 22:49:28 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 22:49:28 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 22:49:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:49:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:49:30 INFO - Installing collected packages: mozsystemmonitor 22:49:30 INFO - Running setup.py install for mozsystemmonitor 22:49:30 INFO - Successfully installed mozsystemmonitor 22:49:30 INFO - Cleaning up... 22:49:30 INFO - Return code: 0 22:49:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:49:30 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')]} 22:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:30 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')]} 22:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:30 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:30 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 22:49:30 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 22:49:30 INFO - Using env: {'DISPLAY': ':0', 22:49:30 INFO - 'HOME': '/home/cltbld', 22:49:30 INFO - 'LANG': 'en_US.UTF-8', 22:49:30 INFO - 'LANGUAGE': 'en_US:en', 22:49:30 INFO - 'LOGNAME': 'cltbld', 22:49:30 INFO - 'MAIL': '/var/mail/cltbld', 22:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:30 INFO - 'MOZ_NO_REMOTE': '1', 22:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:30 INFO - 'NO_EM_RESTART': '1', 22:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:30 INFO - 'PWD': '/builds/slave/test', 22:49:30 INFO - 'SHELL': '/bin/bash', 22:49:30 INFO - 'SHLVL': '1', 22:49:30 INFO - 'TERM': 'linux', 22:49:30 INFO - 'TMOUT': '86400', 22:49:30 INFO - 'USER': 'cltbld', 22:49:30 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:30 INFO - '_': '/tools/buildbot/bin/python'} 22:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:31 INFO - Downloading/unpacking jsonschema==2.5.1 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:49:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:49:33 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 22:49:33 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 22:49:33 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 22:49:33 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 22:49:33 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 22:49:33 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 22:49:33 INFO - Downloading functools32-3.2.3-2.tar.gz 22:49:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:49:33 INFO - warning: no files found matching '*.txt' 22:49:33 INFO - no previously-included directories found matching 'build' 22:49:33 INFO - no previously-included directories found matching 'dist' 22:49:33 INFO - no previously-included directories found matching '.git*' 22:49:33 INFO - Installing collected packages: jsonschema, functools32 22:49:33 INFO - Running setup.py install for functools32 22:49:33 INFO - warning: no files found matching '*.txt' 22:49:33 INFO - no previously-included directories found matching 'build' 22:49:33 INFO - no previously-included directories found matching 'dist' 22:49:33 INFO - no previously-included directories found matching '.git*' 22:49:33 INFO - Successfully installed jsonschema functools32 22:49:33 INFO - Cleaning up... 22:49:33 INFO - Return code: 0 22:49:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:49:33 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')]} 22:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:33 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')]} 22:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:33 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:33 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 22:49:33 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 22:49:33 INFO - Using env: {'DISPLAY': ':0', 22:49:33 INFO - 'HOME': '/home/cltbld', 22:49:33 INFO - 'LANG': 'en_US.UTF-8', 22:49:33 INFO - 'LANGUAGE': 'en_US:en', 22:49:33 INFO - 'LOGNAME': 'cltbld', 22:49:33 INFO - 'MAIL': '/var/mail/cltbld', 22:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:33 INFO - 'MOZ_NO_REMOTE': '1', 22:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:33 INFO - 'NO_EM_RESTART': '1', 22:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:33 INFO - 'PWD': '/builds/slave/test', 22:49:33 INFO - 'SHELL': '/bin/bash', 22:49:33 INFO - 'SHLVL': '1', 22:49:33 INFO - 'TERM': 'linux', 22:49:33 INFO - 'TMOUT': '86400', 22:49:33 INFO - 'USER': 'cltbld', 22:49:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:33 INFO - '_': '/tools/buildbot/bin/python'} 22:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:49:33 INFO - Cleaning up... 22:49:33 INFO - Return code: 0 22:49:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:49:33 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')]} 22:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:33 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')]} 22:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:33 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:33 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 22:49:33 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 22:49:33 INFO - Using env: {'DISPLAY': ':0', 22:49:33 INFO - 'HOME': '/home/cltbld', 22:49:33 INFO - 'LANG': 'en_US.UTF-8', 22:49:33 INFO - 'LANGUAGE': 'en_US:en', 22:49:33 INFO - 'LOGNAME': 'cltbld', 22:49:33 INFO - 'MAIL': '/var/mail/cltbld', 22:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:33 INFO - 'MOZ_NO_REMOTE': '1', 22:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:33 INFO - 'NO_EM_RESTART': '1', 22:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:33 INFO - 'PWD': '/builds/slave/test', 22:49:33 INFO - 'SHELL': '/bin/bash', 22:49:33 INFO - 'SHLVL': '1', 22:49:33 INFO - 'TERM': 'linux', 22:49:33 INFO - 'TMOUT': '86400', 22:49:33 INFO - 'USER': 'cltbld', 22:49:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:33 INFO - '_': '/tools/buildbot/bin/python'} 22:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:34 INFO - Downloading/unpacking blobuploader==1.2.4 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:35 INFO - Downloading blobuploader-1.2.4.tar.gz 22:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:49:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:49:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:36 INFO - Downloading docopt-0.6.1.tar.gz 22:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:49:36 INFO - Installing collected packages: blobuploader, requests, docopt 22:49:36 INFO - Running setup.py install for blobuploader 22:49:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:49:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:49:36 INFO - Running setup.py install for requests 22:49:37 INFO - Running setup.py install for docopt 22:49:37 INFO - Successfully installed blobuploader requests docopt 22:49:37 INFO - Cleaning up... 22:49:37 INFO - Return code: 0 22:49:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:37 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:37 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 22:49:37 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 22:49:37 INFO - Using env: {'DISPLAY': ':0', 22:49:37 INFO - 'HOME': '/home/cltbld', 22:49:37 INFO - 'LANG': 'en_US.UTF-8', 22:49:37 INFO - 'LANGUAGE': 'en_US:en', 22:49:37 INFO - 'LOGNAME': 'cltbld', 22:49:37 INFO - 'MAIL': '/var/mail/cltbld', 22:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:37 INFO - 'MOZ_NO_REMOTE': '1', 22:49:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:37 INFO - 'NO_EM_RESTART': '1', 22:49:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:37 INFO - 'PWD': '/builds/slave/test', 22:49:37 INFO - 'SHELL': '/bin/bash', 22:49:37 INFO - 'SHLVL': '1', 22:49:37 INFO - 'TERM': 'linux', 22:49:37 INFO - 'TMOUT': '86400', 22:49:37 INFO - 'USER': 'cltbld', 22:49:37 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:37 INFO - '_': '/tools/buildbot/bin/python'} 22:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:37 INFO - Running setup.py (path:/tmp/pip-gZvXkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:37 INFO - Running setup.py (path:/tmp/pip-YGAsPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:37 INFO - Running setup.py (path:/tmp/pip-0lnlhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:37 INFO - Running setup.py (path:/tmp/pip-FKi6oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:37 INFO - Running setup.py (path:/tmp/pip-B3eMZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:37 INFO - Running setup.py (path:/tmp/pip-gRif2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:38 INFO - Running setup.py (path:/tmp/pip-306bxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:38 INFO - Running setup.py (path:/tmp/pip-rjDG6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:38 INFO - Running setup.py (path:/tmp/pip-uoxUre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:38 INFO - Running setup.py (path:/tmp/pip-9dbkZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:38 INFO - Running setup.py (path:/tmp/pip-lkNm9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:38 INFO - Running setup.py (path:/tmp/pip-wVEOBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:38 INFO - Running setup.py (path:/tmp/pip-uhepR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:38 INFO - Running setup.py (path:/tmp/pip-fWf50M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:38 INFO - Running setup.py (path:/tmp/pip-FtTcIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:38 INFO - Running setup.py (path:/tmp/pip-oN7dbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:38 INFO - Running setup.py (path:/tmp/pip-iBoVn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:49:38 INFO - Running setup.py install for manifestparser 22:49:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:49:38 INFO - Running setup.py install for mozcrash 22:49:39 INFO - Running setup.py install for mozdebug 22:49:39 INFO - Running setup.py install for mozdevice 22:49:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for mozfile 22:49:39 INFO - Running setup.py install for mozhttpd 22:49:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for mozinfo 22:49:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for mozInstall 22:49:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for mozleak 22:49:39 INFO - Running setup.py install for mozlog 22:49:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for moznetwork 22:49:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Running setup.py install for mozprocess 22:49:40 INFO - Running setup.py install for mozprofile 22:49:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:49:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:49:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:49:40 INFO - Running setup.py install for mozrunner 22:49:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:49:40 INFO - Running setup.py install for mozscreenshot 22:49:40 INFO - Running setup.py install for moztest 22:49:40 INFO - Running setup.py install for mozversion 22:49:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:49:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:49:40 INFO - Cleaning up... 22:49:40 INFO - Return code: 0 22:49:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:40 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:40 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 22:49:40 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 22:49:40 INFO - Using env: {'DISPLAY': ':0', 22:49:40 INFO - 'HOME': '/home/cltbld', 22:49:40 INFO - 'LANG': 'en_US.UTF-8', 22:49:40 INFO - 'LANGUAGE': 'en_US:en', 22:49:40 INFO - 'LOGNAME': 'cltbld', 22:49:40 INFO - 'MAIL': '/var/mail/cltbld', 22:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:40 INFO - 'MOZ_NO_REMOTE': '1', 22:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:40 INFO - 'NO_EM_RESTART': '1', 22:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:40 INFO - 'PWD': '/builds/slave/test', 22:49:40 INFO - 'SHELL': '/bin/bash', 22:49:40 INFO - 'SHLVL': '1', 22:49:40 INFO - 'TERM': 'linux', 22:49:40 INFO - 'TMOUT': '86400', 22:49:40 INFO - 'USER': 'cltbld', 22:49:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:40 INFO - '_': '/tools/buildbot/bin/python'} 22:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:40 INFO - Running setup.py (path:/tmp/pip-kE2zod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:40 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)) 22:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:40 INFO - Running setup.py (path:/tmp/pip-NEeOmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:41 INFO - Running setup.py (path:/tmp/pip-q5QpMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:41 INFO - Running setup.py (path:/tmp/pip-sx1lE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:41 INFO - Running setup.py (path:/tmp/pip-lZ5KWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:41 INFO - Running setup.py (path:/tmp/pip-plU9cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:41 INFO - Running setup.py (path:/tmp/pip-cvHPMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:41 INFO - Running setup.py (path:/tmp/pip-TGJHux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:41 INFO - Running setup.py (path:/tmp/pip-iwAzVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:41 INFO - Running setup.py (path:/tmp/pip-aRYZ7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:41 INFO - Running setup.py (path:/tmp/pip-UvEI7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:41 INFO - Running setup.py (path:/tmp/pip-YdSFMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:41 INFO - Running setup.py (path:/tmp/pip-HVD8FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:41 INFO - Running setup.py (path:/tmp/pip-Z41gCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:41 INFO - Running setup.py (path:/tmp/pip-vZmue9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:41 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)) 22:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:41 INFO - Running setup.py (path:/tmp/pip-8yiGy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:42 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)) 22:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:42 INFO - Running setup.py (path:/tmp/pip-HXWarS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:42 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)) 22:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:42 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)) 22:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:44 INFO - Downloading blessings-1.6.tar.gz 22:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:49:44 INFO - Installing collected packages: blessings 22:49:44 INFO - Running setup.py install for blessings 22:49:44 INFO - Successfully installed blessings 22:49:44 INFO - Cleaning up... 22:49:44 INFO - Return code: 0 22:49:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:49:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:44 INFO - Reading from file tmpfile_stdout 22:49:44 INFO - Current package versions: 22:49:44 INFO - argparse == 1.2.1 22:49:44 INFO - blessings == 1.6 22:49:44 INFO - blobuploader == 1.2.4 22:49:44 INFO - docopt == 0.6.1 22:49:44 INFO - functools32 == 3.2.3-2 22:49:44 INFO - jsonschema == 2.5.1 22:49:44 INFO - manifestparser == 1.1 22:49:44 INFO - mozInstall == 1.12 22:49:44 INFO - mozcrash == 0.17 22:49:44 INFO - mozdebug == 0.1 22:49:44 INFO - mozdevice == 0.48 22:49:44 INFO - mozfile == 1.2 22:49:44 INFO - mozhttpd == 0.7 22:49:44 INFO - mozinfo == 0.9 22:49:44 INFO - mozleak == 0.1 22:49:44 INFO - mozlog == 3.4 22:49:44 INFO - moznetwork == 0.27 22:49:44 INFO - mozprocess == 0.23 22:49:44 INFO - mozprofile == 0.28 22:49:44 INFO - mozrunner == 6.12 22:49:44 INFO - mozscreenshot == 0.1 22:49:44 INFO - mozsystemmonitor == 0.3 22:49:44 INFO - moztest == 0.7 22:49:44 INFO - mozversion == 1.4 22:49:44 INFO - psutil == 3.1.1 22:49:44 INFO - requests == 1.2.3 22:49:44 INFO - wsgiref == 0.1.2 22:49:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 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 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:44 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 22:49:44 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 22:49:44 INFO - Using env: {'DISPLAY': ':0', 22:49:44 INFO - 'HOME': '/home/cltbld', 22:49:44 INFO - 'LANG': 'en_US.UTF-8', 22:49:44 INFO - 'LANGUAGE': 'en_US:en', 22:49:44 INFO - 'LOGNAME': 'cltbld', 22:49:44 INFO - 'MAIL': '/var/mail/cltbld', 22:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:44 INFO - 'MOZ_NO_REMOTE': '1', 22:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:44 INFO - 'NO_EM_RESTART': '1', 22:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:44 INFO - 'PWD': '/builds/slave/test', 22:49:44 INFO - 'SHELL': '/bin/bash', 22:49:44 INFO - 'SHLVL': '1', 22:49:44 INFO - 'TERM': 'linux', 22:49:44 INFO - 'TMOUT': '86400', 22:49:44 INFO - 'USER': 'cltbld', 22:49:44 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:44 INFO - '_': '/tools/buildbot/bin/python'} 22:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 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)) 22:49:44 INFO - Cleaning up... 22:49:44 INFO - Return code: 0 22:49:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f786a4a6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbdca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, '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': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', '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 22:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:49:44 INFO - Using env: {'DISPLAY': ':0', 22:49:44 INFO - 'HOME': '/home/cltbld', 22:49:44 INFO - 'LANG': 'en_US.UTF-8', 22:49:44 INFO - 'LANGUAGE': 'en_US:en', 22:49:44 INFO - 'LOGNAME': 'cltbld', 22:49:44 INFO - 'MAIL': '/var/mail/cltbld', 22:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:44 INFO - 'MOZ_NO_REMOTE': '1', 22:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:44 INFO - 'NO_EM_RESTART': '1', 22:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:44 INFO - 'PWD': '/builds/slave/test', 22:49:44 INFO - 'SHELL': '/bin/bash', 22:49:44 INFO - 'SHLVL': '1', 22:49:44 INFO - 'TERM': 'linux', 22:49:44 INFO - 'TMOUT': '86400', 22:49:44 INFO - 'USER': 'cltbld', 22:49:44 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:44 INFO - '_': '/tools/buildbot/bin/python'} 22:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:49:44 INFO - Cleaning up... 22:49:44 INFO - Return code: 0 22:49:44 INFO - Running post-action listener: _resource_record_post_action 22:49:44 INFO - Running post-action listener: _start_resource_monitoring 22:49:45 INFO - Starting resource monitoring. 22:49:45 INFO - [mozharness: 2016-10-21 05:49:45.016875Z] Finished create-virtualenv step (success) 22:49:45 INFO - [mozharness: 2016-10-21 05:49:45.017414Z] Running install step. 22:49:45 INFO - Running pre-action listener: _resource_record_pre_action 22:49:45 INFO - Running main action method: install 22:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:45 INFO - Reading from file tmpfile_stdout 22:49:45 INFO - Detecting whether we're running mozinstall >=1.0... 22:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:49:45 INFO - Reading from file tmpfile_stdout 22:49:45 INFO - Output received: 22:49:45 INFO - Usage: mozinstall [options] installer 22:49:45 INFO - Options: 22:49:45 INFO - -h, --help show this help message and exit 22:49:45 INFO - -d DEST, --destination=DEST 22:49:45 INFO - Directory to install application into. [default: 22:49:45 INFO - "/builds/slave/test"] 22:49:45 INFO - --app=APP Application being installed. [default: firefox] 22:49:45 INFO - mkdir: /builds/slave/test/build/application 22:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:50:02 INFO - Reading from file tmpfile_stdout 22:50:02 INFO - Output received: 22:50:02 INFO - /builds/slave/test/build/application/firefox/firefox 22:50:02 INFO - Running post-action listener: _resource_record_post_action 22:50:02 INFO - [mozharness: 2016-10-21 05:50:02.230752Z] Finished install step (success) 22:50:02 INFO - [mozharness: 2016-10-21 05:50:02.231029Z] Running run-tests step. 22:50:02 INFO - Running pre-action listener: _resource_record_pre_action 22:50:02 INFO - Running main action method: run_tests 22:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:50:02 INFO - Python 2.7.3 22:50:02 INFO - Return code: 0 22:50:02 INFO - grabbing minidump binary from tooltool 22:50:02 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')]} 22:50:02 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 0x1bbaf60>, '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 0x1bbbd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:50:02 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 22:50:02 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 22:50:02 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 22:50:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:50:02 INFO - Return code: 0 22:50:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:50:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:50:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:50:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:50:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 22:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 22:50:02 INFO - Using env: {'DISPLAY': ':0', 22:50:02 INFO - 'HOME': '/home/cltbld', 22:50:02 INFO - 'LANG': 'en_US.UTF-8', 22:50:02 INFO - 'LANGUAGE': 'en_US:en', 22:50:02 INFO - 'LOGNAME': 'cltbld', 22:50:02 INFO - 'MAIL': '/var/mail/cltbld', 22:50:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:50:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:02 INFO - 'MOZ_NO_REMOTE': '1', 22:50:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:50:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:02 INFO - 'NO_EM_RESTART': '1', 22:50:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:02 INFO - 'PWD': '/builds/slave/test', 22:50:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:50:02 INFO - 'SHELL': '/bin/bash', 22:50:02 INFO - 'SHLVL': '1', 22:50:02 INFO - 'TERM': 'linux', 22:50:02 INFO - 'TMOUT': '86400', 22:50:02 INFO - 'USER': 'cltbld', 22:50:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743', 22:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:02 INFO - '_': '/tools/buildbot/bin/python'} 22:50:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 22:50:02 INFO - mozversion application_buildid: 20161020193007 22:50:02 INFO - mozversion application_changeset: 1171f3ae792b91d7ca49ef0907b251e381f1df56 22:50:02 INFO - mozversion application_display_name: Nightly 22:50:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:50:02 INFO - mozversion application_name: Firefox 22:50:02 INFO - mozversion application_remotingname: firefox 22:50:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:50:02 INFO - mozversion application_vendor: Mozilla 22:50:02 INFO - mozversion application_version: 52.0a1 22:50:02 INFO - mozversion platform_buildid: 20161020193007 22:50:02 INFO - mozversion platform_changeset: 1171f3ae792b91d7ca49ef0907b251e381f1df56 22:50:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:50:02 INFO - mozversion platform_version: 52.0a1 22:50:02 INFO - using testdate: 1477029002 22:50:02 INFO - actual date: 1477029002 22:50:02 INFO - starting webserver on 'localhost:48591' 22:50:02 INFO - SUITE-START | Running 2 tests 22:50:02 INFO - TEST-START | dromaeo_css 22:50:02 INFO - operating with platform_type : linux_ 22:50:02 INFO - Initialising browser for dromaeo_css test... 22:50:02 INFO - TEST-INFO | started process 12122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSy66sX/profile http://localhost:48591/getInfo.html) 22:50:06 INFO - PROCESS | 12122 | __metrics Screen width/height:1600/1200 22:50:06 INFO - PROCESS | 12122 | colorDepth:24 22:50:06 INFO - PROCESS | 12122 | Browser inner width/height: 1024/697 22:50:06 INFO - PROCESS | 12122 | __metrics 22:50:06 INFO - PROCESS | 12122 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:50:06 INFO - PROCESS | 12122 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:50:07 INFO - PROCESS | 12122 | console.error: PushService: 22:50:07 INFO - PROCESS | 12122 | stateChangeProcessEnqueue: Error transitioning state 22:50:07 INFO - PROCESS | 12122 | UnknownError 22:50:08 INFO - TEST-INFO | 12122: exit 0 22:50:08 INFO - Browser initialized. 22:50:08 INFO - Running cycle 1/1 for dromaeo_css test... 22:50:08 INFO - TEST-INFO | started process 12205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSy66sX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:20 INFO - PROCESS | 12205 | RSS: Main: 171782144 22:50:20 INFO - PROCESS | 12205 | 22:51:12 INFO - PROCESS | 12205 | 1477029072031 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] 22:53:22 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48591/tests/dromaeo/cssquery-ext.html) 22:53:23 INFO - PROCESS | 12205 | RSS: Main: 281935872 22:53:23 INFO - PROCESS | 12205 | 22:56:26 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-ext.html (next: http://localhost:48591/tests/dromaeo/cssquery-jquery.html) 22:56:27 INFO - PROCESS | 12205 | RSS: Main: 274554880 22:56:27 INFO - PROCESS | 12205 | 22:59:29 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48591/tests/dromaeo/cssquery-mootools.html) 22:59:29 INFO - PROCESS | 12205 | RSS: Main: 255549440 22:59:29 INFO - PROCESS | 12205 | 23:02:31 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48591/tests/dromaeo/cssquery-prototype.html) 23:02:32 INFO - PROCESS | 12205 | RSS: Main: 251957248 23:02:32 INFO - PROCESS | 12205 | 23:05:35 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48591/tests/dromaeo/cssquery-yui.html) 23:05:35 INFO - PROCESS | 12205 | RSS: Main: 210079744 23:05:35 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | Cycle 1(1): loaded http://localhost:48591/tests/dromaeo/cssquery-yui.html (next: http://localhost:48591/tests/dromaeo/cssquery-dojo.html) 23:08:38 INFO - PROCESS | 12205 | RSS: Main: 302841856 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | __start_tp_report 23:08:38 INFO - PROCESS | 12205 | _x_x_mozilla_page_load 23:08:38 INFO - PROCESS | 12205 | _x_x_mozilla_page_load_details 23:08:38 INFO - PROCESS | 12205 | |i|pagename|runs| 23:08:38 INFO - PROCESS | 12205 | |0;dojo.html;2965.91102266932;2985.5223164293716;3022.999999999999;3040.5135178371465;3046.345035817299;5107.46371630979;5215.400228973669;5288.576913846892;5359.705216213107;5389.568290135952;5243;5293.100172970595;5391.083515802313;5399.244105825178;5410.269613602162;4742.928856067162;4801.207800712896;4849.490803465638;4859.149648811462;4884.535969082932;4848.6363522735755;4869.780859861315;4903.141950158708;4905.950940490603;4964.180910149821;4711.835085772016;4888.511148885119;4889.706617602955;4917.926231106519;5017.147084995559;4744.098621261984;4811.302361157647;4812.494688057738;4849.442314133849;4866.221404575286;4828.0585285869265;4830.203016502308;4839;4868.610511159134;4893.632977526664;5370.624056316059;5392.78428862845;5394.379646340682;5397.62216644835;5400.594955378323;5473.67157970519;5518.034343989826;5637.520810731093;5699.2306038684665;5730.169125476783;5720.342160651537;5738.45484678959;5741.799037033726;5789;5811.564132690065;5336.91994620081;5350.3312085989255;5350.973245133828;5359.5444387227135;5360.008398446261;5598.468145526169;5602.467765562265;5864.149698293723;5867.3839246879315;5939.16851640762;5573.526250268692;5583.469570390806;5590.18942253371;5643.322801263875;5721.456461636138;5668.178114173422;5723.8569035774435;5730.455606717355;5731.799387021518;5736.5123964462655;5397.514223719884;5692.8861422771315;5704.087346024616;5713.914291285634;5717.971410142923;5388.245645609618;5522.22688823565;5522.337319521683;5523.6685798851395;5529.585281103933;4265.765382903908;4336.262835318003;4347.739135651871;4380.496242932039;4433.445819272591;3881.243157584244;3888.6111388860886;3891.3579259421876;3942.6648734857285;3943.231069941391;5462.071447853795;5463.31708536433;5474.09677403224;5475.6440831345835;5535.916961245584;5406.756695948692;5503.587230957694;5518.978988887069;5648.209250704905;5663.745131469093;5387.865303367447;5389.51494365509;5448.237246785374;5453.972730136404;5455.318085239345;4916.311716359713;4924.359833221658;4925.236588328743;4938.703677779345;4984.451710311862;5652.576056795675;5805.912911306418;5857.502112320501;5871.383504731942;5879.647221166743;5554.722263886709;5672.744726487235;5701.857453563694;5735.225744524436;5827.3007239131575;5901.7639294427745;5921.733521991435;5927.170196172453;5927.31835838884;5943.197668314722;5614.63504872182;5654.575906807088;5655.830325090254;5673.432656734293;5679.602427830012;5484.341878974548;5605.5515558756015;5693.1460280956535;5724.914126288192;5755.942440575541;5627.409122042291;5628.352739435018;5629.155626656035;5637.830865074055;5646;5564.721763911707;5593.468620481048;5609.046462101534;5611.074172761449;5615.887682246251;5341.946580534301;5398.055340315507;5558.194061860927;5606.299212598426;5622.803201887914;5686.175504551899;5687.658740475585;5690.146478028329;5738.856528586652;5762.654240745569;5198.818041368534;5512.255845460972;5552;5562.944370556243;5567.610267281251 23:08:38 INFO - PROCESS | 12205 | |1;ext.html;580.4949693766421;601.3235110500686;616.3897741236177;620.2494981072903;634.9587276827009;30782;42156.367654485206;42887.35668964968;43066.78466607662;43176.784116079456;44254.77872610641;44745.10509789802;44762.104757904825;44767.55232447675;44772.552274477246;7958.164392738766;8197.7130800422;8740.125987401241;8894.955525222365;8899.110088991114;10281.383117013003;10515.84226236607;10534.525946332395;10850.24048316618;10851.457427128651;10699.518521666505;10796.730081747979;10800.675979720621;10801.783964320708;10811.405372704497;8125.715599953974;8300;10480.161587073017;10576.206784491156;10647.2014598905;10221.437820919846;10561.947190264076;10585.259031867772;10586.788264234672;10594.198986172369;41408.79295603533;41883.16233675309;41954.64362850964;42097.158056839;42138;3219.4687876500348;3254.9674503255137;3358.9664103358896;3495.667911548399;3901.141748815256;31159.376812463852;31791.84104079488;31832.522512162337;31880;32710.182245443815;3623.2753449310244;3626.5829429615405;3754.342989976743;3782.281366540376;3879.72841901067;259245;319483;319598;320394;321081;59620.40379596235;59691.70154149202;60138;60167.27574501673;60412.260835220586;62512.687436562526;63030.369696303365;63084;63436.36563634305;63615.09154725366;16042.278097485641;16427.58931026734;16558.6688266234;16639.084850333107;16651.750223746654;11633.709157271136;11738.119641026957;12212.389380530938;12521.499140034297;12646.925011374136;18635.720464193048;18754.624907501773;18890.90554547246;18936.90531547361;18946.905265473586;6884.896726549106;7099.929560226035;7199.640017999079;7208.315210055036;7306.2693730626515;822.6668199379142;874.8600223964136;966.6375109334;979.86771785808;999.0758548342899;28795.71204287972;29583.83564646448;29824.141166137622;29917;30010.249743756143;1009.3338396658318;1013.7212266626736;1026.4200726589454;1131.9773604528027;1132.69983454383;14939.70120597582;15876.682466350609;15940.760888586681;16013.839861601235;16048.117353545667;490811;492197;492897;493369;494682;56418;57022.42977570254;57188;57190.71404643033;57191.42808571944;1191.398343836357;1227.7974134267745;1286.7233544787916;1305.1646945954597;1307.6796184934562;26617.334566635214;29633;30158.84920575383;30559.847200763856;30766.53850192204;48965.755171223915;49469.25796112989;49507.029578225425;49513;49550.75224623854;346467;346675;347426;348339;350392;24484.265472035866;25273.62089568695;25282.36794080163;26708.599371009837;27550.311242219104;42503.362449562665;42756.1448771029;42891.785541072095;44056.559434405244;44249.55750442583;36153.81923090473;36285.63714362823;36948.630513694516;37119.814400927826;41676.79161604172;39579;39687.206255875295;40086.599134008284;40470.59529404668;40746.38880416856;37782.81108594439;38016;38080;38179.618203817605;38441.80779096198;33509.32981340408;36238.200070886895;36359.45460818037;36514.634853652184;36920.81539592392;26150.215493535223;26392;26566.468670626866;26726.331841704112;27027.189184324503 23:08:38 INFO - PROCESS | 12205 | |2;jquery.html;351.9630438803927;359.4392747314192;369.2670049950848;376.1818045750493;379.21124061951144;14150;17870.91064544677;18802.2479100836;19943.601127977432;20062.59874802503;18486.075696215168;18537.16582753776;19030.429087127373;19180;19181.424557263268;5419.430959749238;5680.914786278209;5684.687342196177;5686.31764188298;5686.943130568703;4143.70994030418;4329.848455304065;4350.630196433307;5507.118860982244;5602.635828671123;6222.284437289702;6323.05154226865;6323.968380158093;6324.494040476751;6331.271903731061;6260.686965651722;6310.864044472016;6321.0834429008;6370.77702280418;6401.423871851509;5348.893022139574;5514.117741161435;5517.337919449652;5519.503244707996;5523;22048.669269960803;22334.77665223343;22593.887030564907;22609.54780904373;22754.43113922165;2179.705739725133;2183.617866873291;2184.388371256051;2258.570871534403;2906.80820863445;13730.313484325743;14278.143311401245;14826;15127.773083403757;15140.924295378563;1850.4633656239675;1879.1074239736154;1901.6291823094502;1936.2642195965584;1970.6945423459388;64488;67169.3283067168;75787;79866.20133798645;81049.59475202586;9969.252306077073;10050.64822731201;10129.240306977006;10186.490675466197;10386.792264154674;14057.367418466194;14073.788893166611;14175.291235438186;14191.858081419261;14200.573982780534;7566.508176968479;7582.696692132363;7744.574048427278;7816.41376896735;7842.411819113589;7090.149182098078;7285.453590980697;7407.407407407394;7535.660895259726;7603.581803000778;6107.236595425572;6114.296855861544;6176.66028368444;6292.4966002719675;6376.74493020274;1703.8381353771372;1714.9228284727217;1783.286685325854;1793.5695433096223;1806.1691621854095;860.9268212202034;893.6425429827991;954.2783145731702;961.5432669481941;1042.7862288230895;18331.541711457052;19341.51646208856;19373.612527749647;19511.121999510055;19534;937.6577549194495;942.5852624845046;961.4663861556903;964.6961207219704;986.4475893499663;9617.855732164024;10437.530311135864;10810.945945270381;10849.240553161202;10902.890971090188;463724;468339.4073918548;472945;473968;475996;78882;80486;81158.59420702978;81533.59233203797;82013;983.1692220074169;991.0683957080322;1018.9796204075877;1028.88682244953;1031.5925209542188;16140;16626.750598740786;17084.316627334767;17133.91433042827;17470.213840377466;38354.23291534098;39906.80046599748;40111.3983290245;40766.59233407628;41105.588944111376;52334.47665523296;53020.204696928806;53607;54609;55638;8041.919580804117;8099.190080991853;8114.553699546582;8148.674053037812;8334.958325208336;12473.5010599575;12630.87369126297;12701.301428421157;12759.553415630407;12920.547780827626;5054.949450505448;10969.460422486593;11087.33475991443;11154.721131971766;11183.986882361252;10318.793624127626;11338.60314888975;11402.315861048364;11437.142214333791;11581.131415144066;10969.451527423756;11149.609763658233;11177.888221117684;11256.662300131009;11738.823917641414;7880.56656883854;8028.317593004426;8189.181081891763;8221.671133154607;8376.371772117238;10769.784604308026;11089.833652495057;11158;11186.32882027081;11643.243189192675 23:08:38 INFO - PROCESS | 12205 | |3;mootools.html;1338.4913732781542;1383.5780087073445;1440.8847292216624;1515.4695856450244;1767.9646407071866;7839.529628222311;8361.83276334473;8641.395102342847;8731.519766412861;8963.238124759388;8780.824383512327;8790.91209087909;8859.246964008067;8862.86705699415;8873.423227490208;2265.2071774878827;2288.1648198407543;2291.163725240284;2292.266474728088;2293.3120063980823;2221.911123555056;2239.484918468753;2282.7488976212603;2300.850444721096;2303.2744685424123;2246.595612789697;2264.3999340174782;2273.27255278311;2275.260540324393;2279.4073540879326;2137.967930481044;2145.463634091477;2148.033384976759;2156.7196264485674;2158.190678495564;2159.211887661008;2161.6217161996587;2162.48100455891;2162.697222388867;2163.751168615598;8744.125587441269;8775.25410340122;8802.603882825288;8806.471611703255;8913.1086891311;5203.817866374697;5339.866503337409;5350.759215835296;5356.330458692663;5360.812371567015;3218.9678103219135;3236.72487838534;3238.2875767331147;3274;3293.110860067792;939.6945992552447;947.441009804212;950.3680052764903;950.4582388038821;957.4638202606562;12633.2420054797;12733.936330318289;12774.936125319315;12825.551105711254;12825.743485130246;4366.257736184856;4406.6474682026;4490.797914093873;4541.182587134347;4576;9804.607815687436;9825.9017409825;9828.11546960777;9835.606575737034;9835.950820245853;5215.19164529491;5218.9739051304505;5221.451747566527;5221.686698798084;5223.059849227099;4132.111596006813;4169.332906734969;4789.784459699322;4809.879753006133;4848.345473361122;3590.5152804371596;3610.548681414823;3719;3733.197362567061;3780.243951209714;1097.9341239525654;1101.2676570080807;1111.7554138089608;1115.5314767797545;1122.5565901468874;722.2236096196609;862.6764963138822;865.6623916672503;868.7610907000626;870.6038752367657;5395.62230643859;5562.582806289463;5703.201551782754;5732.598718089694;6102.389761023951;867.2368315881984;870.2472361407337;883.5052370672444;902.8690839828188;926.8887733472027;3795.3168429682923;3865.9806700966315;3885.0870045539277;3886.9699529624672;3905.72659913809;11233.887661123284;11574.537018519333;11609.76780464386;11629.767404651859;11648.650540483799;11570.76858462826;11580.131490138274;11626.244294120856;11627.59303424376;11659.650210493699;8166.673333066729;8367.748967530984;8489.40574159803;8496.702615408554;8502.489850608983;5694.031443762092;5729.484346408676;5772.3650398456975;5788.623739456922;5942.168096466412;10370.637027703995;10371.170306375623;10485.108765754996;10540.578376865145;10559.472026398804;10295.794084118426;10433.895661043596;10539.314944528582;10604.310719803188;10700.678979630624;6861.142357205349;7101.183363913216;7306.6711997959155;7458.73894413693;7690.769276921701;8472.872906906277;8602.655893764178;8693.91306086931;8697.304215662858;8707.825843482968;7390.260973902567;7753.340966017951;7813.453058285865;7824.139344671968;8079.515229086039;7297.635118244173;7648.847023059619;7694;7725.343345815669;7744.65149068282;6640.701168447335;6822.11312529368;6823.590584564942;6824.112865327475;6837.487188460985;4880.194767863405;4939.827106051414;4988.8752781180765;5036.06832735945;5051.646384753032;4832.226843704991;4894.339264199288;4938.037082768826;4992.8502144934255;5060.063888180698 23:08:38 INFO - PROCESS | 12205 | |4;prototype.html;305.21711809209376;311.7739638761897;317.52688494143723;320.2458210913299;322.66604064792944;5203.141481655532;5226.111561034624;5368.248445217668;5380;5409.08045632243;5093.694378337303;5181.818636347729;5186.766595503202;5221.164613661815;5276.709780962045;4083.4079058536495;4102;4105.261053010457;4109.404136400228;4113.382992551126;3942.4283478895545;4035.3745169498775;4106.055607210344;4111.321631930728;4118.279301122307;4018.0557568971267;4029.919401611966;4037.091654377756;4046.008727861678;4046.231216068952;4022.275990321726;4023.356262997921;4037.1521980384155;4046.7167298289132;4050.4936882889638;3528.858845646171;3533.8233088345737;3539.026767638894;3540.097275194809;3545.2377738786286;5055.848324550233;5101.82143624975;5116.872078198037;5118.360204974379;5152.278680984665;4582.221022426195;4582.564656357641;4601.585857272828;4606;4616.330632058366;5437.238786569884;5437.429069947638;5440.972795136038;5441.619086663935;5446.428125046853;3762.795905310302;3837.654611085016;3852.672522835562;3865.1689886674217;3871.187050719352;5674.631148975344;5692;5701.543876489913;5703.62926409782;5706.657600543939;3900.5762896542556;3906.43356713275;3915.393114067324;3932.9606703932595;3945.9740467478664;3896.493455850721;3990.4014397839974;4028.657564107025;4045.838166473367;4046.2716710991717;4584.954150458453;4591.678582499193;4604.608608268335;4623.745693986863;4627.282771170407;5521.585881058856;5572.777088916398;5576.721163941867;5628.101527713231;5644.491995720405;1268.6828292926768;1304.5564508067114;1344.946202151903;1365.8429280632656;1371.5611004478471;882.342654722236;900.2662829793697;918.8147289995964;919.2416256588208;922.6217250927087;5329.200619906967;5337.62636615441;5348.224507446398;5350.010248104113;5350.973245133828;5634.5210657093785;5635.746391412396;5643.52030077448;5668.149777533403;5668.914966275509;5175;5351;5371.75827087782;5376.381716102698;5433.26650902131;3015.577819105327;3035;3040.5135178371356;3041.1636799879766;3047.222958145703;5892.467958330865;5895.528357731371;5919.881602367928;5931.377205393372;5934.228550288435;5607.7661365668655;5748.540116790647;5757.280339957505;5776.653400795965;5797.449242321973;5819.166962406828;5831.241938547962;5833.795817146464;5845;5864.208331875142;5392.622516423813;5649.858753531194;5688.658680479019;5704.828855134187;5709.942900570941;5632.295963004624;5655.4910058094965;5662.85842853932;5664.603477756682;5681.409205422488;5910.468057874812;5933.614315069507;5943.9091672331015;5947.732352044255;5978.97010514962;3893.279743247508;3965.92068158641;3999.3601023836045;3999.8200080997008;4011.719179657396;5499.477549632778;5708.600397972268;5735.4551317624755;5740.540756739534;5744.798932037359;5432.782688692408;5456.399796022513;5534.584906131976;5548.889022219614;5591.776328946797;5607.327120745374;5613.326400831927;5620.943790562205;5627.155926611041;5634.211210430461;5320.058178764645;5574.9721251393485;5576.247206627054;5593.188987596857;5609.6073274872;5608.6073974823;5612.663240205601;5618.101103823368;5641.50500117461;5642.520385767256;5496.532794712494;5500.532454741392;5507.449255074461;5531.806386776443;5548.167774833807 23:08:38 INFO - PROCESS | 12205 | |5;yui.html;576.924999750033;579.8202557207265;650.8958566629341;732.051992669493;761.0486891385766;13774.311284435777;14199.432022719091;14280.07179533331;14683.63290917725;14719.116852988827;13944.163350198995;14550.19973901435;15009.699806003873;15044.247787610602;15157.317920693567;785.5757890738994;817.2522142239843;820.7989042684551;821.1378053044309;821.1747194069951;669.0532895952222;676.7394553097068;796.9840603187932;800.2117913854848;801.1187693537121;862.7066797288952;863.8358711844761;865.126222515261;865.4244927123456;866.1079088538816;852.8805967164602;853.6414705823601;853.6841368693623;853.7737499562621;854.5428195915156;508.50420839681385;520.885405210853;604.7369394313488;609.7012463892705;611.541343992006;13983.580492585239;13991.510297139654;14026.08830426019;14032.298385080807;14043.368048437846;12196.512139514409;13124.803127953088;13555.118917270345;13685;14250.144991300554;12469.688257793629;13041.217526948318;13094.14888032275;13128.934355328161;13353.132046416951;4361.171377438277;4394.340848872695;4421.7789110544345;4427.070315233805;4441.933370999438;84562.57718711428;85092.57453712754;85608;85907;86248.56875715643;11095;11343.943280283545;11354.772904541864;11516.539338426537;11554.42227888619;21094.47263818417;21131.788682112983;21211.46971325698;21235.46911327198;21237.68143477849;7864.134945156028;8071.919280807234;8103.918960810316;8494.154736627504;8530.78673033179;4349.497117472398;4359.17175736609;4440.822367105344;4654.138984287918;4674.906501869944;3239.659835717263;3327.550780644582;3335.466325387926;3341.298327351235;3343.6823501767294;621.6425555305717;628.6887990444759;628.8616504369031;645.8320836582428;646.3601034975435;479.8416522547551;546.5081426715908;560.9874177110355;569.5016860247283;596.9044952807529;8374.53940033304;8526.829463410697;8675.436096653697;9073.185362927441;9085.591148398338;514.572904489273;520.0535026252185;547.887683024979;565.5108331293402;636.3922454056365;4193.412922190896;4411.205982923105;4429.512753597101;4461.620762235247;4509.413776209072;114433.42783286197;116987.41506292413;119733.40133299452;123346;125063.37468312783;100269.49865250627;100374;100634.4968275154;101658;109101;72519.63740181371;91141.54429227945;91155;91528;91856.54071729598;8390.706325278587;8525.062243153125;8605.139486051345;8684.956575217084;8761.211490965843;51603.74198128985;52059.21911171411;53075.73462132664;53321.53410028336;53708.46291537034;46312.30531542097;46419.303710443695;48392.5160748388;74702;75266;9742.561584728846;9912.306138570231;10058.899411006088;10316.43259620728;10326.380417174994;11654.650360489199;11708.531658733555;12009.63971080869;12010.939945300217;12578.685532861751;10553.841692374774;10594.2584019121;10613.850762283575;10928.617498387519;11050.889491104987;13554.118982266516;13728.93135534316;14217.289135543388;14242.786358204428;14413.423463061361;13208.80186797218;13348.799768003682;13408.865911340761;13573.38919748594;13986.370613322222;11154.609588664358;11530.250533715282;11668.88331116712;11889.167758256839;11947.701307467383;11397.601083962021;11440.313581185155;11548.538058477567;11747.941260293645;11959.461824218106 23:08:38 INFO - PROCESS | 12205 | __end_tp_report 23:08:38 INFO - PROCESS | 12205 | __start_cc_report 23:08:38 INFO - PROCESS | 12205 | _x_x_mozilla_cycle_collect,1053 23:08:38 INFO - PROCESS | 12205 | __end_cc_report 23:08:38 INFO - PROCESS | 12205 | __startTimestamp1477030118739__endTimestamp 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | ------- Summary: start ------- 23:08:38 INFO - PROCESS | 12205 | Number of tests: 6 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#0] dojo.html Cycles:165 Average:5291.36 Median:5503.59 stddev:603.60 (11.0%) stddev-sans-first:577.23 23:08:38 INFO - PROCESS | 12205 | Values: 2965.9 2985.5 3023.0 3040.5 3046.3 5107.5 5215.4 5288.6 5359.7 5389.6 5243.0 5293.1 5391.1 5399.2 5410.3 4742.9 4801.2 4849.5 4859.1 4884.5 4848.6 4869.8 4903.1 4906.0 4964.2 4711.8 4888.5 4889.7 4917.9 5017.1 4744.1 4811.3 4812.5 4849.4 4866.2 4828.1 4830.2 4839.0 4868.6 4893.6 5370.6 5392.8 5394.4 5397.6 5400.6 5473.7 5518.0 5637.5 5699.2 5730.2 5720.3 5738.5 5741.8 5789.0 5811.6 5336.9 5350.3 5351.0 5359.5 5360.0 5598.5 5602.5 5864.1 5867.4 5939.2 5573.5 5583.5 5590.2 5643.3 5721.5 5668.2 5723.9 5730.5 5731.8 5736.5 5397.5 5692.9 5704.1 5713.9 5718.0 5388.2 5522.2 5522.3 5523.7 5529.6 4265.8 4336.3 4347.7 4380.5 4433.4 3881.2 3888.6 3891.4 3942.7 3943.2 5462.1 5463.3 5474.1 5475.6 5535.9 5406.8 5503.6 5519.0 5648.2 5663.7 5387.9 5389.5 5448.2 5454.0 5455.3 4916.3 4924.4 4925.2 4938.7 4984.5 5652.6 5805.9 5857.5 5871.4 5879.6 5554.7 5672.7 5701.9 5735.2 5827.3 5901.8 5921.7 5927.2 5927.3 5943.2 5614.6 5654.6 5655.8 5673.4 5679.6 5484.3 5605.6 5693.1 5724.9 5755.9 5627.4 5628.4 5629.2 5637.8 5646.0 5564.7 5593.5 5609.0 5611.1 5615.9 5341.9 5398.1 5558.2 5606.3 5622.8 5686.2 5687.7 5690.1 5738.9 5762.7 5198.8 5512.3 5552.0 5562.9 5567.6 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#1] ext.html Cycles:180 Average:54864.35 Median:19930.74 stddev:103433.89 (519.0%) stddev-sans-first:103643.75 23:08:38 INFO - PROCESS | 12205 | Values: 580.5 601.3 616.4 620.2 635.0 30782.0 42156.4 42887.4 43066.8 43176.8 44254.8 44745.1 44762.1 44767.6 44772.6 7958.2 8197.7 8740.1 8895.0 8899.1 10281.4 10515.8 10534.5 10850.2 10851.5 10699.5 10796.7 10800.7 10801.8 10811.4 8125.7 8300.0 10480.2 10576.2 10647.2 10221.4 10561.9 10585.3 10586.8 10594.2 41408.8 41883.2 41954.6 42097.2 42138.0 3219.5 3255.0 3359.0 3495.7 3901.1 31159.4 31791.8 31832.5 31880.0 32710.2 3623.3 3626.6 3754.3 3782.3 3879.7 259245.0 319483.0 319598.0 320394.0 321081.0 59620.4 59691.7 60138.0 60167.3 60412.3 62512.7 63030.4 63084.0 63436.4 63615.1 16042.3 16427.6 16558.7 16639.1 16651.8 11633.7 11738.1 12212.4 12521.5 12646.9 18635.7 18754.6 18890.9 18936.9 18946.9 6884.9 7099.9 7199.6 7208.3 7306.3 822.7 874.9 966.6 979.9 999.1 28795.7 29583.8 29824.1 29917.0 30010.2 1009.3 1013.7 1026.4 1132.0 1132.7 14939.7 15876.7 15940.8 16013.8 16048.1 490811.0 492197.0 492897.0 493369.0 494682.0 56418.0 57022.4 57188.0 57190.7 57191.4 1191.4 1227.8 1286.7 1305.2 1307.7 26617.3 29633.0 30158.8 30559.8 30766.5 48965.8 49469.3 49507.0 49513.0 49550.8 346467.0 346675.0 347426.0 348339.0 350392.0 24484.3 25273.6 25282.4 26708.6 27550.3 42503.4 42756.1 42891.8 44056.6 44249.6 36153.8 36285.6 36948.6 37119.8 41676.8 39579.0 39687.2 40086.6 40470.6 40746.4 37782.8 38016.0 38080.0 38179.6 38441.8 33509.3 36238.2 36359.5 36514.6 36920.8 26150.2 26392.0 26566.5 26726.3 27027.2 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#2] jquery.html Cycles:180 Average:27669.00 Median:19366.72 stddev:77386.01 (399.6%) stddev-sans-first:77575.91 23:08:38 INFO - PROCESS | 12205 | Values: 352.0 359.4 369.3 376.2 379.2 14150.0 17870.9 18802.2 19943.6 20062.6 18486.1 18537.2 19030.4 19180.0 19181.4 5419.4 5680.9 5684.7 5686.3 5686.9 4143.7 4329.8 4350.6 5507.1 5602.6 6222.3 6323.1 6324.0 6324.5 6331.3 6260.7 6310.9 6321.1 6370.8 6401.4 5348.9 5514.1 5517.3 5519.5 5523.0 22048.7 22334.8 22593.9 22609.5 22754.4 2179.7 2183.6 2184.4 2258.6 2906.8 13730.3 14278.1 14826.0 15127.8 15140.9 1850.5 1879.1 1901.6 1936.3 1970.7 64488.0 67169.3 75787.0 79866.2 81049.6 9969.3 10050.6 10129.2 10186.5 10386.8 14057.4 14073.8 14175.3 14191.9 14200.6 7566.5 7582.7 7744.6 7816.4 7842.4 7090.1 7285.5 7407.4 7535.7 7603.6 6107.2 6114.3 6176.7 6292.5 6376.7 1703.8 1714.9 1783.3 1793.6 1806.2 860.9 893.6 954.3 961.5 1042.8 18331.5 19341.5 19373.6 19511.1 19534.0 937.7 942.6 961.5 964.7 986.4 9617.9 10437.5 10810.9 10849.2 10902.9 463724.0 468339.4 472945.0 473968.0 475996.0 78882.0 80486.0 81158.6 81533.6 82013.0 983.2 991.1 1019.0 1028.9 1031.6 16140.0 16626.8 17084.3 17133.9 17470.2 38354.2 39906.8 40111.4 40766.6 41105.6 52334.5 53020.2 53607.0 54609.0 55638.0 8041.9 8099.2 8114.6 8148.7 8335.0 12473.5 12630.9 12701.3 12759.6 12920.5 5054.9 10969.5 11087.3 11154.7 11184.0 10318.8 11338.6 11402.3 11437.1 11581.1 10969.5 11149.6 11177.9 11256.7 11738.8 7880.6 8028.3 8189.2 8221.7 8376.4 10769.8 11089.8 11158.0 11186.3 11643.2 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#3] mootools.html Cycles:180 Average:5765.75 Median:4990.86 stddev:3439.05 (68.9%) stddev-sans-first:3432.61 23:08:38 INFO - PROCESS | 12205 | Values: 1338.5 1383.6 1440.9 1515.5 1768.0 7839.5 8361.8 8641.4 8731.5 8963.2 8780.8 8790.9 8859.2 8862.9 8873.4 2265.2 2288.2 2291.2 2292.3 2293.3 2221.9 2239.5 2282.7 2300.9 2303.3 2246.6 2264.4 2273.3 2275.3 2279.4 2138.0 2145.5 2148.0 2156.7 2158.2 2159.2 2161.6 2162.5 2162.7 2163.8 8744.1 8775.3 8802.6 8806.5 8913.1 5203.8 5339.9 5350.8 5356.3 5360.8 3219.0 3236.7 3238.3 3274.0 3293.1 939.7 947.4 950.4 950.5 957.5 12633.2 12733.9 12774.9 12825.6 12825.7 4366.3 4406.6 4490.8 4541.2 4576.0 9804.6 9825.9 9828.1 9835.6 9836.0 5215.2 5219.0 5221.5 5221.7 5223.1 4132.1 4169.3 4789.8 4809.9 4848.3 3590.5 3610.5 3719.0 3733.2 3780.2 1097.9 1101.3 1111.8 1115.5 1122.6 722.2 862.7 865.7 868.8 870.6 5395.6 5562.6 5703.2 5732.6 6102.4 867.2 870.2 883.5 902.9 926.9 3795.3 3866.0 3885.1 3887.0 3905.7 11233.9 11574.5 11609.8 11629.8 11648.7 11570.8 11580.1 11626.2 11627.6 11659.7 8166.7 8367.7 8489.4 8496.7 8502.5 5694.0 5729.5 5772.4 5788.6 5942.2 10370.6 10371.2 10485.1 10540.6 10559.5 10295.8 10433.9 10539.3 10604.3 10700.7 6861.1 7101.2 7306.7 7458.7 7690.8 8472.9 8602.7 8693.9 8697.3 8707.8 7390.3 7753.3 7813.5 7824.1 8079.5 7297.6 7648.8 7694.0 7725.3 7744.7 6640.7 6822.1 6823.6 6824.1 6837.5 4880.2 4939.8 4988.9 5036.1 5051.6 4832.2 4894.3 4938.0 4992.9 5060.1 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#4] prototype.html Cycles:180 Average:4644.44 Median:5370.00 stddev:1396.26 (26.0%) stddev-sans-first:1361.67 23:08:38 INFO - PROCESS | 12205 | Values: 305.2 311.8 317.5 320.2 322.7 5203.1 5226.1 5368.2 5380.0 5409.1 5093.7 5181.8 5186.8 5221.2 5276.7 4083.4 4102.0 4105.3 4109.4 4113.4 3942.4 4035.4 4106.1 4111.3 4118.3 4018.1 4029.9 4037.1 4046.0 4046.2 4022.3 4023.4 4037.2 4046.7 4050.5 3528.9 3533.8 3539.0 3540.1 3545.2 5055.8 5101.8 5116.9 5118.4 5152.3 4582.2 4582.6 4601.6 4606.0 4616.3 5437.2 5437.4 5441.0 5441.6 5446.4 3762.8 3837.7 3852.7 3865.2 3871.2 5674.6 5692.0 5701.5 5703.6 5706.7 3900.6 3906.4 3915.4 3933.0 3946.0 3896.5 3990.4 4028.7 4045.8 4046.3 4585.0 4591.7 4604.6 4623.7 4627.3 5521.6 5572.8 5576.7 5628.1 5644.5 1268.7 1304.6 1344.9 1365.8 1371.6 882.3 900.3 918.8 919.2 922.6 5329.2 5337.6 5348.2 5350.0 5351.0 5634.5 5635.7 5643.5 5668.1 5668.9 5175.0 5351.0 5371.8 5376.4 5433.3 3015.6 3035.0 3040.5 3041.2 3047.2 5892.5 5895.5 5919.9 5931.4 5934.2 5607.8 5748.5 5757.3 5776.7 5797.4 5819.2 5831.2 5833.8 5845.0 5864.2 5392.6 5649.9 5688.7 5704.8 5709.9 5632.3 5655.5 5662.9 5664.6 5681.4 5910.5 5933.6 5943.9 5947.7 5979.0 3893.3 3965.9 3999.4 3999.8 4011.7 5499.5 5708.6 5735.5 5740.5 5744.8 5432.8 5456.4 5534.6 5548.9 5591.8 5607.3 5613.3 5620.9 5627.2 5634.2 5320.1 5575.0 5576.2 5593.2 5609.6 5608.6 5612.7 5618.1 5641.5 5642.5 5496.5 5500.5 5507.4 5531.8 5548.2 23:08:38 INFO - PROCESS | 12205 | 23:08:38 INFO - PROCESS | 12205 | [#5] yui.html Cycles:180 Average:20568.75 Median:4428.29 stddev:30780.76 (695.1%) stddev-sans-first:30830.51 23:08:38 INFO - PROCESS | 12205 | Values: 576.9 579.8 650.9 732.1 761.0 13774.3 14199.4 14280.1 14683.6 14719.1 13944.2 14550.2 15009.7 15044.2 15157.3 785.6 817.3 820.8 821.1 821.2 669.1 676.7 797.0 800.2 801.1 862.7 863.8 865.1 865.4 866.1 852.9 853.6 853.7 853.8 854.5 508.5 520.9 604.7 609.7 611.5 13983.6 13991.5 14026.1 14032.3 14043.4 12196.5 13124.8 13555.1 13685.0 14250.1 12469.7 13041.2 13094.1 13128.9 13353.1 4361.2 4394.3 4421.8 4427.1 4441.9 84562.6 85092.6 85608.0 85907.0 86248.6 11095.0 11343.9 11354.8 11516.5 11554.4 21094.5 21131.8 21211.5 21235.5 21237.7 7864.1 8071.9 8103.9 8494.2 8530.8 4349.5 4359.2 4440.8 4654.1 4674.9 3239.7 3327.6 3335.5 3341.3 3343.7 621.6 628.7 628.9 645.8 646.4 479.8 546.5 561.0 569.5 596.9 8374.5 8526.8 8675.4 9073.2 9085.6 514.6 520.1 547.9 565.5 636.4 4193.4 4411.2 4429.5 4461.6 4509.4 114433.4 116987.4 119733.4 123346.0 125063.4 100269.5 100374.0 100634.5 101658.0 109101.0 72519.6 91141.5 91155.0 91528.0 91856.5 8390.7 8525.1 8605.1 8685.0 8761.2 51603.7 52059.2 53075.7 53321.5 53708.5 46312.3 46419.3 48392.5 74702.0 75266.0 9742.6 9912.3 10058.9 10316.4 10326.4 11654.7 11708.5 12009.6 12010.9 12578.7 10553.8 10594.3 10613.9 10928.6 11050.9 13554.1 13728.9 14217.3 14242.8 14413.4 13208.8 13348.8 13408.9 13573.4 13986.4 11154.6 11530.3 11668.9 11889.2 11947.7 11397.6 11440.3 11548.5 11747.9 11959.5 23:08:38 INFO - PROCESS | 12205 | -------- Summary: end -------- 23:08:38 INFO - PROCESS | 12205 | 23:08:39 INFO - TEST-INFO | 12205: exit 0 23:08:39 INFO - TEST-OK | dromaeo_css | took 1117227ms 23:08:39 INFO - TEST-START | kraken 23:08:39 INFO - operating with platform_type : linux_ 23:08:39 INFO - Initialising browser for kraken test... 23:08:39 INFO - TEST-INFO | started process 12515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YeGVP/profile http://localhost:48591/getInfo.html) 23:08:43 INFO - PROCESS | 12515 | __metrics Screen width/height:1600/1200 23:08:43 INFO - PROCESS | 12515 | colorDepth:24 23:08:43 INFO - PROCESS | 12515 | Browser inner width/height: 1024/697 23:08:43 INFO - PROCESS | 12515 | __metrics 23:08:44 INFO - PROCESS | 12515 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:08:44 INFO - PROCESS | 12515 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:08:44 INFO - PROCESS | 12515 | console.error: PushService: 23:08:44 INFO - PROCESS | 12515 | stateChangeProcessEnqueue: Error transitioning state 23:08:44 INFO - PROCESS | 12515 | UnknownError 23:08:45 INFO - TEST-INFO | 12515: exit 0 23:08:45 INFO - Browser initialized. 23:08:45 INFO - Running cycle 1/1 for kraken test... 23:08:45 INFO - TEST-INFO | started process 12600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YeGVP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:57 INFO - PROCESS | 12600 | RSS: Main: 171626496 23:08:57 INFO - PROCESS | 12600 | 23:09:00 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48591/tests/kraken/driver.html?testName=audio-beat-detection) 23:09:00 INFO - PROCESS | 12600 | RSS: Main: 228548608 23:09:00 INFO - PROCESS | 12600 | 23:09:03 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48591/tests/kraken/driver.html?testName=audio-dft) 23:09:04 INFO - PROCESS | 12600 | RSS: Main: 215633920 23:09:04 INFO - PROCESS | 12600 | 23:09:07 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48591/tests/kraken/driver.html?testName=audio-fft) 23:09:07 INFO - PROCESS | 12600 | RSS: Main: 297267200 23:09:07 INFO - PROCESS | 12600 | 23:09:09 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48591/tests/kraken/driver.html?testName=audio-oscillator) 23:09:10 INFO - PROCESS | 12600 | RSS: Main: 208429056 23:09:10 INFO - PROCESS | 12600 | 23:09:12 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48591/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:09:13 INFO - PROCESS | 12600 | RSS: Main: 210907136 23:09:13 INFO - PROCESS | 12600 | 23:09:16 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48591/tests/kraken/driver.html?testName=imaging-darkroom) 23:09:16 INFO - PROCESS | 12600 | RSS: Main: 247263232 23:09:16 INFO - PROCESS | 12600 | 23:09:20 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48591/tests/kraken/driver.html?testName=imaging-desaturate) 23:09:20 INFO - PROCESS | 12600 | RSS: Main: 246575104 23:09:20 INFO - PROCESS | 12600 | 23:09:23 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48591/tests/kraken/driver.html?testName=json-parse-financial) 23:09:24 INFO - PROCESS | 12600 | RSS: Main: 246165504 23:09:24 INFO - PROCESS | 12600 | 23:09:25 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48591/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:09:26 INFO - PROCESS | 12600 | RSS: Main: 208334848 23:09:26 INFO - PROCESS | 12600 | 23:09:27 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:09:28 INFO - PROCESS | 12600 | RSS: Main: 215678976 23:09:28 INFO - PROCESS | 12600 | 23:09:30 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:09:31 INFO - PROCESS | 12600 | RSS: Main: 225693696 23:09:31 INFO - PROCESS | 12600 | 23:09:33 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:09:34 INFO - PROCESS | 12600 | RSS: Main: 233971712 23:09:34 INFO - PROCESS | 12600 | 23:09:37 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:09:37 INFO - PROCESS | 12600 | RSS: Main: 230416384 23:09:37 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | Cycle 1(1): loaded http://localhost:48591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48591/tests/kraken/driver.html?testName=ai-astar) 23:09:39 INFO - PROCESS | 12600 | RSS: Main: 283234304 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | __start_tp_report 23:09:39 INFO - PROCESS | 12600 | _x_x_mozilla_page_load 23:09:39 INFO - PROCESS | 12600 | _x_x_mozilla_page_load_details 23:09:39 INFO - PROCESS | 12600 | |i|pagename|runs| 23:09:39 INFO - PROCESS | 12600 | |0;ai-astar;103;113;113;113;113;113;114;114;114;112 23:09:39 INFO - PROCESS | 12600 | |1;audio-beat-detection;151;127;123;159;129;128;130;122;162;127 23:09:39 INFO - PROCESS | 12600 | |2;audio-dft;160;160;161;160;160;161;163;161;160;160 23:09:39 INFO - PROCESS | 12600 | |3;audio-fft;77;77;76;109;77;76;76;75;97;77 23:09:39 INFO - PROCESS | 12600 | |4;audio-oscillator;83;83;119;82;83;83;108;83;83;115 23:09:39 INFO - PROCESS | 12600 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;116;117;117 23:09:39 INFO - PROCESS | 12600 | |6;imaging-darkroom;146;145;147;147;147;147;146;147;146;146 23:09:39 INFO - PROCESS | 12600 | |7;imaging-desaturate;84;85;84;84;84;85;84;84;84;84 23:09:39 INFO - PROCESS | 12600 | |8;json-parse-financial;54;53;53;54;52;55;54;55;56;53 23:09:39 INFO - PROCESS | 12600 | |9;json-stringify-tinderbox;59;60;59;60;60;59;59;59;59;60 23:09:39 INFO - PROCESS | 12600 | |10;stanford-crypto-aes;80;80;80;80;80;80;81;79;80;81 23:09:39 INFO - PROCESS | 12600 | |11;stanford-crypto-ccm;144;132;145;133;152;133;138;132;173;140 23:09:39 INFO - PROCESS | 12600 | |12;stanford-crypto-pbkdf2;170;168;166;164;165;172;171;165;170;168 23:09:39 INFO - PROCESS | 12600 | |13;stanford-crypto-sha256-iterative;68;66;65;67;66;66;67;67;64;63 23:09:39 INFO - PROCESS | 12600 | __end_tp_report 23:09:39 INFO - PROCESS | 12600 | __start_cc_report 23:09:39 INFO - PROCESS | 12600 | _x_x_mozilla_cycle_collect,1701 23:09:39 INFO - PROCESS | 12600 | __end_cc_report 23:09:39 INFO - PROCESS | 12600 | __startTimestamp1477030179917__endTimestamp 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | ------- Summary: start ------- 23:09:39 INFO - PROCESS | 12600 | Number of tests: 14 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.29 (2.9%) stddev-sans-first:0.67 23:09:39 INFO - PROCESS | 12600 | Values: 103.0 113.0 113.0 113.0 113.0 113.0 114.0 114.0 114.0 112.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:129.50 stddev:15.30 (11.8%) stddev-sans-first:15.20 23:09:39 INFO - PROCESS | 12600 | Values: 151.0 127.0 123.0 159.0 129.0 128.0 130.0 122.0 162.0 127.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:0.97 (0.6%) stddev-sans-first:1.00 23:09:39 INFO - PROCESS | 12600 | Values: 160.0 160.0 161.0 160.0 160.0 161.0 163.0 161.0 160.0 160.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#3] audio-fft Cycles:10 Average:81.70 Median:77.00 stddev:11.60 (15.1%) stddev-sans-first:12.17 23:09:39 INFO - PROCESS | 12600 | Values: 77.0 77.0 76.0 109.0 77.0 76.0 76.0 75.0 97.0 77.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:83.00 stddev:15.27 (18.4%) stddev-sans-first:15.83 23:09:39 INFO - PROCESS | 12600 | Values: 83.0 83.0 119.0 82.0 83.0 83.0 108.0 83.0 83.0 115.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 23:09:39 INFO - PROCESS | 12600 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 23:09:39 INFO - PROCESS | 12600 | Values: 146.0 145.0 147.0 147.0 147.0 147.0 146.0 147.0 146.0 146.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 23:09:39 INFO - PROCESS | 12600 | Values: 84.0 85.0 84.0 84.0 84.0 85.0 84.0 84.0 84.0 84.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.20 (2.2%) stddev-sans-first:1.27 23:09:39 INFO - PROCESS | 12600 | Values: 54.0 53.0 53.0 54.0 52.0 55.0 54.0 55.0 56.0 53.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 23:09:39 INFO - PROCESS | 12600 | Values: 59.0 60.0 59.0 60.0 60.0 59.0 59.0 59.0 59.0 60.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 23:09:39 INFO - PROCESS | 12600 | Values: 80.0 80.0 80.0 80.0 80.0 80.0 81.0 79.0 80.0 81.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:142.00 stddev:12.72 (9.0%) stddev-sans-first:13.47 23:09:39 INFO - PROCESS | 12600 | Values: 144.0 132.0 145.0 133.0 152.0 133.0 138.0 132.0 173.0 140.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:169.00 stddev:2.81 (1.7%) stddev-sans-first:2.87 23:09:39 INFO - PROCESS | 12600 | Values: 170.0 168.0 166.0 164.0 165.0 172.0 171.0 165.0 170.0 168.0 23:09:39 INFO - PROCESS | 12600 | 23:09:39 INFO - PROCESS | 12600 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.52 (2.3%) stddev-sans-first:1.41 23:09:39 INFO - PROCESS | 12600 | Values: 68.0 66.0 65.0 67.0 66.0 66.0 67.0 67.0 64.0 63.0 23:09:39 INFO - PROCESS | 12600 | -------- Summary: end -------- 23:09:39 INFO - PROCESS | 12600 | 23:09:41 INFO - TEST-INFO | 12600: exit 0 23:09:41 INFO - TEST-OK | kraken | took 61439ms 23:09:41 INFO - SUITE-END | took 1178s 23:09:41 INFO - Completed test suite (00:19:39) 23:09:41 INFO - javascript benchmark 23:09:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2965.91102266932, 2985.5223164293716, 3022.999999999999, 3040.5135178371465, 3046.345035817299, 5107.46371630979, 5215.400228973669, 5288.576913846892, 5359.705216213107, 5389.568290135952, 5243.0, 5293.100172970595, 5391.083515802313, 5399.244105825178, 5410.269613602162, 4742.928856067162, 4801.207800712896, 4849.490803465638, 4859.149648811462, 4884.535969082932, 4848.6363522735755, 4869.780859861315, 4903.141950158708, 4905.950940490603, 4964.180910149821, 4711.835085772016, 4888.511148885119, 4889.706617602955, 4917.926231106519, 5017.147084995559, 4744.098621261984, 4811.302361157647, 4812.494688057738, 4849.442314133849, 4866.221404575286, 4828.0585285869265, 4830.203016502308, 4839.0, 4868.610511159134, 4893.632977526664, 5370.624056316059, 5392.78428862845, 5394.379646340682, 5397.62216644835, 5400.594955378323, 5473.67157970519, 5518.034343989826, 5637.520810731093, 5699.2306038684665, 5730.169125476783, 5720.342160651537, 5738.45484678959, 5741.799037033726, 5789.0, 5811.564132690065, 5336.91994620081, 5350.3312085989255, 5350.973245133828, 5359.5444387227135, 5360.008398446261, 5598.468145526169, 5602.467765562265, 5864.149698293723, 5867.3839246879315, 5939.16851640762, 5573.526250268692, 5583.469570390806, 5590.18942253371, 5643.322801263875, 5721.456461636138, 5668.178114173422, 5723.8569035774435, 5730.455606717355, 5731.799387021518, 5736.5123964462655, 5397.514223719884, 5692.8861422771315, 5704.087346024616, 5713.914291285634, 5717.971410142923, 5388.245645609618, 5522.22688823565, 5522.337319521683, 5523.6685798851395, 5529.585281103933, 4265.765382903908, 4336.262835318003, 4347.739135651871, 4380.496242932039, 4433.445819272591, 3881.243157584244, 3888.6111388860886, 3891.3579259421876, 3942.6648734857285, 3943.231069941391, 5462.071447853795, 5463.31708536433, 5474.09677403224, 5475.6440831345835, 5535.916961245584, 5406.756695948692, 5503.587230957694, 5518.978988887069, 5648.209250704905, 5663.745131469093, 5387.865303367447, 5389.51494365509, 5448.237246785374, 5453.972730136404, 5455.318085239345, 4916.311716359713, 4924.359833221658, 4925.236588328743, 4938.703677779345, 4984.451710311862, 5652.576056795675, 5805.912911306418, 5857.502112320501, 5871.383504731942, 5879.647221166743, 5554.722263886709, 5672.744726487235, 5701.857453563694, 5735.225744524436, 5827.3007239131575, 5901.7639294427745, 5921.733521991435, 5927.170196172453, 5927.31835838884, 5943.197668314722, 5614.63504872182, 5654.575906807088, 5655.830325090254, 5673.432656734293, 5679.602427830012, 5484.341878974548, 5605.5515558756015, 5693.1460280956535, 5724.914126288192, 5755.942440575541, 5627.409122042291, 5628.352739435018, 5629.155626656035, 5637.830865074055, 5646.0, 5564.721763911707, 5593.468620481048, 5609.046462101534, 5611.074172761449, 5615.887682246251, 5341.946580534301, 5398.055340315507, 5558.194061860927, 5606.299212598426, 5622.803201887914, 5686.175504551899, 5687.658740475585, 5690.146478028329, 5738.856528586652, 5762.654240745569, 5198.818041368534, 5512.255845460972, 5552.0, 5562.944370556243, 5567.610267281251], "value": 5250.061937907525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.4949693766421, 601.3235110500686, 616.3897741236177, 620.2494981072903, 634.9587276827009, 30782.0, 42156.367654485206, 42887.35668964968, 43066.78466607662, 43176.784116079456, 44254.77872610641, 44745.10509789802, 44762.104757904825, 44767.55232447675, 44772.552274477246, 7958.164392738766, 8197.7130800422, 8740.125987401241, 8894.955525222365, 8899.110088991114, 10281.383117013003, 10515.84226236607, 10534.525946332395, 10850.24048316618, 10851.457427128651, 10699.518521666505, 10796.730081747979, 10800.675979720621, 10801.783964320708, 10811.405372704497, 8125.715599953974, 8300.0, 10480.161587073017, 10576.206784491156, 10647.2014598905, 10221.437820919846, 10561.947190264076, 10585.259031867772, 10586.788264234672, 10594.198986172369, 41408.79295603533, 41883.16233675309, 41954.64362850964, 42097.158056839, 42138.0, 3219.4687876500348, 3254.9674503255137, 3358.9664103358896, 3495.667911548399, 3901.141748815256, 31159.376812463852, 31791.84104079488, 31832.522512162337, 31880.0, 32710.182245443815, 3623.2753449310244, 3626.5829429615405, 3754.342989976743, 3782.281366540376, 3879.72841901067, 259245.0, 319483.0, 319598.0, 320394.0, 321081.0, 59620.40379596235, 59691.70154149202, 60138.0, 60167.27574501673, 60412.260835220586, 62512.687436562526, 63030.369696303365, 63084.0, 63436.36563634305, 63615.09154725366, 16042.278097485641, 16427.58931026734, 16558.6688266234, 16639.084850333107, 16651.750223746654, 11633.709157271136, 11738.119641026957, 12212.389380530938, 12521.499140034297, 12646.925011374136, 18635.720464193048, 18754.624907501773, 18890.90554547246, 18936.90531547361, 18946.905265473586, 6884.896726549106, 7099.929560226035, 7199.640017999079, 7208.315210055036, 7306.2693730626515, 822.6668199379142, 874.8600223964136, 966.6375109334, 979.86771785808, 999.0758548342899, 28795.71204287972, 29583.83564646448, 29824.141166137622, 29917.0, 30010.249743756143, 1009.3338396658318, 1013.7212266626736, 1026.4200726589454, 1131.9773604528027, 1132.69983454383, 14939.70120597582, 15876.682466350609, 15940.760888586681, 16013.839861601235, 16048.117353545667, 490811.0, 492197.0, 492897.0, 493369.0, 494682.0, 56418.0, 57022.42977570254, 57188.0, 57190.71404643033, 57191.42808571944, 1191.398343836357, 1227.7974134267745, 1286.7233544787916, 1305.1646945954597, 1307.6796184934562, 26617.334566635214, 29633.0, 30158.84920575383, 30559.847200763856, 30766.53850192204, 48965.755171223915, 49469.25796112989, 49507.029578225425, 49513.0, 49550.75224623854, 346467.0, 346675.0, 347426.0, 348339.0, 350392.0, 24484.265472035866, 25273.62089568695, 25282.36794080163, 26708.599371009837, 27550.311242219104, 42503.362449562665, 42756.1448771029, 42891.785541072095, 44056.559434405244, 44249.55750442583, 36153.81923090473, 36285.63714362823, 36948.630513694516, 37119.814400927826, 41676.79161604172, 39579.0, 39687.206255875295, 40086.599134008284, 40470.59529404668, 40746.38880416856, 37782.81108594439, 38016.0, 38080.0, 38179.618203817605, 38441.80779096198, 33509.32981340408, 36238.200070886895, 36359.45460818037, 36514.634853652184, 36920.81539592392, 26150.215493535223, 26392.0, 26566.468670626866, 26726.331841704112, 27027.189184324503], "value": 19546.693380079087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9630438803927, 359.4392747314192, 369.2670049950848, 376.1818045750493, 379.21124061951144, 14150.0, 17870.91064544677, 18802.2479100836, 19943.601127977432, 20062.59874802503, 18486.075696215168, 18537.16582753776, 19030.429087127373, 19180.0, 19181.424557263268, 5419.430959749238, 5680.914786278209, 5684.687342196177, 5686.31764188298, 5686.943130568703, 4143.70994030418, 4329.848455304065, 4350.630196433307, 5507.118860982244, 5602.635828671123, 6222.284437289702, 6323.05154226865, 6323.968380158093, 6324.494040476751, 6331.271903731061, 6260.686965651722, 6310.864044472016, 6321.0834429008, 6370.77702280418, 6401.423871851509, 5348.893022139574, 5514.117741161435, 5517.337919449652, 5519.503244707996, 5523.0, 22048.669269960803, 22334.77665223343, 22593.887030564907, 22609.54780904373, 22754.43113922165, 2179.705739725133, 2183.617866873291, 2184.388371256051, 2258.570871534403, 2906.80820863445, 13730.313484325743, 14278.143311401245, 14826.0, 15127.773083403757, 15140.924295378563, 1850.4633656239675, 1879.1074239736154, 1901.6291823094502, 1936.2642195965584, 1970.6945423459388, 64488.0, 67169.3283067168, 75787.0, 79866.20133798645, 81049.59475202586, 9969.252306077073, 10050.64822731201, 10129.240306977006, 10186.490675466197, 10386.792264154674, 14057.367418466194, 14073.788893166611, 14175.291235438186, 14191.858081419261, 14200.573982780534, 7566.508176968479, 7582.696692132363, 7744.574048427278, 7816.41376896735, 7842.411819113589, 7090.149182098078, 7285.453590980697, 7407.407407407394, 7535.660895259726, 7603.581803000778, 6107.236595425572, 6114.296855861544, 6176.66028368444, 6292.4966002719675, 6376.74493020274, 1703.8381353771372, 1714.9228284727217, 1783.286685325854, 1793.5695433096223, 1806.1691621854095, 860.9268212202034, 893.6425429827991, 954.2783145731702, 961.5432669481941, 1042.7862288230895, 18331.541711457052, 19341.51646208856, 19373.612527749647, 19511.121999510055, 19534.0, 937.6577549194495, 942.5852624845046, 961.4663861556903, 964.6961207219704, 986.4475893499663, 9617.855732164024, 10437.530311135864, 10810.945945270381, 10849.240553161202, 10902.890971090188, 463724.0, 468339.4073918548, 472945.0, 473968.0, 475996.0, 78882.0, 80486.0, 81158.59420702978, 81533.59233203797, 82013.0, 983.1692220074169, 991.0683957080322, 1018.9796204075877, 1028.88682244953, 1031.5925209542188, 16140.0, 16626.750598740786, 17084.316627334767, 17133.91433042827, 17470.213840377466, 38354.23291534098, 39906.80046599748, 40111.3983290245, 40766.59233407628, 41105.588944111376, 52334.47665523296, 53020.204696928806, 53607.0, 54609.0, 55638.0, 8041.919580804117, 8099.190080991853, 8114.553699546582, 8148.674053037812, 8334.958325208336, 12473.5010599575, 12630.87369126297, 12701.301428421157, 12759.553415630407, 12920.547780827626, 5054.949450505448, 10969.460422486593, 11087.33475991443, 11154.721131971766, 11183.986882361252, 10318.793624127626, 11338.60314888975, 11402.315861048364, 11437.142214333791, 11581.131415144066, 10969.451527423756, 11149.609763658233, 11177.888221117684, 11256.662300131009, 11738.823917641414, 7880.56656883854, 8028.317593004426, 8189.181081891763, 8221.671133154607, 8376.371772117238, 10769.784604308026, 11089.833652495057, 11158.0, 11186.32882027081, 11643.243189192675], "value": 8991.088680009505, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.4913732781542, 1383.5780087073445, 1440.8847292216624, 1515.4695856450244, 1767.9646407071866, 7839.529628222311, 8361.83276334473, 8641.395102342847, 8731.519766412861, 8963.238124759388, 8780.824383512327, 8790.91209087909, 8859.246964008067, 8862.86705699415, 8873.423227490208, 2265.2071774878827, 2288.1648198407543, 2291.163725240284, 2292.266474728088, 2293.3120063980823, 2221.911123555056, 2239.484918468753, 2282.7488976212603, 2300.850444721096, 2303.2744685424123, 2246.595612789697, 2264.3999340174782, 2273.27255278311, 2275.260540324393, 2279.4073540879326, 2137.967930481044, 2145.463634091477, 2148.033384976759, 2156.7196264485674, 2158.190678495564, 2159.211887661008, 2161.6217161996587, 2162.48100455891, 2162.697222388867, 2163.751168615598, 8744.125587441269, 8775.25410340122, 8802.603882825288, 8806.471611703255, 8913.1086891311, 5203.817866374697, 5339.866503337409, 5350.759215835296, 5356.330458692663, 5360.812371567015, 3218.9678103219135, 3236.72487838534, 3238.2875767331147, 3274.0, 3293.110860067792, 939.6945992552447, 947.441009804212, 950.3680052764903, 950.4582388038821, 957.4638202606562, 12633.2420054797, 12733.936330318289, 12774.936125319315, 12825.551105711254, 12825.743485130246, 4366.257736184856, 4406.6474682026, 4490.797914093873, 4541.182587134347, 4576.0, 9804.607815687436, 9825.9017409825, 9828.11546960777, 9835.606575737034, 9835.950820245853, 5215.19164529491, 5218.9739051304505, 5221.451747566527, 5221.686698798084, 5223.059849227099, 4132.111596006813, 4169.332906734969, 4789.784459699322, 4809.879753006133, 4848.345473361122, 3590.5152804371596, 3610.548681414823, 3719.0, 3733.197362567061, 3780.243951209714, 1097.9341239525654, 1101.2676570080807, 1111.7554138089608, 1115.5314767797545, 1122.5565901468874, 722.2236096196609, 862.6764963138822, 865.6623916672503, 868.7610907000626, 870.6038752367657, 5395.62230643859, 5562.582806289463, 5703.201551782754, 5732.598718089694, 6102.389761023951, 867.2368315881984, 870.2472361407337, 883.5052370672444, 902.8690839828188, 926.8887733472027, 3795.3168429682923, 3865.9806700966315, 3885.0870045539277, 3886.9699529624672, 3905.72659913809, 11233.887661123284, 11574.537018519333, 11609.76780464386, 11629.767404651859, 11648.650540483799, 11570.76858462826, 11580.131490138274, 11626.244294120856, 11627.59303424376, 11659.650210493699, 8166.673333066729, 8367.748967530984, 8489.40574159803, 8496.702615408554, 8502.489850608983, 5694.031443762092, 5729.484346408676, 5772.3650398456975, 5788.623739456922, 5942.168096466412, 10370.637027703995, 10371.170306375623, 10485.108765754996, 10540.578376865145, 10559.472026398804, 10295.794084118426, 10433.895661043596, 10539.314944528582, 10604.310719803188, 10700.678979630624, 6861.142357205349, 7101.183363913216, 7306.6711997959155, 7458.73894413693, 7690.769276921701, 8472.872906906277, 8602.655893764178, 8693.91306086931, 8697.304215662858, 8707.825843482968, 7390.260973902567, 7753.340966017951, 7813.453058285865, 7824.139344671968, 8079.515229086039, 7297.635118244173, 7648.847023059619, 7694.0, 7725.343345815669, 7744.65149068282, 6640.701168447335, 6822.11312529368, 6823.590584564942, 6824.112865327475, 6837.487188460985, 4880.194767863405, 4939.827106051414, 4988.8752781180765, 5036.06832735945, 5051.646384753032, 4832.226843704991, 4894.339264199288, 4938.037082768826, 4992.8502144934255, 5060.063888180698], "value": 4510.194808895906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.21711809209376, 311.7739638761897, 317.52688494143723, 320.2458210913299, 322.66604064792944, 5203.141481655532, 5226.111561034624, 5368.248445217668, 5380.0, 5409.08045632243, 5093.694378337303, 5181.818636347729, 5186.766595503202, 5221.164613661815, 5276.709780962045, 4083.4079058536495, 4102.0, 4105.261053010457, 4109.404136400228, 4113.382992551126, 3942.4283478895545, 4035.3745169498775, 4106.055607210344, 4111.321631930728, 4118.279301122307, 4018.0557568971267, 4029.919401611966, 4037.091654377756, 4046.008727861678, 4046.231216068952, 4022.275990321726, 4023.356262997921, 4037.1521980384155, 4046.7167298289132, 4050.4936882889638, 3528.858845646171, 3533.8233088345737, 3539.026767638894, 3540.097275194809, 3545.2377738786286, 5055.848324550233, 5101.82143624975, 5116.872078198037, 5118.360204974379, 5152.278680984665, 4582.221022426195, 4582.564656357641, 4601.585857272828, 4606.0, 4616.330632058366, 5437.238786569884, 5437.429069947638, 5440.972795136038, 5441.619086663935, 5446.428125046853, 3762.795905310302, 3837.654611085016, 3852.672522835562, 3865.1689886674217, 3871.187050719352, 5674.631148975344, 5692.0, 5701.543876489913, 5703.62926409782, 5706.657600543939, 3900.5762896542556, 3906.43356713275, 3915.393114067324, 3932.9606703932595, 3945.9740467478664, 3896.493455850721, 3990.4014397839974, 4028.657564107025, 4045.838166473367, 4046.2716710991717, 4584.954150458453, 4591.678582499193, 4604.608608268335, 4623.745693986863, 4627.282771170407, 5521.585881058856, 5572.777088916398, 5576.721163941867, 5628.101527713231, 5644.491995720405, 1268.6828292926768, 1304.5564508067114, 1344.946202151903, 1365.8429280632656, 1371.5611004478471, 882.342654722236, 900.2662829793697, 918.8147289995964, 919.2416256588208, 922.6217250927087, 5329.200619906967, 5337.62636615441, 5348.224507446398, 5350.010248104113, 5350.973245133828, 5634.5210657093785, 5635.746391412396, 5643.52030077448, 5668.149777533403, 5668.914966275509, 5175.0, 5351.0, 5371.75827087782, 5376.381716102698, 5433.26650902131, 3015.577819105327, 3035.0, 3040.5135178371356, 3041.1636799879766, 3047.222958145703, 5892.467958330865, 5895.528357731371, 5919.881602367928, 5931.377205393372, 5934.228550288435, 5607.7661365668655, 5748.540116790647, 5757.280339957505, 5776.653400795965, 5797.449242321973, 5819.166962406828, 5831.241938547962, 5833.795817146464, 5845.0, 5864.208331875142, 5392.622516423813, 5649.858753531194, 5688.658680479019, 5704.828855134187, 5709.942900570941, 5632.295963004624, 5655.4910058094965, 5662.85842853932, 5664.603477756682, 5681.409205422488, 5910.468057874812, 5933.614315069507, 5943.9091672331015, 5947.732352044255, 5978.97010514962, 3893.279743247508, 3965.92068158641, 3999.3601023836045, 3999.8200080997008, 4011.719179657396, 5499.477549632778, 5708.600397972268, 5735.4551317624755, 5740.540756739534, 5744.798932037359, 5432.782688692408, 5456.399796022513, 5534.584906131976, 5548.889022219614, 5591.776328946797, 5607.327120745374, 5613.326400831927, 5620.943790562205, 5627.155926611041, 5634.211210430461, 5320.058178764645, 5574.9721251393485, 5576.247206627054, 5593.188987596857, 5609.6073274872, 5608.6073974823, 5612.663240205601, 5618.101103823368, 5641.50500117461, 5642.520385767256, 5496.532794712494, 5500.532454741392, 5507.449255074461, 5531.806386776443, 5548.167774833807], "value": 4190.8639475464515, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.924999750033, 579.8202557207265, 650.8958566629341, 732.051992669493, 761.0486891385766, 13774.311284435777, 14199.432022719091, 14280.07179533331, 14683.63290917725, 14719.116852988827, 13944.163350198995, 14550.19973901435, 15009.699806003873, 15044.247787610602, 15157.317920693567, 785.5757890738994, 817.2522142239843, 820.7989042684551, 821.1378053044309, 821.1747194069951, 669.0532895952222, 676.7394553097068, 796.9840603187932, 800.2117913854848, 801.1187693537121, 862.7066797288952, 863.8358711844761, 865.126222515261, 865.4244927123456, 866.1079088538816, 852.8805967164602, 853.6414705823601, 853.6841368693623, 853.7737499562621, 854.5428195915156, 508.50420839681385, 520.885405210853, 604.7369394313488, 609.7012463892705, 611.541343992006, 13983.580492585239, 13991.510297139654, 14026.08830426019, 14032.298385080807, 14043.368048437846, 12196.512139514409, 13124.803127953088, 13555.118917270345, 13685.0, 14250.144991300554, 12469.688257793629, 13041.217526948318, 13094.14888032275, 13128.934355328161, 13353.132046416951, 4361.171377438277, 4394.340848872695, 4421.7789110544345, 4427.070315233805, 4441.933370999438, 84562.57718711428, 85092.57453712754, 85608.0, 85907.0, 86248.56875715643, 11095.0, 11343.943280283545, 11354.772904541864, 11516.539338426537, 11554.42227888619, 21094.47263818417, 21131.788682112983, 21211.46971325698, 21235.46911327198, 21237.68143477849, 7864.134945156028, 8071.919280807234, 8103.918960810316, 8494.154736627504, 8530.78673033179, 4349.497117472398, 4359.17175736609, 4440.822367105344, 4654.138984287918, 4674.906501869944, 3239.659835717263, 3327.550780644582, 3335.466325387926, 3341.298327351235, 3343.6823501767294, 621.6425555305717, 628.6887990444759, 628.8616504369031, 645.8320836582428, 646.3601034975435, 479.8416522547551, 546.5081426715908, 560.9874177110355, 569.5016860247283, 596.9044952807529, 8374.53940033304, 8526.829463410697, 8675.436096653697, 9073.185362927441, 9085.591148398338, 514.572904489273, 520.0535026252185, 547.887683024979, 565.5108331293402, 636.3922454056365, 4193.412922190896, 4411.205982923105, 4429.512753597101, 4461.620762235247, 4509.413776209072, 114433.42783286197, 116987.41506292413, 119733.40133299452, 123346.0, 125063.37468312783, 100269.49865250627, 100374.0, 100634.4968275154, 101658.0, 109101.0, 72519.63740181371, 91141.54429227945, 91155.0, 91528.0, 91856.54071729598, 8390.706325278587, 8525.062243153125, 8605.139486051345, 8684.956575217084, 8761.211490965843, 51603.74198128985, 52059.21911171411, 53075.73462132664, 53321.53410028336, 53708.46291537034, 46312.30531542097, 46419.303710443695, 48392.5160748388, 74702.0, 75266.0, 9742.561584728846, 9912.306138570231, 10058.899411006088, 10316.43259620728, 10326.380417174994, 11654.650360489199, 11708.531658733555, 12009.63971080869, 12010.939945300217, 12578.685532861751, 10553.841692374774, 10594.2584019121, 10613.850762283575, 10928.617498387519, 11050.889491104987, 13554.118982266516, 13728.93135534316, 14217.289135543388, 14242.786358204428, 14413.423463061361, 13208.80186797218, 13348.799768003682, 13408.865911340761, 13573.38919748594, 13986.370613322222, 11154.609588664358, 11530.250533715282, 11668.88331116712, 11889.167758256839, 11947.701307467383, 11397.601083962021, 11440.313581185155, 11548.538058477567, 11747.941260293645, 11959.461824218106], "value": 7189.685610434436, "unit": "score"}], "name": "dromaeo_css", "value": 7074.851616155389, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 127.0, 123.0, 159.0, 129.0, 128.0, 130.0, 122.0, 162.0, 127.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 160.0, 161.0, 163.0, 161.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 109.0, 77.0, 76.0, 76.0, 75.0, 97.0, 77.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 119.0, 82.0, 83.0, 83.0, 108.0, 83.0, 83.0, 115.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 52.0, 55.0, 54.0, 55.0, 56.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 80.0, 80.0, 80.0, 81.0, 79.0, 80.0, 81.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 132.0, 145.0, 133.0, 152.0, 133.0, 138.0, 132.0, 173.0, 140.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 166.0, 164.0, 165.0, 172.0, 171.0, 165.0, 170.0, 168.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 67.0, 66.0, 66.0, 67.0, 67.0, 64.0, 63.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1499.2000000000003, "alertThreshold": 2.0}]} 23:09:41 INFO - Return code: 0 23:09:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:09:41 INFO - # TBPL SUCCESS # 23:09:41 INFO - Running post-action listener: _resource_record_post_action 23:09:41 INFO - [mozharness: 2016-10-21 06:09:41.716675Z] Finished run-tests step (success) 23:09:41 INFO - Running post-run listener: _resource_record_post_run 23:09:41 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405708800; Read time: 72; Write time: 310592 23:09:41 INFO - TinderboxPrint: CPU usage
13.0% 23:09:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 72 23:09:41 INFO - TinderboxPrint: I/O write bytes / time
405,708,800 / 310,592 23:09:41 INFO - TinderboxPrint: CPU idle
8,296.7 (86.9%) 23:09:41 INFO - TinderboxPrint: CPU user
1,196.9 (12.5%) 23:09:41 INFO - TinderboxPrint: Swap in / out
0 / 0 23:09:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 116670464; Read time: 0; Write time: 113864 23:09:41 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288706560; Read time: 72; Write time: 196724 23:09:42 INFO - Running post-run listener: _upload_blobber_files 23:09:42 INFO - Blob upload gear active. 23:09:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:09: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 23:09: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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:09: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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:09:42 INFO - (blobuploader) - INFO - Open directory for files ... 23:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:43 INFO - (blobuploader) - INFO - Done attempting. 23:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:46 INFO - (blobuploader) - INFO - Done attempting. 23:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:47 INFO - (blobuploader) - INFO - Done attempting. 23:09:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:09:47 INFO - Return code: 0 23:09:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:09:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:09:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b9cb4cdd62b70c4a431c9281a905da9f5887060c3a23e192196696ddc1477770e22afb630fb1044086aa2235d6b04df21541fc1220c0aea4ee7a90c5fb0c9230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13036bf40240e9122e4e4d386901757aa850bd2b0dd5455fc20bbe759b5e79b816d75517de006bc89f18fdf363a3db71f441e4a1d30234c7a831d00c857ac8c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3ae9ae14d0d9742a9c3c57319bfbdfbad090f5b662ac33fcefcfdadc2cb07400f2c4588d620ce98e85ffb8c348f83f679753204079316c9fb6883e822d385710"} 23:09:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:09:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:09:47 INFO - Contents: 23:09:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b9cb4cdd62b70c4a431c9281a905da9f5887060c3a23e192196696ddc1477770e22afb630fb1044086aa2235d6b04df21541fc1220c0aea4ee7a90c5fb0c9230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13036bf40240e9122e4e4d386901757aa850bd2b0dd5455fc20bbe759b5e79b816d75517de006bc89f18fdf363a3db71f441e4a1d30234c7a831d00c857ac8c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3ae9ae14d0d9742a9c3c57319bfbdfbad090f5b662ac33fcefcfdadc2cb07400f2c4588d620ce98e85ffb8c348f83f679753204079316c9fb6883e822d385710"} 23:09:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:09:47 INFO - Copying logs to upload dir... 23:09:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:09:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.160608 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-20 23:09:47.335239) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 23:09:47.339134) ========= 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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b9cb4cdd62b70c4a431c9281a905da9f5887060c3a23e192196696ddc1477770e22afb630fb1044086aa2235d6b04df21541fc1220c0aea4ee7a90c5fb0c9230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13036bf40240e9122e4e4d386901757aa850bd2b0dd5455fc20bbe759b5e79b816d75517de006bc89f18fdf363a3db71f441e4a1d30234c7a831d00c857ac8c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3ae9ae14d0d9742a9c3c57319bfbdfbad090f5b662ac33fcefcfdadc2cb07400f2c4588d620ce98e85ffb8c348f83f679753204079316c9fb6883e822d385710"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477017007/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b9cb4cdd62b70c4a431c9281a905da9f5887060c3a23e192196696ddc1477770e22afb630fb1044086aa2235d6b04df21541fc1220c0aea4ee7a90c5fb0c9230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13036bf40240e9122e4e4d386901757aa850bd2b0dd5455fc20bbe759b5e79b816d75517de006bc89f18fdf363a3db71f441e4a1d30234c7a831d00c857ac8c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3ae9ae14d0d9742a9c3c57319bfbdfbad090f5b662ac33fcefcfdadc2cb07400f2c4588d620ce98e85ffb8c348f83f679753204079316c9fb6883e822d385710"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 23:09:47.368791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 23:09:47.369481) ========= 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=826874ccc97bd7bf541b4875000001d5-1477028949.537485-1496295743 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 23:09:47.449653) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 23:09:47.450005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 23:09:47.450319) ========= ========= Total master_lag: 0.17 =========