builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1486065692.71 results: success (0) buildid: 20170202101509 builduid: 9bec15c897d74b64af1f2f9d2986fa76 revision: d171c36d484800b1bb00db1612460a7120dd2fdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.709313) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.709728) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.710000) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.726641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.726968) ========= 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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-02 12:01:32-- 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% 32.7M=0s 2017-02-02 12:01:33 (32.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089117 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.827877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.828184) ========= 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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020295 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:32.880320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 12:01:32.883716) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d171c36d484800b1bb00db1612460a7120dd2fdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d171c36d484800b1bb00db1612460a7120dd2fdf --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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-02 12:01:33,121 truncating revision to first 12 chars 2017-02-02 12:01:33,121 Setting DEBUG logging. 2017-02-02 12:01:33,121 attempt 1/10 2017-02-02 12:01:33,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d171c36d4848?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 12:01:33,932 unpacking tar archive at: mozilla-beta-d171c36d4848/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119593 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 12:01:34.016319) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:34.016646) ========= script_repo_revision: d171c36d484800b1bb00db1612460a7120dd2fdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:34.017021) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:34.017282) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 12:01:34.030047) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-02-02 12:01:34.030403) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:01:34 INFO - MultiFileLogger online at 20170202 12:01:34 in /builds/slave/test 12:01:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:01:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:34 INFO - {'append_to_log': False, 12:01:34 INFO - 'base_work_dir': '/builds/slave/test', 12:01:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:01:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:34 INFO - 'branch': 'Mozilla-Beta', 12:01:34 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:34 INFO - 'config_files': ('talos/linux_config.py',), 12:01:34 INFO - 'default_actions': ('clobber', 12:01:34 INFO - 'read-buildbot-config', 12:01:34 INFO - 'download-and-extract', 12:01:34 INFO - 'populate-webroot', 12:01:34 INFO - 'create-virtualenv', 12:01:34 INFO - 'install', 12:01:34 INFO - 'run-tests'), 12:01:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:34 INFO - 'download_minidump_stackwalk': True, 12:01:34 INFO - 'download_symbols': 'ondemand', 12:01:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:34 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:34 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:34 INFO - 'installer_path': 'installer.exe', 12:01:34 INFO - 'log_level': 'info', 12:01:34 INFO - 'log_name': 'talos', 12:01:34 INFO - 'log_to_console': True, 12:01:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:01:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:01:34 INFO - 'opt_config_files': (), 12:01:34 INFO - 'pip_index': False, 12:01:34 INFO - 'sps_profile': False, 12:01:34 INFO - 'sps_profile_interval': 0, 12:01:34 INFO - 'suite': 'dromaeojs', 12:01:34 INFO - 'system_bits': '32', 12:01:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:34 INFO - 'title': 'talos-linux64-ix-018', 12:01:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:34 INFO - 'use_talos_json': True, 12:01:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:34 INFO - 'work_dir': 'build'} 12:01:34 INFO - [mozharness: 2017-02-02 20:01:34.371485Z] Running clobber step. 12:01:34 INFO - Running pre-action listener: _resource_record_pre_action 12:01:34 INFO - Running main action method: clobber 12:01:34 INFO - rmtree: /builds/slave/test/build 12:01:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:34 INFO - Running post-action listener: _resource_record_post_action 12:01:34 INFO - [mozharness: 2017-02-02 20:01:34.839752Z] Finished clobber step (success) 12:01:34 INFO - [mozharness: 2017-02-02 20:01:34.839851Z] Running read-buildbot-config step. 12:01:34 INFO - Running pre-action listener: _resource_record_pre_action 12:01:34 INFO - Running main action method: read_buildbot_config 12:01:34 INFO - Using buildbot properties: 12:01:34 INFO - { 12:01:34 INFO - "project": "", 12:01:34 INFO - "product": "firefox", 12:01:34 INFO - "script_repo_revision": "production", 12:01:34 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:01:34 INFO - "repository": "", 12:01:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:01:34 INFO - "buildid": "20170202101509", 12:01:34 INFO - "pgo_build": "True", 12:01:34 INFO - "basedir": "/builds/slave/test", 12:01:34 INFO - "buildnumber": 126, 12:01:34 INFO - "slavename": "talos-linux64-ix-018", 12:01:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:01:34 INFO - "platform": "ubuntu64_hw", 12:01:34 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:01:34 INFO - "branch": "mozilla-beta", 12:01:34 INFO - "repo_path": "releases/mozilla-beta", 12:01:34 INFO - "revision": "d171c36d484800b1bb00db1612460a7120dd2fdf", 12:01:34 INFO - "stage_platform": "linux64-pgo", 12:01:34 INFO - "builduid": "9bec15c897d74b64af1f2f9d2986fa76", 12:01:34 INFO - "slavebuilddir": "test" 12:01:34 INFO - } 12:01:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 12:01:34 INFO - Running post-action listener: _resource_record_post_action 12:01:34 INFO - [mozharness: 2017-02-02 20:01:34.842280Z] Finished read-buildbot-config step (success) 12:01:34 INFO - [mozharness: 2017-02-02 20:01:34.842368Z] Running download-and-extract step. 12:01:34 INFO - Running pre-action listener: _resource_record_pre_action 12:01:34 INFO - Running main action method: download_and_extract 12:01:34 INFO - mkdir: /builds/slave/test/build/tests 12:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:34 INFO - trying https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 12:01:34 INFO - Downloading https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 12:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:01:35 INFO - Downloaded 1327 bytes. 12:01:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 12:01:35 INFO - Using the following test package requirements: 12:01:35 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 12:01:35 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:01:35 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 12:01:35 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'jsshell-linux-x86_64.zip'], 12:01:35 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:01:35 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 12:01:35 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 12:01:35 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 12:01:35 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:01:35 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 12:01:35 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:01:35 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:01:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 12:01:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:35 INFO - Fetch https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 12:01:36 INFO - Http code: 200 12:01:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 12:01:36 INFO - x-amz-cf-id: mn7Occ5vjLmvWiPNr5kxDUpOmsMMVcZNq-EIHiKIuMGXcJqmJ3ECdg== 12:01:36 INFO - x-amz-version-id: 8R1D8OAiElBoc0_C.uUa0RvM3BYzqMY1 12:01:36 INFO - Expected file size: 21956314 12:01:36 INFO - Obtained file size: 21956314 12:01:40 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:01:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 12:01:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:01:40 INFO - Fetch https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 12:01:40 INFO - Http code: 200 12:01:40 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 12:01:40 INFO - x-amz-cf-id: v-ZKnCTrO49NjGyZOzjiLyhVBvW0PHWQSsxLCl-f7hsqLp9vQEGOqg== 12:01:40 INFO - x-amz-version-id: 8R1D8OAiElBoc0_C.uUa0RvM3BYzqMY1 12:01:41 INFO - Expected file size: 21956314 12:01:41 INFO - Obtained file size: 21956314 12:01:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 12:01:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:01:45 INFO - Fetch https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 12:01:46 INFO - Http code: 200 12:01:46 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 12:01:46 INFO - x-amz-cf-id: MGDU2yn-CFZqN8ULwb1hpWFDUouCRA_-2aAYs8sE1-ic78fsPon21w== 12:01:46 INFO - x-amz-version-id: Irs8kaYWxHG5wLluf0xOdETsVZwCyDTJ 12:01:46 INFO - Expected file size: 14403005 12:01:46 INFO - Obtained file size: 14403005 12:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:46 INFO - trying https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 12:01:46 INFO - Downloading https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 12:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:01:47 INFO - Downloaded 57704034 bytes. 12:01:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 12:01:47 INFO - mkdir: /builds/slave/test/properties 12:01:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:47 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:47 INFO - Contents: 12:01:47 INFO - build_url:https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 12:01:48 INFO - Running post-action listener: _resource_record_post_action 12:01:48 INFO - Running post-action listener: set_extra_try_arguments 12:01:48 INFO - [mozharness: 2017-02-02 20:01:48.444848Z] Finished download-and-extract step (success) 12:01:48 INFO - [mozharness: 2017-02-02 20:01:48.445107Z] Running populate-webroot step. 12:01:48 INFO - Running pre-action listener: _resource_record_pre_action 12:01:48 INFO - Running main action method: populate_webroot 12:01:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:48 INFO - '--tppagecycles', 12:01:48 INFO - '7'], 12:01:48 INFO - 'tests': ['tsvgm']}}, 12:01:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:01:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['damp', 'tps']}, 12:01:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'tests': ['damp', 'tps']}, 12:01:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['dromaeo_dom']}, 12:01:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['basic_compositor_video']}, 12:01:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:01:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['a11yr', 12:01:48 INFO - 'ts_paint', 12:01:48 INFO - 'tpaint', 12:01:48 INFO - 'sessionrestore', 12:01:48 INFO - 'sessionrestore_no_auto_restore', 12:01:48 INFO - 'tabpaint']}, 12:01:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['tsvgx', 12:01:48 INFO - 'tsvgr_opacity', 12:01:48 INFO - 'tart', 12:01:48 INFO - 'tscrollx', 12:01:48 INFO - 'cart']}, 12:01:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:48 INFO - 'tsvgr_opacity', 12:01:48 INFO - 'tart', 12:01:48 INFO - 'tscrollx', 12:01:48 INFO - 'cart']}, 12:01:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'talos_options': ['--disable-e10s'], 12:01:48 INFO - 'tests': ['tp5o']}, 12:01:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'tests': ['tp5o']}, 12:01:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'talos_options': ['--disable-e10s', 12:01:48 INFO - '--xperf_path', 12:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:48 INFO - 'tests': ['tp5n']}, 12:01:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:48 INFO - 'talos_options': ['--xperf_path', 12:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:48 INFO - 'tests': ['tp5n']}}, 12:01:48 INFO - 'talos.zip': {'path': '', 12:01:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:48 INFO - Running post-action listener: _resource_record_post_action 12:01:48 INFO - [mozharness: 2017-02-02 20:01:48.465952Z] Finished populate-webroot step (success) 12:01:48 INFO - [mozharness: 2017-02-02 20:01:48.466040Z] Running create-virtualenv step. 12:01:48 INFO - Running pre-action listener: _resource_record_pre_action 12:01:48 INFO - Running main action method: create_virtualenv 12:01:48 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:48 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 12:01:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:01:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:48 INFO - Using real prefix '/usr' 12:01:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:49 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:51 INFO - Installing pip.................done. 12:01:51 INFO - Return code: 0 12:01:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:01:51 INFO - Reading from file tmpfile_stdout 12:01:51 INFO - Output received: 12:01:51 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) 12:01:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:51 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:51 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 12:01:51 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 12:01:51 INFO - Using env: {'DISPLAY': ':0', 12:01:51 INFO - 'HOME': '/home/cltbld', 12:01:51 INFO - 'LANG': 'en_US.UTF-8', 12:01:51 INFO - 'LANGUAGE': 'en_US:en', 12:01:51 INFO - 'LOGNAME': 'cltbld', 12:01:51 INFO - 'MAIL': '/var/mail/cltbld', 12:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:51 INFO - 'MOZ_NO_REMOTE': '1', 12:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:51 INFO - 'NO_EM_RESTART': '1', 12:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:51 INFO - 'PWD': '/builds/slave/test', 12:01:51 INFO - 'SHELL': '/bin/bash', 12:01:51 INFO - 'SHLVL': '1', 12:01:51 INFO - 'TERM': 'linux', 12:01:51 INFO - 'TMOUT': '86400', 12:01:51 INFO - 'USER': 'cltbld', 12:01:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:51 INFO - '_': '/tools/buildbot/bin/python'} 12:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:51 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 12:01:51 INFO - Cleaning up... 12:01:51 INFO - Return code: 0 12:01:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:51 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:51 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 12:01:51 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 12:01:51 INFO - Using env: {'DISPLAY': ':0', 12:01:51 INFO - 'HOME': '/home/cltbld', 12:01:51 INFO - 'LANG': 'en_US.UTF-8', 12:01:51 INFO - 'LANGUAGE': 'en_US:en', 12:01:51 INFO - 'LOGNAME': 'cltbld', 12:01:51 INFO - 'MAIL': '/var/mail/cltbld', 12:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:51 INFO - 'MOZ_NO_REMOTE': '1', 12:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:51 INFO - 'NO_EM_RESTART': '1', 12:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:51 INFO - 'PWD': '/builds/slave/test', 12:01:51 INFO - 'SHELL': '/bin/bash', 12:01:51 INFO - 'SHLVL': '1', 12:01:51 INFO - 'TERM': 'linux', 12:01:51 INFO - 'TMOUT': '86400', 12:01:51 INFO - 'USER': 'cltbld', 12:01:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:51 INFO - '_': '/tools/buildbot/bin/python'} 12:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:51 INFO - Downloading/unpacking psutil>=3.1.1 12:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:53 INFO - Installing collected packages: psutil 12:01:53 INFO - Running setup.py install for psutil 12:01:53 INFO - building 'psutil._psutil_linux' extension 12:01:53 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 12:01:54 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 12:01:54 INFO - building 'psutil._psutil_posix' extension 12:01:54 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 12:01:54 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 12:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:54 INFO - Successfully installed psutil 12:01:54 INFO - Cleaning up... 12:01:54 INFO - Return code: 0 12:01:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01:54 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')]} 12:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:54 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')]} 12:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:54 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:54 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 12:01:54 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 12:01:54 INFO - Using env: {'DISPLAY': ':0', 12:01:54 INFO - 'HOME': '/home/cltbld', 12:01:54 INFO - 'LANG': 'en_US.UTF-8', 12:01:54 INFO - 'LANGUAGE': 'en_US:en', 12:01:54 INFO - 'LOGNAME': 'cltbld', 12:01:54 INFO - 'MAIL': '/var/mail/cltbld', 12:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:54 INFO - 'MOZ_NO_REMOTE': '1', 12:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:54 INFO - 'NO_EM_RESTART': '1', 12:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:54 INFO - 'PWD': '/builds/slave/test', 12:01:54 INFO - 'SHELL': '/bin/bash', 12:01:54 INFO - 'SHLVL': '1', 12:01:54 INFO - 'TERM': 'linux', 12:01:54 INFO - 'TMOUT': '86400', 12:01:54 INFO - 'USER': 'cltbld', 12:01:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:54 INFO - '_': '/tools/buildbot/bin/python'} 12:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:54 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 12:01:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:01:56 INFO - Installing collected packages: mozsystemmonitor 12:01:56 INFO - Running setup.py install for mozsystemmonitor 12:01:56 INFO - Successfully installed mozsystemmonitor 12:01:56 INFO - Cleaning up... 12:01:56 INFO - Return code: 0 12:01:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:01:56 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')]} 12:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:56 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')]} 12:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:56 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:56 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 12:01:56 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 12:01:56 INFO - Using env: {'DISPLAY': ':0', 12:01:56 INFO - 'HOME': '/home/cltbld', 12:01:56 INFO - 'LANG': 'en_US.UTF-8', 12:01:56 INFO - 'LANGUAGE': 'en_US:en', 12:01:56 INFO - 'LOGNAME': 'cltbld', 12:01:56 INFO - 'MAIL': '/var/mail/cltbld', 12:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:56 INFO - 'MOZ_NO_REMOTE': '1', 12:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:56 INFO - 'NO_EM_RESTART': '1', 12:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:56 INFO - 'PWD': '/builds/slave/test', 12:01:56 INFO - 'SHELL': '/bin/bash', 12:01:56 INFO - 'SHLVL': '1', 12:01:56 INFO - 'TERM': 'linux', 12:01:56 INFO - 'TMOUT': '86400', 12:01:56 INFO - 'USER': 'cltbld', 12:01:56 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:56 INFO - '_': '/tools/buildbot/bin/python'} 12:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:56 INFO - Downloading/unpacking jsonschema==2.5.1 12:01:56 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 12:01:56 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 12:01:56 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 12:01:56 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 12:01:56 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 12:01:56 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 12:01:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:59 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:59 INFO - warning: no files found matching '*.txt' 12:01:59 INFO - no previously-included directories found matching 'build' 12:01:59 INFO - no previously-included directories found matching 'dist' 12:01:59 INFO - no previously-included directories found matching '.git*' 12:01:59 INFO - Installing collected packages: jsonschema, functools32 12:01:59 INFO - Running setup.py install for functools32 12:01:59 INFO - warning: no files found matching '*.txt' 12:01:59 INFO - no previously-included directories found matching 'build' 12:01:59 INFO - no previously-included directories found matching 'dist' 12:01:59 INFO - no previously-included directories found matching '.git*' 12:01:59 INFO - Successfully installed jsonschema functools32 12:01:59 INFO - Cleaning up... 12:01:59 INFO - Return code: 0 12:01:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:01:59 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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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')]} 12:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:59 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 12:01:59 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 12:01:59 INFO - Using env: {'DISPLAY': ':0', 12:01:59 INFO - 'HOME': '/home/cltbld', 12:01:59 INFO - 'LANG': 'en_US.UTF-8', 12:01:59 INFO - 'LANGUAGE': 'en_US:en', 12:01:59 INFO - 'LOGNAME': 'cltbld', 12:01:59 INFO - 'MAIL': '/var/mail/cltbld', 12:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:59 INFO - 'MOZ_NO_REMOTE': '1', 12:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:59 INFO - 'NO_EM_RESTART': '1', 12:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:59 INFO - 'PWD': '/builds/slave/test', 12:01:59 INFO - 'SHELL': '/bin/bash', 12:01:59 INFO - 'SHLVL': '1', 12:01:59 INFO - 'TERM': 'linux', 12:01:59 INFO - 'TMOUT': '86400', 12:01:59 INFO - 'USER': 'cltbld', 12:01:59 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:59 INFO - '_': '/tools/buildbot/bin/python'} 12:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:01:59 INFO - Cleaning up... 12:01:59 INFO - Return code: 0 12:01:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:01:59 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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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')]} 12:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:59 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:01:59 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 12:01:59 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 12:01:59 INFO - Using env: {'DISPLAY': ':0', 12:01:59 INFO - 'HOME': '/home/cltbld', 12:01:59 INFO - 'LANG': 'en_US.UTF-8', 12:01:59 INFO - 'LANGUAGE': 'en_US:en', 12:01:59 INFO - 'LOGNAME': 'cltbld', 12:01:59 INFO - 'MAIL': '/var/mail/cltbld', 12:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:59 INFO - 'MOZ_NO_REMOTE': '1', 12:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:59 INFO - 'NO_EM_RESTART': '1', 12:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:59 INFO - 'PWD': '/builds/slave/test', 12:01:59 INFO - 'SHELL': '/bin/bash', 12:01:59 INFO - 'SHLVL': '1', 12:01:59 INFO - 'TERM': 'linux', 12:01:59 INFO - 'TMOUT': '86400', 12:01:59 INFO - 'USER': 'cltbld', 12:01:59 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:59 INFO - '_': '/tools/buildbot/bin/python'} 12:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:02:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02:02 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 12:02:02 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 12:02:02 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 12:02:02 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 12:02:02 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 12:02:02 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 12:02:02 INFO - Downloading docopt-0.6.1.tar.gz 12:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:02 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:02 INFO - Running setup.py install for blobuploader 12:02:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:02 INFO - Running setup.py install for requests 12:02:03 INFO - Running setup.py install for docopt 12:02:03 INFO - Successfully installed blobuploader requests docopt 12:02:03 INFO - Cleaning up... 12:02:03 INFO - Return code: 0 12:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:03 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:02:03 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 12:02:03 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 12:02:03 INFO - Using env: {'DISPLAY': ':0', 12:02:03 INFO - 'HOME': '/home/cltbld', 12:02:03 INFO - 'LANG': 'en_US.UTF-8', 12:02:03 INFO - 'LANGUAGE': 'en_US:en', 12:02:03 INFO - 'LOGNAME': 'cltbld', 12:02:03 INFO - 'MAIL': '/var/mail/cltbld', 12:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:03 INFO - 'MOZ_NO_REMOTE': '1', 12:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:03 INFO - 'NO_EM_RESTART': '1', 12:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:03 INFO - 'PWD': '/builds/slave/test', 12:02:03 INFO - 'SHELL': '/bin/bash', 12:02:03 INFO - 'SHLVL': '1', 12:02:03 INFO - 'TERM': 'linux', 12:02:03 INFO - 'TMOUT': '86400', 12:02:03 INFO - 'USER': 'cltbld', 12:02:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:03 INFO - '_': '/tools/buildbot/bin/python'} 12:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:03 INFO - Running setup.py (path:/tmp/pip-Cg0piC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:03 INFO - Running setup.py (path:/tmp/pip-PUaxkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:03 INFO - Running setup.py (path:/tmp/pip-48GTM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:03 INFO - Running setup.py (path:/tmp/pip-M9RaQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:03 INFO - Running setup.py (path:/tmp/pip-9l3_JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:03 INFO - Running setup.py (path:/tmp/pip-7X5FGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:03 INFO - Running setup.py (path:/tmp/pip-21JpoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:04 INFO - Running setup.py (path:/tmp/pip-7QWFem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:04 INFO - Running setup.py (path:/tmp/pip-NX23Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:04 INFO - Running setup.py (path:/tmp/pip-Etn4mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:04 INFO - Running setup.py (path:/tmp/pip-mvI8N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:04 INFO - Running setup.py (path:/tmp/pip-IhRoVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:04 INFO - Running setup.py (path:/tmp/pip-p57ZtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:04 INFO - Running setup.py (path:/tmp/pip-h2Ie3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:04 INFO - Running setup.py (path:/tmp/pip-4gpywG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:04 INFO - Running setup.py (path:/tmp/pip-WRvarP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:04 INFO - Running setup.py (path:/tmp/pip-XFR7Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:02:04 INFO - Running setup.py install for manifestparser 12:02:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:02:04 INFO - Running setup.py install for mozcrash 12:02:04 INFO - Running setup.py install for mozdebug 12:02:05 INFO - Running setup.py install for mozdevice 12:02:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for mozfile 12:02:05 INFO - Running setup.py install for mozhttpd 12:02:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for mozinfo 12:02:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for mozInstall 12:02:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for mozleak 12:02:05 INFO - Running setup.py install for mozlog 12:02:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for moznetwork 12:02:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:02:05 INFO - Running setup.py install for mozprocess 12:02:05 INFO - Running setup.py install for mozprofile 12:02:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:02:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:02:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:02:06 INFO - Running setup.py install for mozrunner 12:02:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:02:06 INFO - Running setup.py install for mozscreenshot 12:02:06 INFO - Running setup.py install for moztest 12:02:06 INFO - Running setup.py install for mozversion 12:02:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:02:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:02:06 INFO - Cleaning up... 12:02:06 INFO - Return code: 0 12:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:06 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:02:06 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 12:02:06 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 12:02:06 INFO - Using env: {'DISPLAY': ':0', 12:02:06 INFO - 'HOME': '/home/cltbld', 12:02:06 INFO - 'LANG': 'en_US.UTF-8', 12:02:06 INFO - 'LANGUAGE': 'en_US:en', 12:02:06 INFO - 'LOGNAME': 'cltbld', 12:02:06 INFO - 'MAIL': '/var/mail/cltbld', 12:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:06 INFO - 'MOZ_NO_REMOTE': '1', 12:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:06 INFO - 'NO_EM_RESTART': '1', 12:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:06 INFO - 'PWD': '/builds/slave/test', 12:02:06 INFO - 'SHELL': '/bin/bash', 12:02:06 INFO - 'SHLVL': '1', 12:02:06 INFO - 'TERM': 'linux', 12:02:06 INFO - 'TMOUT': '86400', 12:02:06 INFO - 'USER': 'cltbld', 12:02:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:06 INFO - '_': '/tools/buildbot/bin/python'} 12:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:06 INFO - Running setup.py (path:/tmp/pip-CjoGgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:06 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)) 12:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:06 INFO - Running setup.py (path:/tmp/pip-JjWOPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:06 INFO - Running setup.py (path:/tmp/pip-Pv5yFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:06 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)) 12:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:06 INFO - Running setup.py (path:/tmp/pip-KuEqg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:07 INFO - Running setup.py (path:/tmp/pip-iEKUgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:07 INFO - Running setup.py (path:/tmp/pip-lHWLHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:07 INFO - Running setup.py (path:/tmp/pip-JyUdLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:07 INFO - Running setup.py (path:/tmp/pip-rFs1Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:07 INFO - Running setup.py (path:/tmp/pip-zNkin9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:07 INFO - Running setup.py (path:/tmp/pip-nHH1xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:07 INFO - Running setup.py (path:/tmp/pip-2PakZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:07 INFO - Running setup.py (path:/tmp/pip-qJJPZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:07 INFO - Running setup.py (path:/tmp/pip-bgdEx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:07 INFO - Running setup.py (path:/tmp/pip-BHwtyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:07 INFO - Running setup.py (path:/tmp/pip-NLsJsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:07 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)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:07 INFO - Running setup.py (path:/tmp/pip-GCSXYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:07 INFO - Running setup.py (path:/tmp/pip-4ut5ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:08 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)) 12:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:08 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)) 12:02:08 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)) 12:02:08 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)) 12:02:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:09 INFO - Downloading blessings-1.6.tar.gz 12:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:10 INFO - Installing collected packages: blessings 12:02:10 INFO - Running setup.py install for blessings 12:02:10 INFO - Successfully installed blessings 12:02:10 INFO - Cleaning up... 12:02:10 INFO - Return code: 0 12:02:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:10 INFO - Reading from file tmpfile_stdout 12:02:10 INFO - Current package versions: 12:02:10 INFO - argparse == 1.2.1 12:02:10 INFO - blessings == 1.6 12:02:10 INFO - blobuploader == 1.2.4 12:02:10 INFO - docopt == 0.6.1 12:02:10 INFO - functools32 == 3.2.3-2 12:02:10 INFO - jsonschema == 2.5.1 12:02:10 INFO - manifestparser == 1.1 12:02:10 INFO - mozInstall == 1.12 12:02:10 INFO - mozcrash == 1.0 12:02:10 INFO - mozdebug == 0.1 12:02:10 INFO - mozdevice == 0.48 12:02:10 INFO - mozfile == 1.2 12:02:10 INFO - mozhttpd == 0.7 12:02:10 INFO - mozinfo == 0.9 12:02:10 INFO - mozleak == 0.1 12:02:10 INFO - mozlog == 3.4 12:02:10 INFO - moznetwork == 0.27 12:02:10 INFO - mozprocess == 0.23 12:02:10 INFO - mozprofile == 0.28 12:02:10 INFO - mozrunner == 6.13 12:02:10 INFO - mozscreenshot == 0.1 12:02:10 INFO - mozsystemmonitor == 0.3 12:02:10 INFO - moztest == 0.8 12:02:10 INFO - mozversion == 1.4 12:02:10 INFO - psutil == 3.1.1 12:02:10 INFO - requests == 1.2.3 12:02:10 INFO - wsgiref == 0.1.2 12:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:10 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:02:10 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 12:02:10 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 12:02:10 INFO - Using env: {'DISPLAY': ':0', 12:02:10 INFO - 'HOME': '/home/cltbld', 12:02:10 INFO - 'LANG': 'en_US.UTF-8', 12:02:10 INFO - 'LANGUAGE': 'en_US:en', 12:02:10 INFO - 'LOGNAME': 'cltbld', 12:02:10 INFO - 'MAIL': '/var/mail/cltbld', 12:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:10 INFO - 'MOZ_NO_REMOTE': '1', 12:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:10 INFO - 'NO_EM_RESTART': '1', 12:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:10 INFO - 'PWD': '/builds/slave/test', 12:02:10 INFO - 'SHELL': '/bin/bash', 12:02:10 INFO - 'SHLVL': '1', 12:02:10 INFO - 'TERM': 'linux', 12:02:10 INFO - 'TMOUT': '86400', 12:02:10 INFO - 'USER': 'cltbld', 12:02:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:10 INFO - '_': '/tools/buildbot/bin/python'} 12:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 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)) 12:02:10 INFO - Cleaning up... 12:02:10 INFO - Return code: 0 12:02:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:10 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 0x7f8814791f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', '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 12:02:10 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 12:02:10 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 12:02:10 INFO - Using env: {'DISPLAY': ':0', 12:02:10 INFO - 'HOME': '/home/cltbld', 12:02:10 INFO - 'LANG': 'en_US.UTF-8', 12:02:10 INFO - 'LANGUAGE': 'en_US:en', 12:02:10 INFO - 'LOGNAME': 'cltbld', 12:02:10 INFO - 'MAIL': '/var/mail/cltbld', 12:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:10 INFO - 'MOZ_NO_REMOTE': '1', 12:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:10 INFO - 'NO_EM_RESTART': '1', 12:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:10 INFO - 'PWD': '/builds/slave/test', 12:02:10 INFO - 'SHELL': '/bin/bash', 12:02:10 INFO - 'SHLVL': '1', 12:02:10 INFO - 'TERM': 'linux', 12:02:10 INFO - 'TMOUT': '86400', 12:02:10 INFO - 'USER': 'cltbld', 12:02:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:10 INFO - '_': '/tools/buildbot/bin/python'} 12:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:10 INFO - Cleaning up... 12:02:10 INFO - Return code: 0 12:02:10 INFO - Running post-action listener: _resource_record_post_action 12:02:10 INFO - Running post-action listener: _start_resource_monitoring 12:02:10 INFO - Starting resource monitoring. 12:02:10 INFO - [mozharness: 2017-02-02 20:02:10.960971Z] Finished create-virtualenv step (success) 12:02:10 INFO - [mozharness: 2017-02-02 20:02:10.961547Z] Running install step. 12:02:10 INFO - Running pre-action listener: _resource_record_pre_action 12:02:10 INFO - Running main action method: install 12:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:11 INFO - Reading from file tmpfile_stdout 12:02:11 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:11 INFO - Reading from file tmpfile_stdout 12:02:11 INFO - Output received: 12:02:11 INFO - Usage: mozinstall [options] installer 12:02:11 INFO - Options: 12:02:11 INFO - -h, --help show this help message and exit 12:02:11 INFO - -d DEST, --destination=DEST 12:02:11 INFO - Directory to install application into. [default: 12:02:11 INFO - "/builds/slave/test"] 12:02:11 INFO - --app=APP Application being installed. [default: firefox] 12:02:11 INFO - mkdir: /builds/slave/test/build/application 12:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:02:24 INFO - Reading from file tmpfile_stdout 12:02:24 INFO - Output received: 12:02:24 INFO - /builds/slave/test/build/application/firefox/firefox 12:02:24 INFO - Running post-action listener: _resource_record_post_action 12:02:24 INFO - [mozharness: 2017-02-02 20:02:24.882388Z] Finished install step (success) 12:02:24 INFO - [mozharness: 2017-02-02 20:02:24.882632Z] Running run-tests step. 12:02:24 INFO - Running pre-action listener: _resource_record_pre_action 12:02:24 INFO - Running main action method: run_tests 12:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:24 INFO - Python 2.7.3 12:02:24 INFO - Return code: 0 12:02:24 INFO - grabbing minidump binary from tooltool 12:02:24 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')]} 12:02:24 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 0x279df60>, '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 0x279ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x279f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:24 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 12:02:24 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 12:02:24 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 12:02:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:24 INFO - Return code: 0 12:02:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:02:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:02:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 12:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 12:02:24 INFO - Using env: {'DISPLAY': ':0', 12:02:24 INFO - 'HOME': '/home/cltbld', 12:02:24 INFO - 'LANG': 'en_US.UTF-8', 12:02:24 INFO - 'LANGUAGE': 'en_US:en', 12:02:24 INFO - 'LOGNAME': 'cltbld', 12:02:24 INFO - 'MAIL': '/var/mail/cltbld', 12:02:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:24 INFO - 'MOZ_NO_REMOTE': '1', 12:02:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:24 INFO - 'NO_EM_RESTART': '1', 12:02:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:24 INFO - 'PWD': '/builds/slave/test', 12:02:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:24 INFO - 'SHELL': '/bin/bash', 12:02:24 INFO - 'SHLVL': '1', 12:02:24 INFO - 'TERM': 'linux', 12:02:24 INFO - 'TMOUT': '86400', 12:02:24 INFO - 'USER': 'cltbld', 12:02:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905', 12:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:24 INFO - '_': '/tools/buildbot/bin/python'} 12:02:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 12:02:25 INFO - mozversion application_buildid: 20170202101509 12:02:25 INFO - mozversion application_changeset: d171c36d484800b1bb00db1612460a7120dd2fdf 12:02:25 INFO - mozversion application_display_name: Firefox 12:02:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:25 INFO - mozversion application_name: Firefox 12:02:25 INFO - mozversion application_remotingname: firefox 12:02:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:02:25 INFO - mozversion application_vendor: Mozilla 12:02:25 INFO - mozversion application_version: 52.0 12:02:25 INFO - mozversion platform_buildid: 20170202101509 12:02:25 INFO - mozversion platform_changeset: d171c36d484800b1bb00db1612460a7120dd2fdf 12:02:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:02:25 INFO - mozversion platform_version: 52.0 12:02:25 INFO - using testdate: 1486065745 12:02:25 INFO - actual date: 1486065745 12:02:25 INFO - starting webserver on 'localhost:37006' 12:02:25 INFO - SUITE-START | Running 2 tests 12:02:25 INFO - TEST-START | dromaeo_css 12:02:25 INFO - operating with platform_type : linux_ 12:02:25 INFO - Initialising browser for dromaeo_css test... 12:02:25 INFO - TEST-INFO | started process 19926 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqyQyNI/profile http://localhost:37006/getInfo.html) 12:02:28 INFO - PROCESS | 19926 | __metrics Screen width/height:1600/1200 12:02:28 INFO - PROCESS | 19926 | colorDepth:24 12:02:28 INFO - PROCESS | 19926 | Browser inner width/height: 1024/697 12:02:28 INFO - PROCESS | 19926 | __metrics 12:02:29 INFO - PROCESS | 19926 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:02:29 INFO - PROCESS | 19926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:02:29 INFO - PROCESS | 19926 | console.error: PushService: 12:02:29 INFO - PROCESS | 19926 | stateChangeProcessEnqueue: Error transitioning state 12:02:29 INFO - PROCESS | 19926 | UnknownError 12:02:30 INFO - TEST-INFO | 19926: exit 0 12:02:30 INFO - Browser initialized. 12:02:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:30 INFO - TEST-INFO | started process 20018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqyQyNI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:42 INFO - PROCESS | 20018 | RSS: Main: 142127104 12:02:42 INFO - PROCESS | 20018 | 12:05:43 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37006/tests/dromaeo/cssquery-ext.html) 12:05:44 INFO - PROCESS | 20018 | RSS: Main: 245743616 12:05:44 INFO - PROCESS | 20018 | 12:08:46 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-ext.html (next: http://localhost:37006/tests/dromaeo/cssquery-jquery.html) 12:08:47 INFO - PROCESS | 20018 | RSS: Main: 227401728 12:08:47 INFO - PROCESS | 20018 | 12:11:49 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37006/tests/dromaeo/cssquery-mootools.html) 12:11:49 INFO - PROCESS | 20018 | RSS: Main: 218226688 12:11:49 INFO - PROCESS | 20018 | 12:14:51 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37006/tests/dromaeo/cssquery-prototype.html) 12:14:52 INFO - PROCESS | 20018 | RSS: Main: 221966336 12:14:52 INFO - PROCESS | 20018 | 12:17:54 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37006/tests/dromaeo/cssquery-yui.html) 12:17:55 INFO - PROCESS | 20018 | RSS: Main: 183013376 12:17:55 INFO - PROCESS | 20018 | 12:20:57 INFO - PROCESS | 20018 | Cycle 1(1): loaded http://localhost:37006/tests/dromaeo/cssquery-yui.html (next: http://localhost:37006/tests/dromaeo/cssquery-dojo.html) 12:20:58 INFO - PROCESS | 20018 | RSS: Main: 263081984 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | __start_tp_report 12:20:58 INFO - PROCESS | 20018 | _x_x_mozilla_page_load 12:20:58 INFO - PROCESS | 20018 | _x_x_mozilla_page_load_details 12:20:58 INFO - PROCESS | 20018 | |i|pagename|runs| 12:20:58 INFO - PROCESS | 20018 | |0;dojo.html;3206.743460523158;3260.836958152092;3265.4775235962243;3292.127586189659;3311.801291922485;5668.206451096845;5764.048931926227;5767.134929760533;5773.191753154561;5775.451332123447;5731.742071606777;5774.307083150017;5779.971100144493;5786.132080187974;5794.101914203306;5192.818251361213;5204;5231.86920326993;5252.684838909678;5291.126964050927;5026.748662566875;5052.570531504808;5055.39335279767;5291.867703307429;5307.018201632691;5089.592832573404;5169.276301317818;5175.301334319875;5191.506806853343;5194.194899790538;5174.818881339135;5184.585233181337;5186.896262074737;5194.948050519484;5218.60860435465;4994.200927851526;5085.110105731519;5098.566621837148;5109.054824857413;5167.147420675622;5507.586930980193;5513.61404701671;5570.832875013756;5626;5659.207710920475;5952.136940143699;6018.217631707894;6061.090836374579;6061.242344706911;6064.3935606439;5959.40405959405;6096.268447786294;6097.146399504073;6117.357677443849;6130.264368275791;5605.411431799643;5619.129034999541;5661.66030038199;5681.346645135863;5698.8290351289525;6224.06639004153;6225.035119556476;6226.25284965807;6228.906566401508;6231.937680623227;5824.184614154022;5964.701764911737;5970.492508136857;5982.40175982407;5984.970075149684;5974.2233509643465;6047;6116.244470650255;6134.325224225332;6139.140520327158;6026.54800889935;6089.908651370233;6092.208012958376;6093.329733729286;6108.144859719643;6085.786997455068;6087.1173679816175;6088.908666370008;6104;6124.265088189446;4382.561743825592;4407.823687052482;4441.045175287264;4443.822247110144;4450.844220452269;3971.5631280559114;3987.1029018470613;3992.7604343739467;4013.8795836124964;4021.195760847842;5733.197352370671;5738.483536481736;5740.282464691913;5742.770289188469;5782.88434231313;5978.85052873673;5996.280446346466;5999.130126131687;6011.909821352683;6040.124181993674;5795.1017592273265;5823.475887170175;5827.446392592697;5832.970835145882;5854.385289544622;5160.122779127482;5188.143956247252;5197.818076367309;5199.168133098761;5200.609954253446;6094.421030002142;6154.815355539341;6187.690615469299;6339.175907132043;6355.142055822497;6046.909296360646;6096.390360963868;6105.725242364193;6116.602420842631;6136.386361363828;6244.406781355764;6271.749130034747;6289.96855015722;6312.684365781784;6322.462590679844;5808.30300363959;5825.097109947965;5974.61165024272;5991.820245392645;5993.580449368503;5995.430434108753;6004.669743164168;6019.337872834072;6104.298005729221;6124.510039196943;5921.496672782861;5939.554533409925;5940.435658612425;5940.910886336795;5946.970265148647;5669.971650141723;5905.734241959036;5908.291005079418;5915.438033386821;5925.259342582177;5808.593398462067;5879.794207202727;5932.9703351484695;5950.3454619992635;6037.758489660365;6025.18659980897;6055.697215139314;6057.697115144137;6061;6080.118382834553;5596.300462442195;5632.239647647515;5894.145348924296;5908.911366329423;5912.733926973383 12:20:58 INFO - PROCESS | 20018 | |1;ext.html;583.4807021750643;602.0607851751267;639.4532674563245;642.3223499208336;661.9172603424572;34365.140871478194;45835;46782.29826552604;46867.296990545125;49818.75090624551;47690.28464573034;48378;48385.75807120968;48405.51594484063;48469.515304846944;8519.659213631478;8984.101589841013;9129.95435022824;9130.497822619753;9147.496887671176;8480.533570653612;10769.192310576738;11000.834987475195;11015.614453494129;11357.375344356056;11009.339439633648;11079.833802492969;11080;11092.889071109266;11096.889031109666;10839.458027098653;10919.727006824813;10920.945395272973;10925.399103049329;10937.179711521667;10578.52396642153;10618.309809862334;10633.255672102956;10634.415107169103;10635.57457701691;44261.557384426065;44722.32916506255;44869;44912.65262042144;44919;3608.8736894208578;3617.3850445424077;3741.494898188737;3825.5409350877794;4085.019595297137;32582.6741732582;32839.77416431823;33037.17407064819;33077.503837442215;33121.33757324864;4071.307877660805;4136.482939632546;4398.010447649285;4431.6233120184825;4500.257457519506;364851;365982;367803.9999999973;368474;369283;66086.33913660802;66262.66868665625;66580;66670;66831.66584167047;40185;40894.182116358104;41148;70945.64527177434;71014.2898571018;16816.327346905986;16879.15604219784;17012.149392530326;17020.744688829676;17087.060211688477;12087.758244835055;12455.501779928702;12794.104412691022;12822.23066616006;12962.416691248916;19474.516119412023;20872.79127208739;20911.790882090983;21069.578608427746;21109.894450527958;7429.888551671729;7504.33596073397;7637.961810190914;7638.923610763822;7674.155842857282;949.9477528735848;1035.782485678001;1037.3516552154904;1038.901304376083;1179.8348231247633;33496.16259593481;33791.32417351639;33901.83049084788;33970.15074623154;34037.659623403946;1089.6785448292774;1095.2497539185683;1101.262154356583;1114.9386783726973;1148.3167515328366;16604.169791510376;17756.644867102586;17806.46580602584;17836.46490605284;17863.910680446515;548789;550578;551033;551756;551839;63616.68191659012;64256.67871660612;64277.67861160664;64346.67826660837;64375.67812160909;1270.7776139175792;1286.8584455709865;1337.458329376604;1352.661834541345;1369.2948131711978;28981.565276520447;32653.18367040843;33286;33979.830100849336;35915.64084359123;60612;61306.69346653238;61403;61521.077183841386;61521.38478615336;484116;484604;485705;487650;488822;38909.416358754075;40925.59074409218;43991.340129898716;44548.331775024046;48061.75969120132;48171.75914120547;49329.50670493249;49368.25947610717;49698;50835.491645084556;40883.77348679544;41435.7928210357;42403;42936.570634294505;43474;42536.57463425326;42828.78585607052;43022.35466468068;43075.784621076695;43082.56917430786;41752;42541;42940.78529607332;43067;43110.35334470048;35777;37475.81262093672;38333;38352.6164738349;38681.2263754729;28350.291242719097;28584.857075714488;28763.137105886857;28817.56773648439;28947.42105157843 12:20:58 INFO - PROCESS | 20018 | |2;jquery.html;413.2726401533304;416.36296466406407;430.7630803058318;436.76196472922254;437.57773748332875;18849.71725424118;19207.711884321747;20601.175952961883;20605.690914636292;20731.170753169892;19766.11052502637;19907.800921990813;20137.395878123643;20520;20703;5747.137929310606;5975.342712301665;5985.1022346647915;5986.551008674345;5987.191729116579;5746.138079288098;5849.093390524476;5860.765569377219;5870;5932.495737862266;5858.7949421770245;5862.120681897707;5862.443067908542;5865.413458654143;5873.853153671171;5693.77224911003;5798.942010579904;5839.240898683186;5857.414258574151;5896.616719913213;5294.232336311252;5298.284731561228;5301.204819277139;5304.761285742113;5306.204069389622;23780.286591402284;24096.51806963851;24097;24169.51660966771;24357.391065223335;2341.9180328688412;2635.71007189209;2805.2846524136216;3118.688131186886;3204.086835251965;14566.927165364212;14901.105933644016;15060;15062.246887655683;15283.617909552238;1979.7327360806396;2031.177373163871;2061.2579471390286;2061.938141855747;2099.191811152702;53786.46213537853;54160.18759718607;58417.833814069054;66201.33798662;80584.59707701424;11673.124515661359;11714.648560543026;11872.22830516031;11915.344656044;11994.160408771302;16789.580260493585;16926.74609880853;16943.15284235783;16958.152092395332;16959.660806783795;8153.510789352539;8420.621072051772;8496.447730897471;8667.696630617898;8827.602757875782;8015.398845086642;8092.231238032377;8164.7958801030445;8207.220314070153;8232.665770363012;4536.296873984537;5048.9649621828175;5050.141475949097;5071.670341427795;5095.108356037678;2071.78246284141;2097.727295451612;2111.6199084164996;2120.533482633818;2160;897.1521911793293;961.1157734883924;989.4508547756064;1005.3716427232979;1033.1631378583372;19792.70310945337;19957.700634490495;19998.50003749918;20259.493512662302;20357.592848142955;994.1798016636304;1003.5233264199447;1007.596961215505;1009.65671671632;1030.6907927621683;10622.521986510626;11090.112790976702;11139.944300278446;11157.665270041749;11293.153013523854;459485;461556;467746;476496;481961;67512.6624366875;96346.51826740822;97033;98150;99462.50268748755;980.2011360740973;986.9161121304769;1021.7854250607455;1021.8416145497459;1026.4200726589454;17442.389516366868;18022.459326220236;18182;18671.533211669273;18781.436556903314;44316.55683443124;44985.77507112444;45488;45625.77187114043;45787.77106114448;55645.72177139088;56622.43377566172;57649.71175144098;58195;60139;8490.320774337915;8591.87112193305;8603.612837422206;8682.175193356621;8819.955900220459;12789.552365667158;12857.807132892827;12878.162919410208;12891.346221019916;13218.140820846615;10490.790184196121;10694.83957740619;10843.566257349934;11170.106391488824;11346.149038821955;10421.218408619536;10813.351198928089;10971.506282217479;11028.889711103107;11491.310521368745;11187.888121118685;11206.38364889939;11367;11371.772564548828;11552.42237888119;8560.357973151915;8657.870131948152;8674.436161649473;8740.475571465733;8902.777430564287;8911.55442227873;10982;11005.339679619248;11285.37930413835;11501.597444089417 12:20:58 INFO - PROCESS | 20018 | |3;mootools.html;1531.816182058153;1541.1215607103948;1731.8268173182682;1789.973150402745;1856.4337876947534;8147.551884646342;8698.56507174641;8701.303895688345;8743.169398907094;8758.86861697075;7901.486403383776;8124.268815806577;8507.404481686284;8732.91267087329;8783.341249406305;2456.3122325748773;2493.7132229793533;2500.1374525788574;2500.8374455660414;2505.2985164153974;2447.79193768529;2545.7072436669837;2549.6940367155967;2553.706323772771;2555.591105423133;2325.1280769711357;2325.662778897059;2341.496578235679;2342.754010828864;2351.329870986769;2386.916457923982;2402.303332033708;2415.4323733922515;2418.322869596507;2418.5646583614944;2446.1193970170725;2454.1410506322823;2473.282748003077;2473.8392004519646;2476.3437689012426;8490.83018339636;8607.26838218746;8614.87077693835;8619.439736417113;8969.14793094655;5451.291332126798;5601.131824567158;5602.047651899187;5604.943950560483;5605.327360716699;3540.805255710935;3624.1845584743487;3630.2920930418577;3647.4528820676846;3663.21240933198;1096.2435919215795;1107.402082473098;1120.1542835159485;1125.2854437432209;1172.5602898912907;12707.745845083138;12724.936375318157;12734.490620375174;12765.872341276561;12783.808242876457;4544.931826022612;4772.904541909143;4779.617630589544;4844.975775121173;4853.296272040534;9834.164096051773;9840.655577054911;9844.163246124159;9859.26055545837;9872.703818885446;5391.137418013099;5398.91901621476;5402.027635025733;5403.621746477709;5407.837764866981;4522.819087236539;4633.2818413146015;4940.827071052496;5030.144875371196;5100.821471248562;4202.768847713405;4216.156768646221;4258.872233832928;4304.246756817544;4305.397244385788;1303.4069498378215;1317.4598414649947;1318.314476472229;1325.3505782166862;1341.9865801342057;987.8320685483485;995.5669283861544;1009.6213919780082;1010.2170817616259;1020.6580795433613;5732.3407808101765;5789.8552536186935;5854.677992710442;5894.734736936763;6197.442230199304;467.75676648143457;467.8292423265552;472.773068926917;473.80100357849784;482.88893554482667;4076.490438695163;4194.643455306333;4257.723247988871;4258.1483703258855;4329.610335069859;10816.837747433794;11071.833922491169;11143.554257829757;11158.107351411867;11199.104071674246;10940.617078402218;11017.228793984505;11025.117990560735;11077.11383089351;11082.445877706083;7947.721829735816;8194.795130121676;8277.089520152778;8279;8290.46112002718;5487.533559647474;5671.291088613923;5727.856803579777;5753.079507278815;5772.711364431678;9675.225981921289;9697.418154910727;9697.466639334903;9734.36726612768;9827.26295527824;9661.758456038655;10006.649767258112;10036.447995360033;10299.433531155559;10329.896701032894;7162.140543134858;7331.963340183265;7337.70649174027;8051.83896322082;8159.428839981144;7650.73222437212;8261.339092872677;8353.874691879506;8456.704015359433;8744.868826967473;7676.462647614613;7695.615219239127;7812.101608315116;7933;7999.040115186215;7730.265624765639;7765.805854853674;7775.727849525239;7825.686972521263;7844.764657060069;7117.466190035664;7134.821629459305;7174.426045916419;7203.6758345875605;7204.891926621;5301.549368303583;5326.254324394666;5409.323834520685;5410.1343784994215;5430.239766432782;4912.803487860446;5030.346055012971;5057.494250574913;5077.695338279715;5153.845384638467 12:20:58 INFO - PROCESS | 20018 | |4;prototype.html;349.49323480952626;359.96040435552095;364.82306081550445;372.71114885963357;374.24029220682024;5292.2800089970815;5388.568914486841;5433.48381903719;5519.613627046108;5533.257024037425;5272;5363.211130212297;5389.299391079154;5443.292371991645;5484.862878428041;4298.183345164424;4313.784310784448;4315.978420107895;4325.697201195918;4368.148211098837;4239.491261048678;4280.74315541067;4311.417958575599;4344.804483798237;4362.869113926571;4266.296061149907;4279.40088387626;4279.572042795727;4287.292596721537;4298.26929421999;4277.187334406468;4279.74321540707;4302.096559722462;4306.935395969063;4307.418498502693;3733.6826369758605;3890.2025084857537;3898.1229223424784;3904.570497245301;3908.4399718184636;5249.055170069386;5252.23842542833;5254.264402983586;5269.973650131763;5278.340207474066;4929.285253638238;4933.815884187805;4948.752562371903;4951.499492756135;4957;5495.780168793243;5680.914786278209;5703.572232082611;5704;5707.229524014247;4222.345536441856;4225.767582782945;4227.661787057028;4248.596383343577;4254.595813397722;5877.500412464946;5889.8822023559715;5896.1450589664255;5917.467427931464;5958.851028724273;4220.902565333035;4344.4352234209355;4390.297552391602;4392.253316936129;4392.824287028547;4342.370356298382;4355.237833379146;4363.603646832983;4365.738055716667;4389.824407023747;4906.6016185387;4909.435414927258;4928.211486162197;4978.6763860348965;4986.426560945465;5517.951589198039;5656.434356564393;5696.3164420269;5714.171445140431;5734.426557344233;1458.6070302860742;1462.56854228003;1472.1976522795103;1473.4400927647425;1476.7341878461766;1011.3376270825679;1029.8661174047327;1043.984340234897;1046.1264843855465;1053.6733612579972;5301.833596608817;5320.813771518056;5324.680519168863;5328.866778330495;5355.518003379714;5526.50261476469;5549.861253468695;5634.943650563524;5640.464155905264;5668.178114173422;5107.463716309734;5294.655847369909;5363.383210930715;5376.247325374451;5397.595180361489;2967.9258018549276;2978.9851050744605;2980.7913446059;2993.7754668399957;2998;5638.915416268759;5759.740811663485;5801.274840644919;5804.88390232193;5851.444112809277;5703.914441283384;5711.286089238845;5727.656340619576;5731.226284451629;5791.189233507313;5733.971330143406;5738.942610573841;5741.598088133874;5745.874026610098;5804.245448091806;5340.626156169031;5594.636348637326;5600.795828896807;5603.35561410443;5618.887622247614;5497.39528651856;5505.448516265631;5517.724113794214;5549.417311182267;5556.610847288178;5719.199312096393;5731.283589551306;5740.770369185186;5771.884562308815;5784.479396854304;4094.3244364679495;4220.683448741295;4240.957590424056;4279.16556271524;4286.914261714686;5677.460641239076;5693.231413759089;5707;5715.2855893013375;5731.226284451545;5232.686038837682;5473.452654734495;5482.643628164156;5571.192177134212;5597.328320601554;5415.512603865671;5418.322709661293;5439.755211015593;5447.319085114361;5455.372632147354;5424.213489044146;5440.401555828935;5442.782288708408;5444.428335024765;5459.553218397207;5254.894902102013;5450.991566560199;5485.50630443262;5486.643368181056;5545.445455454423;5337.386200586982;5338.519533242027;5374.704391258519;5384.838454846361;5391.4608539145775 12:20:58 INFO - PROCESS | 20018 | |5;yui.html;809.3323008517972;814.2956342763507;819.3772732723132;820.6266148902248;907.2515174980634;14871;15104.924475377622;15248.618784530365;15253.38986440541;15332.386704531818;14505.129692218448;14667.633309167277;14734.336954837032;14862.925685371558;14874.85125148751;740.4076738609091;853.2320911179941;868.8957325120995;870.0646804684957;871.0375035585681;757.3184134279138;836.6820608168917;855.5764896376303;858.4763294390417;864.2740098317412;904.8552231642939;906.4017748286133;907.3013779389865;907.5280853955938;908.1236606674557;887.6848718704864;895.7491902267376;896.5427631907709;896.5920506169678;902.41794042842;660.2308310817891;660.8810414125455;661.8643178148516;663.0154220981838;663.0286630086897;13196.604101876863;13247.40386682592;13254.138480998705;13277.933610331984;14928.178950157737;13212.481782427294;14004.22976736279;14081.2959352032;14367.78448323276;14593.051451655714;12040.81938770919;12171.391430428443;12216.144869859114;12509.124361294713;12517.56188533397;3839.0786211309087;3873.5351757789244;3903.1803321302277;3949.3878448840474;3957.920841583181;62423;63291.36708632901;63772.362276377105;64410.677946610434;64740.3525964739;11513;11561.364124973206;11574.768504629861;11768.764624707457;11945.343006134746;18661.533461663566;18741.250349985847;18805.341813036477;19251.32620358281;19736.11187496566;8587.22714955657;8673.739787806377;9004.324675649352;9246.121618446237;9250.583723732449;4857.489963553776;4966.751662416865;4984.651074424755;5020.698758074527;5133.358330208724;3271.6074071111143;3371.679690429405;3372.8482218300237;3373.274745929588;3382.255903701182;622.6450922973952;629.288903538998;636.3159603426335;636.678477368935;642.7396904253785;686.7321744519642;757.257887270478;761.3072104384983;772.8917951486796;798.4730078148394;9054.139856713733;9151.542422878963;9358.672446464341;9850.655227067018;10063.591097246392;676.2932735291632;765.689895592286;774.5933384972822;782.2568559868034;794.7099308752264;4168.749875007509;4362.105768317488;4363.410939523187;4394.384786129945;4524.276115821453;54370;70384.64807675929;87661.56169219113;90027.54986225026;92099.5395023034;50135.49864501308;62208.68895655493;63882.36117638857;77856.22143778604;80737;71100;71894.64052679703;71992.64003680053;73007;74525;7572.545647261182;8434.873476897974;8453.704120355758;8595.699150529701;8799.604017819085;48151.51848481611;48380.516194837604;48901.75549122231;49525.75237123791;49553.75223123861;40157.598424015385;43412;63547.364526355996;64874;66843;11483.540059672843;11654.941725291319;11862.644120676394;11930.701732456759;12209.328486932949;13588.418200541866;13669.658258543219;13721.794173087212;13795.10331828428;14449;12444.813327800272;12691.873081269068;12758;12760.808587871004;12888.162269452456;12420;12709.428075736429;12836.614901552968;13439.529616463376;14207.502737404555;12669.556565520163;12967.481300748243;13228.933855330662;13300.201987880759;13562.59312220635;11342.60300889465;11498.25261358009;11529.135314851588;11811.173217874666;12060.336681482251;11112.611058612909;11574.71063223426;11834.230774999598;11953.462094205956;12227.14409991292 12:20:58 INFO - PROCESS | 20018 | __end_tp_report 12:20:58 INFO - PROCESS | 20018 | __start_cc_report 12:20:58 INFO - PROCESS | 20018 | _x_x_mozilla_cycle_collect,1064 12:20:58 INFO - PROCESS | 20018 | __end_cc_report 12:20:58 INFO - PROCESS | 20018 | __startTimestamp1486066858110__endTimestamp 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | ------- Summary: start ------- 12:20:58 INFO - PROCESS | 20018 | Number of tests: 6 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#0] dojo.html Cycles:165 Average:5628.40 Median:5879.79 stddev:664.41 (11.3%) stddev-sans-first:638.71 12:20:58 INFO - PROCESS | 20018 | Values: 3206.7 3260.8 3265.5 3292.1 3311.8 5668.2 5764.0 5767.1 5773.2 5775.5 5731.7 5774.3 5780.0 5786.1 5794.1 5192.8 5204.0 5231.9 5252.7 5291.1 5026.7 5052.6 5055.4 5291.9 5307.0 5089.6 5169.3 5175.3 5191.5 5194.2 5174.8 5184.6 5186.9 5194.9 5218.6 4994.2 5085.1 5098.6 5109.1 5167.1 5507.6 5513.6 5570.8 5626.0 5659.2 5952.1 6018.2 6061.1 6061.2 6064.4 5959.4 6096.3 6097.1 6117.4 6130.3 5605.4 5619.1 5661.7 5681.3 5698.8 6224.1 6225.0 6226.3 6228.9 6231.9 5824.2 5964.7 5970.5 5982.4 5985.0 5974.2 6047.0 6116.2 6134.3 6139.1 6026.5 6089.9 6092.2 6093.3 6108.1 6085.8 6087.1 6088.9 6104.0 6124.3 4382.6 4407.8 4441.0 4443.8 4450.8 3971.6 3987.1 3992.8 4013.9 4021.2 5733.2 5738.5 5740.3 5742.8 5782.9 5978.9 5996.3 5999.1 6011.9 6040.1 5795.1 5823.5 5827.4 5833.0 5854.4 5160.1 5188.1 5197.8 5199.2 5200.6 6094.4 6154.8 6187.7 6339.2 6355.1 6046.9 6096.4 6105.7 6116.6 6136.4 6244.4 6271.7 6290.0 6312.7 6322.5 5808.3 5825.1 5974.6 5991.8 5993.6 5995.4 6004.7 6019.3 6104.3 6124.5 5921.5 5939.6 5940.4 5940.9 5947.0 5670.0 5905.7 5908.3 5915.4 5925.3 5808.6 5879.8 5933.0 5950.3 6037.8 6025.2 6055.7 6057.7 6061.0 6080.1 5596.3 5632.2 5894.1 5908.9 5912.7 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#1] ext.html Cycles:180 Average:64256.26 Median:4078.16 stddev:125610.52 (3080.1%) stddev-sans-first:125871.92 12:20:58 INFO - PROCESS | 20018 | Values: 583.5 602.1 639.5 642.3 661.9 34365.1 45835.0 46782.3 46867.3 49818.8 47690.3 48378.0 48385.8 48405.5 48469.5 8519.7 8984.1 9130.0 9130.5 9147.5 8480.5 10769.2 11000.8 11015.6 11357.4 11009.3 11079.8 11080.0 11092.9 11096.9 10839.5 10919.7 10920.9 10925.4 10937.2 10578.5 10618.3 10633.3 10634.4 10635.6 44261.6 44722.3 44869.0 44912.7 44919.0 3608.9 3617.4 3741.5 3825.5 4085.0 32582.7 32839.8 33037.2 33077.5 33121.3 4071.3 4136.5 4398.0 4431.6 4500.3 364851.0 365982.0 367804.0 368474.0 369283.0 66086.3 66262.7 66580.0 66670.0 66831.7 40185.0 40894.2 41148.0 70945.6 71014.3 16816.3 16879.2 17012.1 17020.7 17087.1 12087.8 12455.5 12794.1 12822.2 12962.4 19474.5 20872.8 20911.8 21069.6 21109.9 7429.9 7504.3 7638.0 7638.9 7674.2 949.9 1035.8 1037.4 1038.9 1179.8 33496.2 33791.3 33901.8 33970.2 34037.7 1089.7 1095.2 1101.3 1114.9 1148.3 16604.2 17756.6 17806.5 17836.5 17863.9 548789.0 550578.0 551033.0 551756.0 551839.0 63616.7 64256.7 64277.7 64346.7 64375.7 1270.8 1286.9 1337.5 1352.7 1369.3 28981.6 32653.2 33286.0 33979.8 35915.6 60612.0 61306.7 61403.0 61521.1 61521.4 484116.0 484604.0 485705.0 487650.0 488822.0 38909.4 40925.6 43991.3 44548.3 48061.8 48171.8 49329.5 49368.3 49698.0 50835.5 40883.8 41435.8 42403.0 42936.6 43474.0 42536.6 42828.8 43022.4 43075.8 43082.6 41752.0 42541.0 42940.8 43067.0 43110.4 35777.0 37475.8 38333.0 38352.6 38681.2 28350.3 28584.9 28763.1 28817.6 28947.4 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#2] jquery.html Cycles:180 Average:28306.75 Median:2961.99 stddev:77245.06 (2607.9%) stddev-sans-first:77433.36 12:20:58 INFO - PROCESS | 20018 | Values: 413.3 416.4 430.8 436.8 437.6 18849.7 19207.7 20601.2 20605.7 20731.2 19766.1 19907.8 20137.4 20520.0 20703.0 5747.1 5975.3 5985.1 5986.6 5987.2 5746.1 5849.1 5860.8 5870.0 5932.5 5858.8 5862.1 5862.4 5865.4 5873.9 5693.8 5798.9 5839.2 5857.4 5896.6 5294.2 5298.3 5301.2 5304.8 5306.2 23780.3 24096.5 24097.0 24169.5 24357.4 2341.9 2635.7 2805.3 3118.7 3204.1 14566.9 14901.1 15060.0 15062.2 15283.6 1979.7 2031.2 2061.3 2061.9 2099.2 53786.5 54160.2 58417.8 66201.3 80584.6 11673.1 11714.6 11872.2 11915.3 11994.2 16789.6 16926.7 16943.2 16958.2 16959.7 8153.5 8420.6 8496.4 8667.7 8827.6 8015.4 8092.2 8164.8 8207.2 8232.7 4536.3 5049.0 5050.1 5071.7 5095.1 2071.8 2097.7 2111.6 2120.5 2160.0 897.2 961.1 989.5 1005.4 1033.2 19792.7 19957.7 19998.5 20259.5 20357.6 994.2 1003.5 1007.6 1009.7 1030.7 10622.5 11090.1 11139.9 11157.7 11293.2 459485.0 461556.0 467746.0 476496.0 481961.0 67512.7 96346.5 97033.0 98150.0 99462.5 980.2 986.9 1021.8 1021.8 1026.4 17442.4 18022.5 18182.0 18671.5 18781.4 44316.6 44985.8 45488.0 45625.8 45787.8 55645.7 56622.4 57649.7 58195.0 60139.0 8490.3 8591.9 8603.6 8682.2 8820.0 12789.6 12857.8 12878.2 12891.3 13218.1 10490.8 10694.8 10843.6 11170.1 11346.1 10421.2 10813.4 10971.5 11028.9 11491.3 11187.9 11206.4 11367.0 11371.8 11552.4 8560.4 8657.9 8674.4 8740.5 8902.8 8911.6 10982.0 11005.3 11285.4 11501.6 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#3] mootools.html Cycles:180 Average:5821.56 Median:4985.49 stddev:3263.32 (65.5%) stddev-sans-first:3256.56 12:20:58 INFO - PROCESS | 20018 | Values: 1531.8 1541.1 1731.8 1790.0 1856.4 8147.6 8698.6 8701.3 8743.2 8758.9 7901.5 8124.3 8507.4 8732.9 8783.3 2456.3 2493.7 2500.1 2500.8 2505.3 2447.8 2545.7 2549.7 2553.7 2555.6 2325.1 2325.7 2341.5 2342.8 2351.3 2386.9 2402.3 2415.4 2418.3 2418.6 2446.1 2454.1 2473.3 2473.8 2476.3 8490.8 8607.3 8614.9 8619.4 8969.1 5451.3 5601.1 5602.0 5604.9 5605.3 3540.8 3624.2 3630.3 3647.5 3663.2 1096.2 1107.4 1120.2 1125.3 1172.6 12707.7 12724.9 12734.5 12765.9 12783.8 4544.9 4772.9 4779.6 4845.0 4853.3 9834.2 9840.7 9844.2 9859.3 9872.7 5391.1 5398.9 5402.0 5403.6 5407.8 4522.8 4633.3 4940.8 5030.1 5100.8 4202.8 4216.2 4258.9 4304.2 4305.4 1303.4 1317.5 1318.3 1325.4 1342.0 987.8 995.6 1009.6 1010.2 1020.7 5732.3 5789.9 5854.7 5894.7 6197.4 467.8 467.8 472.8 473.8 482.9 4076.5 4194.6 4257.7 4258.1 4329.6 10816.8 11071.8 11143.6 11158.1 11199.1 10940.6 11017.2 11025.1 11077.1 11082.4 7947.7 8194.8 8277.1 8279.0 8290.5 5487.5 5671.3 5727.9 5753.1 5772.7 9675.2 9697.4 9697.5 9734.4 9827.3 9661.8 10006.6 10036.4 10299.4 10329.9 7162.1 7332.0 7337.7 8051.8 8159.4 7650.7 8261.3 8353.9 8456.7 8744.9 7676.5 7695.6 7812.1 7933.0 7999.0 7730.3 7765.8 7775.7 7825.7 7844.8 7117.5 7134.8 7174.4 7203.7 7204.9 5301.5 5326.3 5409.3 5410.1 5430.2 4912.8 5030.3 5057.5 5077.7 5153.8 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#4] prototype.html Cycles:180 Average:4735.79 Median:5337.95 stddev:1332.11 (25.0%) stddev-sans-first:1294.53 12:20:58 INFO - PROCESS | 20018 | Values: 349.5 360.0 364.8 372.7 374.2 5292.3 5388.6 5433.5 5519.6 5533.3 5272.0 5363.2 5389.3 5443.3 5484.9 4298.2 4313.8 4316.0 4325.7 4368.1 4239.5 4280.7 4311.4 4344.8 4362.9 4266.3 4279.4 4279.6 4287.3 4298.3 4277.2 4279.7 4302.1 4306.9 4307.4 3733.7 3890.2 3898.1 3904.6 3908.4 5249.1 5252.2 5254.3 5270.0 5278.3 4929.3 4933.8 4948.8 4951.5 4957.0 5495.8 5680.9 5703.6 5704.0 5707.2 4222.3 4225.8 4227.7 4248.6 4254.6 5877.5 5889.9 5896.1 5917.5 5958.9 4220.9 4344.4 4390.3 4392.3 4392.8 4342.4 4355.2 4363.6 4365.7 4389.8 4906.6 4909.4 4928.2 4978.7 4986.4 5518.0 5656.4 5696.3 5714.2 5734.4 1458.6 1462.6 1472.2 1473.4 1476.7 1011.3 1029.9 1044.0 1046.1 1053.7 5301.8 5320.8 5324.7 5328.9 5355.5 5526.5 5549.9 5634.9 5640.5 5668.2 5107.5 5294.7 5363.4 5376.2 5397.6 2967.9 2979.0 2980.8 2993.8 2998.0 5638.9 5759.7 5801.3 5804.9 5851.4 5703.9 5711.3 5727.7 5731.2 5791.2 5734.0 5738.9 5741.6 5745.9 5804.2 5340.6 5594.6 5600.8 5603.4 5618.9 5497.4 5505.4 5517.7 5549.4 5556.6 5719.2 5731.3 5740.8 5771.9 5784.5 4094.3 4220.7 4241.0 4279.2 4286.9 5677.5 5693.2 5707.0 5715.3 5731.2 5232.7 5473.5 5482.6 5571.2 5597.3 5415.5 5418.3 5439.8 5447.3 5455.4 5424.2 5440.4 5442.8 5444.4 5459.6 5254.9 5451.0 5485.5 5486.6 5545.4 5337.4 5338.5 5374.7 5384.8 5391.5 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - PROCESS | 20018 | [#5] yui.html Cycles:180 Average:17385.94 Median:26879.62 stddev:22439.76 (83.5%) stddev-sans-first:22468.18 12:20:58 INFO - PROCESS | 20018 | Values: 809.3 814.3 819.4 820.6 907.3 14871.0 15104.9 15248.6 15253.4 15332.4 14505.1 14667.6 14734.3 14862.9 14874.9 740.4 853.2 868.9 870.1 871.0 757.3 836.7 855.6 858.5 864.3 904.9 906.4 907.3 907.5 908.1 887.7 895.7 896.5 896.6 902.4 660.2 660.9 661.9 663.0 663.0 13196.6 13247.4 13254.1 13277.9 14928.2 13212.5 14004.2 14081.3 14367.8 14593.1 12040.8 12171.4 12216.1 12509.1 12517.6 3839.1 3873.5 3903.2 3949.4 3957.9 62423.0 63291.4 63772.4 64410.7 64740.4 11513.0 11561.4 11574.8 11768.8 11945.3 18661.5 18741.3 18805.3 19251.3 19736.1 8587.2 8673.7 9004.3 9246.1 9250.6 4857.5 4966.8 4984.7 5020.7 5133.4 3271.6 3371.7 3372.8 3373.3 3382.3 622.6 629.3 636.3 636.7 642.7 686.7 757.3 761.3 772.9 798.5 9054.1 9151.5 9358.7 9850.7 10063.6 676.3 765.7 774.6 782.3 794.7 4168.7 4362.1 4363.4 4394.4 4524.3 54370.0 70384.6 87661.6 90027.5 92099.5 50135.5 62208.7 63882.4 77856.2 80737.0 71100.0 71894.6 71992.6 73007.0 74525.0 7572.5 8434.9 8453.7 8595.7 8799.6 48151.5 48380.5 48901.8 49525.8 49553.8 40157.6 43412.0 63547.4 64874.0 66843.0 11483.5 11654.9 11862.6 11930.7 12209.3 13588.4 13669.7 13721.8 13795.1 14449.0 12444.8 12691.9 12758.0 12760.8 12888.2 12420.0 12709.4 12836.6 13439.5 14207.5 12669.6 12967.5 13228.9 13300.2 13562.6 11342.6 11498.3 11529.1 11811.2 12060.3 11112.6 11574.7 11834.2 11953.5 12227.1 12:20:58 INFO - PROCESS | 20018 | -------- Summary: end -------- 12:20:58 INFO - PROCESS | 20018 | 12:20:58 INFO - TEST-INFO | 20018: exit 0 12:20:58 INFO - TEST-OK | dromaeo_css | took 1113692ms 12:20:58 INFO - TEST-START | kraken 12:20:58 INFO - operating with platform_type : linux_ 12:20:58 INFO - Initialising browser for kraken test... 12:20:58 INFO - TEST-INFO | started process 20322 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6r7c4u/profile http://localhost:37006/getInfo.html) 12:21:02 INFO - PROCESS | 20322 | __metrics Screen width/height:1600/1200 12:21:02 INFO - PROCESS | 20322 | colorDepth:24 12:21:02 INFO - PROCESS | 20322 | Browser inner width/height: 1024/697 12:21:02 INFO - PROCESS | 20322 | __metrics 12:21:03 INFO - PROCESS | 20322 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:21:03 INFO - PROCESS | 20322 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:21:03 INFO - PROCESS | 20322 | console.error: PushService: 12:21:03 INFO - PROCESS | 20322 | stateChangeProcessEnqueue: Error transitioning state 12:21:03 INFO - PROCESS | 20322 | UnknownError 12:21:04 INFO - TEST-INFO | 20322: exit 0 12:21:04 INFO - Browser initialized. 12:21:04 INFO - Running cycle 1/1 for kraken test... 12:21:04 INFO - TEST-INFO | started process 20414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6r7c4u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:15 INFO - PROCESS | 20414 | RSS: Main: 141959168 12:21:15 INFO - PROCESS | 20414 | 12:21:18 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37006/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:19 INFO - PROCESS | 20414 | RSS: Main: 183488512 12:21:19 INFO - PROCESS | 20414 | 12:21:21 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37006/tests/kraken/driver.html?testName=audio-dft) 12:21:22 INFO - PROCESS | 20414 | RSS: Main: 173322240 12:21:22 INFO - PROCESS | 20414 | 12:21:25 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37006/tests/kraken/driver.html?testName=audio-fft) 12:21:25 INFO - PROCESS | 20414 | RSS: Main: 264699904 12:21:25 INFO - PROCESS | 20414 | 12:21:27 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37006/tests/kraken/driver.html?testName=audio-oscillator) 12:21:28 INFO - PROCESS | 20414 | RSS: Main: 171098112 12:21:28 INFO - PROCESS | 20414 | 12:21:30 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37006/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:31 INFO - PROCESS | 20414 | RSS: Main: 174395392 12:21:31 INFO - PROCESS | 20414 | 12:21:34 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37006/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:34 INFO - PROCESS | 20414 | RSS: Main: 212045824 12:21:34 INFO - PROCESS | 20414 | 12:21:38 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37006/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:38 INFO - PROCESS | 20414 | RSS: Main: 210325504 12:21:38 INFO - PROCESS | 20414 | 12:21:41 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37006/tests/kraken/driver.html?testName=json-parse-financial) 12:21:41 INFO - PROCESS | 20414 | RSS: Main: 210014208 12:21:41 INFO - PROCESS | 20414 | 12:21:43 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37006/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:43 INFO - PROCESS | 20414 | RSS: Main: 171921408 12:21:43 INFO - PROCESS | 20414 | 12:21:45 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:46 INFO - PROCESS | 20414 | RSS: Main: 179146752 12:21:46 INFO - PROCESS | 20414 | 12:21:48 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:48 INFO - PROCESS | 20414 | RSS: Main: 189124608 12:21:48 INFO - PROCESS | 20414 | 12:21:51 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:52 INFO - PROCESS | 20414 | RSS: Main: 196292608 12:21:52 INFO - PROCESS | 20414 | 12:21:54 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:55 INFO - PROCESS | 20414 | RSS: Main: 192233472 12:21:55 INFO - PROCESS | 20414 | 12:21:56 INFO - PROCESS | 20414 | Cycle 1(1): loaded http://localhost:37006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37006/tests/kraken/driver.html?testName=ai-astar) 12:21:57 INFO - PROCESS | 20414 | RSS: Main: 244752384 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | __start_tp_report 12:21:57 INFO - PROCESS | 20414 | _x_x_mozilla_page_load 12:21:57 INFO - PROCESS | 20414 | _x_x_mozilla_page_load_details 12:21:57 INFO - PROCESS | 20414 | |i|pagename|runs| 12:21:57 INFO - PROCESS | 20414 | |0;ai-astar;99;111;110;110;111;111;112;110;110;110 12:21:57 INFO - PROCESS | 20414 | |1;audio-beat-detection;144;117;119;158;120;118;119;116;159;117 12:21:57 INFO - PROCESS | 20414 | |2;audio-dft;161;159;160;162;162;161;160;160;161;160 12:21:57 INFO - PROCESS | 20414 | |3;audio-fft;74;74;76;102;76;76;76;75;97;74 12:21:57 INFO - PROCESS | 20414 | |4;audio-oscillator;82;82;120;82;84;82;108;82;82;118 12:21:57 INFO - PROCESS | 20414 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;116 12:21:57 INFO - PROCESS | 20414 | |6;imaging-darkroom;146;146;146;146;145;146;146;145;145;146 12:21:57 INFO - PROCESS | 20414 | |7;imaging-desaturate;85;84;85;85;84;84;84;84;85;84 12:21:57 INFO - PROCESS | 20414 | |8;json-parse-financial;52;51;51;50;51;51;51;51;52;51 12:21:57 INFO - PROCESS | 20414 | |9;json-stringify-tinderbox;59;59;58;58;59;59;59;59;60;59 12:21:57 INFO - PROCESS | 20414 | |10;stanford-crypto-aes;77;80;74;74;85;77;76;81;77;75 12:21:57 INFO - PROCESS | 20414 | |11;stanford-crypto-ccm;123;121;120;123;136;130;135;119;173;119 12:21:57 INFO - PROCESS | 20414 | |12;stanford-crypto-pbkdf2;151;154;156;151;153;155;152;151;156;152 12:21:57 INFO - PROCESS | 20414 | |13;stanford-crypto-sha256-iterative;62;62;62;62;61;61;66;60;60;60 12:21:57 INFO - PROCESS | 20414 | __end_tp_report 12:21:57 INFO - PROCESS | 20414 | __start_cc_report 12:21:57 INFO - PROCESS | 20414 | _x_x_mozilla_cycle_collect,1579 12:21:57 INFO - PROCESS | 20414 | __end_cc_report 12:21:57 INFO - PROCESS | 20414 | __startTimestamp1486066917123__endTimestamp 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | ------- Summary: start ------- 12:21:57 INFO - PROCESS | 20414 | Number of tests: 14 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:3.72 (3.3%) stddev-sans-first:0.73 12:21:57 INFO - PROCESS | 20414 | Values: 99.0 111.0 110.0 110.0 111.0 111.0 112.0 110.0 110.0 110.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:119.50 stddev:17.71 (14.8%) stddev-sans-first:17.90 12:21:57 INFO - PROCESS | 20414 | Values: 144.0 117.0 119.0 158.0 120.0 118.0 119.0 116.0 159.0 117.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 12:21:57 INFO - PROCESS | 20414 | Values: 161.0 159.0 160.0 162.0 162.0 161.0 160.0 160.0 161.0 160.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#3] audio-fft Cycles:10 Average:80.00 Median:76.00 stddev:10.38 (13.7%) stddev-sans-first:10.78 12:21:57 INFO - PROCESS | 20414 | Values: 74.0 74.0 76.0 102.0 76.0 76.0 76.0 75.0 97.0 74.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:82.00 stddev:16.26 (19.8%) stddev-sans-first:16.82 12:21:57 INFO - PROCESS | 20414 | Values: 82.0 82.0 120.0 82.0 84.0 82.0 108.0 82.0 82.0 118.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:21:57 INFO - PROCESS | 20414 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:21:57 INFO - PROCESS | 20414 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.50 12:21:57 INFO - PROCESS | 20414 | Values: 85.0 84.0 85.0 85.0 84.0 84.0 84.0 84.0 85.0 84.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 12:21:57 INFO - PROCESS | 20414 | Values: 52.0 51.0 51.0 50.0 51.0 51.0 51.0 51.0 52.0 51.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 12:21:57 INFO - PROCESS | 20414 | Values: 59.0 59.0 58.0 58.0 59.0 59.0 59.0 59.0 60.0 59.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:77.00 stddev:3.47 (4.5%) stddev-sans-first:3.67 12:21:57 INFO - PROCESS | 20414 | Values: 77.0 80.0 74.0 74.0 85.0 77.0 76.0 81.0 77.0 75.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:126.50 stddev:16.43 (13.0%) stddev-sans-first:17.24 12:21:57 INFO - PROCESS | 20414 | Values: 123.0 121.0 120.0 123.0 136.0 130.0 135.0 119.0 173.0 119.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.10 Median:153.50 stddev:2.02 (1.3%) stddev-sans-first:2.00 12:21:57 INFO - PROCESS | 20414 | Values: 151.0 154.0 156.0 151.0 153.0 155.0 152.0 151.0 156.0 152.0 12:21:57 INFO - PROCESS | 20414 | 12:21:57 INFO - PROCESS | 20414 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.78 (2.9%) stddev-sans-first:1.88 12:21:57 INFO - PROCESS | 20414 | Values: 62.0 62.0 62.0 62.0 61.0 61.0 66.0 60.0 60.0 60.0 12:21:57 INFO - PROCESS | 20414 | -------- Summary: end -------- 12:21:57 INFO - PROCESS | 20414 | 12:21:58 INFO - TEST-INFO | 20414: exit 0 12:21:58 INFO - TEST-OK | kraken | took 59346ms 12:21:58 INFO - SUITE-END | took 1173s 12:21:58 INFO - Completed test suite (00:19:33) 12:21:58 INFO - javascript benchmark 12:21:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3206.743460523158, 3260.836958152092, 3265.4775235962243, 3292.127586189659, 3311.801291922485, 5668.206451096845, 5764.048931926227, 5767.134929760533, 5773.191753154561, 5775.451332123447, 5731.742071606777, 5774.307083150017, 5779.971100144493, 5786.132080187974, 5794.101914203306, 5192.818251361213, 5204.0, 5231.86920326993, 5252.684838909678, 5291.126964050927, 5026.748662566875, 5052.570531504808, 5055.39335279767, 5291.867703307429, 5307.018201632691, 5089.592832573404, 5169.276301317818, 5175.301334319875, 5191.506806853343, 5194.194899790538, 5174.818881339135, 5184.585233181337, 5186.896262074737, 5194.948050519484, 5218.60860435465, 4994.200927851526, 5085.110105731519, 5098.566621837148, 5109.054824857413, 5167.147420675622, 5507.586930980193, 5513.61404701671, 5570.832875013756, 5626.0, 5659.207710920475, 5952.136940143699, 6018.217631707894, 6061.090836374579, 6061.242344706911, 6064.3935606439, 5959.40405959405, 6096.268447786294, 6097.146399504073, 6117.357677443849, 6130.264368275791, 5605.411431799643, 5619.129034999541, 5661.66030038199, 5681.346645135863, 5698.8290351289525, 6224.06639004153, 6225.035119556476, 6226.25284965807, 6228.906566401508, 6231.937680623227, 5824.184614154022, 5964.701764911737, 5970.492508136857, 5982.40175982407, 5984.970075149684, 5974.2233509643465, 6047.0, 6116.244470650255, 6134.325224225332, 6139.140520327158, 6026.54800889935, 6089.908651370233, 6092.208012958376, 6093.329733729286, 6108.144859719643, 6085.786997455068, 6087.1173679816175, 6088.908666370008, 6104.0, 6124.265088189446, 4382.561743825592, 4407.823687052482, 4441.045175287264, 4443.822247110144, 4450.844220452269, 3971.5631280559114, 3987.1029018470613, 3992.7604343739467, 4013.8795836124964, 4021.195760847842, 5733.197352370671, 5738.483536481736, 5740.282464691913, 5742.770289188469, 5782.88434231313, 5978.85052873673, 5996.280446346466, 5999.130126131687, 6011.909821352683, 6040.124181993674, 5795.1017592273265, 5823.475887170175, 5827.446392592697, 5832.970835145882, 5854.385289544622, 5160.122779127482, 5188.143956247252, 5197.818076367309, 5199.168133098761, 5200.609954253446, 6094.421030002142, 6154.815355539341, 6187.690615469299, 6339.175907132043, 6355.142055822497, 6046.909296360646, 6096.390360963868, 6105.725242364193, 6116.602420842631, 6136.386361363828, 6244.406781355764, 6271.749130034747, 6289.96855015722, 6312.684365781784, 6322.462590679844, 5808.30300363959, 5825.097109947965, 5974.61165024272, 5991.820245392645, 5993.580449368503, 5995.430434108753, 6004.669743164168, 6019.337872834072, 6104.298005729221, 6124.510039196943, 5921.496672782861, 5939.554533409925, 5940.435658612425, 5940.910886336795, 5946.970265148647, 5669.971650141723, 5905.734241959036, 5908.291005079418, 5915.438033386821, 5925.259342582177, 5808.593398462067, 5879.794207202727, 5932.9703351484695, 5950.3454619992635, 6037.758489660365, 6025.18659980897, 6055.697215139314, 6057.697115144137, 6061.0, 6080.118382834553, 5596.300462442195, 5632.239647647515, 5894.145348924296, 5908.911366329423, 5912.733926973383], "value": 5581.549949749646, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.4807021750643, 602.0607851751267, 639.4532674563245, 642.3223499208336, 661.9172603424572, 34365.140871478194, 45835.0, 46782.29826552604, 46867.296990545125, 49818.75090624551, 47690.28464573034, 48378.0, 48385.75807120968, 48405.51594484063, 48469.515304846944, 8519.659213631478, 8984.101589841013, 9129.95435022824, 9130.497822619753, 9147.496887671176, 8480.533570653612, 10769.192310576738, 11000.834987475195, 11015.614453494129, 11357.375344356056, 11009.339439633648, 11079.833802492969, 11080.0, 11092.889071109266, 11096.889031109666, 10839.458027098653, 10919.727006824813, 10920.945395272973, 10925.399103049329, 10937.179711521667, 10578.52396642153, 10618.309809862334, 10633.255672102956, 10634.415107169103, 10635.57457701691, 44261.557384426065, 44722.32916506255, 44869.0, 44912.65262042144, 44919.0, 3608.8736894208578, 3617.3850445424077, 3741.494898188737, 3825.5409350877794, 4085.019595297137, 32582.6741732582, 32839.77416431823, 33037.17407064819, 33077.503837442215, 33121.33757324864, 4071.307877660805, 4136.482939632546, 4398.010447649285, 4431.6233120184825, 4500.257457519506, 364851.0, 365982.0, 367803.9999999973, 368474.0, 369283.0, 66086.33913660802, 66262.66868665625, 66580.0, 66670.0, 66831.66584167047, 40185.0, 40894.182116358104, 41148.0, 70945.64527177434, 71014.2898571018, 16816.327346905986, 16879.15604219784, 17012.149392530326, 17020.744688829676, 17087.060211688477, 12087.758244835055, 12455.501779928702, 12794.104412691022, 12822.23066616006, 12962.416691248916, 19474.516119412023, 20872.79127208739, 20911.790882090983, 21069.578608427746, 21109.894450527958, 7429.888551671729, 7504.33596073397, 7637.961810190914, 7638.923610763822, 7674.155842857282, 949.9477528735848, 1035.782485678001, 1037.3516552154904, 1038.901304376083, 1179.8348231247633, 33496.16259593481, 33791.32417351639, 33901.83049084788, 33970.15074623154, 34037.659623403946, 1089.6785448292774, 1095.2497539185683, 1101.262154356583, 1114.9386783726973, 1148.3167515328366, 16604.169791510376, 17756.644867102586, 17806.46580602584, 17836.46490605284, 17863.910680446515, 548789.0, 550578.0, 551033.0, 551756.0, 551839.0, 63616.68191659012, 64256.67871660612, 64277.67861160664, 64346.67826660837, 64375.67812160909, 1270.7776139175792, 1286.8584455709865, 1337.458329376604, 1352.661834541345, 1369.2948131711978, 28981.565276520447, 32653.18367040843, 33286.0, 33979.830100849336, 35915.64084359123, 60612.0, 61306.69346653238, 61403.0, 61521.077183841386, 61521.38478615336, 484116.0, 484604.0, 485705.0, 487650.0, 488822.0, 38909.416358754075, 40925.59074409218, 43991.340129898716, 44548.331775024046, 48061.75969120132, 48171.75914120547, 49329.50670493249, 49368.25947610717, 49698.0, 50835.491645084556, 40883.77348679544, 41435.7928210357, 42403.0, 42936.570634294505, 43474.0, 42536.57463425326, 42828.78585607052, 43022.35466468068, 43075.784621076695, 43082.56917430786, 41752.0, 42541.0, 42940.78529607332, 43067.0, 43110.35334470048, 35777.0, 37475.81262093672, 38333.0, 38352.6164738349, 38681.2263754729, 28350.291242719097, 28584.857075714488, 28763.137105886857, 28817.56773648439, 28947.42105157843], "value": 21528.09491572737, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.2726401533304, 416.36296466406407, 430.7630803058318, 436.76196472922254, 437.57773748332875, 18849.71725424118, 19207.711884321747, 20601.175952961883, 20605.690914636292, 20731.170753169892, 19766.11052502637, 19907.800921990813, 20137.395878123643, 20520.0, 20703.0, 5747.137929310606, 5975.342712301665, 5985.1022346647915, 5986.551008674345, 5987.191729116579, 5746.138079288098, 5849.093390524476, 5860.765569377219, 5870.0, 5932.495737862266, 5858.7949421770245, 5862.120681897707, 5862.443067908542, 5865.413458654143, 5873.853153671171, 5693.77224911003, 5798.942010579904, 5839.240898683186, 5857.414258574151, 5896.616719913213, 5294.232336311252, 5298.284731561228, 5301.204819277139, 5304.761285742113, 5306.204069389622, 23780.286591402284, 24096.51806963851, 24097.0, 24169.51660966771, 24357.391065223335, 2341.9180328688412, 2635.71007189209, 2805.2846524136216, 3118.688131186886, 3204.086835251965, 14566.927165364212, 14901.105933644016, 15060.0, 15062.246887655683, 15283.617909552238, 1979.7327360806396, 2031.177373163871, 2061.2579471390286, 2061.938141855747, 2099.191811152702, 53786.46213537853, 54160.18759718607, 58417.833814069054, 66201.33798662, 80584.59707701424, 11673.124515661359, 11714.648560543026, 11872.22830516031, 11915.344656044, 11994.160408771302, 16789.580260493585, 16926.74609880853, 16943.15284235783, 16958.152092395332, 16959.660806783795, 8153.510789352539, 8420.621072051772, 8496.447730897471, 8667.696630617898, 8827.602757875782, 8015.398845086642, 8092.231238032377, 8164.7958801030445, 8207.220314070153, 8232.665770363012, 4536.296873984537, 5048.9649621828175, 5050.141475949097, 5071.670341427795, 5095.108356037678, 2071.78246284141, 2097.727295451612, 2111.6199084164996, 2120.533482633818, 2160.0, 897.1521911793293, 961.1157734883924, 989.4508547756064, 1005.3716427232979, 1033.1631378583372, 19792.70310945337, 19957.700634490495, 19998.50003749918, 20259.493512662302, 20357.592848142955, 994.1798016636304, 1003.5233264199447, 1007.596961215505, 1009.65671671632, 1030.6907927621683, 10622.521986510626, 11090.112790976702, 11139.944300278446, 11157.665270041749, 11293.153013523854, 459485.0, 461556.0, 467746.0, 476496.0, 481961.0, 67512.6624366875, 96346.51826740822, 97033.0, 98150.0, 99462.50268748755, 980.2011360740973, 986.9161121304769, 1021.7854250607455, 1021.8416145497459, 1026.4200726589454, 17442.389516366868, 18022.459326220236, 18182.0, 18671.533211669273, 18781.436556903314, 44316.55683443124, 44985.77507112444, 45488.0, 45625.77187114043, 45787.77106114448, 55645.72177139088, 56622.43377566172, 57649.71175144098, 58195.0, 60139.0, 8490.320774337915, 8591.87112193305, 8603.612837422206, 8682.175193356621, 8819.955900220459, 12789.552365667158, 12857.807132892827, 12878.162919410208, 12891.346221019916, 13218.140820846615, 10490.790184196121, 10694.83957740619, 10843.566257349934, 11170.106391488824, 11346.149038821955, 10421.218408619536, 10813.351198928089, 10971.506282217479, 11028.889711103107, 11491.310521368745, 11187.888121118685, 11206.38364889939, 11367.0, 11371.772564548828, 11552.42237888119, 8560.357973151915, 8657.870131948152, 8674.436161649473, 8740.475571465733, 8902.777430564287, 8911.55442227873, 10982.0, 11005.339679619248, 11285.37930413835, 11501.597444089417], "value": 9452.755690876475, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1531.816182058153, 1541.1215607103948, 1731.8268173182682, 1789.973150402745, 1856.4337876947534, 8147.551884646342, 8698.56507174641, 8701.303895688345, 8743.169398907094, 8758.86861697075, 7901.486403383776, 8124.268815806577, 8507.404481686284, 8732.91267087329, 8783.341249406305, 2456.3122325748773, 2493.7132229793533, 2500.1374525788574, 2500.8374455660414, 2505.2985164153974, 2447.79193768529, 2545.7072436669837, 2549.6940367155967, 2553.706323772771, 2555.591105423133, 2325.1280769711357, 2325.662778897059, 2341.496578235679, 2342.754010828864, 2351.329870986769, 2386.916457923982, 2402.303332033708, 2415.4323733922515, 2418.322869596507, 2418.5646583614944, 2446.1193970170725, 2454.1410506322823, 2473.282748003077, 2473.8392004519646, 2476.3437689012426, 8490.83018339636, 8607.26838218746, 8614.87077693835, 8619.439736417113, 8969.14793094655, 5451.291332126798, 5601.131824567158, 5602.047651899187, 5604.943950560483, 5605.327360716699, 3540.805255710935, 3624.1845584743487, 3630.2920930418577, 3647.4528820676846, 3663.21240933198, 1096.2435919215795, 1107.402082473098, 1120.1542835159485, 1125.2854437432209, 1172.5602898912907, 12707.745845083138, 12724.936375318157, 12734.490620375174, 12765.872341276561, 12783.808242876457, 4544.931826022612, 4772.904541909143, 4779.617630589544, 4844.975775121173, 4853.296272040534, 9834.164096051773, 9840.655577054911, 9844.163246124159, 9859.26055545837, 9872.703818885446, 5391.137418013099, 5398.91901621476, 5402.027635025733, 5403.621746477709, 5407.837764866981, 4522.819087236539, 4633.2818413146015, 4940.827071052496, 5030.144875371196, 5100.821471248562, 4202.768847713405, 4216.156768646221, 4258.872233832928, 4304.246756817544, 4305.397244385788, 1303.4069498378215, 1317.4598414649947, 1318.314476472229, 1325.3505782166862, 1341.9865801342057, 987.8320685483485, 995.5669283861544, 1009.6213919780082, 1010.2170817616259, 1020.6580795433613, 5732.3407808101765, 5789.8552536186935, 5854.677992710442, 5894.734736936763, 6197.442230199304, 467.75676648143457, 467.8292423265552, 472.773068926917, 473.80100357849784, 482.88893554482667, 4076.490438695163, 4194.643455306333, 4257.723247988871, 4258.1483703258855, 4329.610335069859, 10816.837747433794, 11071.833922491169, 11143.554257829757, 11158.107351411867, 11199.104071674246, 10940.617078402218, 11017.228793984505, 11025.117990560735, 11077.11383089351, 11082.445877706083, 7947.721829735816, 8194.795130121676, 8277.089520152778, 8279.0, 8290.46112002718, 5487.533559647474, 5671.291088613923, 5727.856803579777, 5753.079507278815, 5772.711364431678, 9675.225981921289, 9697.418154910727, 9697.466639334903, 9734.36726612768, 9827.26295527824, 9661.758456038655, 10006.649767258112, 10036.447995360033, 10299.433531155559, 10329.896701032894, 7162.140543134858, 7331.963340183265, 7337.70649174027, 8051.83896322082, 8159.428839981144, 7650.73222437212, 8261.339092872677, 8353.874691879506, 8456.704015359433, 8744.868826967473, 7676.462647614613, 7695.615219239127, 7812.101608315116, 7933.0, 7999.040115186215, 7730.265624765639, 7765.805854853674, 7775.727849525239, 7825.686972521263, 7844.764657060069, 7117.466190035664, 7134.821629459305, 7174.426045916419, 7203.6758345875605, 7204.891926621, 5301.549368303583, 5326.254324394666, 5409.323834520685, 5410.1343784994215, 5430.239766432782, 4912.803487860446, 5030.346055012971, 5057.494250574913, 5077.695338279715, 5153.845384638467], "value": 4630.126308791965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.49323480952626, 359.96040435552095, 364.82306081550445, 372.71114885963357, 374.24029220682024, 5292.2800089970815, 5388.568914486841, 5433.48381903719, 5519.613627046108, 5533.257024037425, 5272.0, 5363.211130212297, 5389.299391079154, 5443.292371991645, 5484.862878428041, 4298.183345164424, 4313.784310784448, 4315.978420107895, 4325.697201195918, 4368.148211098837, 4239.491261048678, 4280.74315541067, 4311.417958575599, 4344.804483798237, 4362.869113926571, 4266.296061149907, 4279.40088387626, 4279.572042795727, 4287.292596721537, 4298.26929421999, 4277.187334406468, 4279.74321540707, 4302.096559722462, 4306.935395969063, 4307.418498502693, 3733.6826369758605, 3890.2025084857537, 3898.1229223424784, 3904.570497245301, 3908.4399718184636, 5249.055170069386, 5252.23842542833, 5254.264402983586, 5269.973650131763, 5278.340207474066, 4929.285253638238, 4933.815884187805, 4948.752562371903, 4951.499492756135, 4957.0, 5495.780168793243, 5680.914786278209, 5703.572232082611, 5704.0, 5707.229524014247, 4222.345536441856, 4225.767582782945, 4227.661787057028, 4248.596383343577, 4254.595813397722, 5877.500412464946, 5889.8822023559715, 5896.1450589664255, 5917.467427931464, 5958.851028724273, 4220.902565333035, 4344.4352234209355, 4390.297552391602, 4392.253316936129, 4392.824287028547, 4342.370356298382, 4355.237833379146, 4363.603646832983, 4365.738055716667, 4389.824407023747, 4906.6016185387, 4909.435414927258, 4928.211486162197, 4978.6763860348965, 4986.426560945465, 5517.951589198039, 5656.434356564393, 5696.3164420269, 5714.171445140431, 5734.426557344233, 1458.6070302860742, 1462.56854228003, 1472.1976522795103, 1473.4400927647425, 1476.7341878461766, 1011.3376270825679, 1029.8661174047327, 1043.984340234897, 1046.1264843855465, 1053.6733612579972, 5301.833596608817, 5320.813771518056, 5324.680519168863, 5328.866778330495, 5355.518003379714, 5526.50261476469, 5549.861253468695, 5634.943650563524, 5640.464155905264, 5668.178114173422, 5107.463716309734, 5294.655847369909, 5363.383210930715, 5376.247325374451, 5397.595180361489, 2967.9258018549276, 2978.9851050744605, 2980.7913446059, 2993.7754668399957, 2998.0, 5638.915416268759, 5759.740811663485, 5801.274840644919, 5804.88390232193, 5851.444112809277, 5703.914441283384, 5711.286089238845, 5727.656340619576, 5731.226284451629, 5791.189233507313, 5733.971330143406, 5738.942610573841, 5741.598088133874, 5745.874026610098, 5804.245448091806, 5340.626156169031, 5594.636348637326, 5600.795828896807, 5603.35561410443, 5618.887622247614, 5497.39528651856, 5505.448516265631, 5517.724113794214, 5549.417311182267, 5556.610847288178, 5719.199312096393, 5731.283589551306, 5740.770369185186, 5771.884562308815, 5784.479396854304, 4094.3244364679495, 4220.683448741295, 4240.957590424056, 4279.16556271524, 4286.914261714686, 5677.460641239076, 5693.231413759089, 5707.0, 5715.2855893013375, 5731.226284451545, 5232.686038837682, 5473.452654734495, 5482.643628164156, 5571.192177134212, 5597.328320601554, 5415.512603865671, 5418.322709661293, 5439.755211015593, 5447.319085114361, 5455.372632147354, 5424.213489044146, 5440.401555828935, 5442.782288708408, 5444.428335024765, 5459.553218397207, 5254.894902102013, 5450.991566560199, 5485.50630443262, 5486.643368181056, 5545.445455454423, 5337.386200586982, 5338.519533242027, 5374.704391258519, 5384.838454846361, 5391.4608539145775], "value": 4321.4711201008595, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.3323008517972, 814.2956342763507, 819.3772732723132, 820.6266148902248, 907.2515174980634, 14871.0, 15104.924475377622, 15248.618784530365, 15253.38986440541, 15332.386704531818, 14505.129692218448, 14667.633309167277, 14734.336954837032, 14862.925685371558, 14874.85125148751, 740.4076738609091, 853.2320911179941, 868.8957325120995, 870.0646804684957, 871.0375035585681, 757.3184134279138, 836.6820608168917, 855.5764896376303, 858.4763294390417, 864.2740098317412, 904.8552231642939, 906.4017748286133, 907.3013779389865, 907.5280853955938, 908.1236606674557, 887.6848718704864, 895.7491902267376, 896.5427631907709, 896.5920506169678, 902.41794042842, 660.2308310817891, 660.8810414125455, 661.8643178148516, 663.0154220981838, 663.0286630086897, 13196.604101876863, 13247.40386682592, 13254.138480998705, 13277.933610331984, 14928.178950157737, 13212.481782427294, 14004.22976736279, 14081.2959352032, 14367.78448323276, 14593.051451655714, 12040.81938770919, 12171.391430428443, 12216.144869859114, 12509.124361294713, 12517.56188533397, 3839.0786211309087, 3873.5351757789244, 3903.1803321302277, 3949.3878448840474, 3957.920841583181, 62423.0, 63291.36708632901, 63772.362276377105, 64410.677946610434, 64740.3525964739, 11513.0, 11561.364124973206, 11574.768504629861, 11768.764624707457, 11945.343006134746, 18661.533461663566, 18741.250349985847, 18805.341813036477, 19251.32620358281, 19736.11187496566, 8587.22714955657, 8673.739787806377, 9004.324675649352, 9246.121618446237, 9250.583723732449, 4857.489963553776, 4966.751662416865, 4984.651074424755, 5020.698758074527, 5133.358330208724, 3271.6074071111143, 3371.679690429405, 3372.8482218300237, 3373.274745929588, 3382.255903701182, 622.6450922973952, 629.288903538998, 636.3159603426335, 636.678477368935, 642.7396904253785, 686.7321744519642, 757.257887270478, 761.3072104384983, 772.8917951486796, 798.4730078148394, 9054.139856713733, 9151.542422878963, 9358.672446464341, 9850.655227067018, 10063.591097246392, 676.2932735291632, 765.689895592286, 774.5933384972822, 782.2568559868034, 794.7099308752264, 4168.749875007509, 4362.105768317488, 4363.410939523187, 4394.384786129945, 4524.276115821453, 54370.0, 70384.64807675929, 87661.56169219113, 90027.54986225026, 92099.5395023034, 50135.49864501308, 62208.68895655493, 63882.36117638857, 77856.22143778604, 80737.0, 71100.0, 71894.64052679703, 71992.64003680053, 73007.0, 74525.0, 7572.545647261182, 8434.873476897974, 8453.704120355758, 8595.699150529701, 8799.604017819085, 48151.51848481611, 48380.516194837604, 48901.75549122231, 49525.75237123791, 49553.75223123861, 40157.598424015385, 43412.0, 63547.364526355996, 64874.0, 66843.0, 11483.540059672843, 11654.941725291319, 11862.644120676394, 11930.701732456759, 12209.328486932949, 13588.418200541866, 13669.658258543219, 13721.794173087212, 13795.10331828428, 14449.0, 12444.813327800272, 12691.873081269068, 12758.0, 12760.808587871004, 12888.162269452456, 12420.0, 12709.428075736429, 12836.614901552968, 13439.529616463376, 14207.502737404555, 12669.556565520163, 12967.481300748243, 13228.933855330662, 13300.201987880759, 13562.59312220635, 11342.60300889465, 11498.25261358009, 11529.135314851588, 11811.173217874666, 12060.336681482251, 11112.611058612909, 11574.71063223426, 11834.230774999598, 11953.462094205956, 12227.14409991292], "value": 7239.554831112858, "unit": "score"}], "name": "dromaeo_css", "value": 7402.58766168623, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 111.0, 110.0, 110.0, 111.0, 111.0, 112.0, 110.0, 110.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 119.0, 158.0, 120.0, 118.0, 119.0, 116.0, 159.0, 117.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 162.0, 162.0, 161.0, 160.0, 160.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 102.0, 76.0, 76.0, 76.0, 75.0, 97.0, 74.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 120.0, 82.0, 84.0, 82.0, 108.0, 82.0, 82.0, 118.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0, 52.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 74.0, 74.0, 85.0, 77.0, 76.0, 81.0, 77.0, 75.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 120.0, 123.0, 136.0, 130.0, 135.0, 119.0, 173.0, 119.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 154.0, 156.0, 151.0, 153.0, 155.0, 152.0, 151.0, 156.0, 152.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 61.0, 61.0, 66.0, 60.0, 60.0, 60.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1449.9999999999998, "alertThreshold": 2.0}]} 12:21:58 INFO - Return code: 0 12:21:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:21:58 INFO - # TBPL SUCCESS # 12:21:58 INFO - Running post-action listener: _resource_record_post_action 12:21:58 INFO - [mozharness: 2017-02-02 20:21:58.415677Z] Finished run-tests step (success) 12:21:58 INFO - Running post-run listener: _resource_record_post_run 12:21:58 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322121728; Read time: 24; Write time: 219832 12:21:58 INFO - TinderboxPrint: CPU usage
13.0% 12:21:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 12:21:58 INFO - TinderboxPrint: I/O write bytes / time
322,121,728 / 219,832 12:21:58 INFO - TinderboxPrint: CPU idle
8,244.7 (87.0%) 12:21:58 INFO - TinderboxPrint: CPU user
1,189.6 (12.6%) 12:21:58 INFO - TinderboxPrint: Swap in / out
0 / 0 12:21:58 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100904960; Read time: 0; Write time: 111928 12:21:58 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220815360; Read time: 24; Write time: 107904 12:21:59 INFO - Running post-run listener: _upload_blobber_files 12:21:59 INFO - Blob upload gear active. 12:21:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:21:59 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:00 INFO - (blobuploader) - INFO - Done attempting. 12:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:00 INFO - (blobuploader) - INFO - Done attempting. 12:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:04 INFO - (blobuploader) - INFO - Done attempting. 12:22:04 INFO - (blobuploader) - INFO - Iteration through files over. 12:22:04 INFO - Return code: 0 12:22:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:22:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:22:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/165f664266d96dbac636084c56cafc9758efcc2f94101240507b15cba3dae15f02611d651fd44635e1818fbc5cfa30c9389641c7ab7127b12f15e50c410624a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f558946d321f9317be419adba346238526c25cf2a592dfce9b79e86d1d004b55a708246f50256a4f3a78b5fe61956345a6784c04e44150f37a2e291cec2a209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dbe95342a47195819cec379020d9a6a3c20f6077f911499b1a5940beceea5fa949409bd0ad547d09664a046fa9dab28a24a0b8dfc45404ebc3d60c555209d65"} 12:22:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:22:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:22:04 INFO - Contents: 12:22:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/165f664266d96dbac636084c56cafc9758efcc2f94101240507b15cba3dae15f02611d651fd44635e1818fbc5cfa30c9389641c7ab7127b12f15e50c410624a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f558946d321f9317be419adba346238526c25cf2a592dfce9b79e86d1d004b55a708246f50256a4f3a78b5fe61956345a6784c04e44150f37a2e291cec2a209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dbe95342a47195819cec379020d9a6a3c20f6077f911499b1a5940beceea5fa949409bd0ad547d09664a046fa9dab28a24a0b8dfc45404ebc3d60c555209d65"} 12:22:04 INFO - Running post-run listener: copy_logs_to_upload_dir 12:22:04 INFO - Copying logs to upload dir... 12:22:04 INFO - mkdir: /builds/slave/test/build/upload/logs 12:22:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.310778 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-02-02 12:22:04.359390) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 12:22:04.363062) ========= 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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 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-Beta/sha512/165f664266d96dbac636084c56cafc9758efcc2f94101240507b15cba3dae15f02611d651fd44635e1818fbc5cfa30c9389641c7ab7127b12f15e50c410624a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f558946d321f9317be419adba346238526c25cf2a592dfce9b79e86d1d004b55a708246f50256a4f3a78b5fe61956345a6784c04e44150f37a2e291cec2a209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dbe95342a47195819cec379020d9a6a3c20f6077f911499b1a5940beceea5fa949409bd0ad547d09664a046fa9dab28a24a0b8dfc45404ebc3d60c555209d65"} build_url:https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011481 build_url: 'https://queue.taskcluster.net/v1/task/RTEKKwRnS-qw25Fb2d72bg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/165f664266d96dbac636084c56cafc9758efcc2f94101240507b15cba3dae15f02611d651fd44635e1818fbc5cfa30c9389641c7ab7127b12f15e50c410624a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f558946d321f9317be419adba346238526c25cf2a592dfce9b79e86d1d004b55a708246f50256a4f3a78b5fe61956345a6784c04e44150f37a2e291cec2a209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dbe95342a47195819cec379020d9a6a3c20f6077f911499b1a5940beceea5fa949409bd0ad547d09664a046fa9dab28a24a0b8dfc45404ebc3d60c555209d65"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 12:22:04.388620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:22:04.388954) ========= 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=62c568580acbfdaac3eec7be000001dc-1486065692.148684-365252905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 12:22:04.472536) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 12:22:04.472874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 12:22:04.473337) ========= ========= Total master_lag: 0.17 =========