builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1483612138.35 results: success (0) buildid: 20170105004018 builduid: e7ec886f8516491a8b9dac27187e1e32 revision: 5af63cf90f2874f840c132461bff7f6130483362 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.350145) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.350624) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.350912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.365615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.365894) ========= 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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 02:28:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 64.9M=0s 2017-01-05 02:28:59 (64.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127355 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.507023) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.507360) ========= 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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013219 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:28:58.557687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 02:28:58.558067) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5af63cf90f2874f840c132461bff7f6130483362 --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-aurora --rev 5af63cf90f2874f840c132461bff7f6130483362 --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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 02:28:59,530 truncating revision to first 12 chars 2017-01-05 02:28:59,530 Setting DEBUG logging. 2017-01-05 02:28:59,531 attempt 1/10 2017-01-05 02:28:59,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5af63cf90f28?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 02:29:00,896 unpacking tar archive at: mozilla-aurora-5af63cf90f28/testing/mozharness/ program finished with exit code 0 elapsedTime=1.700473 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 02:29:00.272342) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 02:29:00.272719) ========= script_repo_revision: 5af63cf90f2874f840c132461bff7f6130483362 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 02:29:00.273134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 02:29:00.273399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 02:29:00.282144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-01-05 02:29:00.282530) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:29:01 INFO - MultiFileLogger online at 20170105 02:29:01 in /builds/slave/test 02:29:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:29:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:29:01 INFO - {'append_to_log': False, 02:29:01 INFO - 'base_work_dir': '/builds/slave/test', 02:29:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:29:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:29:01 INFO - 'branch': 'Mozilla-Aurora', 02:29:01 INFO - 'buildbot_json_path': 'buildprops.json', 02:29:01 INFO - 'config_files': ('talos/linux_config.py',), 02:29:01 INFO - 'default_actions': ('clobber', 02:29:01 INFO - 'read-buildbot-config', 02:29:01 INFO - 'download-and-extract', 02:29:01 INFO - 'populate-webroot', 02:29:01 INFO - 'create-virtualenv', 02:29:01 INFO - 'install', 02:29:01 INFO - 'run-tests'), 02:29:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:29:01 INFO - 'download_minidump_stackwalk': True, 02:29:01 INFO - 'download_symbols': 'ondemand', 02:29:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:29:01 INFO - 'tooltool.py': '/tools/tooltool.py', 02:29:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:29:01 INFO - '/tools/misc-python/virtualenv.py')}, 02:29:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:29:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:29:01 INFO - 'installer_path': 'installer.exe', 02:29:01 INFO - 'log_level': 'info', 02:29:01 INFO - 'log_name': 'talos', 02:29:01 INFO - 'log_to_console': True, 02:29:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:29:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:29:01 INFO - 'opt_config_files': (), 02:29:01 INFO - 'pip_index': False, 02:29:01 INFO - 'sps_profile': False, 02:29:01 INFO - 'sps_profile_interval': 0, 02:29:01 INFO - 'suite': 'dromaeojs', 02:29:01 INFO - 'system_bits': '32', 02:29:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:29:01 INFO - 'title': 'talos-linux64-ix-049', 02:29:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:29:01 INFO - 'use_talos_json': True, 02:29:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:29:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:29:01 INFO - 'work_dir': 'build'} 02:29:01 INFO - [mozharness: 2017-01-05 10:29:01.347861Z] Running clobber step. 02:29:01 INFO - Running pre-action listener: _resource_record_pre_action 02:29:01 INFO - Running main action method: clobber 02:29:01 INFO - rmtree: /builds/slave/test/build 02:29:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:29:01 INFO - Running post-action listener: _resource_record_post_action 02:29:01 INFO - [mozharness: 2017-01-05 10:29:01.823516Z] Finished clobber step (success) 02:29:01 INFO - [mozharness: 2017-01-05 10:29:01.823615Z] Running read-buildbot-config step. 02:29:01 INFO - Running pre-action listener: _resource_record_pre_action 02:29:01 INFO - Running main action method: read_buildbot_config 02:29:01 INFO - Using buildbot properties: 02:29:01 INFO - { 02:29:01 INFO - "project": "", 02:29:01 INFO - "product": "firefox", 02:29:01 INFO - "script_repo_revision": "production", 02:29:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 02:29:01 INFO - "repository": "", 02:29:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 02:29:01 INFO - "buildid": "20170105004018", 02:29:01 INFO - "stage_platform": "linux64-pgo", 02:29:01 INFO - "basedir": "/builds/slave/test", 02:29:01 INFO - "nightly_build": "True", 02:29:01 INFO - "buildnumber": 994, 02:29:01 INFO - "slavename": "talos-linux64-ix-049", 02:29:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:29:01 INFO - "platform": "ubuntu64_hw", 02:29:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 02:29:01 INFO - "branch": "mozilla-aurora", 02:29:01 INFO - "repo_path": "releases/mozilla-aurora", 02:29:01 INFO - "revision": "5af63cf90f2874f840c132461bff7f6130483362", 02:29:01 INFO - "pgo_build": "True", 02:29:01 INFO - "builduid": "e7ec886f8516491a8b9dac27187e1e32", 02:29:01 INFO - "slavebuilddir": "test" 02:29:01 INFO - } 02:29:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 02:29:01 INFO - Running post-action listener: _resource_record_post_action 02:29:01 INFO - [mozharness: 2017-01-05 10:29:01.826097Z] Finished read-buildbot-config step (success) 02:29:01 INFO - [mozharness: 2017-01-05 10:29:01.826183Z] Running download-and-extract step. 02:29:01 INFO - Running pre-action listener: _resource_record_pre_action 02:29:01 INFO - Running main action method: download_and_extract 02:29:01 INFO - mkdir: /builds/slave/test/build/tests 02:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:01 INFO - trying https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 02:29:01 INFO - Downloading https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 02:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:29:02 INFO - Downloaded 1361 bytes. 02:29:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 02:29:02 INFO - Using the following test package requirements: 02:29:02 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 02:29:02 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 02:29:02 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 02:29:02 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'jsshell-linux-x86_64.zip'], 02:29:02 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 02:29:02 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 02:29:02 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 02:29:02 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 02:29:02 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 02:29:02 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 02:29:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 02:29:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:29:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 02:29:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:29:02 INFO - Fetch https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 02:29:02 INFO - Http code: 200 02:29:02 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 02:29:02 INFO - x-amz-cf-id: 34c6J7mqWWX5MNa3wGl9T4kTfrc37I7mFG8m2c_Ma8K6QEvSeBXsuA== 02:29:02 INFO - x-amz-version-id: AnAuoE2jKMLRBxAQJtwvlWirlfbs.aHL 02:29:03 INFO - Expected file size: 21942907 02:29:03 INFO - Obtained file size: 21942907 02:29:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:29:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 02:29:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:29:07 INFO - Fetch https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 02:29:07 INFO - Http code: 200 02:29:07 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 02:29:07 INFO - x-amz-cf-id: 7OF8TX4QtdjCa_KE-VbdeSU3ssFRiTDgIa15owQG4XHHk9OE51hgcA== 02:29:07 INFO - x-amz-version-id: AnAuoE2jKMLRBxAQJtwvlWirlfbs.aHL 02:29:08 INFO - Expected file size: 21942907 02:29:08 INFO - Obtained file size: 21942907 02:29:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 02:29:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:29:12 INFO - Fetch https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 02:29:12 INFO - Http code: 200 02:29:12 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 02:29:12 INFO - x-amz-cf-id: AIwguAgQNNHkC4Wuq8vzXSQnHrFK6qJB4q4g7Ejtg4_y9NKGDdCYbA== 02:29:12 INFO - x-amz-version-id: Wn4cPVoxTq2W7Dkf14PzbERMd87TV1dk 02:29:12 INFO - Expected file size: 14409038 02:29:12 INFO - Obtained file size: 14409038 02:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:13 INFO - trying https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 02:29:13 INFO - Downloading https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 02:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:29:14 INFO - Downloaded 64059851 bytes. 02:29:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 02:29:14 INFO - mkdir: /builds/slave/test/properties 02:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:14 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:14 INFO - Contents: 02:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 02:29:15 INFO - Running post-action listener: _resource_record_post_action 02:29:15 INFO - Running post-action listener: set_extra_try_arguments 02:29:15 INFO - [mozharness: 2017-01-05 10:29:15.027449Z] Finished download-and-extract step (success) 02:29:15 INFO - [mozharness: 2017-01-05 10:29:15.027677Z] Running populate-webroot step. 02:29:15 INFO - Running pre-action listener: _resource_record_pre_action 02:29:15 INFO - Running main action method: populate_webroot 02:29:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:29:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:29:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:29:15 INFO - '--tppagecycles', 02:29:15 INFO - '7'], 02:29:15 INFO - 'tests': ['tsvgm']}}, 02:29:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['damp', 'tps']}, 02:29:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'tests': ['damp', 'tps']}, 02:29:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['dromaeo_dom']}, 02:29:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['basic_compositor_video']}, 02:29:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:29:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['a11yr', 02:29:15 INFO - 'ts_paint', 02:29:15 INFO - 'tpaint', 02:29:15 INFO - 'sessionrestore', 02:29:15 INFO - 'sessionrestore_no_auto_restore', 02:29:15 INFO - 'tabpaint']}, 02:29:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['tsvgx', 02:29:15 INFO - 'tsvgr_opacity', 02:29:15 INFO - 'tart', 02:29:15 INFO - 'tscrollx', 02:29:15 INFO - 'cart']}, 02:29:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:15 INFO - 'tsvgr_opacity', 02:29:15 INFO - 'tart', 02:29:15 INFO - 'tscrollx', 02:29:15 INFO - 'cart']}, 02:29:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'talos_options': ['--disable-e10s'], 02:29:15 INFO - 'tests': ['tp5o']}, 02:29:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'tests': ['tp5o']}, 02:29:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'talos_options': ['--disable-e10s', 02:29:15 INFO - '--xperf_path', 02:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:15 INFO - 'tests': ['tp5n']}, 02:29:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:15 INFO - 'talos_options': ['--xperf_path', 02:29:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:15 INFO - 'tests': ['tp5n']}}, 02:29:15 INFO - 'talos.zip': {'path': '', 02:29:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:29:15 INFO - Running post-action listener: _resource_record_post_action 02:29:15 INFO - [mozharness: 2017-01-05 10:29:15.045755Z] Finished populate-webroot step (success) 02:29:15 INFO - [mozharness: 2017-01-05 10:29:15.045843Z] Running create-virtualenv step. 02:29:15 INFO - Running pre-action listener: _resource_record_pre_action 02:29:15 INFO - Running main action method: create_virtualenv 02:29:15 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:15 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 02:29:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:29:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:15 INFO - Using real prefix '/usr' 02:29:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:15 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:17 INFO - Installing pip.................done. 02:29:17 INFO - Return code: 0 02:29:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:17 INFO - Reading from file tmpfile_stdout 02:29:17 INFO - Output received: 02:29:17 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) 02:29:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:17 INFO - Using env: {'DISPLAY': ':0', 02:29:17 INFO - 'HOME': '/home/cltbld', 02:29:17 INFO - 'LANG': 'en_US.UTF-8', 02:29:17 INFO - 'LANGUAGE': 'en_US:en', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MAIL': '/var/mail/cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SHLVL': '1', 02:29:17 INFO - 'TERM': 'linux', 02:29:17 INFO - 'TMOUT': '86400', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - '_': '/tools/buildbot/bin/python'} 02:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:18 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 02:29:18 INFO - Cleaning up... 02:29:18 INFO - Return code: 0 02:29:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:18 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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:18 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 02:29:18 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 02:29:18 INFO - Using env: {'DISPLAY': ':0', 02:29:18 INFO - 'HOME': '/home/cltbld', 02:29:18 INFO - 'LANG': 'en_US.UTF-8', 02:29:18 INFO - 'LANGUAGE': 'en_US:en', 02:29:18 INFO - 'LOGNAME': 'cltbld', 02:29:18 INFO - 'MAIL': '/var/mail/cltbld', 02:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:18 INFO - 'MOZ_NO_REMOTE': '1', 02:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:18 INFO - 'NO_EM_RESTART': '1', 02:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:18 INFO - 'PWD': '/builds/slave/test', 02:29:18 INFO - 'SHELL': '/bin/bash', 02:29:18 INFO - 'SHLVL': '1', 02:29:18 INFO - 'TERM': 'linux', 02:29:18 INFO - 'TMOUT': '86400', 02:29:18 INFO - 'USER': 'cltbld', 02:29:18 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:18 INFO - '_': '/tools/buildbot/bin/python'} 02:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:18 INFO - Downloading/unpacking psutil>=3.1.1 02:29:18 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 02:29:18 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 02:29:18 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 02:29:18 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 02:29:18 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 02:29:18 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 02:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:20 INFO - Installing collected packages: psutil 02:29:20 INFO - Running setup.py install for psutil 02:29:20 INFO - building 'psutil._psutil_linux' extension 02:29:20 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 02:29:20 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 02:29:20 INFO - building 'psutil._psutil_posix' extension 02:29:20 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 02:29:20 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 02:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:20 INFO - Successfully installed psutil 02:29:20 INFO - Cleaning up... 02:29:21 INFO - Return code: 0 02:29:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:21 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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:21 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 02:29:21 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 02:29:21 INFO - Using env: {'DISPLAY': ':0', 02:29:21 INFO - 'HOME': '/home/cltbld', 02:29:21 INFO - 'LANG': 'en_US.UTF-8', 02:29:21 INFO - 'LANGUAGE': 'en_US:en', 02:29:21 INFO - 'LOGNAME': 'cltbld', 02:29:21 INFO - 'MAIL': '/var/mail/cltbld', 02:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:21 INFO - 'MOZ_NO_REMOTE': '1', 02:29:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:21 INFO - 'NO_EM_RESTART': '1', 02:29:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:21 INFO - 'PWD': '/builds/slave/test', 02:29:21 INFO - 'SHELL': '/bin/bash', 02:29:21 INFO - 'SHLVL': '1', 02:29:21 INFO - 'TERM': 'linux', 02:29:21 INFO - 'TMOUT': '86400', 02:29:21 INFO - 'USER': 'cltbld', 02:29:21 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:21 INFO - '_': '/tools/buildbot/bin/python'} 02:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29:21 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 02:29:21 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 02:29:21 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 02:29:21 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 02:29:21 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 02:29:21 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 02:29:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:23 INFO - Installing collected packages: mozsystemmonitor 02:29:23 INFO - Running setup.py install for mozsystemmonitor 02:29:23 INFO - Successfully installed mozsystemmonitor 02:29:23 INFO - Cleaning up... 02:29:23 INFO - Return code: 0 02:29:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:29:23 INFO - Using env: {'DISPLAY': ':0', 02:29:23 INFO - 'HOME': '/home/cltbld', 02:29:23 INFO - 'LANG': 'en_US.UTF-8', 02:29:23 INFO - 'LANGUAGE': 'en_US:en', 02:29:23 INFO - 'LOGNAME': 'cltbld', 02:29:23 INFO - 'MAIL': '/var/mail/cltbld', 02:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:23 INFO - 'MOZ_NO_REMOTE': '1', 02:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:23 INFO - 'NO_EM_RESTART': '1', 02:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:23 INFO - 'PWD': '/builds/slave/test', 02:29:23 INFO - 'SHELL': '/bin/bash', 02:29:23 INFO - 'SHLVL': '1', 02:29:23 INFO - 'TERM': 'linux', 02:29:23 INFO - 'TMOUT': '86400', 02:29:23 INFO - 'USER': 'cltbld', 02:29:23 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:23 INFO - '_': '/tools/buildbot/bin/python'} 02:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:23 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:25 INFO - warning: no files found matching '*.txt' 02:29:25 INFO - no previously-included directories found matching 'build' 02:29:25 INFO - no previously-included directories found matching 'dist' 02:29:25 INFO - no previously-included directories found matching '.git*' 02:29:25 INFO - Installing collected packages: jsonschema, functools32 02:29:26 INFO - Running setup.py install for functools32 02:29:26 INFO - warning: no files found matching '*.txt' 02:29:26 INFO - no previously-included directories found matching 'build' 02:29:26 INFO - no previously-included directories found matching 'dist' 02:29:26 INFO - no previously-included directories found matching '.git*' 02:29:26 INFO - Successfully installed jsonschema functools32 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:29:26 INFO - Using env: {'DISPLAY': ':0', 02:29:26 INFO - 'HOME': '/home/cltbld', 02:29:26 INFO - 'LANG': 'en_US.UTF-8', 02:29:26 INFO - 'LANGUAGE': 'en_US:en', 02:29:26 INFO - 'LOGNAME': 'cltbld', 02:29:26 INFO - 'MAIL': '/var/mail/cltbld', 02:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:26 INFO - 'MOZ_NO_REMOTE': '1', 02:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:26 INFO - 'NO_EM_RESTART': '1', 02:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:26 INFO - 'PWD': '/builds/slave/test', 02:29:26 INFO - 'SHELL': '/bin/bash', 02:29:26 INFO - 'SHLVL': '1', 02:29:26 INFO - 'TERM': 'linux', 02:29:26 INFO - 'TMOUT': '86400', 02:29:26 INFO - 'USER': 'cltbld', 02:29:26 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:26 INFO - '_': '/tools/buildbot/bin/python'} 02:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:26 INFO - Using env: {'DISPLAY': ':0', 02:29:26 INFO - 'HOME': '/home/cltbld', 02:29:26 INFO - 'LANG': 'en_US.UTF-8', 02:29:26 INFO - 'LANGUAGE': 'en_US:en', 02:29:26 INFO - 'LOGNAME': 'cltbld', 02:29:26 INFO - 'MAIL': '/var/mail/cltbld', 02:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:26 INFO - 'MOZ_NO_REMOTE': '1', 02:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:26 INFO - 'NO_EM_RESTART': '1', 02:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:26 INFO - 'PWD': '/builds/slave/test', 02:29:26 INFO - 'SHELL': '/bin/bash', 02:29:26 INFO - 'SHLVL': '1', 02:29:26 INFO - 'TERM': 'linux', 02:29:26 INFO - 'TMOUT': '86400', 02:29:26 INFO - 'USER': 'cltbld', 02:29:26 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:26 INFO - '_': '/tools/buildbot/bin/python'} 02:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:26 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:28 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:29 INFO - Downloading docopt-0.6.1.tar.gz 02:29:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:29 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:29 INFO - Running setup.py install for blobuploader 02:29:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:29 INFO - Running setup.py install for requests 02:29:29 INFO - Running setup.py install for docopt 02:29:29 INFO - Successfully installed blobuploader requests docopt 02:29:29 INFO - Cleaning up... 02:29:30 INFO - Return code: 0 02:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:30 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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:30 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 02:29:30 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 02:29:30 INFO - Using env: {'DISPLAY': ':0', 02:29:30 INFO - 'HOME': '/home/cltbld', 02:29:30 INFO - 'LANG': 'en_US.UTF-8', 02:29:30 INFO - 'LANGUAGE': 'en_US:en', 02:29:30 INFO - 'LOGNAME': 'cltbld', 02:29:30 INFO - 'MAIL': '/var/mail/cltbld', 02:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:30 INFO - 'MOZ_NO_REMOTE': '1', 02:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:30 INFO - 'NO_EM_RESTART': '1', 02:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:30 INFO - 'PWD': '/builds/slave/test', 02:29:30 INFO - 'SHELL': '/bin/bash', 02:29:30 INFO - 'SHLVL': '1', 02:29:30 INFO - 'TERM': 'linux', 02:29:30 INFO - 'TMOUT': '86400', 02:29:30 INFO - 'USER': 'cltbld', 02:29:30 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:30 INFO - '_': '/tools/buildbot/bin/python'} 02:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:30 INFO - Running setup.py (path:/tmp/pip-SrK40T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:30 INFO - Running setup.py (path:/tmp/pip-GlTKbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:30 INFO - Running setup.py (path:/tmp/pip-kTmcBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:30 INFO - Running setup.py (path:/tmp/pip-_HULoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:30 INFO - Running setup.py (path:/tmp/pip-Vrs48o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:30 INFO - Running setup.py (path:/tmp/pip-EVLKqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:30 INFO - Running setup.py (path:/tmp/pip-OSTm7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:30 INFO - Running setup.py (path:/tmp/pip-g19WOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:30 INFO - Running setup.py (path:/tmp/pip-eHbEku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:30 INFO - Running setup.py (path:/tmp/pip-EjaiDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:30 INFO - Running setup.py (path:/tmp/pip-Sj7m3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:31 INFO - Running setup.py (path:/tmp/pip-XBLrES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:31 INFO - Running setup.py (path:/tmp/pip-OXpa1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:31 INFO - Running setup.py (path:/tmp/pip-xwwdKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:31 INFO - Running setup.py (path:/tmp/pip-2tdnQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:31 INFO - Running setup.py (path:/tmp/pip-Nnwpoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:31 INFO - Running setup.py (path:/tmp/pip-CZaa7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:31 INFO - Running setup.py install for manifestparser 02:29:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:31 INFO - Running setup.py install for mozcrash 02:29:31 INFO - Running setup.py install for mozdebug 02:29:31 INFO - Running setup.py install for mozdevice 02:29:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:31 INFO - Running setup.py install for mozfile 02:29:32 INFO - Running setup.py install for mozhttpd 02:29:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozinfo 02:29:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozInstall 02:29:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozleak 02:29:32 INFO - Running setup.py install for mozlog 02:29:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for moznetwork 02:29:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozprocess 02:29:32 INFO - Running setup.py install for mozprofile 02:29:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozrunner 02:29:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:32 INFO - Running setup.py install for mozscreenshot 02:29:33 INFO - Running setup.py install for moztest 02:29:33 INFO - Running setup.py install for mozversion 02:29:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:33 INFO - Cleaning up... 02:29:33 INFO - Return code: 0 02:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:33 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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:33 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 02:29:33 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 02:29:33 INFO - Using env: {'DISPLAY': ':0', 02:29:33 INFO - 'HOME': '/home/cltbld', 02:29:33 INFO - 'LANG': 'en_US.UTF-8', 02:29:33 INFO - 'LANGUAGE': 'en_US:en', 02:29:33 INFO - 'LOGNAME': 'cltbld', 02:29:33 INFO - 'MAIL': '/var/mail/cltbld', 02:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:33 INFO - 'MOZ_NO_REMOTE': '1', 02:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:33 INFO - 'NO_EM_RESTART': '1', 02:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:33 INFO - 'PWD': '/builds/slave/test', 02:29:33 INFO - 'SHELL': '/bin/bash', 02:29:33 INFO - 'SHLVL': '1', 02:29:33 INFO - 'TERM': 'linux', 02:29:33 INFO - 'TMOUT': '86400', 02:29:33 INFO - 'USER': 'cltbld', 02:29:33 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:33 INFO - '_': '/tools/buildbot/bin/python'} 02:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:33 INFO - Running setup.py (path:/tmp/pip-VREWZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:33 INFO - Running setup.py (path:/tmp/pip-h2S0Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:33 INFO - Running setup.py (path:/tmp/pip-W3GCgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:33 INFO - Running setup.py (path:/tmp/pip-39nwSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:33 INFO - Running setup.py (path:/tmp/pip-XxTYeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:33 INFO - Running setup.py (path:/tmp/pip-RAUH9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:33 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)) 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:33 INFO - Running setup.py (path:/tmp/pip-1_3gX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:34 INFO - Running setup.py (path:/tmp/pip-iiWuLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:34 INFO - Running setup.py (path:/tmp/pip-0FRx8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:34 INFO - Running setup.py (path:/tmp/pip-5uJQhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:34 INFO - Running setup.py (path:/tmp/pip-icXHxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:34 INFO - Running setup.py (path:/tmp/pip-Bss2Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:34 INFO - Running setup.py (path:/tmp/pip-_kId_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:34 INFO - Running setup.py (path:/tmp/pip-xOAlQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:34 INFO - Running setup.py (path:/tmp/pip-XKnmoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:34 INFO - Running setup.py (path:/tmp/pip-UVn0fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:34 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)) 02:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:34 INFO - Running setup.py (path:/tmp/pip-700dge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:34 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)) 02:29:34 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)) 02:29:34 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)) 02:29:34 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)) 02:29:34 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)) 02:29:34 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)) 02:29:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:36 INFO - Downloading blessings-1.6.tar.gz 02:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:36 INFO - Installing collected packages: blessings 02:29:36 INFO - Running setup.py install for blessings 02:29:36 INFO - Successfully installed blessings 02:29:36 INFO - Cleaning up... 02:29:37 INFO - Return code: 0 02:29:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:37 INFO - Reading from file tmpfile_stdout 02:29:37 INFO - Current package versions: 02:29:37 INFO - argparse == 1.2.1 02:29:37 INFO - blessings == 1.6 02:29:37 INFO - blobuploader == 1.2.4 02:29:37 INFO - docopt == 0.6.1 02:29:37 INFO - functools32 == 3.2.3-2 02:29:37 INFO - jsonschema == 2.5.1 02:29:37 INFO - manifestparser == 1.1 02:29:37 INFO - mozInstall == 1.12 02:29:37 INFO - mozcrash == 1.0 02:29:37 INFO - mozdebug == 0.1 02:29:37 INFO - mozdevice == 0.48 02:29:37 INFO - mozfile == 1.2 02:29:37 INFO - mozhttpd == 0.7 02:29:37 INFO - mozinfo == 0.9 02:29:37 INFO - mozleak == 0.1 02:29:37 INFO - mozlog == 3.4 02:29:37 INFO - moznetwork == 0.27 02:29:37 INFO - mozprocess == 0.23 02:29:37 INFO - mozprofile == 0.28 02:29:37 INFO - mozrunner == 6.13 02:29:37 INFO - mozscreenshot == 0.1 02:29:37 INFO - mozsystemmonitor == 0.3 02:29:37 INFO - moztest == 0.8 02:29:37 INFO - mozversion == 1.4 02:29:37 INFO - psutil == 3.1.1 02:29:37 INFO - requests == 1.2.3 02:29:37 INFO - wsgiref == 0.1.2 02:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 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 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:37 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 02:29:37 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 02:29:37 INFO - Using env: {'DISPLAY': ':0', 02:29:37 INFO - 'HOME': '/home/cltbld', 02:29:37 INFO - 'LANG': 'en_US.UTF-8', 02:29:37 INFO - 'LANGUAGE': 'en_US:en', 02:29:37 INFO - 'LOGNAME': 'cltbld', 02:29:37 INFO - 'MAIL': '/var/mail/cltbld', 02:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:37 INFO - 'MOZ_NO_REMOTE': '1', 02:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:37 INFO - 'NO_EM_RESTART': '1', 02:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:37 INFO - 'PWD': '/builds/slave/test', 02:29:37 INFO - 'SHELL': '/bin/bash', 02:29:37 INFO - 'SHLVL': '1', 02:29:37 INFO - 'TERM': 'linux', 02:29:37 INFO - 'TMOUT': '86400', 02:29:37 INFO - 'USER': 'cltbld', 02:29:37 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:37 INFO - '_': '/tools/buildbot/bin/python'} 02:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 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)) 02:29:37 INFO - Cleaning up... 02:29:37 INFO - Return code: 0 02:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf9ed9cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, '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': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', '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 02:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:37 INFO - Using env: {'DISPLAY': ':0', 02:29:37 INFO - 'HOME': '/home/cltbld', 02:29:37 INFO - 'LANG': 'en_US.UTF-8', 02:29:37 INFO - 'LANGUAGE': 'en_US:en', 02:29:37 INFO - 'LOGNAME': 'cltbld', 02:29:37 INFO - 'MAIL': '/var/mail/cltbld', 02:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:37 INFO - 'MOZ_NO_REMOTE': '1', 02:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:37 INFO - 'NO_EM_RESTART': '1', 02:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:37 INFO - 'PWD': '/builds/slave/test', 02:29:37 INFO - 'SHELL': '/bin/bash', 02:29:37 INFO - 'SHLVL': '1', 02:29:37 INFO - 'TERM': 'linux', 02:29:37 INFO - 'TMOUT': '86400', 02:29:37 INFO - 'USER': 'cltbld', 02:29:37 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:37 INFO - '_': '/tools/buildbot/bin/python'} 02:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:37 INFO - Cleaning up... 02:29:37 INFO - Return code: 0 02:29:37 INFO - Running post-action listener: _resource_record_post_action 02:29:37 INFO - Running post-action listener: _start_resource_monitoring 02:29:37 INFO - Starting resource monitoring. 02:29:37 INFO - [mozharness: 2017-01-05 10:29:37.732369Z] Finished create-virtualenv step (success) 02:29:37 INFO - [mozharness: 2017-01-05 10:29:37.732865Z] Running install step. 02:29:37 INFO - Running pre-action listener: _resource_record_pre_action 02:29:37 INFO - Running main action method: install 02:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:37 INFO - Reading from file tmpfile_stdout 02:29:37 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:38 INFO - Reading from file tmpfile_stdout 02:29:38 INFO - Output received: 02:29:38 INFO - Usage: mozinstall [options] installer 02:29:38 INFO - Options: 02:29:38 INFO - -h, --help show this help message and exit 02:29:38 INFO - -d DEST, --destination=DEST 02:29:38 INFO - Directory to install application into. [default: 02:29:38 INFO - "/builds/slave/test"] 02:29:38 INFO - --app=APP Application being installed. [default: firefox] 02:29:38 INFO - mkdir: /builds/slave/test/build/application 02:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:29:54 INFO - Reading from file tmpfile_stdout 02:29:54 INFO - Output received: 02:29:54 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:54 INFO - Running post-action listener: _resource_record_post_action 02:29:54 INFO - [mozharness: 2017-01-05 10:29:54.570294Z] Finished install step (success) 02:29:54 INFO - [mozharness: 2017-01-05 10:29:54.570537Z] Running run-tests step. 02:29:54 INFO - Running pre-action listener: _resource_record_pre_action 02:29:54 INFO - Running main action method: run_tests 02:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:54 INFO - Python 2.7.3 02:29:54 INFO - Return code: 0 02:29:54 INFO - grabbing minidump binary from tooltool 02:29: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')]} 02:29:54 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 0x2005f60>, '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 0x2006d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20071d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:54 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 02:29:54 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 02:29:54 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 02:29:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:54 INFO - Return code: 0 02:29:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 02:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 02:29:54 INFO - Using env: {'DISPLAY': ':0', 02:29:54 INFO - 'HOME': '/home/cltbld', 02:29:54 INFO - 'LANG': 'en_US.UTF-8', 02:29:54 INFO - 'LANGUAGE': 'en_US:en', 02:29:54 INFO - 'LOGNAME': 'cltbld', 02:29:54 INFO - 'MAIL': '/var/mail/cltbld', 02:29:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:54 INFO - 'MOZ_NO_REMOTE': '1', 02:29:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:54 INFO - 'NO_EM_RESTART': '1', 02:29:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:54 INFO - 'PWD': '/builds/slave/test', 02:29:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:54 INFO - 'SHELL': '/bin/bash', 02:29:54 INFO - 'SHLVL': '1', 02:29:54 INFO - 'TERM': 'linux', 02:29:54 INFO - 'TMOUT': '86400', 02:29:54 INFO - 'USER': 'cltbld', 02:29:54 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317', 02:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:54 INFO - '_': '/tools/buildbot/bin/python'} 02:29:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 02:29:54 INFO - mozversion application_buildid: 20170105004018 02:29:54 INFO - mozversion application_changeset: 5af63cf90f2874f840c132461bff7f6130483362 02:29:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:54 INFO - mozversion application_name: Firefox 02:29:54 INFO - mozversion application_remotingname: firefox-dev 02:29:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:54 INFO - mozversion application_vendor: Mozilla 02:29:54 INFO - mozversion application_version: 52.0a2 02:29:54 INFO - mozversion platform_buildid: 20170105004018 02:29:54 INFO - mozversion platform_changeset: 5af63cf90f2874f840c132461bff7f6130483362 02:29:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:54 INFO - mozversion platform_version: 52.0a2 02:29:54 INFO - using testdate: 1483612194 02:29:54 INFO - actual date: 1483612194 02:29:54 INFO - starting webserver on 'localhost:38266' 02:29:54 INFO - SUITE-START | Running 2 tests 02:29:54 INFO - TEST-START | dromaeo_css 02:29:54 INFO - operating with platform_type : linux_ 02:29:54 INFO - Initialising browser for dromaeo_css test... 02:29:54 INFO - TEST-INFO | started process 15502 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAi7rqI/profile http://localhost:38266/getInfo.html) 02:29:58 INFO - PROCESS | 15502 | __metrics Screen width/height:1600/1200 02:29:58 INFO - PROCESS | 15502 | colorDepth:24 02:29:58 INFO - PROCESS | 15502 | Browser inner width/height: 1024/697 02:29:58 INFO - PROCESS | 15502 | __metrics 02:29:59 INFO - PROCESS | 15502 | 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" 02:29:59 INFO - PROCESS | 15502 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:29:59 INFO - PROCESS | 15502 | console.error: PushService: 02:29:59 INFO - PROCESS | 15502 | stateChangeProcessEnqueue: Error transitioning state 02:29:59 INFO - PROCESS | 15502 | UnknownError 02:30:00 INFO - TEST-INFO | 15502: exit 0 02:30:00 INFO - Browser initialized. 02:30:00 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:00 INFO - TEST-INFO | started process 15596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAi7rqI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:12 INFO - PROCESS | 15596 | RSS: Main: 144416768 02:30:12 INFO - PROCESS | 15596 | 02:33:14 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38266/tests/dromaeo/cssquery-ext.html) 02:33:15 INFO - PROCESS | 15596 | RSS: Main: 235970560 02:33:15 INFO - PROCESS | 15596 | 02:36:18 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-ext.html (next: http://localhost:38266/tests/dromaeo/cssquery-jquery.html) 02:36:18 INFO - PROCESS | 15596 | RSS: Main: 227258368 02:36:18 INFO - PROCESS | 15596 | 02:39:20 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38266/tests/dromaeo/cssquery-mootools.html) 02:39:21 INFO - PROCESS | 15596 | RSS: Main: 225370112 02:39:21 INFO - PROCESS | 15596 | 02:42:23 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38266/tests/dromaeo/cssquery-prototype.html) 02:42:24 INFO - PROCESS | 15596 | RSS: Main: 175398912 02:42:24 INFO - PROCESS | 15596 | 02:45:26 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38266/tests/dromaeo/cssquery-yui.html) 02:45:27 INFO - PROCESS | 15596 | RSS: Main: 174227456 02:45:27 INFO - PROCESS | 15596 | 02:48:29 INFO - PROCESS | 15596 | Cycle 1(1): loaded http://localhost:38266/tests/dromaeo/cssquery-yui.html (next: http://localhost:38266/tests/dromaeo/cssquery-dojo.html) 02:48:30 INFO - PROCESS | 15596 | RSS: Main: 258199552 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | __start_tp_report 02:48:30 INFO - PROCESS | 15596 | _x_x_mozilla_page_load 02:48:30 INFO - PROCESS | 15596 | _x_x_mozilla_page_load_details 02:48:30 INFO - PROCESS | 15596 | |i|pagename|runs| 02:48:30 INFO - PROCESS | 15596 | |0;dojo.html;3212.6947939945703;3261.8532166052532;3262.673732626736;3272.541844141819;3288.7697861149727;5529.391766905637;5590.049691552435;5638.548916086714;5638.633488823224;5652.886942261158;5602;5630.71846407679;5637.041702910505;5646.661200327983;5652.06740887753;5061.949380506194;5096.439391666932;5112.744362781865;5173.327467429229;5185.688858668473;5045.697258164503;5184.585233181355;5189.558887494568;5214.374275086995;5216.087184742674;4982.97848940968;5021.748912554376;5052.747362631853;5070.062038522885;5112.156494178456;5044.924326135111;5086.160783470723;5104.744762761884;5195.610329225323;5231.764570594332;4973.925391119136;5050.797968081272;5107.872303192413;5121.769520371555;5123.308353372285;5403.108487099624;5419.864503387407;5454.290942177531;5485.424030476783;5500.889982200373;5827.417258274181;5841.678707671076;5847.532197424196;5948.672822994734;5950.940490595125;6048.092786082079;6049.092636104576;6050.304215015286;6063.211782468295;6076.3316035236085;5454.836354909359;5525.171224316345;5530.6128570999645;5554;5619.100943848965;6050.969745151334;6182.041783523471;6185.597936134137;6185.907211391833;6187.257529096537;5941.910871336933;5948.791792287249;5955.404459554096;5957.672328021914;5970.0746384310505;5797.710114494258;5809.3609702932645;6015.639061656315;6018;6035.336113027563;5872.471477567039;5924.7037648118285;5939.287285525765;6022.69886505673;6033.9698301508215;6017.939820601826;6044.637321760709;6052.364501727343;6058.212432383762;6063.029915213545;4509.6392288616835;4623.190941585209;4640.048789998043;4652.464966528825;4659.930101048487;4039.2729308724124;4081.0409553754844;4119.237940980928;4123.154753275571;4180.226658068267;5598.020346439357;5600.103983362724;5612.747426365823;5617.662940223599;5622.044252477089;5774.942250577525;6050.42520960508;6069.332373438918;6075.513958883279;6080.847978800565;5487.5884308677005;5589.8602534936135;5598.10430331145;5643.492085712305;5729.369769325371;5176.767045482887;5184.170532714747;5187;5190.37715474138;5191.870203244949;6210.689465526705;6221.471174949999;6223.315435302113;6226.6575338355915;6227.346128656426;5880.735366908414;5882.970585147047;5938.584299099021;5997.850053748692;6005.339412664516;6220.844478888064;6224.253089629274;6228.719707613259;6229.345918678474;6236.532260080603;5951.702414879325;5965.970170149121;5972;5976.133460648269;5976.372480889618;5785.681787501728;5790.449907258803;5796.014677504919;5836.970815145897;6081.939180608138;5780.248567686174;5785.450382213683;5786.797462088806;5789.073748200268;5817.127430885401;5775.133729940542;5777.24895763548;5779.884402311846;5780;5785.942140578541;5724.112762521816;5734.369219385948;5741.483266506035;5746.885062298814;5747.224124743107;5881.176635271151;5910.615809972338;5946.762129514771;5962.046072628532;5991.041433370639;5780.161876527964;5793.246877905845;5796.565257605781;5812.709364531672;5829.970850145722 02:48:30 INFO - PROCESS | 15596 | |1;ext.html;568.8122919436586;591.5297338615801;624.821925751161;624.906264060391;646.4505170604989;33684.32631347371;44204.77897610512;45514;45663.54336456634;45740.54259457404;45700;45908.311375329315;46057.53942460574;46079.308810367875;46083;8810.999999999984;8955.507447090407;8956.596953137092;8963.910360896372;8964.282857371394;8883.24492418142;8967.95516022419;8987.415817971843;8988.325875559327;8995.190432861042;8958.731238062868;8983.820323593523;8985.730428087136;9044.728658140266;9064.138906803875;8932.732018039436;8991.595378207996;8993;8999.5050272235;9003.909960900439;8828.293736501064;8881.156290152425;8893.4663920165;8896.644134234688;8898.021217666052;43554;43927;44053.55946440527;44065.559344406465;44131.55868441307;3670.229251857113;3768.171002379472;3885.5337359516757;4062.9796851015853;4383.145286669101;33400.33199336024;33425;33526.83236583826;33826.83086584551;35066.82466587676;3865.1110244643887;3885.9611403886165;3978.5424676162324;4000.819963101638;4100.302948498763;345630.9999999975;346754;346903;346943;347941;38553.22893542113;64014.359856401774;64385;64397.35602644007;64462.355376446576;38984.41523377151;41586.584134158875;41597;41598.1680366391;41608;15593.376264949502;15684;15687.921560392126;15688.921555392379;15689.529314120595;12930.612081637566;12939.417726202344;12953;12965.740685186243;12989;9591.51990981996;9600.807983840285;9619.663311784054;9678.75803104928;9703.6118555257;3567.7680950738118;3592.1558433768046;3613.8735144269826;3627.582827974764;3668.743187976869;1039.5010395010438;1057.370864335719;1061.4321338084087;1071.8338657508098;1124.966251012471;32706.83646581752;34706;34822;34829.82585087109;35023.29953400918;1137.374444055766;1159.0206275697024;1178.247282405524;1225.9877401225874;1238.6531771104105;17195.484135475956;18095.36666216676;18136.183871725803;18365.90817045933;18374.448766537025;509431;511788;511879;512266;512829;62738;62776.68611657004;62941.37058629447;62981.68509157425;63450;1272.853621833501;1278.2266728629231;1287.201934800411;1316.9802452963213;1357.864213578654;30872.53691194589;33644.32711345809;36534.26931461409;36535.45196822103;36637.45043824398;54375;55554;55656;55661.443385565624;55745;447917;452507;454236;454291;454599;39698.40452393159;39877.2024559513;40648;47310.76344618255;47705.28442073302;51225.23162152496;51523.742381289354;52134.21798672947;52292.47707523028;52616.47383526116;42657.66167821974;43822.78088609537;43898.34152487779;44111.33832992572;45132.77433612811;42983.355249671906;43675;43762;45050;45292.54707452883;43782.34326485042;44218;45111.774441127585;45119;45624.543754563354;35950.820245898605;38207.235855283296;38511;39396.60603394044;39511.60488395079;29527;29535.26161845971;29951.251218719706;30150.396992059596;30159.547606786353 02:48:30 INFO - PROCESS | 15596 | |2;jquery.html;396.4924896132951;416.3775156141569;422.2715815218748;425.638207523605;446.3527884567376;15083.924580377097;19600.215991360346;20012.19951201952;21058.157673693073;21210.57578848422;20071.698924516142;20347.796522034776;20367.898160509212;21013.68479472809;21237.256696015644;5840;5857.765689372431;6082.635041897501;6088.786892458765;6091.847703807396;5856.209411729405;6001.129836173752;6008.128821320905;6008.879822403549;6011.699415029253;5790.913136302959;5905.9409405906035;5969.432903874125;5978.8206353809455;5982.132590774335;5924.6445213287125;5962.850928726773;5965.582409231356;5969.5821292509545;5970;5229.2156176573435;5332.893342133174;5406.405295417501;5409.486098820606;5411.053065713484;24634.13780517673;24900.875495622407;24929.62605560918;24944.750552494424;25131;2489.3029951613576;2495.7753802157895;2538.6572812670424;2561.2700380391498;2660.361513236809;14877.553673389815;15488.225588720632;15635.687286254324;15737.68524629501;16430.424935127332;1991.2134706790737;2013.7180794688754;2018.606371757508;2070.8446866484924;2098.779628139054;78075.21924780736;89684.5515772417;90089;90188.54905725495;90878;10419.06228439444;10501.002404771534;10782.35305881634;10786.946065269623;11280.605178818701;15563.766543501857;28177.57733633997;32708.67291327104;33032.50451243233;34594.48108278378;9834.950825245827;10183.592656293813;10264.76822781271;10495.26533142688;10592.894071059345;8188.50868947865;8612.397132200686;8654.87017694735;8966.86549701755;9138.223251023604;9140.54297285133;9142.588583513625;9258.444493330288;9276.675316363895;9374;3396.7792093513845;3404.5233667286598;3450.6204317525053;3451.87918422854;3534.3991521441417;921.3136213520836;981.1856159387692;990.7077412163285;1042.2756184451732;1044.5978298355187;19451.51371215731;20410.727999920196;20667.17331306731;21027.68458473124;21295.657477677443;937.540605103508;968.6754937096151;973.7030205787162;977.8582105594792;1051.6529545250048;10880.564777408816;11381.146414018816;11473.311601303949;11724.355160466086;11760.470778814974;347311;347973;350250;444613;446502;99627;101224.49387753014;102560;102715;102720;1038.5222797513077;1055.8152323343384;1056.5351245451977;1088.0479580367178;1091.2088735666737;18306;18524.81475185285;18687.813121868607;18710;19002.809971900104;30117.247068823453;37311.626883730816;41163.1767364657;43478.56521434872;43794.124117518106;59470;60184.69907650434;60939;61515.38484615096;62738;9051.59267832936;9284.30367722437;9304.72085837426;9339.486328252011;9564.091411315914;10612.946935265274;13114;13903.304834758424;13924.373403196678;14201.360938757987;11559.479823407799;11680.299182048764;11727.76544469123;11868;12080.697982550506;11394.886051139383;11457.312561246352;11599.942000289944;11799.646010619696;11877.346745928711;12131.636050918487;12272.509099635914;12407.627771166879;12439.75120497567;12918.28949407754;9161.31290153228;9222.95388523053;9246.427132403878;9342.065793420876;9361.906380936103;11538.134639901873;11546.307221566733;12024.038076953997;12025.338606376734;12098.697532561757 02:48:30 INFO - PROCESS | 15596 | |3;mootools.html;1372.8627137286278;1514.8030756001717;1518.7645914883192;1693.1280390598836;1845.7508236388085;7925.088614809291;8291.46105503142;8811.206991370784;8840.38117331787;9064.81870362594;8852.380333376666;8892.510911899843;8904.332175086878;8909.24271436927;8946.060663630322;2454.3250606083293;2472.208893154191;2481.9751802482015;2484.3292311075998;2486.0677246032737;2434.2332165367943;2513.7234904160528;2518.508890766292;2518.5970244760842;2518.7607177318127;2420.9636855447184;2434.500927309897;2453.766892145243;2457.103157347573;2457.938551536208;2377.2036367816804;2402.627592723122;2407.38611654028;2413.987930060356;2437.171361737009;2382.02337041812;2405;2407.711074671033;2410.626352915301;2412.819038572114;8739.082396348418;8811.074837142072;8826.161514656098;8837.955810220908;8863.778405539913;5295.391030031557;5580.051391263495;5581.190727344512;5589.80435684749;5605.075162598167;3348.665133486656;3403.8298085095894;3435.1412146963257;3451.87918422854;3647.288778688157;1061.4904845674043;1119.457063324287;1123.7303047268679;1130.711668524521;1146.8623765148152;12668.113232073758;12732.236065835987;12750.489980400773;12818.230906145662;12824.87175128246;4371.934420983688;4660.883477913079;4695.882602934886;4742.928856067162;4775.546323099369;9866;9936.652217172365;9937.602495900228;9945.751356216153;9946.950265248628;5435.42927992563;5440.537554307849;5444.455554444524;5452;5456.48163424474;4313.374560688682;4442.955570444319;4577.06170235101;5098.490150984946;5118.513741194581;3841.5390153181797;3858.093348063203;3952.1107750756423;3955.4264631628253;3957.08986933007;1248.5754843353122;1270.720441502868;1276.942537585811;1280.4110109349617;1280.801475771257;893.2184338703635;917.5733284022828;980.9558569864373;988.9357191782511;1018.4500369800224;6043.063325184604;6070.605410648293;6160.568760186834;6278.246610406781;6291.1192433059405;927.5084205371164;958.8034452937131;967.351874244258;970.6699722094522;984.7390441532999;4068.633822955889;4099.97950010248;4337.982837980354;4340.804663790137;4391.8462853799965;10624.203184761174;11044.171687123498;11206;11236.55053797839;11249.943750281196;10868.293560918515;11114.88851114882;11138.33170009802;11213.551457941754;11229.943850280697;8443.57782110892;8504.44721093127;8656.870146947675;8661.610227539775;8662.393632445668;5991.610545314541;6086.087086936818;6090.512758979183;6092.817215483543;6131.540134489842;10053.497325133569;10115.291929564859;10189.74525636865;10222.284440089123;10232.130268927225;9080.682176123804;9408.059194080537;10353.792924141626;10453;10464.63373781914;7336.082989626297;7537.095548534211;7550.697972081275;7657;7750.302472777477;8720.127987201482;8720.563971801512;8746.037935827182;8799;8799.296056315607;7859.09620393662;7864.6854125834325;7964.800879977815;7997.360211182975;8101.635426405944;7655.540667559741;7708.845823083395;7895.171007044178;7917.564533950688;8139.1453897339925;7254.238304978113;7304.853902922018;7373.520721153108;7391.704331826667;7393.482456228227;5269.631125821155;5393.622446428869;5408.75660595282;5423.403425623257;5497.752601133038;5346.1713434417725;5483.2049352844415;5490.752916118864;5533.501984821385;5556.888862222652 02:48:30 INFO - PROCESS | 15596 | |4;prototype.html;344.74661124073816;350.20852872508124;352.7689363466929;353.55981802655697;354.655983695815;5394.518521480119;5400.513953744162;5411.783528658849;5417.945820541793;5418.674879507227;5301.655392399492;5325.334333208349;5344.144936810111;5391.865203369918;5392;3958.772780438059;4012.1574469361394;4180.602842729951;4214.494260688722;4258.276093064186;4233.555476674951;4240.703150779446;4244;4276.18752437036;4284.7429154250685;4214.304639734441;4219.198352313051;4220.978895105535;4222.9788851055855;4239.364095385686;3977.423273625337;4048.3320252158505;4184.8744537663915;4195.622393984556;4244.61798438139;3737.6636102750613;3750.13746838226;3752.4558938953974;3764.661180493769;3765.058735316171;5218.76515556797;5219.686818790847;5224.4775522447835;5228.895422091575;5244.213367994793;4749.978754567785;4789.664723469358;4812.72462797359;4846.345743324641;4866.853994380174;5567.548120165523;5609.859753506195;5611.186377975171;5613.550915926716;5639.577031722637;4159.251334759742;4194.8112334945;4197.223513649985;4202.919554820383;4206.47419072616;5698.8290351289525;5757.251557297524;5777.832817337461;5791.739371728282;5806.941930580682;4267.573242675708;4270.594293542109;4271.743695378287;4275.520198284988;4299.505556860939;4357.520672725997;4363.869083927487;4364.934525982113;4366.366876802846;4376.496702879146;4745.982957243158;4771.594414474739;4824.817919609718;4894.486078961729;4914.115459217303;5574.275344205308;5590.692511911884;5593.804216852391;5596.244506991586;5597.552195824324;1440.2510694438834;1457.9125252484885;1457.921138357629;1471.3820195517908;1478.290420598119;949.5489642419795;949.7293271417612;988.8763904511936;1005.4721271332609;1039.5529922133555;5049.772760225798;5294.92057619136;5441.782328706887;5467.890642187134;5487.670739755627;5733.627314224562;5749.080147176432;5762.538996880239;5770.88458230833;5775.5090817281;5375.220593013991;5428.565714742811;5448.5016620428905;5500.642458240201;5527.894421115793;2933.7066293370917;2934.427786581639;2948.631421072366;2949.7935144539674;2979.8676502928747;5686.815383385318;5710.2862142232225;5712.400197979236;5757.65454072757;5761.53907687384;5424.321959755031;5569.443055694479;5624.746886390122;5630.23991761107;5655.3779084300695;5595.776168953196;5611.663300202001;5640.689762063043;5683.062294721325;5690.829275121753;5264.578833693219;5528;5541.722913854372;5561.749721262472;5561.8320152766755;5443.945560544344;5465.918011229755;5538.750756215899;5545.029619816435;5555;5683.4370548099605;5699.373068962494;5718.1708652246025;5728.971355143198;5746.913796292975;3946.723729338919;4109.56849530795;4110.588941105985;4153.460050193456;4192.161567686414;5770;5778.393268706725;5790.913136303043;5803.912941305799;5815.680137592473;5660.632058916157;5668.971655141863;5693.373728889736;5697.088465845445;5701.201831743642;5423.345879506631;5544.833654990357;5580.107182850725;5587.832365029056;5609.467100625434;5564.276644036249;5575.275214222125;5581.74882130297;5588.832335029955;5591.21722958778;5449.2834757051405;5549.1676248563035;5579.81950288348;5668.971655141698;5698.230738850368;5537.224788529689;5542.168674698827;5545.694986775766;5556.166575013619;5556.694381809039 02:48:30 INFO - PROCESS | 15596 | |5;yui.html;779.8908152858604;800.16782546152;806.4999700185886;815.0586073085589;861.8577934640784;15493.380264789408;16142.273597688103;16389.180540972964;16608.00351978882;16653.833461665396;13251.138675986078;13456.394462249198;14840.332185051671;15069.246537673127;15109.697806043872;834.9081601023883;844.4215712237107;844.4468872888268;849.6389034660244;849.7068511363569;863.8574635185219;865.1953683074738;866.138192498465;866.8309679612478;867.2368315882015;896.4621227263655;900.2168113741021;900.2258058070055;900.3157600223811;900.3292547052455;886.4016788667624;889.5596679643553;890.4345740454793;890.7105190813012;890.8173824366022;653.137858027404;654.89521676532;655.203927228415;655.2595567009249;655.6033599672224;13980.650483737887;14007.509737159253;15068.924655376763;15522.379104835793;15649.921750391175;14510.927445362706;14523.128612283192;14755.409783608642;14891.553253402415;14966;12134;12174.57388991381;12227.633171004794;12230.694232644255;12339.876601233962;3842.1931394407206;3886.941695874564;3914.354131568288;3961.643452089297;3963.7621742695533;65866;68663;69369.95945060879;69473.2210711571;70120;11682.70793230176;11828.23116497425;11902.702432439086;11940.701482462835;12197.573084942162;21182.258620948192;21355.35933921985;21479.57040859205;21494.89252553727;21824.672629910565;8410;8610.87083693745;8646.178613031752;8832.160944710242;8996.325275604226;4957.355543779286;5049.509364427565;5121.4878512149235;5131.538161565477;5160.638755287094;3579.1052236940764;3708.332500149999;3708.49935258742;3710.424884142962;3710.6103859094947;636.8662580857979;662.5494663628682;664.5613894829389;670.5339788846802;675.5676367125044;675.7398401615413;709.521073275537;727.7707522130512;749.5053264845176;762.6186906546727;9373.625054997723;9503.66737164196;9771.31600787938;9786.41281523111;9925;620.9441150296404;702.6802804723839;722.6639612580176;749.93047002264;785.1441928298068;4546.636269098464;4724.574788269072;4758.809647614056;4796.496367881392;4828.61371090312;93112;93777.53111234537;94068;95068.52465737765;97531.51234243925;43956.78021609935;47762;48472.27291590629;50383.49616503861;81587.5920620405;58581.70709146427;68806.31193688099;69191.65404173048;69298.3070169302;70647.29352706509;9680.322377433793;9933.602655893683;10049.447280399649;10125.797484050425;10202.489875506046;46184;46309;47077.764611176724;48103.6706696399;48788;62628.68685656542;64399;65029;65558;67792.6610366945;12286.569970051005;12304.260584243057;12759.106862519906;12798.424070917017;13386;14560.271986400427;14647.414103435744;14922.701545968803;15382.23088845531;15846.920765396098;13654.249016304198;14082.155070695791;14397.568072957829;14862.925685371503;15083.622909426913;12113.273203607812;12193.634190974284;12349.753004939672;12402.76899954704;12563.309018004098;13471.461141554228;13477.46090156422;13539.661508462368;13791.724165516433;14234.715305694035;11366.886331136582;11392.145589080686;11604.30374177552;11844;12261.938690306848;12079.154459187772;12275;12316.50733970667;12393.442295096922;12486.750264994467 02:48:30 INFO - PROCESS | 15596 | __end_tp_report 02:48:30 INFO - PROCESS | 15596 | __start_cc_report 02:48:30 INFO - PROCESS | 15596 | _x_x_mozilla_cycle_collect,1016 02:48:30 INFO - PROCESS | 15596 | __end_cc_report 02:48:30 INFO - PROCESS | 15596 | __startTimestamp1483613310026__endTimestamp 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | ------- Summary: start ------- 02:48:30 INFO - PROCESS | 15596 | Number of tests: 6 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#0] dojo.html Cycles:165 Average:5559.10 Median:5777.25 stddev:624.38 (10.8%) stddev-sans-first:598.55 02:48:30 INFO - PROCESS | 15596 | Values: 3212.7 3261.9 3262.7 3272.5 3288.8 5529.4 5590.0 5638.5 5638.6 5652.9 5602.0 5630.7 5637.0 5646.7 5652.1 5061.9 5096.4 5112.7 5173.3 5185.7 5045.7 5184.6 5189.6 5214.4 5216.1 4983.0 5021.7 5052.7 5070.1 5112.2 5044.9 5086.2 5104.7 5195.6 5231.8 4973.9 5050.8 5107.9 5121.8 5123.3 5403.1 5419.9 5454.3 5485.4 5500.9 5827.4 5841.7 5847.5 5948.7 5950.9 6048.1 6049.1 6050.3 6063.2 6076.3 5454.8 5525.2 5530.6 5554.0 5619.1 6051.0 6182.0 6185.6 6185.9 6187.3 5941.9 5948.8 5955.4 5957.7 5970.1 5797.7 5809.4 6015.6 6018.0 6035.3 5872.5 5924.7 5939.3 6022.7 6034.0 6017.9 6044.6 6052.4 6058.2 6063.0 4509.6 4623.2 4640.0 4652.5 4659.9 4039.3 4081.0 4119.2 4123.2 4180.2 5598.0 5600.1 5612.7 5617.7 5622.0 5774.9 6050.4 6069.3 6075.5 6080.8 5487.6 5589.9 5598.1 5643.5 5729.4 5176.8 5184.2 5187.0 5190.4 5191.9 6210.7 6221.5 6223.3 6226.7 6227.3 5880.7 5883.0 5938.6 5997.9 6005.3 6220.8 6224.3 6228.7 6229.3 6236.5 5951.7 5966.0 5972.0 5976.1 5976.4 5785.7 5790.4 5796.0 5837.0 6081.9 5780.2 5785.5 5786.8 5789.1 5817.1 5775.1 5777.2 5779.9 5780.0 5785.9 5724.1 5734.4 5741.5 5746.9 5747.2 5881.2 5910.6 5946.8 5962.0 5991.0 5780.2 5793.2 5796.6 5812.7 5830.0 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#1] ext.html Cycles:180 Average:60516.26 Median:24102.96 stddev:117119.81 (485.9%) stddev-sans-first:117361.88 02:48:30 INFO - PROCESS | 15596 | Values: 568.8 591.5 624.8 624.9 646.5 33684.3 44204.8 45514.0 45663.5 45740.5 45700.0 45908.3 46057.5 46079.3 46083.0 8811.0 8955.5 8956.6 8963.9 8964.3 8883.2 8968.0 8987.4 8988.3 8995.2 8958.7 8983.8 8985.7 9044.7 9064.1 8932.7 8991.6 8993.0 8999.5 9003.9 8828.3 8881.2 8893.5 8896.6 8898.0 43554.0 43927.0 44053.6 44065.6 44131.6 3670.2 3768.2 3885.5 4063.0 4383.1 33400.3 33425.0 33526.8 33826.8 35066.8 3865.1 3886.0 3978.5 4000.8 4100.3 345631.0 346754.0 346903.0 346943.0 347941.0 38553.2 64014.4 64385.0 64397.4 64462.4 38984.4 41586.6 41597.0 41598.2 41608.0 15593.4 15684.0 15687.9 15688.9 15689.5 12930.6 12939.4 12953.0 12965.7 12989.0 9591.5 9600.8 9619.7 9678.8 9703.6 3567.8 3592.2 3613.9 3627.6 3668.7 1039.5 1057.4 1061.4 1071.8 1125.0 32706.8 34706.0 34822.0 34829.8 35023.3 1137.4 1159.0 1178.2 1226.0 1238.7 17195.5 18095.4 18136.2 18365.9 18374.4 509431.0 511788.0 511879.0 512266.0 512829.0 62738.0 62776.7 62941.4 62981.7 63450.0 1272.9 1278.2 1287.2 1317.0 1357.9 30872.5 33644.3 36534.3 36535.5 36637.5 54375.0 55554.0 55656.0 55661.4 55745.0 447917.0 452507.0 454236.0 454291.0 454599.0 39698.4 39877.2 40648.0 47310.8 47705.3 51225.2 51523.7 52134.2 52292.5 52616.5 42657.7 43822.8 43898.3 44111.3 45132.8 42983.4 43675.0 43762.0 45050.0 45292.5 43782.3 44218.0 45111.8 45119.0 45624.5 35950.8 38207.2 38511.0 39396.6 39511.6 29527.0 29535.3 29951.3 30150.4 30159.5 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#2] jquery.html Cycles:180 Average:27670.85 Median:2610.82 stddev:65381.73 (2504.3%) stddev-sans-first:65533.07 02:48:30 INFO - PROCESS | 15596 | Values: 396.5 416.4 422.3 425.6 446.4 15083.9 19600.2 20012.2 21058.2 21210.6 20071.7 20347.8 20367.9 21013.7 21237.3 5840.0 5857.8 6082.6 6088.8 6091.8 5856.2 6001.1 6008.1 6008.9 6011.7 5790.9 5905.9 5969.4 5978.8 5982.1 5924.6 5962.9 5965.6 5969.6 5970.0 5229.2 5332.9 5406.4 5409.5 5411.1 24634.1 24900.9 24929.6 24944.8 25131.0 2489.3 2495.8 2538.7 2561.3 2660.4 14877.6 15488.2 15635.7 15737.7 16430.4 1991.2 2013.7 2018.6 2070.8 2098.8 78075.2 89684.6 90089.0 90188.5 90878.0 10419.1 10501.0 10782.4 10786.9 11280.6 15563.8 28177.6 32708.7 33032.5 34594.5 9835.0 10183.6 10264.8 10495.3 10592.9 8188.5 8612.4 8654.9 8966.9 9138.2 9140.5 9142.6 9258.4 9276.7 9374.0 3396.8 3404.5 3450.6 3451.9 3534.4 921.3 981.2 990.7 1042.3 1044.6 19451.5 20410.7 20667.2 21027.7 21295.7 937.5 968.7 973.7 977.9 1051.7 10880.6 11381.1 11473.3 11724.4 11760.5 347311.0 347973.0 350250.0 444613.0 446502.0 99627.0 101224.5 102560.0 102715.0 102720.0 1038.5 1055.8 1056.5 1088.0 1091.2 18306.0 18524.8 18687.8 18710.0 19002.8 30117.2 37311.6 41163.2 43478.6 43794.1 59470.0 60184.7 60939.0 61515.4 62738.0 9051.6 9284.3 9304.7 9339.5 9564.1 10612.9 13114.0 13903.3 13924.4 14201.4 11559.5 11680.3 11727.8 11868.0 12080.7 11394.9 11457.3 11599.9 11799.6 11877.3 12131.6 12272.5 12407.6 12439.8 12918.3 9161.3 9223.0 9246.4 9342.1 9361.9 11538.1 11546.3 12024.0 12025.3 12098.7 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#3] mootools.html Cycles:180 Average:5904.85 Median:5320.78 stddev:3315.55 (62.3%) stddev-sans-first:3307.35 02:48:30 INFO - PROCESS | 15596 | Values: 1372.9 1514.8 1518.8 1693.1 1845.8 7925.1 8291.5 8811.2 8840.4 9064.8 8852.4 8892.5 8904.3 8909.2 8946.1 2454.3 2472.2 2482.0 2484.3 2486.1 2434.2 2513.7 2518.5 2518.6 2518.8 2421.0 2434.5 2453.8 2457.1 2457.9 2377.2 2402.6 2407.4 2414.0 2437.2 2382.0 2405.0 2407.7 2410.6 2412.8 8739.1 8811.1 8826.2 8838.0 8863.8 5295.4 5580.1 5581.2 5589.8 5605.1 3348.7 3403.8 3435.1 3451.9 3647.3 1061.5 1119.5 1123.7 1130.7 1146.9 12668.1 12732.2 12750.5 12818.2 12824.9 4371.9 4660.9 4695.9 4742.9 4775.5 9866.0 9936.7 9937.6 9945.8 9947.0 5435.4 5440.5 5444.5 5452.0 5456.5 4313.4 4443.0 4577.1 5098.5 5118.5 3841.5 3858.1 3952.1 3955.4 3957.1 1248.6 1270.7 1276.9 1280.4 1280.8 893.2 917.6 981.0 988.9 1018.5 6043.1 6070.6 6160.6 6278.2 6291.1 927.5 958.8 967.4 970.7 984.7 4068.6 4100.0 4338.0 4340.8 4391.8 10624.2 11044.2 11206.0 11236.6 11249.9 10868.3 11114.9 11138.3 11213.6 11229.9 8443.6 8504.4 8656.9 8661.6 8662.4 5991.6 6086.1 6090.5 6092.8 6131.5 10053.5 10115.3 10189.7 10222.3 10232.1 9080.7 9408.1 10353.8 10453.0 10464.6 7336.1 7537.1 7550.7 7657.0 7750.3 8720.1 8720.6 8746.0 8799.0 8799.3 7859.1 7864.7 7964.8 7997.4 8101.6 7655.5 7708.8 7895.2 7917.6 8139.1 7254.2 7304.9 7373.5 7391.7 7393.5 5269.6 5393.6 5408.8 5423.4 5497.8 5346.2 5483.2 5490.8 5533.5 5556.9 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#4] prototype.html Cycles:180 Average:4715.06 Median:5421.01 stddev:1353.04 (25.0%) stddev-sans-first:1316.47 02:48:30 INFO - PROCESS | 15596 | Values: 344.7 350.2 352.8 353.6 354.7 5394.5 5400.5 5411.8 5417.9 5418.7 5301.7 5325.3 5344.1 5391.9 5392.0 3958.8 4012.2 4180.6 4214.5 4258.3 4233.6 4240.7 4244.0 4276.2 4284.7 4214.3 4219.2 4221.0 4223.0 4239.4 3977.4 4048.3 4184.9 4195.6 4244.6 3737.7 3750.1 3752.5 3764.7 3765.1 5218.8 5219.7 5224.5 5228.9 5244.2 4750.0 4789.7 4812.7 4846.3 4866.9 5567.5 5609.9 5611.2 5613.6 5639.6 4159.3 4194.8 4197.2 4202.9 4206.5 5698.8 5757.3 5777.8 5791.7 5806.9 4267.6 4270.6 4271.7 4275.5 4299.5 4357.5 4363.9 4364.9 4366.4 4376.5 4746.0 4771.6 4824.8 4894.5 4914.1 5574.3 5590.7 5593.8 5596.2 5597.6 1440.3 1457.9 1457.9 1471.4 1478.3 949.5 949.7 988.9 1005.5 1039.6 5049.8 5294.9 5441.8 5467.9 5487.7 5733.6 5749.1 5762.5 5770.9 5775.5 5375.2 5428.6 5448.5 5500.6 5527.9 2933.7 2934.4 2948.6 2949.8 2979.9 5686.8 5710.3 5712.4 5757.7 5761.5 5424.3 5569.4 5624.7 5630.2 5655.4 5595.8 5611.7 5640.7 5683.1 5690.8 5264.6 5528.0 5541.7 5561.7 5561.8 5443.9 5465.9 5538.8 5545.0 5555.0 5683.4 5699.4 5718.2 5729.0 5746.9 3946.7 4109.6 4110.6 4153.5 4192.2 5770.0 5778.4 5790.9 5803.9 5815.7 5660.6 5669.0 5693.4 5697.1 5701.2 5423.3 5544.8 5580.1 5587.8 5609.5 5564.3 5575.3 5581.7 5588.8 5591.2 5449.3 5549.2 5579.8 5669.0 5698.2 5537.2 5542.2 5545.7 5556.2 5556.7 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - PROCESS | 15596 | [#5] yui.html Cycles:180 Average:18010.47 Median:26279.25 stddev:23298.01 (88.7%) stddev-sans-first:23327.44 02:48:30 INFO - PROCESS | 15596 | Values: 779.9 800.2 806.5 815.1 861.9 15493.4 16142.3 16389.2 16608.0 16653.8 13251.1 13456.4 14840.3 15069.2 15109.7 834.9 844.4 844.4 849.6 849.7 863.9 865.2 866.1 866.8 867.2 896.5 900.2 900.2 900.3 900.3 886.4 889.6 890.4 890.7 890.8 653.1 654.9 655.2 655.3 655.6 13980.7 14007.5 15068.9 15522.4 15649.9 14510.9 14523.1 14755.4 14891.6 14966.0 12134.0 12174.6 12227.6 12230.7 12339.9 3842.2 3886.9 3914.4 3961.6 3963.8 65866.0 68663.0 69370.0 69473.2 70120.0 11682.7 11828.2 11902.7 11940.7 12197.6 21182.3 21355.4 21479.6 21494.9 21824.7 8410.0 8610.9 8646.2 8832.2 8996.3 4957.4 5049.5 5121.5 5131.5 5160.6 3579.1 3708.3 3708.5 3710.4 3710.6 636.9 662.5 664.6 670.5 675.6 675.7 709.5 727.8 749.5 762.6 9373.6 9503.7 9771.3 9786.4 9925.0 620.9 702.7 722.7 749.9 785.1 4546.6 4724.6 4758.8 4796.5 4828.6 93112.0 93777.5 94068.0 95068.5 97531.5 43956.8 47762.0 48472.3 50383.5 81587.6 58581.7 68806.3 69191.7 69298.3 70647.3 9680.3 9933.6 10049.4 10125.8 10202.5 46184.0 46309.0 47077.8 48103.7 48788.0 62628.7 64399.0 65029.0 65558.0 67792.7 12286.6 12304.3 12759.1 12798.4 13386.0 14560.3 14647.4 14922.7 15382.2 15846.9 13654.2 14082.2 14397.6 14862.9 15083.6 12113.3 12193.6 12349.8 12402.8 12563.3 13471.5 13477.5 13539.7 13791.7 14234.7 11366.9 11392.1 11604.3 11844.0 12261.9 12079.2 12275.0 12316.5 12393.4 12486.8 02:48:30 INFO - PROCESS | 15596 | -------- Summary: end -------- 02:48:30 INFO - PROCESS | 15596 | 02:48:30 INFO - TEST-INFO | 15596: exit 0 02:48:30 INFO - TEST-OK | dromaeo_css | took 1115951ms 02:48:30 INFO - TEST-START | kraken 02:48:30 INFO - operating with platform_type : linux_ 02:48:30 INFO - Initialising browser for kraken test... 02:48:30 INFO - TEST-INFO | started process 15911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHle2fK/profile http://localhost:38266/getInfo.html) 02:48:34 INFO - PROCESS | 15911 | __metrics Screen width/height:1600/1200 02:48:34 INFO - PROCESS | 15911 | colorDepth:24 02:48:34 INFO - PROCESS | 15911 | Browser inner width/height: 1024/697 02:48:34 INFO - PROCESS | 15911 | __metrics 02:48:35 INFO - PROCESS | 15911 | 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" 02:48:35 INFO - PROCESS | 15911 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:48:35 INFO - PROCESS | 15911 | console.error: PushService: 02:48:35 INFO - PROCESS | 15911 | stateChangeProcessEnqueue: Error transitioning state 02:48:35 INFO - PROCESS | 15911 | UnknownError 02:48:36 INFO - TEST-INFO | 15911: exit 0 02:48:36 INFO - Browser initialized. 02:48:36 INFO - Running cycle 1/1 for kraken test... 02:48:36 INFO - TEST-INFO | started process 16003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHle2fK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:48 INFO - PROCESS | 16003 | RSS: Main: 144486400 02:48:48 INFO - PROCESS | 16003 | 02:48:50 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38266/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:51 INFO - PROCESS | 16003 | RSS: Main: 184754176 02:48:51 INFO - PROCESS | 16003 | 02:48:54 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38266/tests/kraken/driver.html?testName=audio-dft) 02:48:54 INFO - PROCESS | 16003 | RSS: Main: 175239168 02:48:54 INFO - PROCESS | 16003 | 02:48:57 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38266/tests/kraken/driver.html?testName=audio-fft) 02:48:58 INFO - PROCESS | 16003 | RSS: Main: 266829824 02:48:58 INFO - PROCESS | 16003 | 02:49:00 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38266/tests/kraken/driver.html?testName=audio-oscillator) 02:49:00 INFO - PROCESS | 16003 | RSS: Main: 172974080 02:49:00 INFO - PROCESS | 16003 | 02:49:03 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:03 INFO - PROCESS | 16003 | RSS: Main: 175374336 02:49:03 INFO - PROCESS | 16003 | 02:49:06 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38266/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:07 INFO - PROCESS | 16003 | RSS: Main: 213577728 02:49:07 INFO - PROCESS | 16003 | 02:49:10 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38266/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:11 INFO - PROCESS | 16003 | RSS: Main: 213405696 02:49:11 INFO - PROCESS | 16003 | 02:49:13 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38266/tests/kraken/driver.html?testName=json-parse-financial) 02:49:14 INFO - PROCESS | 16003 | RSS: Main: 211128320 02:49:14 INFO - PROCESS | 16003 | 02:49:15 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:16 INFO - PROCESS | 16003 | RSS: Main: 173318144 02:49:16 INFO - PROCESS | 16003 | 02:49:18 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:18 INFO - PROCESS | 16003 | RSS: Main: 180580352 02:49:18 INFO - PROCESS | 16003 | 02:49:20 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:21 INFO - PROCESS | 16003 | RSS: Main: 190111744 02:49:21 INFO - PROCESS | 16003 | 02:49:23 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:24 INFO - PROCESS | 16003 | RSS: Main: 198885376 02:49:24 INFO - PROCESS | 16003 | 02:49:27 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:27 INFO - PROCESS | 16003 | RSS: Main: 194240512 02:49:27 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | Cycle 1(1): loaded http://localhost:38266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38266/tests/kraken/driver.html?testName=ai-astar) 02:49:29 INFO - PROCESS | 16003 | RSS: Main: 246398976 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | __start_tp_report 02:49:29 INFO - PROCESS | 16003 | _x_x_mozilla_page_load 02:49:29 INFO - PROCESS | 16003 | _x_x_mozilla_page_load_details 02:49:29 INFO - PROCESS | 16003 | |i|pagename|runs| 02:49:29 INFO - PROCESS | 16003 | |0;ai-astar;101;111;111;112;110;110;110;110;112;110 02:49:29 INFO - PROCESS | 16003 | |1;audio-beat-detection;153;118;124;153;118;119;117;117;150;117 02:49:29 INFO - PROCESS | 16003 | |2;audio-dft;161;160;162;160;161;162;160;161;160;161 02:49:29 INFO - PROCESS | 16003 | |3;audio-fft;75;75;74;106;75;75;75;74;100;74 02:49:29 INFO - PROCESS | 16003 | |4;audio-oscillator;82;82;115;82;84;82;105;82;81;115 02:49:29 INFO - PROCESS | 16003 | |5;imaging-gaussian-blur;117;117;118;117;117;118;118;117;116;117 02:49:29 INFO - PROCESS | 16003 | |6;imaging-darkroom;145;146;145;146;146;145;146;146;146;146 02:49:29 INFO - PROCESS | 16003 | |7;imaging-desaturate;84;84;84;84;84;85;84;85;84;84 02:49:29 INFO - PROCESS | 16003 | |8;json-parse-financial;50;51;50;50;50;49;51;49;51;49 02:49:29 INFO - PROCESS | 16003 | |9;json-stringify-tinderbox;59;59;58;60;60;59;60;59;61;60 02:49:29 INFO - PROCESS | 16003 | |10;stanford-crypto-aes;75;75;74;74;76;75;74;74;76;74 02:49:29 INFO - PROCESS | 16003 | |11;stanford-crypto-ccm;126;130;129;133;120;119;122;126;168;121 02:49:29 INFO - PROCESS | 16003 | |12;stanford-crypto-pbkdf2;154;151;157;151;157;158;155;150;157;152 02:49:29 INFO - PROCESS | 16003 | |13;stanford-crypto-sha256-iterative;70;62;63;64;62;62;62;66;62;61 02:49:29 INFO - PROCESS | 16003 | __end_tp_report 02:49:29 INFO - PROCESS | 16003 | __start_cc_report 02:49:29 INFO - PROCESS | 16003 | _x_x_mozilla_cycle_collect,1629 02:49:29 INFO - PROCESS | 16003 | __end_cc_report 02:49:29 INFO - PROCESS | 16003 | __startTimestamp1483613369663__endTimestamp 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | ------- Summary: start ------- 02:49:29 INFO - PROCESS | 16003 | Number of tests: 14 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:3.16 (2.9%) stddev-sans-first:0.87 02:49:29 INFO - PROCESS | 16003 | Values: 101.0 111.0 111.0 112.0 110.0 110.0 110.0 110.0 112.0 110.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:121.50 stddev:16.30 (13.4%) stddev-sans-first:14.70 02:49:29 INFO - PROCESS | 16003 | Values: 153.0 118.0 124.0 153.0 118.0 119.0 117.0 117.0 150.0 117.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 02:49:29 INFO - PROCESS | 16003 | Values: 161.0 160.0 162.0 160.0 161.0 162.0 160.0 161.0 160.0 161.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:12.06 (16.1%) stddev-sans-first:12.63 02:49:29 INFO - PROCESS | 16003 | Values: 75.0 75.0 74.0 106.0 75.0 75.0 75.0 74.0 100.0 74.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:82.00 stddev:14.54 (17.7%) stddev-sans-first:15.05 02:49:29 INFO - PROCESS | 16003 | Values: 82.0 82.0 115.0 82.0 84.0 82.0 105.0 82.0 81.0 115.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 02:49:29 INFO - PROCESS | 16003 | Values: 117.0 117.0 118.0 117.0 117.0 118.0 118.0 117.0 116.0 117.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 02:49:29 INFO - PROCESS | 16003 | Values: 145.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 02:49:29 INFO - PROCESS | 16003 | Values: 84.0 84.0 84.0 84.0 84.0 85.0 84.0 85.0 84.0 84.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 02:49:29 INFO - PROCESS | 16003 | Values: 50.0 51.0 50.0 50.0 50.0 49.0 51.0 49.0 51.0 49.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 02:49:29 INFO - PROCESS | 16003 | Values: 59.0 59.0 58.0 60.0 60.0 59.0 60.0 59.0 61.0 60.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:75.00 stddev:0.82 (1.1%) stddev-sans-first:0.87 02:49:29 INFO - PROCESS | 16003 | Values: 75.0 75.0 74.0 74.0 76.0 75.0 74.0 74.0 76.0 74.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:127.50 stddev:14.33 (11.2%) stddev-sans-first:15.15 02:49:29 INFO - PROCESS | 16003 | Values: 126.0 130.0 129.0 133.0 120.0 119.0 122.0 126.0 168.0 121.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:156.00 stddev:3.01 (1.9%) stddev-sans-first:3.19 02:49:29 INFO - PROCESS | 16003 | Values: 154.0 151.0 157.0 151.0 157.0 158.0 155.0 150.0 157.0 152.0 02:49:29 INFO - PROCESS | 16003 | 02:49:29 INFO - PROCESS | 16003 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:62.50 stddev:2.72 (4.3%) stddev-sans-first:1.50 02:49:29 INFO - PROCESS | 16003 | Values: 70.0 62.0 63.0 64.0 62.0 62.0 62.0 66.0 62.0 61.0 02:49:29 INFO - PROCESS | 16003 | -------- Summary: end -------- 02:49:29 INFO - PROCESS | 16003 | 02:49:30 INFO - TEST-INFO | 16003: exit 0 02:49:30 INFO - TEST-OK | kraken | took 59927ms 02:49:30 INFO - SUITE-END | took 1175s 02:49:30 INFO - Completed test suite (00:19:35) 02:49:30 INFO - javascript benchmark 02:49:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3212.6947939945703, 3261.8532166052532, 3262.673732626736, 3272.541844141819, 3288.7697861149727, 5529.391766905637, 5590.049691552435, 5638.548916086714, 5638.633488823224, 5652.886942261158, 5602.0, 5630.71846407679, 5637.041702910505, 5646.661200327983, 5652.06740887753, 5061.949380506194, 5096.439391666932, 5112.744362781865, 5173.327467429229, 5185.688858668473, 5045.697258164503, 5184.585233181355, 5189.558887494568, 5214.374275086995, 5216.087184742674, 4982.97848940968, 5021.748912554376, 5052.747362631853, 5070.062038522885, 5112.156494178456, 5044.924326135111, 5086.160783470723, 5104.744762761884, 5195.610329225323, 5231.764570594332, 4973.925391119136, 5050.797968081272, 5107.872303192413, 5121.769520371555, 5123.308353372285, 5403.108487099624, 5419.864503387407, 5454.290942177531, 5485.424030476783, 5500.889982200373, 5827.417258274181, 5841.678707671076, 5847.532197424196, 5948.672822994734, 5950.940490595125, 6048.092786082079, 6049.092636104576, 6050.304215015286, 6063.211782468295, 6076.3316035236085, 5454.836354909359, 5525.171224316345, 5530.6128570999645, 5554.0, 5619.100943848965, 6050.969745151334, 6182.041783523471, 6185.597936134137, 6185.907211391833, 6187.257529096537, 5941.910871336933, 5948.791792287249, 5955.404459554096, 5957.672328021914, 5970.0746384310505, 5797.710114494258, 5809.3609702932645, 6015.639061656315, 6018.0, 6035.336113027563, 5872.471477567039, 5924.7037648118285, 5939.287285525765, 6022.69886505673, 6033.9698301508215, 6017.939820601826, 6044.637321760709, 6052.364501727343, 6058.212432383762, 6063.029915213545, 4509.6392288616835, 4623.190941585209, 4640.048789998043, 4652.464966528825, 4659.930101048487, 4039.2729308724124, 4081.0409553754844, 4119.237940980928, 4123.154753275571, 4180.226658068267, 5598.020346439357, 5600.103983362724, 5612.747426365823, 5617.662940223599, 5622.044252477089, 5774.942250577525, 6050.42520960508, 6069.332373438918, 6075.513958883279, 6080.847978800565, 5487.5884308677005, 5589.8602534936135, 5598.10430331145, 5643.492085712305, 5729.369769325371, 5176.767045482887, 5184.170532714747, 5187.0, 5190.37715474138, 5191.870203244949, 6210.689465526705, 6221.471174949999, 6223.315435302113, 6226.6575338355915, 6227.346128656426, 5880.735366908414, 5882.970585147047, 5938.584299099021, 5997.850053748692, 6005.339412664516, 6220.844478888064, 6224.253089629274, 6228.719707613259, 6229.345918678474, 6236.532260080603, 5951.702414879325, 5965.970170149121, 5972.0, 5976.133460648269, 5976.372480889618, 5785.681787501728, 5790.449907258803, 5796.014677504919, 5836.970815145897, 6081.939180608138, 5780.248567686174, 5785.450382213683, 5786.797462088806, 5789.073748200268, 5817.127430885401, 5775.133729940542, 5777.24895763548, 5779.884402311846, 5780.0, 5785.942140578541, 5724.112762521816, 5734.369219385948, 5741.483266506035, 5746.885062298814, 5747.224124743107, 5881.176635271151, 5910.615809972338, 5946.762129514771, 5962.046072628532, 5991.041433370639, 5780.161876527964, 5793.246877905845, 5796.565257605781, 5812.709364531672, 5829.970850145722], "value": 5517.5669368154195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.8122919436586, 591.5297338615801, 624.821925751161, 624.906264060391, 646.4505170604989, 33684.32631347371, 44204.77897610512, 45514.0, 45663.54336456634, 45740.54259457404, 45700.0, 45908.311375329315, 46057.53942460574, 46079.308810367875, 46083.0, 8810.999999999984, 8955.507447090407, 8956.596953137092, 8963.910360896372, 8964.282857371394, 8883.24492418142, 8967.95516022419, 8987.415817971843, 8988.325875559327, 8995.190432861042, 8958.731238062868, 8983.820323593523, 8985.730428087136, 9044.728658140266, 9064.138906803875, 8932.732018039436, 8991.595378207996, 8993.0, 8999.5050272235, 9003.909960900439, 8828.293736501064, 8881.156290152425, 8893.4663920165, 8896.644134234688, 8898.021217666052, 43554.0, 43927.0, 44053.55946440527, 44065.559344406465, 44131.55868441307, 3670.229251857113, 3768.171002379472, 3885.5337359516757, 4062.9796851015853, 4383.145286669101, 33400.33199336024, 33425.0, 33526.83236583826, 33826.83086584551, 35066.82466587676, 3865.1110244643887, 3885.9611403886165, 3978.5424676162324, 4000.819963101638, 4100.302948498763, 345630.9999999975, 346754.0, 346903.0, 346943.0, 347941.0, 38553.22893542113, 64014.359856401774, 64385.0, 64397.35602644007, 64462.355376446576, 38984.41523377151, 41586.584134158875, 41597.0, 41598.1680366391, 41608.0, 15593.376264949502, 15684.0, 15687.921560392126, 15688.921555392379, 15689.529314120595, 12930.612081637566, 12939.417726202344, 12953.0, 12965.740685186243, 12989.0, 9591.51990981996, 9600.807983840285, 9619.663311784054, 9678.75803104928, 9703.6118555257, 3567.7680950738118, 3592.1558433768046, 3613.8735144269826, 3627.582827974764, 3668.743187976869, 1039.5010395010438, 1057.370864335719, 1061.4321338084087, 1071.8338657508098, 1124.966251012471, 32706.83646581752, 34706.0, 34822.0, 34829.82585087109, 35023.29953400918, 1137.374444055766, 1159.0206275697024, 1178.247282405524, 1225.9877401225874, 1238.6531771104105, 17195.484135475956, 18095.36666216676, 18136.183871725803, 18365.90817045933, 18374.448766537025, 509431.0, 511788.0, 511879.0, 512266.0, 512829.0, 62738.0, 62776.68611657004, 62941.37058629447, 62981.68509157425, 63450.0, 1272.853621833501, 1278.2266728629231, 1287.201934800411, 1316.9802452963213, 1357.864213578654, 30872.53691194589, 33644.32711345809, 36534.26931461409, 36535.45196822103, 36637.45043824398, 54375.0, 55554.0, 55656.0, 55661.443385565624, 55745.0, 447917.0, 452507.0, 454236.0, 454291.0, 454599.0, 39698.40452393159, 39877.2024559513, 40648.0, 47310.76344618255, 47705.28442073302, 51225.23162152496, 51523.742381289354, 52134.21798672947, 52292.47707523028, 52616.47383526116, 42657.66167821974, 43822.78088609537, 43898.34152487779, 44111.33832992572, 45132.77433612811, 42983.355249671906, 43675.0, 43762.0, 45050.0, 45292.54707452883, 43782.34326485042, 44218.0, 45111.774441127585, 45119.0, 45624.543754563354, 35950.820245898605, 38207.235855283296, 38511.0, 39396.60603394044, 39511.60488395079, 29527.0, 29535.26161845971, 29951.251218719706, 30150.396992059596, 30159.547606786353], "value": 19946.649931243035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.4924896132951, 416.3775156141569, 422.2715815218748, 425.638207523605, 446.3527884567376, 15083.924580377097, 19600.215991360346, 20012.19951201952, 21058.157673693073, 21210.57578848422, 20071.698924516142, 20347.796522034776, 20367.898160509212, 21013.68479472809, 21237.256696015644, 5840.0, 5857.765689372431, 6082.635041897501, 6088.786892458765, 6091.847703807396, 5856.209411729405, 6001.129836173752, 6008.128821320905, 6008.879822403549, 6011.699415029253, 5790.913136302959, 5905.9409405906035, 5969.432903874125, 5978.8206353809455, 5982.132590774335, 5924.6445213287125, 5962.850928726773, 5965.582409231356, 5969.5821292509545, 5970.0, 5229.2156176573435, 5332.893342133174, 5406.405295417501, 5409.486098820606, 5411.053065713484, 24634.13780517673, 24900.875495622407, 24929.62605560918, 24944.750552494424, 25131.0, 2489.3029951613576, 2495.7753802157895, 2538.6572812670424, 2561.2700380391498, 2660.361513236809, 14877.553673389815, 15488.225588720632, 15635.687286254324, 15737.68524629501, 16430.424935127332, 1991.2134706790737, 2013.7180794688754, 2018.606371757508, 2070.8446866484924, 2098.779628139054, 78075.21924780736, 89684.5515772417, 90089.0, 90188.54905725495, 90878.0, 10419.06228439444, 10501.002404771534, 10782.35305881634, 10786.946065269623, 11280.605178818701, 15563.766543501857, 28177.57733633997, 32708.67291327104, 33032.50451243233, 34594.48108278378, 9834.950825245827, 10183.592656293813, 10264.76822781271, 10495.26533142688, 10592.894071059345, 8188.50868947865, 8612.397132200686, 8654.87017694735, 8966.86549701755, 9138.223251023604, 9140.54297285133, 9142.588583513625, 9258.444493330288, 9276.675316363895, 9374.0, 3396.7792093513845, 3404.5233667286598, 3450.6204317525053, 3451.87918422854, 3534.3991521441417, 921.3136213520836, 981.1856159387692, 990.7077412163285, 1042.2756184451732, 1044.5978298355187, 19451.51371215731, 20410.727999920196, 20667.17331306731, 21027.68458473124, 21295.657477677443, 937.540605103508, 968.6754937096151, 973.7030205787162, 977.8582105594792, 1051.6529545250048, 10880.564777408816, 11381.146414018816, 11473.311601303949, 11724.355160466086, 11760.470778814974, 347311.0, 347973.0, 350250.0, 444613.0, 446502.0, 99627.0, 101224.49387753014, 102560.0, 102715.0, 102720.0, 1038.5222797513077, 1055.8152323343384, 1056.5351245451977, 1088.0479580367178, 1091.2088735666737, 18306.0, 18524.81475185285, 18687.813121868607, 18710.0, 19002.809971900104, 30117.247068823453, 37311.626883730816, 41163.1767364657, 43478.56521434872, 43794.124117518106, 59470.0, 60184.69907650434, 60939.0, 61515.38484615096, 62738.0, 9051.59267832936, 9284.30367722437, 9304.72085837426, 9339.486328252011, 9564.091411315914, 10612.946935265274, 13114.0, 13903.304834758424, 13924.373403196678, 14201.360938757987, 11559.479823407799, 11680.299182048764, 11727.76544469123, 11868.0, 12080.697982550506, 11394.886051139383, 11457.312561246352, 11599.942000289944, 11799.646010619696, 11877.346745928711, 12131.636050918487, 12272.509099635914, 12407.627771166879, 12439.75120497567, 12918.28949407754, 9161.31290153228, 9222.95388523053, 9246.427132403878, 9342.065793420876, 9361.906380936103, 11538.134639901873, 11546.307221566733, 12024.038076953997, 12025.338606376734, 12098.697532561757], "value": 10160.783192626714, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.8627137286278, 1514.8030756001717, 1518.7645914883192, 1693.1280390598836, 1845.7508236388085, 7925.088614809291, 8291.46105503142, 8811.206991370784, 8840.38117331787, 9064.81870362594, 8852.380333376666, 8892.510911899843, 8904.332175086878, 8909.24271436927, 8946.060663630322, 2454.3250606083293, 2472.208893154191, 2481.9751802482015, 2484.3292311075998, 2486.0677246032737, 2434.2332165367943, 2513.7234904160528, 2518.508890766292, 2518.5970244760842, 2518.7607177318127, 2420.9636855447184, 2434.500927309897, 2453.766892145243, 2457.103157347573, 2457.938551536208, 2377.2036367816804, 2402.627592723122, 2407.38611654028, 2413.987930060356, 2437.171361737009, 2382.02337041812, 2405.0, 2407.711074671033, 2410.626352915301, 2412.819038572114, 8739.082396348418, 8811.074837142072, 8826.161514656098, 8837.955810220908, 8863.778405539913, 5295.391030031557, 5580.051391263495, 5581.190727344512, 5589.80435684749, 5605.075162598167, 3348.665133486656, 3403.8298085095894, 3435.1412146963257, 3451.87918422854, 3647.288778688157, 1061.4904845674043, 1119.457063324287, 1123.7303047268679, 1130.711668524521, 1146.8623765148152, 12668.113232073758, 12732.236065835987, 12750.489980400773, 12818.230906145662, 12824.87175128246, 4371.934420983688, 4660.883477913079, 4695.882602934886, 4742.928856067162, 4775.546323099369, 9866.0, 9936.652217172365, 9937.602495900228, 9945.751356216153, 9946.950265248628, 5435.42927992563, 5440.537554307849, 5444.455554444524, 5452.0, 5456.48163424474, 4313.374560688682, 4442.955570444319, 4577.06170235101, 5098.490150984946, 5118.513741194581, 3841.5390153181797, 3858.093348063203, 3952.1107750756423, 3955.4264631628253, 3957.08986933007, 1248.5754843353122, 1270.720441502868, 1276.942537585811, 1280.4110109349617, 1280.801475771257, 893.2184338703635, 917.5733284022828, 980.9558569864373, 988.9357191782511, 1018.4500369800224, 6043.063325184604, 6070.605410648293, 6160.568760186834, 6278.246610406781, 6291.1192433059405, 927.5084205371164, 958.8034452937131, 967.351874244258, 970.6699722094522, 984.7390441532999, 4068.633822955889, 4099.97950010248, 4337.982837980354, 4340.804663790137, 4391.8462853799965, 10624.203184761174, 11044.171687123498, 11206.0, 11236.55053797839, 11249.943750281196, 10868.293560918515, 11114.88851114882, 11138.33170009802, 11213.551457941754, 11229.943850280697, 8443.57782110892, 8504.44721093127, 8656.870146947675, 8661.610227539775, 8662.393632445668, 5991.610545314541, 6086.087086936818, 6090.512758979183, 6092.817215483543, 6131.540134489842, 10053.497325133569, 10115.291929564859, 10189.74525636865, 10222.284440089123, 10232.130268927225, 9080.682176123804, 9408.059194080537, 10353.792924141626, 10453.0, 10464.63373781914, 7336.082989626297, 7537.095548534211, 7550.697972081275, 7657.0, 7750.302472777477, 8720.127987201482, 8720.563971801512, 8746.037935827182, 8799.0, 8799.296056315607, 7859.09620393662, 7864.6854125834325, 7964.800879977815, 7997.360211182975, 8101.635426405944, 7655.540667559741, 7708.845823083395, 7895.171007044178, 7917.564533950688, 8139.1453897339925, 7254.238304978113, 7304.853902922018, 7373.520721153108, 7391.704331826667, 7393.482456228227, 5269.631125821155, 5393.622446428869, 5408.75660595282, 5423.403425623257, 5497.752601133038, 5346.1713434417725, 5483.2049352844415, 5490.752916118864, 5533.501984821385, 5556.888862222652], "value": 4740.463206737469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.74661124073816, 350.20852872508124, 352.7689363466929, 353.55981802655697, 354.655983695815, 5394.518521480119, 5400.513953744162, 5411.783528658849, 5417.945820541793, 5418.674879507227, 5301.655392399492, 5325.334333208349, 5344.144936810111, 5391.865203369918, 5392.0, 3958.772780438059, 4012.1574469361394, 4180.602842729951, 4214.494260688722, 4258.276093064186, 4233.555476674951, 4240.703150779446, 4244.0, 4276.18752437036, 4284.7429154250685, 4214.304639734441, 4219.198352313051, 4220.978895105535, 4222.9788851055855, 4239.364095385686, 3977.423273625337, 4048.3320252158505, 4184.8744537663915, 4195.622393984556, 4244.61798438139, 3737.6636102750613, 3750.13746838226, 3752.4558938953974, 3764.661180493769, 3765.058735316171, 5218.76515556797, 5219.686818790847, 5224.4775522447835, 5228.895422091575, 5244.213367994793, 4749.978754567785, 4789.664723469358, 4812.72462797359, 4846.345743324641, 4866.853994380174, 5567.548120165523, 5609.859753506195, 5611.186377975171, 5613.550915926716, 5639.577031722637, 4159.251334759742, 4194.8112334945, 4197.223513649985, 4202.919554820383, 4206.47419072616, 5698.8290351289525, 5757.251557297524, 5777.832817337461, 5791.739371728282, 5806.941930580682, 4267.573242675708, 4270.594293542109, 4271.743695378287, 4275.520198284988, 4299.505556860939, 4357.520672725997, 4363.869083927487, 4364.934525982113, 4366.366876802846, 4376.496702879146, 4745.982957243158, 4771.594414474739, 4824.817919609718, 4894.486078961729, 4914.115459217303, 5574.275344205308, 5590.692511911884, 5593.804216852391, 5596.244506991586, 5597.552195824324, 1440.2510694438834, 1457.9125252484885, 1457.921138357629, 1471.3820195517908, 1478.290420598119, 949.5489642419795, 949.7293271417612, 988.8763904511936, 1005.4721271332609, 1039.5529922133555, 5049.772760225798, 5294.92057619136, 5441.782328706887, 5467.890642187134, 5487.670739755627, 5733.627314224562, 5749.080147176432, 5762.538996880239, 5770.88458230833, 5775.5090817281, 5375.220593013991, 5428.565714742811, 5448.5016620428905, 5500.642458240201, 5527.894421115793, 2933.7066293370917, 2934.427786581639, 2948.631421072366, 2949.7935144539674, 2979.8676502928747, 5686.815383385318, 5710.2862142232225, 5712.400197979236, 5757.65454072757, 5761.53907687384, 5424.321959755031, 5569.443055694479, 5624.746886390122, 5630.23991761107, 5655.3779084300695, 5595.776168953196, 5611.663300202001, 5640.689762063043, 5683.062294721325, 5690.829275121753, 5264.578833693219, 5528.0, 5541.722913854372, 5561.749721262472, 5561.8320152766755, 5443.945560544344, 5465.918011229755, 5538.750756215899, 5545.029619816435, 5555.0, 5683.4370548099605, 5699.373068962494, 5718.1708652246025, 5728.971355143198, 5746.913796292975, 3946.723729338919, 4109.56849530795, 4110.588941105985, 4153.460050193456, 4192.161567686414, 5770.0, 5778.393268706725, 5790.913136303043, 5803.912941305799, 5815.680137592473, 5660.632058916157, 5668.971655141863, 5693.373728889736, 5697.088465845445, 5701.201831743642, 5423.345879506631, 5544.833654990357, 5580.107182850725, 5587.832365029056, 5609.467100625434, 5564.276644036249, 5575.275214222125, 5581.74882130297, 5588.832335029955, 5591.21722958778, 5449.2834757051405, 5549.1676248563035, 5579.81950288348, 5668.971655141698, 5698.230738850368, 5537.224788529689, 5542.168674698827, 5545.694986775766, 5556.166575013619, 5556.694381809039], "value": 4289.097976934055, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8908152858604, 800.16782546152, 806.4999700185886, 815.0586073085589, 861.8577934640784, 15493.380264789408, 16142.273597688103, 16389.180540972964, 16608.00351978882, 16653.833461665396, 13251.138675986078, 13456.394462249198, 14840.332185051671, 15069.246537673127, 15109.697806043872, 834.9081601023883, 844.4215712237107, 844.4468872888268, 849.6389034660244, 849.7068511363569, 863.8574635185219, 865.1953683074738, 866.138192498465, 866.8309679612478, 867.2368315882015, 896.4621227263655, 900.2168113741021, 900.2258058070055, 900.3157600223811, 900.3292547052455, 886.4016788667624, 889.5596679643553, 890.4345740454793, 890.7105190813012, 890.8173824366022, 653.137858027404, 654.89521676532, 655.203927228415, 655.2595567009249, 655.6033599672224, 13980.650483737887, 14007.509737159253, 15068.924655376763, 15522.379104835793, 15649.921750391175, 14510.927445362706, 14523.128612283192, 14755.409783608642, 14891.553253402415, 14966.0, 12134.0, 12174.57388991381, 12227.633171004794, 12230.694232644255, 12339.876601233962, 3842.1931394407206, 3886.941695874564, 3914.354131568288, 3961.643452089297, 3963.7621742695533, 65866.0, 68663.0, 69369.95945060879, 69473.2210711571, 70120.0, 11682.70793230176, 11828.23116497425, 11902.702432439086, 11940.701482462835, 12197.573084942162, 21182.258620948192, 21355.35933921985, 21479.57040859205, 21494.89252553727, 21824.672629910565, 8410.0, 8610.87083693745, 8646.178613031752, 8832.160944710242, 8996.325275604226, 4957.355543779286, 5049.509364427565, 5121.4878512149235, 5131.538161565477, 5160.638755287094, 3579.1052236940764, 3708.332500149999, 3708.49935258742, 3710.424884142962, 3710.6103859094947, 636.8662580857979, 662.5494663628682, 664.5613894829389, 670.5339788846802, 675.5676367125044, 675.7398401615413, 709.521073275537, 727.7707522130512, 749.5053264845176, 762.6186906546727, 9373.625054997723, 9503.66737164196, 9771.31600787938, 9786.41281523111, 9925.0, 620.9441150296404, 702.6802804723839, 722.6639612580176, 749.93047002264, 785.1441928298068, 4546.636269098464, 4724.574788269072, 4758.809647614056, 4796.496367881392, 4828.61371090312, 93112.0, 93777.53111234537, 94068.0, 95068.52465737765, 97531.51234243925, 43956.78021609935, 47762.0, 48472.27291590629, 50383.49616503861, 81587.5920620405, 58581.70709146427, 68806.31193688099, 69191.65404173048, 69298.3070169302, 70647.29352706509, 9680.322377433793, 9933.602655893683, 10049.447280399649, 10125.797484050425, 10202.489875506046, 46184.0, 46309.0, 47077.764611176724, 48103.6706696399, 48788.0, 62628.68685656542, 64399.0, 65029.0, 65558.0, 67792.6610366945, 12286.569970051005, 12304.260584243057, 12759.106862519906, 12798.424070917017, 13386.0, 14560.271986400427, 14647.414103435744, 14922.701545968803, 15382.23088845531, 15846.920765396098, 13654.249016304198, 14082.155070695791, 14397.568072957829, 14862.925685371503, 15083.622909426913, 12113.273203607812, 12193.634190974284, 12349.753004939672, 12402.76899954704, 12563.309018004098, 13471.461141554228, 13477.46090156422, 13539.661508462368, 13791.724165516433, 14234.715305694035, 11366.886331136582, 11392.145589080686, 11604.30374177552, 11844.0, 12261.938690306848, 12079.154459187772, 12275.0, 12316.50733970667, 12393.442295096922, 12486.750264994467], "value": 7447.938601176275, "unit": "score"}], "name": "dromaeo_css", "value": 7438.213016253731, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 112.0, 110.0, 110.0, 110.0, 110.0, 112.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 124.0, 153.0, 118.0, 119.0, 117.0, 117.0, 150.0, 117.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 160.0, 161.0, 162.0, 160.0, 161.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 106.0, 75.0, 75.0, 75.0, 74.0, 100.0, 74.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 82.0, 84.0, 82.0, 105.0, 82.0, 81.0, 115.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0, 116.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 50.0, 50.0, 49.0, 51.0, 49.0, 51.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 60.0, 60.0, 59.0, 60.0, 59.0, 61.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 74.0, 76.0, 75.0, 74.0, 74.0, 76.0, 74.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 130.0, 129.0, 133.0, 120.0, 119.0, 122.0, 126.0, 168.0, 121.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 157.0, 151.0, 157.0, 158.0, 155.0, 150.0, 157.0, 152.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 62.0, 63.0, 64.0, 62.0, 62.0, 62.0, 66.0, 62.0, 61.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1448.7000000000005, "alertThreshold": 2.0}]} 02:49:30 INFO - Return code: 0 02:49:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:30 INFO - # TBPL SUCCESS # 02:49:30 INFO - Running post-action listener: _resource_record_post_action 02:49:30 INFO - [mozharness: 2017-01-05 10:49:30.963552Z] Finished run-tests step (success) 02:49:30 INFO - Running post-run listener: _resource_record_post_run 02:49:31 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 49152; Write bytes: 357027840; Read time: 12; Write time: 228256 02:49:31 INFO - TinderboxPrint: CPU usage
13.0% 02:49:31 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 12 02:49:31 INFO - TinderboxPrint: I/O write bytes / time
357,027,840 / 228,256 02:49:31 INFO - TinderboxPrint: CPU idle
8,285.6 (87.0%) 02:49:31 INFO - TinderboxPrint: CPU user
1,191.0 (12.5%) 02:49:31 INFO - TinderboxPrint: Swap in / out
0 / 0 02:49:31 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107851776; Read time: 0; Write time: 89108 02:49:31 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 49152; Write bytes: 246972416; Read time: 12; Write time: 138840 02:49:31 INFO - Running post-run listener: _upload_blobber_files 02:49:31 INFO - Blob upload gear active. 02:49:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:31 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 02:49:31 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:31 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:32 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:32 INFO - (blobuploader) - INFO - Done attempting. 02:49:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:33 INFO - (blobuploader) - INFO - Done attempting. 02:49:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:36 INFO - (blobuploader) - INFO - Done attempting. 02:49:36 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:36 INFO - Return code: 0 02:49:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56449259336a27c93070517736966f30ef11cd32a70c8b95b4b2719838c44b3e69e9a85d41595f42b5fb5e2bedb49c517275df6dbcf1d1aa019dc399c46e1467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a1d5ceafb2de3da83a9a75c222a666c45845abcbdb4e9c7f271dd49c13cd73ec79c9a70f266f4687698f6ca9cfd36cdfed7f3621b6e021392377cdd08d029f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e18f3d3ab29a842c9ec85d06ec9e65aecc85bcf6253611de44dd8533631da3c034ff797e42419d43d045bbbd158a0fa5598f11c64a87e3dbd58c4c9e0cf4cb8e"} 02:49:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:36 INFO - Contents: 02:49:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56449259336a27c93070517736966f30ef11cd32a70c8b95b4b2719838c44b3e69e9a85d41595f42b5fb5e2bedb49c517275df6dbcf1d1aa019dc399c46e1467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a1d5ceafb2de3da83a9a75c222a666c45845abcbdb4e9c7f271dd49c13cd73ec79c9a70f266f4687698f6ca9cfd36cdfed7f3621b6e021392377cdd08d029f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e18f3d3ab29a842c9ec85d06ec9e65aecc85bcf6253611de44dd8533631da3c034ff797e42419d43d045bbbd158a0fa5598f11c64a87e3dbd58c4c9e0cf4cb8e"} 02:49:36 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:36 INFO - Copying logs to upload dir... 02:49:36 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.686771 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-01-05 02:49:35.979597) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 02:49:35.982895) ========= 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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 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-Aurora/sha512/56449259336a27c93070517736966f30ef11cd32a70c8b95b4b2719838c44b3e69e9a85d41595f42b5fb5e2bedb49c517275df6dbcf1d1aa019dc399c46e1467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a1d5ceafb2de3da83a9a75c222a666c45845abcbdb4e9c7f271dd49c13cd73ec79c9a70f266f4687698f6ca9cfd36cdfed7f3621b6e021392377cdd08d029f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e18f3d3ab29a842c9ec85d06ec9e65aecc85bcf6253611de44dd8533631da3c034ff797e42419d43d045bbbd158a0fa5598f11c64a87e3dbd58c4c9e0cf4cb8e"} build_url:https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011495 build_url: 'https://queue.taskcluster.net/v1/task/elBik5SbT-ieS1x1_i_G0A/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56449259336a27c93070517736966f30ef11cd32a70c8b95b4b2719838c44b3e69e9a85d41595f42b5fb5e2bedb49c517275df6dbcf1d1aa019dc399c46e1467", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a1d5ceafb2de3da83a9a75c222a666c45845abcbdb4e9c7f271dd49c13cd73ec79c9a70f266f4687698f6ca9cfd36cdfed7f3621b6e021392377cdd08d029f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e18f3d3ab29a842c9ec85d06ec9e65aecc85bcf6253611de44dd8533631da3c034ff797e42419d43d045bbbd158a0fa5598f11c64a87e3dbd58c4c9e0cf4cb8e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 02:49:36.008075) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:49:36.008396) ========= 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=db7e7c15f3ca076d53731445000001ba-1483612138.545417-822335317 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:49:36.092397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 02:49:36.092736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 02:49:36.093039) ========= ========= Total master_lag: 0.17 =========