builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-069 starttime: 1483006561.29 results: success (0) buildid: 20161229021535 builduid: a2a3c8ac4d69463baa6eaca76226ea32 revision: 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.289790) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.290209) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.290496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.344213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.344521) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 02:16:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-12-29 02:16:04 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157613 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.524476) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.524759) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021889 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:01.574788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 02:16:01.575136) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 02:16:04,259 truncating revision to first 12 chars 2016-12-29 02:16:04,259 Setting DEBUG logging. 2016-12-29 02:16:04,259 attempt 1/10 2016-12-29 02:16:04,259 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/5b9ccdbcb75f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 02:16:05,319 unpacking tar archive at: mozilla-inbound-5b9ccdbcb75f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370441 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 02:16:02.959051) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:02.959357) ========= script_repo_revision: 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:02.959769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:02.960059) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:16:02.970132) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-12-29 02:16:02.970406) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:16:05 INFO - MultiFileLogger online at 20161229 02:16:05 in /builds/slave/test 02:16:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 02:16:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:16:05 INFO - {'append_to_log': False, 02:16:05 INFO - 'base_work_dir': '/builds/slave/test', 02:16:05 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 02:16:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:16:05 INFO - 'branch': 'Mozilla-Inbound', 02:16:05 INFO - 'buildbot_json_path': 'buildprops.json', 02:16:05 INFO - 'config_files': ('talos/linux_config.py',), 02:16:05 INFO - 'default_actions': ('clobber', 02:16:05 INFO - 'read-buildbot-config', 02:16:05 INFO - 'download-and-extract', 02:16:05 INFO - 'populate-webroot', 02:16:05 INFO - 'create-virtualenv', 02:16:05 INFO - 'install', 02:16:05 INFO - 'run-tests'), 02:16:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:16:05 INFO - 'download_minidump_stackwalk': True, 02:16:05 INFO - 'download_symbols': 'ondemand', 02:16:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:16:05 INFO - 'tooltool.py': '/tools/tooltool.py', 02:16:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:16:05 INFO - '/tools/misc-python/virtualenv.py')}, 02:16:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:16:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:16:05 INFO - 'installer_path': 'installer.exe', 02:16:05 INFO - 'log_level': 'info', 02:16:05 INFO - 'log_name': 'talos', 02:16:05 INFO - 'log_to_console': True, 02:16:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:16:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:16:05 INFO - 'opt_config_files': (), 02:16:05 INFO - 'pip_index': False, 02:16:05 INFO - 'sps_profile': False, 02:16:05 INFO - 'sps_profile_interval': 0, 02:16:05 INFO - 'suite': 'dromaeojs-e10s', 02:16:05 INFO - 'system_bits': '32', 02:16:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:16:05 INFO - 'title': 'talos-linux64-ix-069', 02:16:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:16:05 INFO - 'use_talos_json': True, 02:16:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:16:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:16:05 INFO - 'work_dir': 'build'} 02:16:05 INFO - [mozharness: 2016-12-29 10:16:05.760520Z] Running clobber step. 02:16:05 INFO - Running pre-action listener: _resource_record_pre_action 02:16:05 INFO - Running main action method: clobber 02:16:05 INFO - rmtree: /builds/slave/test/build 02:16:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:16:06 INFO - Running post-action listener: _resource_record_post_action 02:16:06 INFO - [mozharness: 2016-12-29 10:16:06.227270Z] Finished clobber step (success) 02:16:06 INFO - [mozharness: 2016-12-29 10:16:06.227372Z] Running read-buildbot-config step. 02:16:06 INFO - Running pre-action listener: _resource_record_pre_action 02:16:06 INFO - Running main action method: read_buildbot_config 02:16:06 INFO - Using buildbot properties: 02:16:06 INFO - { 02:16:06 INFO - "project": "", 02:16:06 INFO - "product": "firefox", 02:16:06 INFO - "script_repo_revision": "production", 02:16:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 02:16:06 INFO - "repository": "", 02:16:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 02:16:06 INFO - "buildid": "20161229021535", 02:16:06 INFO - "stage_platform": "linux64-pgo", 02:16:06 INFO - "basedir": "/builds/slave/test", 02:16:06 INFO - "buildnumber": 2161, 02:16:06 INFO - "platform": "ubuntu64_hw", 02:16:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:16:06 INFO - "slavebuilddir": "test", 02:16:06 INFO - "branch": "mozilla-inbound", 02:16:06 INFO - "revision": "5b9ccdbcb75f7fa8f65bc93ac86159e66170c191", 02:16:06 INFO - "slavename": "talos-linux64-ix-069", 02:16:06 INFO - "builduid": "a2a3c8ac4d69463baa6eaca76226ea32", 02:16:06 INFO - "repo_path": "integration/mozilla-inbound" 02:16:06 INFO - } 02:16:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 02:16:06 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 02:16:06 INFO - Running post-action listener: _resource_record_post_action 02:16:06 INFO - [mozharness: 2016-12-29 10:16:06.229754Z] Finished read-buildbot-config step (success) 02:16:06 INFO - [mozharness: 2016-12-29 10:16:06.229841Z] Running download-and-extract step. 02:16:06 INFO - Running pre-action listener: _resource_record_pre_action 02:16:06 INFO - Running main action method: download_and_extract 02:16:06 INFO - mkdir: /builds/slave/test/build/tests 02:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 02:16:06 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 02:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:16:06 INFO - Downloaded 1361 bytes. 02:16:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 02:16:06 INFO - Using the following test package requirements: 02:16:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 02:16:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:16:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:16:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'jsshell-linux-x86_64.zip'], 02:16:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:16:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 02:16:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:16:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:16:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:16:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 02:16:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:16:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:16:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 02:16:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:16:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 02:16:06 INFO - Expected file size: 21835358 02:16:06 INFO - Obtained file size: 21835358 02:16:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:16:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 02:16:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:16:10 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 02:16:10 INFO - Expected file size: 21835358 02:16:10 INFO - Obtained file size: 21835358 02:16:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 02:16:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:16:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 02:16:15 INFO - Expected file size: 14409776 02:16:15 INFO - Obtained file size: 14409776 02:16:15 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:16:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 02:16:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 02:16:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:16:16 INFO - Downloaded 61249465 bytes. 02:16:16 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 02:16:16 INFO - mkdir: /builds/slave/test/properties 02:16:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:16:16 INFO - Writing to file /builds/slave/test/properties/build_url 02:16:16 INFO - Contents: 02:16:16 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 02:16:16 INFO - Running post-action listener: _resource_record_post_action 02:16:16 INFO - Running post-action listener: set_extra_try_arguments 02:16:16 INFO - [mozharness: 2016-12-29 10:16:16.668278Z] Finished download-and-extract step (success) 02:16:16 INFO - [mozharness: 2016-12-29 10:16:16.668505Z] Running populate-webroot step. 02:16:16 INFO - Running pre-action listener: _resource_record_pre_action 02:16:16 INFO - Running main action method: populate_webroot 02:16:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:16:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:16:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:16:16 INFO - '--tppagecycles', 02:16:16 INFO - '7'], 02:16:16 INFO - 'tests': ['tsvgm']}}, 02:16:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:16:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:16:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:16:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:16:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['damp', 'tps']}, 02:16:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'tests': ['damp', 'tps']}, 02:16:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['dromaeo_dom']}, 02:16:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:16:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['basic_compositor_video']}, 02:16:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:16:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tsvgx', 02:16:16 INFO - 'tsvgr_opacity', 02:16:16 INFO - 'tart', 02:16:16 INFO - 'tscrollx', 02:16:16 INFO - 'cart', 02:16:16 INFO - 'tsvg_static']}, 02:16:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:16:16 INFO - 'tsvgr_opacity', 02:16:16 INFO - 'tart', 02:16:16 INFO - 'tscrollx', 02:16:16 INFO - 'cart', 02:16:16 INFO - 'tsvg_static']}, 02:16:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tp5o']}, 02:16:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:16:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s', 02:16:16 INFO - '--xperf_path', 02:16:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:16 INFO - 'tests': ['tp5n']}, 02:16:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:16:16 INFO - 'talos_options': ['--xperf_path', 02:16:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:16 INFO - 'tests': ['tp5n']}}, 02:16:16 INFO - 'talos.zip': {'path': '', 02:16:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:16 INFO - Running post-action listener: _resource_record_post_action 02:16:16 INFO - [mozharness: 2016-12-29 10:16:16.687284Z] Finished populate-webroot step (success) 02:16:16 INFO - [mozharness: 2016-12-29 10:16:16.687373Z] Running create-virtualenv step. 02:16:16 INFO - Running pre-action listener: _resource_record_pre_action 02:16:16 INFO - Running main action method: create_virtualenv 02:16:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:16:16 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:16:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:16:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:16:16 INFO - Using real prefix '/usr' 02:16:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:16:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:19 INFO - Installing pip.................done. 02:16:19 INFO - Return code: 0 02:16:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:16:19 INFO - Reading from file tmpfile_stdout 02:16:19 INFO - Output received: 02:16:19 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:16:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16:19 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:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:19 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:16:19 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:16:19 INFO - Using env: {'DISPLAY': ':0', 02:16:19 INFO - 'HOME': '/home/cltbld', 02:16:19 INFO - 'LANG': 'en_US.UTF-8', 02:16:19 INFO - 'LANGUAGE': 'en_US:en', 02:16:19 INFO - 'LOGNAME': 'cltbld', 02:16:19 INFO - 'MAIL': '/var/mail/cltbld', 02:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:19 INFO - 'MOZ_NO_REMOTE': '1', 02:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:19 INFO - 'NO_EM_RESTART': '1', 02:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:19 INFO - 'PWD': '/builds/slave/test', 02:16:19 INFO - 'SHELL': '/bin/bash', 02:16:19 INFO - 'SHLVL': '1', 02:16:19 INFO - 'TERM': 'linux', 02:16:19 INFO - 'TMOUT': '86400', 02:16:19 INFO - 'USER': 'cltbld', 02:16:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:19 INFO - '_': '/tools/buildbot/bin/python'} 02:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:19 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:16:19 INFO - Cleaning up... 02:16:19 INFO - Return code: 0 02:16:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:16:19 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:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:19 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:16:19 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:16:19 INFO - Using env: {'DISPLAY': ':0', 02:16:19 INFO - 'HOME': '/home/cltbld', 02:16:19 INFO - 'LANG': 'en_US.UTF-8', 02:16:19 INFO - 'LANGUAGE': 'en_US:en', 02:16:19 INFO - 'LOGNAME': 'cltbld', 02:16:19 INFO - 'MAIL': '/var/mail/cltbld', 02:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:19 INFO - 'MOZ_NO_REMOTE': '1', 02:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:19 INFO - 'NO_EM_RESTART': '1', 02:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:19 INFO - 'PWD': '/builds/slave/test', 02:16:19 INFO - 'SHELL': '/bin/bash', 02:16:19 INFO - 'SHLVL': '1', 02:16:19 INFO - 'TERM': 'linux', 02:16:19 INFO - 'TMOUT': '86400', 02:16:19 INFO - 'USER': 'cltbld', 02:16:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:19 INFO - '_': '/tools/buildbot/bin/python'} 02:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:19 INFO - Downloading/unpacking psutil>=3.1.1 02:16:19 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:16:19 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:16:19 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:16:19 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:16:19 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:16:19 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:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:22 INFO - Installing collected packages: psutil 02:16:22 INFO - Running setup.py install for psutil 02:16:22 INFO - building 'psutil._psutil_linux' extension 02:16:22 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:16:22 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:16:22 INFO - building 'psutil._psutil_posix' extension 02:16:22 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:16:22 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:16:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:22 INFO - Successfully installed psutil 02:16:22 INFO - Cleaning up... 02:16:22 INFO - Return code: 0 02:16:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:16:22 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:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 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:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:22 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:16:22 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:16:22 INFO - Using env: {'DISPLAY': ':0', 02:16:22 INFO - 'HOME': '/home/cltbld', 02:16:22 INFO - 'LANG': 'en_US.UTF-8', 02:16:22 INFO - 'LANGUAGE': 'en_US:en', 02:16:22 INFO - 'LOGNAME': 'cltbld', 02:16:22 INFO - 'MAIL': '/var/mail/cltbld', 02:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:22 INFO - 'MOZ_NO_REMOTE': '1', 02:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:22 INFO - 'NO_EM_RESTART': '1', 02:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:22 INFO - 'PWD': '/builds/slave/test', 02:16:22 INFO - 'SHELL': '/bin/bash', 02:16:22 INFO - 'SHLVL': '1', 02:16:22 INFO - 'TERM': 'linux', 02:16:22 INFO - 'TMOUT': '86400', 02:16:22 INFO - 'USER': 'cltbld', 02:16:22 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:22 INFO - '_': '/tools/buildbot/bin/python'} 02:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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:16:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:16:24 INFO - Installing collected packages: mozsystemmonitor 02:16:24 INFO - Running setup.py install for mozsystemmonitor 02:16:25 INFO - Successfully installed mozsystemmonitor 02:16:25 INFO - Cleaning up... 02:16:25 INFO - Return code: 0 02:16:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:16:25 INFO - Using env: {'DISPLAY': ':0', 02:16:25 INFO - 'HOME': '/home/cltbld', 02:16:25 INFO - 'LANG': 'en_US.UTF-8', 02:16:25 INFO - 'LANGUAGE': 'en_US:en', 02:16:25 INFO - 'LOGNAME': 'cltbld', 02:16:25 INFO - 'MAIL': '/var/mail/cltbld', 02:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:25 INFO - 'MOZ_NO_REMOTE': '1', 02:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:25 INFO - 'NO_EM_RESTART': '1', 02:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:25 INFO - 'PWD': '/builds/slave/test', 02:16:25 INFO - 'SHELL': '/bin/bash', 02:16:25 INFO - 'SHLVL': '1', 02:16:25 INFO - 'TERM': 'linux', 02:16:25 INFO - 'TMOUT': '86400', 02:16:25 INFO - 'USER': 'cltbld', 02:16:25 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:25 INFO - '_': '/tools/buildbot/bin/python'} 02:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:25 INFO - Downloading/unpacking jsonschema==2.5.1 02:16: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:16: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:16: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:16: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:16: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:16: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:16:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:27 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:16:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:27 INFO - warning: no files found matching '*.txt' 02:16:27 INFO - no previously-included directories found matching 'build' 02:16:27 INFO - no previously-included directories found matching 'dist' 02:16:27 INFO - no previously-included directories found matching '.git*' 02:16:27 INFO - Installing collected packages: jsonschema, functools32 02:16:27 INFO - Running setup.py install for functools32 02:16:27 INFO - warning: no files found matching '*.txt' 02:16:27 INFO - no previously-included directories found matching 'build' 02:16:27 INFO - no previously-included directories found matching 'dist' 02:16:27 INFO - no previously-included directories found matching '.git*' 02:16:27 INFO - Successfully installed jsonschema functools32 02:16:27 INFO - Cleaning up... 02:16:27 INFO - Return code: 0 02:16:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:16:27 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:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:27 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:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:27 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:27 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:16:27 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:16:27 INFO - Using env: {'DISPLAY': ':0', 02:16:27 INFO - 'HOME': '/home/cltbld', 02:16:27 INFO - 'LANG': 'en_US.UTF-8', 02:16:27 INFO - 'LANGUAGE': 'en_US:en', 02:16:27 INFO - 'LOGNAME': 'cltbld', 02:16:27 INFO - 'MAIL': '/var/mail/cltbld', 02:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:27 INFO - 'MOZ_NO_REMOTE': '1', 02:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:27 INFO - 'NO_EM_RESTART': '1', 02:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:27 INFO - 'PWD': '/builds/slave/test', 02:16:27 INFO - 'SHELL': '/bin/bash', 02:16:27 INFO - 'SHLVL': '1', 02:16:27 INFO - 'TERM': 'linux', 02:16:27 INFO - 'TMOUT': '86400', 02:16:27 INFO - 'USER': 'cltbld', 02:16:27 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:27 INFO - '_': '/tools/buildbot/bin/python'} 02:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:16:27 INFO - Cleaning up... 02:16:27 INFO - Return code: 0 02:16:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16:27 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:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:27 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:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:16:28 INFO - Using env: {'DISPLAY': ':0', 02:16:28 INFO - 'HOME': '/home/cltbld', 02:16:28 INFO - 'LANG': 'en_US.UTF-8', 02:16:28 INFO - 'LANGUAGE': 'en_US:en', 02:16:28 INFO - 'LOGNAME': 'cltbld', 02:16:28 INFO - 'MAIL': '/var/mail/cltbld', 02:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:28 INFO - 'MOZ_NO_REMOTE': '1', 02:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:28 INFO - 'NO_EM_RESTART': '1', 02:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:28 INFO - 'PWD': '/builds/slave/test', 02:16:28 INFO - 'SHELL': '/bin/bash', 02:16:28 INFO - 'SHLVL': '1', 02:16:28 INFO - 'TERM': 'linux', 02:16:28 INFO - 'TMOUT': '86400', 02:16:28 INFO - 'USER': 'cltbld', 02:16:28 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:28 INFO - '_': '/tools/buildbot/bin/python'} 02:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:28 INFO - Downloading/unpacking blobuploader==1.2.4 02:16: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:16: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:16: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:16: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:16: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:16: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:16:30 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:16:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16:30 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:16:30 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:16:30 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:16:30 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:16:30 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:16:30 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:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:16:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:16:30 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:16:30 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:16:30 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:16:30 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:16:30 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:16:30 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:16:30 INFO - Downloading docopt-0.6.1.tar.gz 02:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:16:31 INFO - Installing collected packages: blobuploader, requests, docopt 02:16:31 INFO - Running setup.py install for blobuploader 02:16:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:16:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:16:31 INFO - Running setup.py install for requests 02:16:31 INFO - Running setup.py install for docopt 02:16:31 INFO - Successfully installed blobuploader requests docopt 02:16:31 INFO - Cleaning up... 02:16:31 INFO - Return code: 0 02:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:31 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:31 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:16:31 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:16:31 INFO - Using env: {'DISPLAY': ':0', 02:16:31 INFO - 'HOME': '/home/cltbld', 02:16:31 INFO - 'LANG': 'en_US.UTF-8', 02:16:31 INFO - 'LANGUAGE': 'en_US:en', 02:16:31 INFO - 'LOGNAME': 'cltbld', 02:16:31 INFO - 'MAIL': '/var/mail/cltbld', 02:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:31 INFO - 'MOZ_NO_REMOTE': '1', 02:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:31 INFO - 'NO_EM_RESTART': '1', 02:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:31 INFO - 'PWD': '/builds/slave/test', 02:16:31 INFO - 'SHELL': '/bin/bash', 02:16:31 INFO - 'SHLVL': '1', 02:16:31 INFO - 'TERM': 'linux', 02:16:31 INFO - 'TMOUT': '86400', 02:16:31 INFO - 'USER': 'cltbld', 02:16:31 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:31 INFO - '_': '/tools/buildbot/bin/python'} 02:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:31 INFO - Running setup.py (path:/tmp/pip-tqVQgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:31 INFO - Running setup.py (path:/tmp/pip-a5wB29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:31 INFO - Running setup.py (path:/tmp/pip-yo_BOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:32 INFO - Running setup.py (path:/tmp/pip-yYl0iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:32 INFO - Running setup.py (path:/tmp/pip-rpkew2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:32 INFO - Running setup.py (path:/tmp/pip-r1NnWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:32 INFO - Running setup.py (path:/tmp/pip-k5YbEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:32 INFO - Running setup.py (path:/tmp/pip-WOmoay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:32 INFO - Running setup.py (path:/tmp/pip-mZJ5t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:32 INFO - Running setup.py (path:/tmp/pip-KbFb5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:32 INFO - Running setup.py (path:/tmp/pip-2EAmnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:32 INFO - Running setup.py (path:/tmp/pip-3WZ81D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:32 INFO - Running setup.py (path:/tmp/pip-RQ3L1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:32 INFO - Running setup.py (path:/tmp/pip-wfIL0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:32 INFO - Running setup.py (path:/tmp/pip-amHdjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:32 INFO - Running setup.py (path:/tmp/pip-AnEXrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:33 INFO - Running setup.py (path:/tmp/pip-fJvacr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:16:33 INFO - Running setup.py install for manifestparser 02:16:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Running setup.py install for mozcrash 02:16:33 INFO - Running setup.py install for mozdebug 02:16:33 INFO - Running setup.py install for mozdevice 02:16:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Running setup.py install for mozfile 02:16:33 INFO - Running setup.py install for mozhttpd 02:16:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Running setup.py install for mozinfo 02:16:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Running setup.py install for mozInstall 02:16:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:16:33 INFO - Running setup.py install for mozleak 02:16:33 INFO - Running setup.py install for mozlog 02:16:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Running setup.py install for moznetwork 02:16:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Running setup.py install for mozprocess 02:16:34 INFO - Running setup.py install for mozprofile 02:16:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Running setup.py install for mozrunner 02:16:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Running setup.py install for mozscreenshot 02:16:34 INFO - Running setup.py install for moztest 02:16:34 INFO - Running setup.py install for mozversion 02:16:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:16:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:16:34 INFO - Cleaning up... 02:16:34 INFO - Return code: 0 02:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:34 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:34 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:16:34 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:16:34 INFO - Using env: {'DISPLAY': ':0', 02:16:34 INFO - 'HOME': '/home/cltbld', 02:16:34 INFO - 'LANG': 'en_US.UTF-8', 02:16:34 INFO - 'LANGUAGE': 'en_US:en', 02:16:34 INFO - 'LOGNAME': 'cltbld', 02:16:34 INFO - 'MAIL': '/var/mail/cltbld', 02:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:34 INFO - 'MOZ_NO_REMOTE': '1', 02:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:34 INFO - 'NO_EM_RESTART': '1', 02:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:34 INFO - 'PWD': '/builds/slave/test', 02:16:34 INFO - 'SHELL': '/bin/bash', 02:16:34 INFO - 'SHLVL': '1', 02:16:34 INFO - 'TERM': 'linux', 02:16:34 INFO - 'TMOUT': '86400', 02:16:34 INFO - 'USER': 'cltbld', 02:16:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:34 INFO - '_': '/tools/buildbot/bin/python'} 02:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:35 INFO - Running setup.py (path:/tmp/pip-OcG8Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:35 INFO - Running setup.py (path:/tmp/pip-KmqEzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:35 INFO - Running setup.py (path:/tmp/pip-7VpkQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:35 INFO - Running setup.py (path:/tmp/pip-L1MRjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:35 INFO - Running setup.py (path:/tmp/pip-ryFAZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:35 INFO - Running setup.py (path:/tmp/pip-46Rkvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:35 INFO - Running setup.py (path:/tmp/pip-zffZ6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:35 INFO - Running setup.py (path:/tmp/pip-hH4UnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:35 INFO - Running setup.py (path:/tmp/pip-NeElkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:35 INFO - Running setup.py (path:/tmp/pip-_Y2kMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:35 INFO - Running setup.py (path:/tmp/pip-HuG75g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:35 INFO - Running setup.py (path:/tmp/pip-mw9Y9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:35 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:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:36 INFO - Running setup.py (path:/tmp/pip-KxOS3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:36 INFO - Running setup.py (path:/tmp/pip-RJP9xP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:36 INFO - Running setup.py (path:/tmp/pip-cLN7nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:36 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:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:36 INFO - Running setup.py (path:/tmp/pip-ZoXpey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:36 INFO - Running setup.py (path:/tmp/pip-G4SC9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:36 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:16:36 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:16:36 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:16:36 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:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:38 INFO - Downloading blessings-1.6.tar.gz 02:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:16:38 INFO - Installing collected packages: blessings 02:16:38 INFO - Running setup.py install for blessings 02:16:38 INFO - Successfully installed blessings 02:16:38 INFO - Cleaning up... 02:16:38 INFO - Return code: 0 02:16:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:16:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:38 INFO - Reading from file tmpfile_stdout 02:16:38 INFO - Current package versions: 02:16:38 INFO - argparse == 1.2.1 02:16:38 INFO - blessings == 1.6 02:16:38 INFO - blobuploader == 1.2.4 02:16:38 INFO - docopt == 0.6.1 02:16:38 INFO - functools32 == 3.2.3-2 02:16:38 INFO - jsonschema == 2.5.1 02:16:38 INFO - manifestparser == 1.1 02:16:38 INFO - mozInstall == 1.12 02:16:38 INFO - mozcrash == 1.0 02:16:38 INFO - mozdebug == 0.1 02:16:38 INFO - mozdevice == 0.49 02:16:38 INFO - mozfile == 1.2 02:16:38 INFO - mozhttpd == 0.7 02:16:38 INFO - mozinfo == 0.9 02:16:38 INFO - mozleak == 0.1 02:16:38 INFO - mozlog == 3.4 02:16:38 INFO - moznetwork == 0.27 02:16:38 INFO - mozprocess == 0.23 02:16:38 INFO - mozprofile == 0.28 02:16:38 INFO - mozrunner == 6.13 02:16:38 INFO - mozscreenshot == 0.1 02:16:38 INFO - mozsystemmonitor == 0.3 02:16:38 INFO - moztest == 0.7 02:16:38 INFO - mozversion == 1.4 02:16:38 INFO - psutil == 3.1.1 02:16:38 INFO - requests == 1.2.3 02:16:38 INFO - wsgiref == 0.1.2 02:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:38 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:38 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:16:38 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:16:38 INFO - Using env: {'DISPLAY': ':0', 02:16:38 INFO - 'HOME': '/home/cltbld', 02:16:38 INFO - 'LANG': 'en_US.UTF-8', 02:16:38 INFO - 'LANGUAGE': 'en_US:en', 02:16:38 INFO - 'LOGNAME': 'cltbld', 02:16:38 INFO - 'MAIL': '/var/mail/cltbld', 02:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:38 INFO - 'MOZ_NO_REMOTE': '1', 02:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:38 INFO - 'NO_EM_RESTART': '1', 02:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:38 INFO - 'PWD': '/builds/slave/test', 02:16:38 INFO - 'SHELL': '/bin/bash', 02:16:38 INFO - 'SHLVL': '1', 02:16:38 INFO - 'TERM': 'linux', 02:16:38 INFO - 'TMOUT': '86400', 02:16:38 INFO - 'USER': 'cltbld', 02:16:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:38 INFO - '_': '/tools/buildbot/bin/python'} 02:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 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:16:39 INFO - Cleaning up... 02:16:39 INFO - Return code: 0 02:16:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:16:39 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:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:39 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:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:39 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 0x20071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2118a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', '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:16:39 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:16:39 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:16:39 INFO - Using env: {'DISPLAY': ':0', 02:16:39 INFO - 'HOME': '/home/cltbld', 02:16:39 INFO - 'LANG': 'en_US.UTF-8', 02:16:39 INFO - 'LANGUAGE': 'en_US:en', 02:16:39 INFO - 'LOGNAME': 'cltbld', 02:16:39 INFO - 'MAIL': '/var/mail/cltbld', 02:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:39 INFO - 'MOZ_NO_REMOTE': '1', 02:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:39 INFO - 'NO_EM_RESTART': '1', 02:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:39 INFO - 'PWD': '/builds/slave/test', 02:16:39 INFO - 'SHELL': '/bin/bash', 02:16:39 INFO - 'SHLVL': '1', 02:16:39 INFO - 'TERM': 'linux', 02:16:39 INFO - 'TMOUT': '86400', 02:16:39 INFO - 'USER': 'cltbld', 02:16:39 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:39 INFO - '_': '/tools/buildbot/bin/python'} 02:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:16:39 INFO - Cleaning up... 02:16:39 INFO - Return code: 0 02:16:39 INFO - Running post-action listener: _resource_record_post_action 02:16:39 INFO - Running post-action listener: _start_resource_monitoring 02:16:39 INFO - Starting resource monitoring. 02:16:39 INFO - [mozharness: 2016-12-29 10:16:39.338295Z] Finished create-virtualenv step (success) 02:16:39 INFO - [mozharness: 2016-12-29 10:16:39.338771Z] Running install step. 02:16:39 INFO - Running pre-action listener: _resource_record_pre_action 02:16:39 INFO - Running main action method: install 02:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:39 INFO - Reading from file tmpfile_stdout 02:16:39 INFO - Detecting whether we're running mozinstall >=1.0... 02:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:16:39 INFO - Reading from file tmpfile_stdout 02:16:39 INFO - Output received: 02:16:39 INFO - Usage: mozinstall [options] installer 02:16:39 INFO - Options: 02:16:39 INFO - -h, --help show this help message and exit 02:16:39 INFO - -d DEST, --destination=DEST 02:16:39 INFO - Directory to install application into. [default: 02:16:39 INFO - "/builds/slave/test"] 02:16:39 INFO - --app=APP Application being installed. [default: firefox] 02:16:39 INFO - mkdir: /builds/slave/test/build/application 02:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:16:56 INFO - Reading from file tmpfile_stdout 02:16:56 INFO - Output received: 02:16:56 INFO - /builds/slave/test/build/application/firefox/firefox 02:16:56 INFO - Running post-action listener: _resource_record_post_action 02:16:56 INFO - [mozharness: 2016-12-29 10:16:56.955780Z] Finished install step (success) 02:16:56 INFO - [mozharness: 2016-12-29 10:16:56.956052Z] Running run-tests step. 02:16:56 INFO - Running pre-action listener: _resource_record_pre_action 02:16:56 INFO - Running main action method: run_tests 02:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:16:56 INFO - Python 2.7.3 02:16:56 INFO - Return code: 0 02:16:56 INFO - grabbing minidump binary from tooltool 02:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:56 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 0x2376820>, '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 0x2377520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23779b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:16:56 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:16:56 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:16:56 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:16:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:16:57 INFO - Return code: 0 02:16:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:16:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:16:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:16:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:16:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:16:57 INFO - Using env: {'DISPLAY': ':0', 02:16:57 INFO - 'HOME': '/home/cltbld', 02:16:57 INFO - 'LANG': 'en_US.UTF-8', 02:16:57 INFO - 'LANGUAGE': 'en_US:en', 02:16:57 INFO - 'LOGNAME': 'cltbld', 02:16:57 INFO - 'MAIL': '/var/mail/cltbld', 02:16:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:16:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:57 INFO - 'MOZ_NO_REMOTE': '1', 02:16:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:57 INFO - 'NO_EM_RESTART': '1', 02:16:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:57 INFO - 'PWD': '/builds/slave/test', 02:16:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:16:57 INFO - 'SHELL': '/bin/bash', 02:16:57 INFO - 'SHLVL': '1', 02:16:57 INFO - 'TERM': 'linux', 02:16:57 INFO - 'TMOUT': '86400', 02:16:57 INFO - 'USER': 'cltbld', 02:16:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252', 02:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:57 INFO - '_': '/tools/buildbot/bin/python'} 02:16:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:16:57 INFO - mozversion application_buildid: 20161229003003 02:16:57 INFO - mozversion application_changeset: 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 02:16:57 INFO - mozversion application_display_name: Nightly 02:16:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:16:57 INFO - mozversion application_name: Firefox 02:16:57 INFO - mozversion application_remotingname: firefox 02:16:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:16:57 INFO - mozversion application_vendor: Mozilla 02:16:57 INFO - mozversion application_version: 53.0a1 02:16:57 INFO - mozversion platform_buildid: 20161229003003 02:16:57 INFO - mozversion platform_changeset: 5b9ccdbcb75f7fa8f65bc93ac86159e66170c191 02:16:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:16:57 INFO - mozversion platform_version: 53.0a1 02:16:57 INFO - using testdate: 1483006617 02:16:57 INFO - actual date: 1483006617 02:16:57 INFO - starting webserver on 'localhost:56146' 02:16:57 INFO - SUITE-START | Running 2 tests 02:16:57 INFO - TEST-START | dromaeo_css 02:16:57 INFO - operating with platform_type : linux_ 02:16:57 INFO - Initialising browser for dromaeo_css test... 02:16:57 INFO - TEST-INFO | started process 7401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5RCspu/profile http://localhost:56146/getInfo.html) 02:16:57 INFO - PROCESS | 7401 | 1483006617709 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 02:17:01 INFO - PROCESS | 7401 | __metrics Screen width/height:1600/1200 02:17:01 INFO - PROCESS | 7401 | colorDepth:24 02:17:01 INFO - PROCESS | 7401 | Browser inner width/height: 1024/697 02:17:01 INFO - PROCESS | 7401 | __metrics 02:17:01 ERROR - PROCESS | 7401 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 02:17:02 INFO - TEST-INFO | 7401: exit 0 02:17:02 INFO - Browser initialized. 02:17:02 INFO - Running cycle 1/1 for dromaeo_css test... 02:17:02 INFO - TEST-INFO | started process 7524 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5RCspu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:14 INFO - PROCESS | 7524 | RSS: Main: 154234880 02:17:14 INFO - PROCESS | 7524 | 02:20:14 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56146/tests/dromaeo/cssquery-ext.html) 02:20:15 INFO - PROCESS | 7524 | RSS: Main: 164524032 02:20:15 INFO - PROCESS | 7524 | 02:22:03 INFO - PROCESS | 7524 | ************************* 02:22:03 INFO - PROCESS | 7524 | A coding exception was thrown and uncaught in a Task. 02:22:03 INFO - PROCESS | 7524 | 02:22:03 INFO - PROCESS | 7524 | Full message: ReferenceError: fetch is not defined 02:22:03 INFO - PROCESS | 7524 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 02:22:03 INFO - PROCESS | 7524 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 02:22:03 INFO - PROCESS | 7524 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 02:22:03 INFO - PROCESS | 7524 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:22:03 INFO - PROCESS | 7524 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:22:03 INFO - PROCESS | 7524 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:22:03 INFO - PROCESS | 7524 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 02:22:03 INFO - PROCESS | 7524 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:22:03 INFO - PROCESS | 7524 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:22:03 INFO - PROCESS | 7524 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:22:03 INFO - PROCESS | 7524 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 02:22:03 INFO - PROCESS | 7524 | 02:22:03 INFO - PROCESS | 7524 | ************************* 02:23:18 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-ext.html (next: http://localhost:56146/tests/dromaeo/cssquery-jquery.html) 02:23:18 INFO - PROCESS | 7524 | RSS: Main: 159850496 02:23:18 INFO - PROCESS | 7524 | 02:26:20 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56146/tests/dromaeo/cssquery-mootools.html) 02:26:21 INFO - PROCESS | 7524 | RSS: Main: 166449152 02:26:21 INFO - PROCESS | 7524 | 02:29:23 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56146/tests/dromaeo/cssquery-prototype.html) 02:29:23 INFO - PROCESS | 7524 | RSS: Main: 159719424 02:29:23 INFO - PROCESS | 7524 | 02:32:26 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56146/tests/dromaeo/cssquery-yui.html) 02:32:27 INFO - PROCESS | 7524 | RSS: Main: 163917824 02:32:27 INFO - PROCESS | 7524 | 02:35:29 INFO - PROCESS | 7524 | Cycle 1(1): loaded http://localhost:56146/tests/dromaeo/cssquery-yui.html (next: http://localhost:56146/tests/dromaeo/cssquery-dojo.html) 02:35:29 INFO - PROCESS | 7524 | RSS: Main: 164184064 02:35:29 INFO - PROCESS | 7524 | 02:35:29 INFO - PROCESS | 7524 | __start_tp_report 02:35:29 INFO - PROCESS | 7524 | _x_x_mozilla_page_load 02:35:29 INFO - PROCESS | 7524 | _x_x_mozilla_page_load_details 02:35:29 INFO - PROCESS | 7524 | |i|pagename|runs| 02:35:29 INFO - PROCESS | 7524 | |0;dojo.html;3091.1190310761435;3121.8126912385273;3133.670964548722;3142.0730884389104;3155.1481100102974;5247.291615631889;5343.572514198864;5349.465053494649;5389.461053894609;5438.646487978279;5287.339082614673;5296.25852380666;5335.413104558505;5381.273528073719;5400;4777.928331075037;4805.087033463639;4879.9512004880025;4912.287718280847;4936.086823937565;4426.822927082912;4964.9573589546235;4978.103941290585;5025.673331233476;5036.395632524084;4804.0872234275375;4805.903881922342;4888.144574699449;4914.97542512287;4959.776810043557;4870.464248932614;4871.3667223260745;4874.8537543873745;4895.902081958377;4920.040592084545;4842.297866809328;4845.345878306419;4865.8783530411665;4877.951220487786;4912.214045752662;5203.271541984125;5223.033738758304;5264.184051472028;5328.786848526054;5368.382635996872;5713.342965558931;5785.797497087624;5786.797462088806;5835.5915085944;5852.912206316909;5790.623609465371;5795.681237531934;5809.390014048507;5810.302763668386;5821.941780582182;5229.581633469314;5271.78912843482;5418.620696551243;5421.647592906488;5425.321834770653;5716.28546431696;5717.342505611825;5757;5790.652560846363;5844.6785426801935;5499.835004949858;5505.366882808448;5544.889102218014;5545.0018996581;5547.972260138674;5572.164175373727;5601;5659.603827732101;5666.121751128581;5673.744681489343;5579.9163012554845;5673.234113394721;5697.886042279131;5727.770889164387;5763.740631671585;5764.221830052889;5766.913496297475;5768.567357448208;5772.191893134965;5795.768169273266;4259.254630439661;4366.083122544302;4379.102284031829;4390.034192477649;4421.955780442218;3815.942760858534;3829.942550861739;3838.405047217686;3876.8836934891997;3922.2939870823525;5497.587680923947;5498.752556134984;5499.670019798825;5511.917321240185;5532.059549876531;5764.135379692996;5774.819910765523;5785.508231800344;5788.710564471844;5821.068629019422;5350.8127215547265;5386.865328366822;5475.014497390508;5488.313960754906;5492.423295553989;4811.27830825379;4835.274708793639;4844.8304309349705;4883.121038213085;4888.0468308679465;6016.969915150396;6029.246344206974;6055.152278680988;6069.393060693896;6085.208922840003;5622.634528755618;5627.915581266366;5733.483986441241;5759;5803.8549036274435;6011.639301641915;6052.425019623129;6053.4551890330085;6056.848578785565;6106.084087386927;5719.2279042328755;5729;5730.971345143247;5735.483806457439;5739.454751798572;5588.608797384307;5690.829275121753;5780.306363236438;5792.4497172768515;5814.854628634149;5665.461781130786;5689.743961521659;5700.372958974426;5705.62913410627;5747.683877386784;5700.914486282626;5718.370979192202;5719.513841323368;5720.942790572208;5721.685307307972;5483.4516548344845;5558.749856256397;5589.748461319332;5594.1608758686525;5688.459596338341;5705.087186050212;5723.599348045597;5733.971330143323;5739.8852022960145;5758.769649214152;5485.232067510472;5493.395726470005;5498.037843377473;5536;5537.363203231519 02:35:29 INFO - PROCESS | 7524 | |1;ext.html;533.4239021856396;553.2613960362916;571.4770984549139;573.0086949577234;573.0802062689384;40387.19225615489;43096.784516077416;43129.78435107824;47293.527064729344;47407;45372.773136134274;45794.313085303744;45974.77012614932;46010;46075.07849843001;8877.778055548615;9072.274218062572;9080.182783549479;9088.772780680503;10177.643782467616;10322.690319290396;10391.688249352532;10398.324108932933;10402.843957340647;10406.843897341547;10353.792924141513;10440.269181157322;10442.895571044268;10467.371957682526;10513.894861051407;10367.844482332772;10415.79168416635;10417.374957502574;10440.164786817037;10450.581976720998;10241.28311018229;10272.948635256851;10287.074163325336;10290.7427314317;10301.175905927508;37636;37807;37893;37984;38194;3947;4060.939085913714;4076.1236334188293;4078.8368465261356;4153.044799696087;32365.838170808995;33796.32407351864;34141;34396.48405273923;34489.4826577599;4319.33050377192;4323.135372925428;4353.434053573015;4380.8685739427865;4499.865004049884;343879;344592;348669;350950;352138.99999999744;60446;62097;62210;62274.377256227766;62344;64738.35261647418;65078.34921650818;65186.34813651897;65274.34725652778;65330.34669653338;16480.4231851887;16928.83071169297;16964.236609352607;16979.49061528156;16996.575085622957;12201.45093470796;12421.937890310672;12454.31501267439;12631.810522842165;12986.090973631755;17407.73888391675;17440.563985900455;17463.912680436515;17482.65034699299;17535.824641753676;7214.242504536948;7304.488685791944;7323.853522929511;7371.741989030358;7426.888596671054;877.1535468273147;893.8525143351275;905.2983937448398;913.6482454255159;943.3726571829793;30042.849785750932;30718.38563228723;30752.53871191934;31076.378472430424;31788.092714436745;999.980000399988;1051.8632577764843;1077.455884778182;1083.8103331916882;1095.8191898336845;16021.679566408606;16408.589785255463;16606.58483537897;16864.662706745796;17182.914085429493;540455;541239;545096;545247;547321;58913;59237.407625924054;59248;59354;59371.40628593745;1353.316575129573;1369.6712788930586;1381.3576686840668;1392.9233892135828;1393.9930300348433;29234.122976310744;29969.70030299669;30038.337739114355;30070;30497.69502305037;49984.500154997986;51300.48699513106;51390.22914656358;51544.22683659673;51863.22205167001;412691;417907.64184716746;417997;418211;418251;24515;25314.620280696174;26364.60453093167;28253.434931300846;28316.433671326045;46333.53666463427;46454.88996210482;46800.297995529414;46825.531744682114;47012.52987470081;33999.32001359909;34099.14752131117;34980;35020.12449688676;37891.24217515689;33390.83304583462;35971.82014089913;36011;36016.27967440689;36166;35791;35847.46228806518;35957.460638090975;36583.26833463369;36837.44743828791;29688.554671680373;30894.38211235808;32644.34711305808;33066.83466582748;35355.64644353523;25745;25752.484950300513;25881.741182588685;26241.868790655924;26266.606000909618 02:35:30 INFO - PROCESS | 7524 | |2;jquery.html;385.8398764512724;388.39992212032405;398.12015445864637;399.3530480621393;430.12040377428156;16121.838781612181;16478.17609119545;17591.472255832312;18761.061946902668;18990.81009189908;11970.162088653798;12094;12361.134720569564;12466.626001219955;12510.249385036905;5102.1836506159025;5291.814786482474;5295.4969277918535;5295.947040529584;5308.203769434577;5097.464766199551;5158.638895277332;5237.292965449673;5258.8685282867855;5264.763085661135;5240.004399164147;5243.161094224924;5247.816326428576;5250.3699556053325;5270.420253772082;5132.255822905677;5233.3981592116825;5246.842594722164;5273.736313184364;5314.548263397616;5107.514786095315;5135.589152867761;5144.382674079097;5161.458046905096;5166.121759300928;13824.516141935084;21372.572548548942;22261.55476890453;22271.665925011137;22541;2621.5412302846926;2700.6894207166233;2712.8643567821728;2772.37621535155;2784.429192015632;10477.633282835064;10657.573697052108;13829.824464920493;13964.511242106477;14986.250687465583;1934.6517626827165;2021.777604463508;2021.9898900505402;2024.5242368043498;2033.3290014295394;76410;77329.61335193344;78693;78927;80686.5965670168;9370.85943710845;9967.551460184164;10028.548715307828;11054.00513953748;11256.15578831591;13210.735785284432;13337.866621333857;13348.065635405623;13360.665983350495;13397.732045359038;7417.703291868264;7479.588622625699;7637.541747495168;7703.922960770433;7753.573553454501;7357.117145942521;7400.407967362706;7613.505122167041;7630.160682324939;7664.961675191588;4038.8586399476467;4183.937240941388;4184.2468355696265;4258.1057977825;4265.317549192115;2033.220931684776;2035;2035.714999900015;2068.8758674479277;2099.685047242926;881.4667126388442;921.2675922641382;922.4557511068501;931.6878845586668;943.5848226780195;17451;17663;18287.45137645873;18530.16614252362;18657.626847462976;925.9444433334022;930.688219446493;941.3222479814523;947.3610653569364;952.3286083311249;9389.342746007851;10209.183265338756;10230.897691023143;10448.843267350996;10943.726406839733;392104;396025;396088;402111;404760;63264.683676582245;78516.21483785204;83376;86483.56758216294;87205;1033.3696445168441;1060.522764755863;1085.039739830256;1088.3796236145479;1103.4593049405894;16857.241424135696;17200.483985480456;17513.124343783016;17678.646427071642;17909.731354029438;37273;38311;39059;39325.80337098296;40573.18853622852;53115.734421327645;55286.72356638191;55332.17001744897;55494.44505555055;55838.441615583324;8468.830623387621;8488.83022339562;8500.787480312794;8663.95668021656;8912.291498136572;11123.610673626385;11991.880081199077;13078;13304.866951330363;13657.385417656427;11547.769044618892;11830.763384732429;11979.460924258256;11996.400179990791;12259.632211033684;11610.825837612274;11671.88328116708;11965.940170299093;11999.82000270014;12036.157468977088;6513.185851768529;8756.387052906235;9198.908010919806;11588.826167607323;11676.766464670829;8581.313494920267;8683.826323473622;8749.65001399937;8773.692920747742;8804.867926980973;10701.411422371844;11143.832842507207;11195.888041119484;11528.942355288169;11753.177277590486 02:35:30 INFO - PROCESS | 7524 | |3;mootools.html;1367.8768910798028;1370.8354997400313;1374.5876237128864;1374.9862501374982;1380.0339762166482;7727.381809455244;7961.363090952725;8115.14790946951;8146.6334014969325;8214.589270536471;8001.6799328026955;8126.10612832588;8146.959265203665;8158.510489370642;8184.631691573879;2190.890455477224;2229.4649284171765;2240.2271216430304;2242.76450972648;2242.9887850560726;2187.332863476639;2242.9663555046686;2243.304575581567;2254.954900901981;2267.489814791667;2244.8989795459242;2247.71903512061;2255.751867294596;2258.6837842702034;2260.8078313343385;2154.482924098213;2175;2180.1170525936864;2184.879831609269;2185.508260641359;2225.799678028969;2241.8654880707045;2255.030336955109;2261.604219261623;2262.694536237603;7936.3254123399;8141.8371632567605;8142.022957245109;8153.551554664491;8154.469959452616;4770.952290477086;4905.754712264373;4929.1620424527555;4936;4936.777844996984;3019.773516986213;3147.795393299451;3191.7287030602192;3205.6473787883315;3207.1982004498877;1343.9193648381129;1395.5115709501592;1413.1944791468866;1428.1502506009012;1466.7286551987809;10740.624078157227;10779.946100269526;10863.07663848566;10872.836907446395;10913.67258982224;3833.9041523962123;4025.939610905839;4035.2534781065597;4060.6548443382053;4112;8395.24442800151;8397.244248017707;8419.579021048923;8470.703525376583;8503.234708876107;4603.424571928509;4669.556392142741;4683.906321873544;4711.505291944365;4715.24556071027;4337.306031035082;4355.15074560464;4409.889752756207;4411.228035093845;4419.9779001105435;2875.439289338559;2999.580058791771;3037.9088627341216;3042.0417568465864;3072.7849050566692;1043.801677681238;1084.2410312781085;1088.0479580367178;1100.1199040767353;1102.895224953628;969.3795970578694;976.9071938165863;995.9452230127413;1000.3847633705219;1016.8220561401725;5458.836234912959;5545.140503222007;5571.777128914879;5605.80379686709;5645.322561292671;966.1546147121269;1001.2240513602017;1033.2508931025097;1039.0440794469107;1040.9115225205942;3946.1121247719607;4021.0349516116507;4028.899277518027;4055.3105971984833;4079.836806527765;9577.664781732607;9654.565544550513;9765.365251258645;9803.362781419184;9819.214462842956;9468.289878259158;9494.477803720862;9648.71053868371;9692.321537492307;9733.513324333755;7484.214157513492;7533.20901305366;7569.810754731286;7597.050368703912;7653.043369578802;5429.619926605258;5455.18172274162;5457.126859702428;5519.613627046068;5570.498655121059;8764.517951512727;8819.647214111365;8858.64565417376;8978.86531702012;8989.146031127033;8998.325125615474;8998.82002359936;9114.27085833145;9136.588853501475;9238.076192380977;6643;6733.2593414725325;6733.360330768569;6738.561993470409;6740.494462915203;7459.813504662427;7486.326230639269;7545.320921117126;7566.546007239584;7623.847523049397;6806.455483561403;7101.964490177516;7105.715771369294;7126.5011449198055;7224.74713385029;6670.266270710315;6766.086578312062;6789.083473730983;6984.126984126984;6987.056747339044;5985.491233245223;6125.632462052291;6159.045347971071;6171.5988460751705;6195;4808.206645903527;4854;4922.507749225048;4975.10448119342;5165.328507294028;4857.708537487762;4912.606527176719;4945.876353091057;4984.800607975785;5110.488951105009 02:35:30 INFO - PROCESS | 7524 | |4;prototype.html;365.8262325395436;379.91262009737744;380.92762375148726;383.1455853446812;383.3444809375967;5262.578993680506;5343.332083489564;5407.864803379917;5438.646487978283;5447.618666693333;5274.393444753845;5307.495787900153;5360.571154307656;5373.731313434332;5398.325209348832;4282.379055037026;4298;4301.204277208711;4322.567743225668;4333.978330108345;4178.874633760976;4226.957730422702;4269.530351661315;4309.806058727349;4311.956880431203;4213.620774130328;4230.280852255109;4231.788410579474;4240.978795106035;4242.857357034693;4227.556106608792;4229.492460904696;4235.470566179228;4249.723767955088;4251.957480425187;3761.9059523512133;3854.2484215577974;3865.381538953782;3883.8058097095313;3887.4168874669026;5206.255904272036;5209.7134657593815;5210.452902445264;5220.817271395521;5245.898361344122;4892.9755351223375;4902.607791376717;4913.336699545552;4919.434265059493;4931.988942266827;5365.704886231256;5572.4148964358565;5629.705167811386;5649.943500565024;5656.886862262772;4172.499300083979;4193.727407718519;4194.4337514435465;4197.412362269285;4199.538050814408;5846.883062338772;5898.233229680157;5903.616264942808;5926;5956.970215148896;4224.9155016899485;4325.134973005349;4328.78793937698;4357.455318085239;4380.167768124046;4323.924516232226;4371.754050095771;4381.737095774264;4402.669799765031;4452.4211852458975;4860.222364421676;4877.024804953929;4890.437599676012;4900.803967841318;4912.631552633567;5416.647917885403;5625.240592520039;5633.155026746021;5659.377468478464;5674.2339784129435;1459.8175228096488;1469.3241109089938;1483.7180935622198;1487.3564710994197;1489.2181604657426;1017.2167431078176;1048.9737756556146;1064.3613831700918;1065.7815147894662;1070.2722148938797;5243.501867322674;5305.8938821223355;5328.200769884471;5346.705931173823;5357.098230128327;5594.776208951596;5627.943720562742;5629.605927585111;5634.267545219095;5644.689542075225;4971.950280497221;5237.764300606482;5278.228929811803;5318.994767956093;5380.542653874464;2933.9315782373374;2954.4386566552284;2964.836933968609;3014.4724673182104;3040.3767227718263;5759.396256392417;5872.207252021008;5879.617824841372;5919.67441790697;5970.791022314285;5585.832425027255;5622.522085622768;5655.4344565543115;5658.01969310738;5674.290713660793;5825.776586916711;5844.6785426801935;5862.208601838697;5888.646681199141;5916.319623243296;5418.701971391612;5521.503064724194;5549.556035517068;5584.609077364546;5606.215129881738;5461.481159289861;5485.204645326485;5506.191842160607;5517.44825517445;5612.270404847343;5855.414458554281;5861.706914654335;5862.853428664317;5889.499392551681;5890.786653811322;3991.900202494961;4163.937540936828;4178.140727376288;4189.601987811153;4197.601227883346;5560.944390556043;5562.833115006556;5563.610547261653;5599.608027438203;5640.85897852557;5314.893702125859;5462.945370546403;5472.945270547243;5479.137093388267;5497.845452455029;5384.219288203267;5411.864703382447;5423.566114710892;5428.430014848542;5434.64674796137;5448.809291674773;5450.536704380013;5474.479924407175;5483.587221622473;5493.285872836505;5253.395859476056;5381.596380271573;5414.3232095988005;5423.755930983037;5498.285222920995;5232.189010703347;5261.342332208474;5274.072889546339;5294.708791016377;5324.550706674973 02:35:30 INFO - PROCESS | 7524 | |5;yui.html;672.5057083043965;694.1219357512747;754.6943487887407;824.1717074340289;843.0978852627688;15224.847751522482;16765.161741912918;16798.076105814205;16798.160091995396;16817.49547513575;15184.468543600977;15366.69266614667;15382.769258461132;15382.923085384557;15476.071435713837;682.3619915379112;698.4517154034085;788.5268838696793;793.9483933544316;812.5327936436543;786.6931896560346;794.9761507154794;807.4065561812092;813.2396209544065;831.9542425166613;858.8582883824192;868.4615538366222;883.4346018548134;884.8805411269493;887.4586502233632;848.5926755157498;874.2044739287281;874.8425283448945;876.5617191404298;877.5436772878099;626.8965620672584;630.8296759874854;635.463033736492;638.2213699286863;638.5817289675224;15943.123128227942;16026.118563479004;16254.83745162545;16475.093869837154;16801.41195058167;15437;15621.218939053002;15775.921120394325;15890.36438542457;15895.841041589552;12134.878651213463;12233.632991010283;12288.877111228952;13204.207747535083;13295.867041329657;3910.804459777028;3949.723519353646;3969.920601587981;4132.690048246393;4135.193637240739;81967.59016204941;82747.17252827455;83348.58325708333;84561;85931.14068859293;11327.886721132849;11373.37446440437;11705;12013.579524716768;12140.514379424723;23316.883415583154;23893.52212955731;23928;23936.640950385758;23983.880080599487;8654.6970856021;8978.191962723386;9069.54652267384;9081.54592270384;9287.315815263326;4519.209138400766;4607.9308810367875;4611.792469338888;4623.86128416148;4786.593139583105;3601.7838929664304;3655.0862284428895;3658.213484100931;3701.1117331840164;3709.3323201823932;585.1749033862234;586.5102639296235;595.9046552551571;596.6628854697169;608.9208402907682;754.3701009656997;761.672480833247;767.5087943716027;767.9884801727978;793.7896457438783;9771;9873;9972.750681233027;10033;10227.28409011362;727.9053723015974;748.7603966730594;750.512167091395;762.9433234969496;777.2965466252937;4710.528947105262;4975.278584605212;4981.950180498221;4982.90034199314;4995.550400463903;69749.65125174342;70975.64512177509;81791;84239.15760842436;86192.13807861967;70650;71429.64285178541;71860;72643;74869;52589.47410525922;65907.34092659011;66352.6682366585;66424.66787666127;66671;9842.65550705722;10640.308379955279;10958.616448424267;11047.392393418439;11072.11423086135;40521.59478405296;40908.18183636251;41178.38232426456;41667;42123;53087;53116;53610.73194634002;54144.45855541394;56514;12816;12902.354882255662;13018.479260829461;13273.933630332172;13456.932715336361;14744.189069601274;14751.852481475047;14773.482928097465;14985.925070374578;14994.400223991355;12837.229766214057;12988.480460781462;13326.533571324957;13362.732745345233;13369.131006484547;12872.292023938773;13171.868281317447;13371.395432547768;13560.186388816308;13595.796063058864;13767.862321376659;13838;14202.644933876323;14567.41730330775;14588.85411145875;11352.716182095184;11583.8262426062;11651.359175245443;11684.298942063504;11866.584669536525;11669.59156429521;11694.181407301407;11769.52921883115;11798.351090690094;11947.402629868646 02:35:30 INFO - PROCESS | 7524 | __end_tp_report 02:35:30 INFO - PROCESS | 7524 | __start_cc_report 02:35:30 INFO - PROCESS | 7524 | _x_x_mozilla_cycle_collect,308 02:35:30 INFO - PROCESS | 7524 | __end_cc_report 02:35:30 INFO - PROCESS | 7524 | __startTimestamp1483007729995__endTimestamp 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | ------- Summary: start ------- 02:35:30 INFO - PROCESS | 7524 | Number of tests: 6 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#0] dojo.html Cycles:165 Average:5337.97 Median:5537.36 stddev:623.09 (11.3%) stddev-sans-first:599.55 02:35:30 INFO - PROCESS | 7524 | Values: 3091.1 3121.8 3133.7 3142.1 3155.1 5247.3 5343.6 5349.5 5389.5 5438.6 5287.3 5296.3 5335.4 5381.3 5400.0 4777.9 4805.1 4880.0 4912.3 4936.1 4426.8 4965.0 4978.1 5025.7 5036.4 4804.1 4805.9 4888.1 4915.0 4959.8 4870.5 4871.4 4874.9 4895.9 4920.0 4842.3 4845.3 4865.9 4878.0 4912.2 5203.3 5223.0 5264.2 5328.8 5368.4 5713.3 5785.8 5786.8 5835.6 5852.9 5790.6 5795.7 5809.4 5810.3 5821.9 5229.6 5271.8 5418.6 5421.6 5425.3 5716.3 5717.3 5757.0 5790.7 5844.7 5499.8 5505.4 5544.9 5545.0 5548.0 5572.2 5601.0 5659.6 5666.1 5673.7 5579.9 5673.2 5697.9 5727.8 5763.7 5764.2 5766.9 5768.6 5772.2 5795.8 4259.3 4366.1 4379.1 4390.0 4422.0 3815.9 3829.9 3838.4 3876.9 3922.3 5497.6 5498.8 5499.7 5511.9 5532.1 5764.1 5774.8 5785.5 5788.7 5821.1 5350.8 5386.9 5475.0 5488.3 5492.4 4811.3 4835.3 4844.8 4883.1 4888.0 6017.0 6029.2 6055.2 6069.4 6085.2 5622.6 5627.9 5733.5 5759.0 5803.9 6011.6 6052.4 6053.5 6056.8 6106.1 5719.2 5729.0 5731.0 5735.5 5739.5 5588.6 5690.8 5780.3 5792.4 5814.9 5665.5 5689.7 5700.4 5705.6 5747.7 5700.9 5718.4 5719.5 5720.9 5721.7 5483.5 5558.7 5589.7 5594.2 5688.5 5705.1 5723.6 5734.0 5739.9 5758.8 5485.2 5493.4 5498.0 5536.0 5537.4 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#1] ext.html Cycles:180 Average:59485.04 Median:35819.23 stddev:117733.50 (328.7%) stddev-sans-first:117980.57 02:35:30 INFO - PROCESS | 7524 | Values: 533.4 553.3 571.5 573.0 573.1 40387.2 43096.8 43129.8 47293.5 47407.0 45372.8 45794.3 45974.8 46010.0 46075.1 8877.8 9072.3 9080.2 9088.8 10177.6 10322.7 10391.7 10398.3 10402.8 10406.8 10353.8 10440.3 10442.9 10467.4 10513.9 10367.8 10415.8 10417.4 10440.2 10450.6 10241.3 10272.9 10287.1 10290.7 10301.2 37636.0 37807.0 37893.0 37984.0 38194.0 3947.0 4060.9 4076.1 4078.8 4153.0 32365.8 33796.3 34141.0 34396.5 34489.5 4319.3 4323.1 4353.4 4380.9 4499.9 343879.0 344592.0 348669.0 350950.0 352139.0 60446.0 62097.0 62210.0 62274.4 62344.0 64738.4 65078.3 65186.3 65274.3 65330.3 16480.4 16928.8 16964.2 16979.5 16996.6 12201.5 12421.9 12454.3 12631.8 12986.1 17407.7 17440.6 17463.9 17482.7 17535.8 7214.2 7304.5 7323.9 7371.7 7426.9 877.2 893.9 905.3 913.6 943.4 30042.8 30718.4 30752.5 31076.4 31788.1 1000.0 1051.9 1077.5 1083.8 1095.8 16021.7 16408.6 16606.6 16864.7 17182.9 540455.0 541239.0 545096.0 545247.0 547321.0 58913.0 59237.4 59248.0 59354.0 59371.4 1353.3 1369.7 1381.4 1392.9 1394.0 29234.1 29969.7 30038.3 30070.0 30497.7 49984.5 51300.5 51390.2 51544.2 51863.2 412691.0 417907.6 417997.0 418211.0 418251.0 24515.0 25314.6 26364.6 28253.4 28316.4 46333.5 46454.9 46800.3 46825.5 47012.5 33999.3 34099.1 34980.0 35020.1 37891.2 33390.8 35971.8 36011.0 36016.3 36166.0 35791.0 35847.5 35957.5 36583.3 36837.4 29688.6 30894.4 32644.3 33066.8 35355.6 25745.0 25752.5 25881.7 26241.9 26266.6 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#2] jquery.html Cycles:180 Average:25360.02 Median:217675.40 stddev:65961.22 (30.3%) stddev-sans-first:66119.61 02:35:30 INFO - PROCESS | 7524 | Values: 385.8 388.4 398.1 399.4 430.1 16121.8 16478.2 17591.5 18761.1 18990.8 11970.2 12094.0 12361.1 12466.6 12510.2 5102.2 5291.8 5295.5 5295.9 5308.2 5097.5 5158.6 5237.3 5258.9 5264.8 5240.0 5243.2 5247.8 5250.4 5270.4 5132.3 5233.4 5246.8 5273.7 5314.5 5107.5 5135.6 5144.4 5161.5 5166.1 13824.5 21372.6 22261.6 22271.7 22541.0 2621.5 2700.7 2712.9 2772.4 2784.4 10477.6 10657.6 13829.8 13964.5 14986.3 1934.7 2021.8 2022.0 2024.5 2033.3 76410.0 77329.6 78693.0 78927.0 80686.6 9370.9 9967.6 10028.5 11054.0 11256.2 13210.7 13337.9 13348.1 13360.7 13397.7 7417.7 7479.6 7637.5 7703.9 7753.6 7357.1 7400.4 7613.5 7630.2 7665.0 4038.9 4183.9 4184.2 4258.1 4265.3 2033.2 2035.0 2035.7 2068.9 2099.7 881.5 921.3 922.5 931.7 943.6 17451.0 17663.0 18287.5 18530.2 18657.6 925.9 930.7 941.3 947.4 952.3 9389.3 10209.2 10230.9 10448.8 10943.7 392104.0 396025.0 396088.0 402111.0 404760.0 63264.7 78516.2 83376.0 86483.6 87205.0 1033.4 1060.5 1085.0 1088.4 1103.5 16857.2 17200.5 17513.1 17678.6 17909.7 37273.0 38311.0 39059.0 39325.8 40573.2 53115.7 55286.7 55332.2 55494.4 55838.4 8468.8 8488.8 8500.8 8664.0 8912.3 11123.6 11991.9 13078.0 13304.9 13657.4 11547.8 11830.8 11979.5 11996.4 12259.6 11610.8 11671.9 11965.9 11999.8 12036.2 6513.2 8756.4 9198.9 11588.8 11676.8 8581.3 8683.8 8749.7 8773.7 8804.9 10701.4 11143.8 11195.9 11528.9 11753.2 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#3] mootools.html Cycles:180 Average:5245.73 Median:4941.33 stddev:2869.89 (58.1%) stddev-sans-first:2863.14 02:35:30 INFO - PROCESS | 7524 | Values: 1367.9 1370.8 1374.6 1375.0 1380.0 7727.4 7961.4 8115.1 8146.6 8214.6 8001.7 8126.1 8147.0 8158.5 8184.6 2190.9 2229.5 2240.2 2242.8 2243.0 2187.3 2243.0 2243.3 2255.0 2267.5 2244.9 2247.7 2255.8 2258.7 2260.8 2154.5 2175.0 2180.1 2184.9 2185.5 2225.8 2241.9 2255.0 2261.6 2262.7 7936.3 8141.8 8142.0 8153.6 8154.5 4771.0 4905.8 4929.2 4936.0 4936.8 3019.8 3147.8 3191.7 3205.6 3207.2 1343.9 1395.5 1413.2 1428.2 1466.7 10740.6 10779.9 10863.1 10872.8 10913.7 3833.9 4025.9 4035.3 4060.7 4112.0 8395.2 8397.2 8419.6 8470.7 8503.2 4603.4 4669.6 4683.9 4711.5 4715.2 4337.3 4355.2 4409.9 4411.2 4420.0 2875.4 2999.6 3037.9 3042.0 3072.8 1043.8 1084.2 1088.0 1100.1 1102.9 969.4 976.9 995.9 1000.4 1016.8 5458.8 5545.1 5571.8 5605.8 5645.3 966.2 1001.2 1033.3 1039.0 1040.9 3946.1 4021.0 4028.9 4055.3 4079.8 9577.7 9654.6 9765.4 9803.4 9819.2 9468.3 9494.5 9648.7 9692.3 9733.5 7484.2 7533.2 7569.8 7597.1 7653.0 5429.6 5455.2 5457.1 5519.6 5570.5 8764.5 8819.6 8858.6 8978.9 8989.1 8998.3 8998.8 9114.3 9136.6 9238.1 6643.0 6733.3 6733.4 6738.6 6740.5 7459.8 7486.3 7545.3 7566.5 7623.8 6806.5 7102.0 7105.7 7126.5 7224.7 6670.3 6766.1 6789.1 6984.1 6987.1 5985.5 6125.6 6159.0 6171.6 6195.0 4808.2 4854.0 4922.5 4975.1 5165.3 4857.7 4912.6 4945.9 4984.8 5110.5 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#4] prototype.html Cycles:180 Average:4714.76 Median:5300.30 stddev:1327.94 (25.1%) stddev-sans-first:1290.93 02:35:30 INFO - PROCESS | 7524 | Values: 365.8 379.9 380.9 383.1 383.3 5262.6 5343.3 5407.9 5438.6 5447.6 5274.4 5307.5 5360.6 5373.7 5398.3 4282.4 4298.0 4301.2 4322.6 4334.0 4178.9 4227.0 4269.5 4309.8 4312.0 4213.6 4230.3 4231.8 4241.0 4242.9 4227.6 4229.5 4235.5 4249.7 4252.0 3761.9 3854.2 3865.4 3883.8 3887.4 5206.3 5209.7 5210.5 5220.8 5245.9 4893.0 4902.6 4913.3 4919.4 4932.0 5365.7 5572.4 5629.7 5649.9 5656.9 4172.5 4193.7 4194.4 4197.4 4199.5 5846.9 5898.2 5903.6 5926.0 5957.0 4224.9 4325.1 4328.8 4357.5 4380.2 4323.9 4371.8 4381.7 4402.7 4452.4 4860.2 4877.0 4890.4 4900.8 4912.6 5416.6 5625.2 5633.2 5659.4 5674.2 1459.8 1469.3 1483.7 1487.4 1489.2 1017.2 1049.0 1064.4 1065.8 1070.3 5243.5 5305.9 5328.2 5346.7 5357.1 5594.8 5627.9 5629.6 5634.3 5644.7 4972.0 5237.8 5278.2 5319.0 5380.5 2933.9 2954.4 2964.8 3014.5 3040.4 5759.4 5872.2 5879.6 5919.7 5970.8 5585.8 5622.5 5655.4 5658.0 5674.3 5825.8 5844.7 5862.2 5888.6 5916.3 5418.7 5521.5 5549.6 5584.6 5606.2 5461.5 5485.2 5506.2 5517.4 5612.3 5855.4 5861.7 5862.9 5889.5 5890.8 3991.9 4163.9 4178.1 4189.6 4197.6 5560.9 5562.8 5563.6 5599.6 5640.9 5314.9 5462.9 5472.9 5479.1 5497.8 5384.2 5411.9 5423.6 5428.4 5434.6 5448.8 5450.5 5474.5 5483.6 5493.3 5253.4 5381.6 5414.3 5423.8 5498.3 5232.2 5261.3 5274.1 5294.7 5324.6 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - PROCESS | 7524 | [#5] yui.html Cycles:180 Average:18093.55 Median:23321.10 stddev:22953.38 (98.4%) stddev-sans-first:22980.50 02:35:30 INFO - PROCESS | 7524 | Values: 672.5 694.1 754.7 824.2 843.1 15224.8 16765.2 16798.1 16798.2 16817.5 15184.5 15366.7 15382.8 15382.9 15476.1 682.4 698.5 788.5 793.9 812.5 786.7 795.0 807.4 813.2 832.0 858.9 868.5 883.4 884.9 887.5 848.6 874.2 874.8 876.6 877.5 626.9 630.8 635.5 638.2 638.6 15943.1 16026.1 16254.8 16475.1 16801.4 15437.0 15621.2 15775.9 15890.4 15895.8 12134.9 12233.6 12288.9 13204.2 13295.9 3910.8 3949.7 3969.9 4132.7 4135.2 81967.6 82747.2 83348.6 84561.0 85931.1 11327.9 11373.4 11705.0 12013.6 12140.5 23316.9 23893.5 23928.0 23936.6 23983.9 8654.7 8978.2 9069.5 9081.5 9287.3 4519.2 4607.9 4611.8 4623.9 4786.6 3601.8 3655.1 3658.2 3701.1 3709.3 585.2 586.5 595.9 596.7 608.9 754.4 761.7 767.5 768.0 793.8 9771.0 9873.0 9972.8 10033.0 10227.3 727.9 748.8 750.5 762.9 777.3 4710.5 4975.3 4982.0 4982.9 4995.6 69749.7 70975.6 81791.0 84239.2 86192.1 70650.0 71429.6 71860.0 72643.0 74869.0 52589.5 65907.3 66352.7 66424.7 66671.0 9842.7 10640.3 10958.6 11047.4 11072.1 40521.6 40908.2 41178.4 41667.0 42123.0 53087.0 53116.0 53610.7 54144.5 56514.0 12816.0 12902.4 13018.5 13273.9 13456.9 14744.2 14751.9 14773.5 14985.9 14994.4 12837.2 12988.5 13326.5 13362.7 13369.1 12872.3 13171.9 13371.4 13560.2 13595.8 13767.9 13838.0 14202.6 14567.4 14588.9 11352.7 11583.8 11651.4 11684.3 11866.6 11669.6 11694.2 11769.5 11798.4 11947.4 02:35:30 INFO - PROCESS | 7524 | -------- Summary: end -------- 02:35:30 INFO - PROCESS | 7524 | 02:35:30 INFO - TEST-INFO | 7524: exit 0 02:35:30 INFO - TEST-OK | dromaeo_css | took 1113519ms 02:35:30 INFO - TEST-START | kraken 02:35:30 INFO - operating with platform_type : linux_ 02:35:30 INFO - Initialising browser for kraken test... 02:35:30 INFO - TEST-INFO | started process 7878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBL5tkn/profile http://localhost:56146/getInfo.html) 02:35:31 INFO - PROCESS | 7878 | 1483007731209 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 02:35:34 INFO - PROCESS | 7878 | __metrics Screen width/height:1600/1200 02:35:34 INFO - PROCESS | 7878 | colorDepth:24 02:35:34 INFO - PROCESS | 7878 | Browser inner width/height: 1024/697 02:35:34 INFO - PROCESS | 7878 | __metrics 02:35:35 ERROR - PROCESS | 7878 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 02:35:36 INFO - TEST-INFO | 7878: exit 0 02:35:36 INFO - Browser initialized. 02:35:36 INFO - Running cycle 1/1 for kraken test... 02:35:36 INFO - TEST-INFO | started process 8002 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBL5tkn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:48 INFO - PROCESS | 8002 | RSS: Main: 154173440 02:35:48 INFO - PROCESS | 8002 | 02:35:50 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56146/tests/kraken/driver.html?testName=audio-beat-detection) 02:35:51 INFO - PROCESS | 8002 | RSS: Main: 160854016 02:35:51 INFO - PROCESS | 8002 | 02:35:54 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56146/tests/kraken/driver.html?testName=audio-dft) 02:35:54 INFO - PROCESS | 8002 | RSS: Main: 159739904 02:35:54 INFO - PROCESS | 8002 | 02:35:57 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56146/tests/kraken/driver.html?testName=audio-fft) 02:35:58 INFO - PROCESS | 8002 | RSS: Main: 155803648 02:35:58 INFO - PROCESS | 8002 | 02:36:00 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56146/tests/kraken/driver.html?testName=audio-oscillator) 02:36:01 INFO - PROCESS | 8002 | RSS: Main: 156356608 02:36:01 INFO - PROCESS | 8002 | 02:36:03 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56146/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:36:03 INFO - PROCESS | 8002 | RSS: Main: 156983296 02:36:03 INFO - PROCESS | 8002 | 02:36:06 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56146/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:07 INFO - PROCESS | 8002 | RSS: Main: 160960512 02:36:07 INFO - PROCESS | 8002 | 02:36:10 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56146/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:11 INFO - PROCESS | 8002 | RSS: Main: 159502336 02:36:11 INFO - PROCESS | 8002 | 02:36:14 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56146/tests/kraken/driver.html?testName=json-parse-financial) 02:36:15 INFO - PROCESS | 8002 | RSS: Main: 158818304 02:36:15 INFO - PROCESS | 8002 | 02:36:16 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56146/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:36:17 INFO - PROCESS | 8002 | RSS: Main: 158986240 02:36:17 INFO - PROCESS | 8002 | 02:36:19 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:36:19 INFO - PROCESS | 8002 | RSS: Main: 159412224 02:36:19 INFO - PROCESS | 8002 | 02:36:21 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:36:22 INFO - PROCESS | 8002 | RSS: Main: 160182272 02:36:22 INFO - PROCESS | 8002 | 02:36:25 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:36:25 INFO - PROCESS | 8002 | RSS: Main: 160423936 02:36:25 INFO - PROCESS | 8002 | 02:36:28 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:36:29 INFO - PROCESS | 8002 | RSS: Main: 160026624 02:36:29 INFO - PROCESS | 8002 | 02:36:30 INFO - PROCESS | 8002 | Cycle 1(1): loaded http://localhost:56146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56146/tests/kraken/driver.html?testName=ai-astar) 02:36:31 INFO - PROCESS | 8002 | RSS: Main: 161591296 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | __start_tp_report 02:36:31 INFO - PROCESS | 8002 | _x_x_mozilla_page_load 02:36:31 INFO - PROCESS | 8002 | _x_x_mozilla_page_load_details 02:36:31 INFO - PROCESS | 8002 | |i|pagename|runs| 02:36:31 INFO - PROCESS | 8002 | |0;ai-astar;106;112;112;113;112;113;113;114;113;112 02:36:31 INFO - PROCESS | 8002 | |1;audio-beat-detection;153;126;128;178;121;122;120;123;141;125 02:36:31 INFO - PROCESS | 8002 | |2;audio-dft;162;162;163;163;165;165;166;163;163;163 02:36:31 INFO - PROCESS | 8002 | |3;audio-fft;75;78;77;112;80;78;78;79;99;77 02:36:31 INFO - PROCESS | 8002 | |4;audio-oscillator;85;87;107;85;90;84;93;83;83;111 02:36:31 INFO - PROCESS | 8002 | |5;imaging-gaussian-blur;118;118;119;118;117;118;118;118;117;120 02:36:31 INFO - PROCESS | 8002 | |6;imaging-darkroom;149;148;148;148;148;148;149;149;149;147 02:36:31 INFO - PROCESS | 8002 | |7;imaging-desaturate;96;96;96;96;97;96;96;97;96;96 02:36:31 INFO - PROCESS | 8002 | |8;json-parse-financial;57;60;56;59;58;58;58;58;58;57 02:36:31 INFO - PROCESS | 8002 | |9;json-stringify-tinderbox;69;70;69;68;70;68;68;68;68;68 02:36:31 INFO - PROCESS | 8002 | |10;stanford-crypto-aes;81;80;81;83;82;89;80;83;86;81 02:36:31 INFO - PROCESS | 8002 | |11;stanford-crypto-ccm;136;135;137;128;126;127;130;128;169;130 02:36:31 INFO - PROCESS | 8002 | |12;stanford-crypto-pbkdf2;166;171;167;169;164;173;169;164;165;165 02:36:31 INFO - PROCESS | 8002 | |13;stanford-crypto-sha256-iterative;70;63;63;64;66;63;62;63;61;63 02:36:31 INFO - PROCESS | 8002 | __end_tp_report 02:36:31 INFO - PROCESS | 8002 | __start_cc_report 02:36:31 INFO - PROCESS | 8002 | _x_x_mozilla_cycle_collect,703 02:36:31 INFO - PROCESS | 8002 | __end_cc_report 02:36:31 INFO - PROCESS | 8002 | __startTimestamp1483007791225__endTimestamp 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | ------- Summary: start ------- 02:36:31 INFO - PROCESS | 8002 | Number of tests: 14 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.21 (2.0%) stddev-sans-first:0.71 02:36:31 INFO - PROCESS | 8002 | Values: 106.0 112.0 112.0 113.0 112.0 113.0 113.0 114.0 113.0 112.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:127.00 stddev:18.73 (14.7%) stddev-sans-first:18.51 02:36:31 INFO - PROCESS | 8002 | Values: 153.0 126.0 128.0 178.0 121.0 122.0 120.0 123.0 141.0 125.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 02:36:31 INFO - PROCESS | 8002 | Values: 162.0 162.0 163.0 163.0 165.0 165.0 166.0 163.0 163.0 163.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#3] audio-fft Cycles:10 Average:83.30 Median:78.00 stddev:12.17 (15.6%) stddev-sans-first:12.53 02:36:31 INFO - PROCESS | 8002 | Values: 75.0 78.0 77.0 112.0 80.0 78.0 78.0 79.0 99.0 77.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:85.00 stddev:10.14 (11.9%) stddev-sans-first:10.54 02:36:31 INFO - PROCESS | 8002 | Values: 85.0 87.0 107.0 85.0 90.0 84.0 93.0 83.0 83.0 111.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 02:36:31 INFO - PROCESS | 8002 | Values: 118.0 118.0 119.0 118.0 117.0 118.0 118.0 118.0 117.0 120.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 02:36:31 INFO - PROCESS | 8002 | Values: 149.0 148.0 148.0 148.0 148.0 148.0 149.0 149.0 149.0 147.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:36:31 INFO - PROCESS | 8002 | Values: 96.0 96.0 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:1.10 (1.9%) stddev-sans-first:1.12 02:36:31 INFO - PROCESS | 8002 | Values: 57.0 60.0 56.0 59.0 58.0 58.0 58.0 58.0 58.0 57.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 02:36:31 INFO - PROCESS | 8002 | Values: 69.0 70.0 69.0 68.0 70.0 68.0 68.0 68.0 68.0 68.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:82.50 stddev:2.88 (3.5%) stddev-sans-first:2.99 02:36:31 INFO - PROCESS | 8002 | Values: 81.0 80.0 81.0 83.0 82.0 89.0 80.0 83.0 86.0 81.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:132.50 stddev:12.70 (9.6%) stddev-sans-first:13.46 02:36:31 INFO - PROCESS | 8002 | Values: 136.0 135.0 137.0 128.0 126.0 127.0 130.0 128.0 169.0 130.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:3.09 (1.8%) stddev-sans-first:3.24 02:36:31 INFO - PROCESS | 8002 | Values: 166.0 171.0 167.0 169.0 164.0 173.0 169.0 164.0 165.0 165.0 02:36:31 INFO - PROCESS | 8002 | 02:36:31 INFO - PROCESS | 8002 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.00 stddev:2.53 (4.0%) stddev-sans-first:1.36 02:36:31 INFO - PROCESS | 8002 | Values: 70.0 63.0 63.0 64.0 66.0 63.0 62.0 63.0 61.0 63.0 02:36:31 INFO - PROCESS | 8002 | -------- Summary: end -------- 02:36:31 INFO - PROCESS | 8002 | 02:36:32 INFO - TEST-INFO | 8002: exit 0 02:36:32 INFO - TEST-OK | kraken | took 61554ms 02:36:32 INFO - SUITE-END | took 1175s 02:36:32 INFO - Completed test suite (00:19:35) 02:36:32 INFO - javascript benchmark 02:36:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7092.923714116897, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3091.1190310761435, 3121.8126912385273, 3133.670964548722, 3142.0730884389104, 3155.1481100102974, 5247.291615631889, 5343.572514198864, 5349.465053494649, 5389.461053894609, 5438.646487978279, 5287.339082614673, 5296.25852380666, 5335.413104558505, 5381.273528073719, 5400.0, 4777.928331075037, 4805.087033463639, 4879.9512004880025, 4912.287718280847, 4936.086823937565, 4426.822927082912, 4964.9573589546235, 4978.103941290585, 5025.673331233476, 5036.395632524084, 4804.0872234275375, 4805.903881922342, 4888.144574699449, 4914.97542512287, 4959.776810043557, 4870.464248932614, 4871.3667223260745, 4874.8537543873745, 4895.902081958377, 4920.040592084545, 4842.297866809328, 4845.345878306419, 4865.8783530411665, 4877.951220487786, 4912.214045752662, 5203.271541984125, 5223.033738758304, 5264.184051472028, 5328.786848526054, 5368.382635996872, 5713.342965558931, 5785.797497087624, 5786.797462088806, 5835.5915085944, 5852.912206316909, 5790.623609465371, 5795.681237531934, 5809.390014048507, 5810.302763668386, 5821.941780582182, 5229.581633469314, 5271.78912843482, 5418.620696551243, 5421.647592906488, 5425.321834770653, 5716.28546431696, 5717.342505611825, 5757.0, 5790.652560846363, 5844.6785426801935, 5499.835004949858, 5505.366882808448, 5544.889102218014, 5545.0018996581, 5547.972260138674, 5572.164175373727, 5601.0, 5659.603827732101, 5666.121751128581, 5673.744681489343, 5579.9163012554845, 5673.234113394721, 5697.886042279131, 5727.770889164387, 5763.740631671585, 5764.221830052889, 5766.913496297475, 5768.567357448208, 5772.191893134965, 5795.768169273266, 4259.254630439661, 4366.083122544302, 4379.102284031829, 4390.034192477649, 4421.955780442218, 3815.942760858534, 3829.942550861739, 3838.405047217686, 3876.8836934891997, 3922.2939870823525, 5497.587680923947, 5498.752556134984, 5499.670019798825, 5511.917321240185, 5532.059549876531, 5764.135379692996, 5774.819910765523, 5785.508231800344, 5788.710564471844, 5821.068629019422, 5350.8127215547265, 5386.865328366822, 5475.014497390508, 5488.313960754906, 5492.423295553989, 4811.27830825379, 4835.274708793639, 4844.8304309349705, 4883.121038213085, 4888.0468308679465, 6016.969915150396, 6029.246344206974, 6055.152278680988, 6069.393060693896, 6085.208922840003, 5622.634528755618, 5627.915581266366, 5733.483986441241, 5759.0, 5803.8549036274435, 6011.639301641915, 6052.425019623129, 6053.4551890330085, 6056.848578785565, 6106.084087386927, 5719.2279042328755, 5729.0, 5730.971345143247, 5735.483806457439, 5739.454751798572, 5588.608797384307, 5690.829275121753, 5780.306363236438, 5792.4497172768515, 5814.854628634149, 5665.461781130786, 5689.743961521659, 5700.372958974426, 5705.62913410627, 5747.683877386784, 5700.914486282626, 5718.370979192202, 5719.513841323368, 5720.942790572208, 5721.685307307972, 5483.4516548344845, 5558.749856256397, 5589.748461319332, 5594.1608758686525, 5688.459596338341, 5705.087186050212, 5723.599348045597, 5733.971330143323, 5739.8852022960145, 5758.769649214152, 5485.232067510472, 5493.395726470005, 5498.037843377473, 5536.0, 5537.363203231519], "value": 5295.192859912177, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.4239021856396, 553.2613960362916, 571.4770984549139, 573.0086949577234, 573.0802062689384, 40387.19225615489, 43096.784516077416, 43129.78435107824, 47293.527064729344, 47407.0, 45372.773136134274, 45794.313085303744, 45974.77012614932, 46010.0, 46075.07849843001, 8877.778055548615, 9072.274218062572, 9080.182783549479, 9088.772780680503, 10177.643782467616, 10322.690319290396, 10391.688249352532, 10398.324108932933, 10402.843957340647, 10406.843897341547, 10353.792924141513, 10440.269181157322, 10442.895571044268, 10467.371957682526, 10513.894861051407, 10367.844482332772, 10415.79168416635, 10417.374957502574, 10440.164786817037, 10450.581976720998, 10241.28311018229, 10272.948635256851, 10287.074163325336, 10290.7427314317, 10301.175905927508, 37636.0, 37807.0, 37893.0, 37984.0, 38194.0, 3947.0, 4060.939085913714, 4076.1236334188293, 4078.8368465261356, 4153.044799696087, 32365.838170808995, 33796.32407351864, 34141.0, 34396.48405273923, 34489.4826577599, 4319.33050377192, 4323.135372925428, 4353.434053573015, 4380.8685739427865, 4499.865004049884, 343879.0, 344592.0, 348669.0, 350950.0, 352138.99999999744, 60446.0, 62097.0, 62210.0, 62274.377256227766, 62344.0, 64738.35261647418, 65078.34921650818, 65186.34813651897, 65274.34725652778, 65330.34669653338, 16480.4231851887, 16928.83071169297, 16964.236609352607, 16979.49061528156, 16996.575085622957, 12201.45093470796, 12421.937890310672, 12454.31501267439, 12631.810522842165, 12986.090973631755, 17407.73888391675, 17440.563985900455, 17463.912680436515, 17482.65034699299, 17535.824641753676, 7214.242504536948, 7304.488685791944, 7323.853522929511, 7371.741989030358, 7426.888596671054, 877.1535468273147, 893.8525143351275, 905.2983937448398, 913.6482454255159, 943.3726571829793, 30042.849785750932, 30718.38563228723, 30752.53871191934, 31076.378472430424, 31788.092714436745, 999.980000399988, 1051.8632577764843, 1077.455884778182, 1083.8103331916882, 1095.8191898336845, 16021.679566408606, 16408.589785255463, 16606.58483537897, 16864.662706745796, 17182.914085429493, 540455.0, 541239.0, 545096.0, 545247.0, 547321.0, 58913.0, 59237.407625924054, 59248.0, 59354.0, 59371.40628593745, 1353.316575129573, 1369.6712788930586, 1381.3576686840668, 1392.9233892135828, 1393.9930300348433, 29234.122976310744, 29969.70030299669, 30038.337739114355, 30070.0, 30497.69502305037, 49984.500154997986, 51300.48699513106, 51390.22914656358, 51544.22683659673, 51863.22205167001, 412691.0, 417907.64184716746, 417997.0, 418211.0, 418251.0, 24515.0, 25314.620280696174, 26364.60453093167, 28253.434931300846, 28316.433671326045, 46333.53666463427, 46454.88996210482, 46800.297995529414, 46825.531744682114, 47012.52987470081, 33999.32001359909, 34099.14752131117, 34980.0, 35020.12449688676, 37891.24217515689, 33390.83304583462, 35971.82014089913, 36011.0, 36016.27967440689, 36166.0, 35791.0, 35847.46228806518, 35957.460638090975, 36583.26833463369, 36837.44743828791, 29688.554671680373, 30894.38211235808, 32644.34711305808, 33066.83466582748, 35355.64644353523, 25745.0, 25752.484950300513, 25881.741182588685, 26241.868790655924, 26266.606000909618], "value": 20013.139165133438, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.8398764512724, 388.39992212032405, 398.12015445864637, 399.3530480621393, 430.12040377428156, 16121.838781612181, 16478.17609119545, 17591.472255832312, 18761.061946902668, 18990.81009189908, 11970.162088653798, 12094.0, 12361.134720569564, 12466.626001219955, 12510.249385036905, 5102.1836506159025, 5291.814786482474, 5295.4969277918535, 5295.947040529584, 5308.203769434577, 5097.464766199551, 5158.638895277332, 5237.292965449673, 5258.8685282867855, 5264.763085661135, 5240.004399164147, 5243.161094224924, 5247.816326428576, 5250.3699556053325, 5270.420253772082, 5132.255822905677, 5233.3981592116825, 5246.842594722164, 5273.736313184364, 5314.548263397616, 5107.514786095315, 5135.589152867761, 5144.382674079097, 5161.458046905096, 5166.121759300928, 13824.516141935084, 21372.572548548942, 22261.55476890453, 22271.665925011137, 22541.0, 2621.5412302846926, 2700.6894207166233, 2712.8643567821728, 2772.37621535155, 2784.429192015632, 10477.633282835064, 10657.573697052108, 13829.824464920493, 13964.511242106477, 14986.250687465583, 1934.6517626827165, 2021.777604463508, 2021.9898900505402, 2024.5242368043498, 2033.3290014295394, 76410.0, 77329.61335193344, 78693.0, 78927.0, 80686.5965670168, 9370.85943710845, 9967.551460184164, 10028.548715307828, 11054.00513953748, 11256.15578831591, 13210.735785284432, 13337.866621333857, 13348.065635405623, 13360.665983350495, 13397.732045359038, 7417.703291868264, 7479.588622625699, 7637.541747495168, 7703.922960770433, 7753.573553454501, 7357.117145942521, 7400.407967362706, 7613.505122167041, 7630.160682324939, 7664.961675191588, 4038.8586399476467, 4183.937240941388, 4184.2468355696265, 4258.1057977825, 4265.317549192115, 2033.220931684776, 2035.0, 2035.714999900015, 2068.8758674479277, 2099.685047242926, 881.4667126388442, 921.2675922641382, 922.4557511068501, 931.6878845586668, 943.5848226780195, 17451.0, 17663.0, 18287.45137645873, 18530.16614252362, 18657.626847462976, 925.9444433334022, 930.688219446493, 941.3222479814523, 947.3610653569364, 952.3286083311249, 9389.342746007851, 10209.183265338756, 10230.897691023143, 10448.843267350996, 10943.726406839733, 392104.0, 396025.0, 396088.0, 402111.0, 404760.0, 63264.683676582245, 78516.21483785204, 83376.0, 86483.56758216294, 87205.0, 1033.3696445168441, 1060.522764755863, 1085.039739830256, 1088.3796236145479, 1103.4593049405894, 16857.241424135696, 17200.483985480456, 17513.124343783016, 17678.646427071642, 17909.731354029438, 37273.0, 38311.0, 39059.0, 39325.80337098296, 40573.18853622852, 53115.734421327645, 55286.72356638191, 55332.17001744897, 55494.44505555055, 55838.441615583324, 8468.830623387621, 8488.83022339562, 8500.787480312794, 8663.95668021656, 8912.291498136572, 11123.610673626385, 11991.880081199077, 13078.0, 13304.866951330363, 13657.385417656427, 11547.769044618892, 11830.763384732429, 11979.460924258256, 11996.400179990791, 12259.632211033684, 11610.825837612274, 11671.88328116708, 11965.940170299093, 11999.82000270014, 12036.157468977088, 6513.185851768529, 8756.387052906235, 9198.908010919806, 11588.826167607323, 11676.766464670829, 8581.313494920267, 8683.826323473622, 8749.65001399937, 8773.692920747742, 8804.867926980973, 10701.411422371844, 11143.832842507207, 11195.888041119484, 11528.942355288169, 11753.177277590486], "value": 8729.868140358638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.8768910798028, 1370.8354997400313, 1374.5876237128864, 1374.9862501374982, 1380.0339762166482, 7727.381809455244, 7961.363090952725, 8115.14790946951, 8146.6334014969325, 8214.589270536471, 8001.6799328026955, 8126.10612832588, 8146.959265203665, 8158.510489370642, 8184.631691573879, 2190.890455477224, 2229.4649284171765, 2240.2271216430304, 2242.76450972648, 2242.9887850560726, 2187.332863476639, 2242.9663555046686, 2243.304575581567, 2254.954900901981, 2267.489814791667, 2244.8989795459242, 2247.71903512061, 2255.751867294596, 2258.6837842702034, 2260.8078313343385, 2154.482924098213, 2175.0, 2180.1170525936864, 2184.879831609269, 2185.508260641359, 2225.799678028969, 2241.8654880707045, 2255.030336955109, 2261.604219261623, 2262.694536237603, 7936.3254123399, 8141.8371632567605, 8142.022957245109, 8153.551554664491, 8154.469959452616, 4770.952290477086, 4905.754712264373, 4929.1620424527555, 4936.0, 4936.777844996984, 3019.773516986213, 3147.795393299451, 3191.7287030602192, 3205.6473787883315, 3207.1982004498877, 1343.9193648381129, 1395.5115709501592, 1413.1944791468866, 1428.1502506009012, 1466.7286551987809, 10740.624078157227, 10779.946100269526, 10863.07663848566, 10872.836907446395, 10913.67258982224, 3833.9041523962123, 4025.939610905839, 4035.2534781065597, 4060.6548443382053, 4112.0, 8395.24442800151, 8397.244248017707, 8419.579021048923, 8470.703525376583, 8503.234708876107, 4603.424571928509, 4669.556392142741, 4683.906321873544, 4711.505291944365, 4715.24556071027, 4337.306031035082, 4355.15074560464, 4409.889752756207, 4411.228035093845, 4419.9779001105435, 2875.439289338559, 2999.580058791771, 3037.9088627341216, 3042.0417568465864, 3072.7849050566692, 1043.801677681238, 1084.2410312781085, 1088.0479580367178, 1100.1199040767353, 1102.895224953628, 969.3795970578694, 976.9071938165863, 995.9452230127413, 1000.3847633705219, 1016.8220561401725, 5458.836234912959, 5545.140503222007, 5571.777128914879, 5605.80379686709, 5645.322561292671, 966.1546147121269, 1001.2240513602017, 1033.2508931025097, 1039.0440794469107, 1040.9115225205942, 3946.1121247719607, 4021.0349516116507, 4028.899277518027, 4055.3105971984833, 4079.836806527765, 9577.664781732607, 9654.565544550513, 9765.365251258645, 9803.362781419184, 9819.214462842956, 9468.289878259158, 9494.477803720862, 9648.71053868371, 9692.321537492307, 9733.513324333755, 7484.214157513492, 7533.20901305366, 7569.810754731286, 7597.050368703912, 7653.043369578802, 5429.619926605258, 5455.18172274162, 5457.126859702428, 5519.613627046068, 5570.498655121059, 8764.517951512727, 8819.647214111365, 8858.64565417376, 8978.86531702012, 8989.146031127033, 8998.325125615474, 8998.82002359936, 9114.27085833145, 9136.588853501475, 9238.076192380977, 6643.0, 6733.2593414725325, 6733.360330768569, 6738.561993470409, 6740.494462915203, 7459.813504662427, 7486.326230639269, 7545.320921117126, 7566.546007239584, 7623.847523049397, 6806.455483561403, 7101.964490177516, 7105.715771369294, 7126.5011449198055, 7224.74713385029, 6670.266270710315, 6766.086578312062, 6789.083473730983, 6984.126984126984, 6987.056747339044, 5985.491233245223, 6125.632462052291, 6159.045347971071, 6171.5988460751705, 6195.0, 4808.206645903527, 4854.0, 4922.507749225048, 4975.10448119342, 5165.328507294028, 4857.708537487762, 4912.606527176719, 4945.876353091057, 4984.800607975785, 5110.488951105009], "value": 4284.01176175049, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.8262325395436, 379.91262009737744, 380.92762375148726, 383.1455853446812, 383.3444809375967, 5262.578993680506, 5343.332083489564, 5407.864803379917, 5438.646487978283, 5447.618666693333, 5274.393444753845, 5307.495787900153, 5360.571154307656, 5373.731313434332, 5398.325209348832, 4282.379055037026, 4298.0, 4301.204277208711, 4322.567743225668, 4333.978330108345, 4178.874633760976, 4226.957730422702, 4269.530351661315, 4309.806058727349, 4311.956880431203, 4213.620774130328, 4230.280852255109, 4231.788410579474, 4240.978795106035, 4242.857357034693, 4227.556106608792, 4229.492460904696, 4235.470566179228, 4249.723767955088, 4251.957480425187, 3761.9059523512133, 3854.2484215577974, 3865.381538953782, 3883.8058097095313, 3887.4168874669026, 5206.255904272036, 5209.7134657593815, 5210.452902445264, 5220.817271395521, 5245.898361344122, 4892.9755351223375, 4902.607791376717, 4913.336699545552, 4919.434265059493, 4931.988942266827, 5365.704886231256, 5572.4148964358565, 5629.705167811386, 5649.943500565024, 5656.886862262772, 4172.499300083979, 4193.727407718519, 4194.4337514435465, 4197.412362269285, 4199.538050814408, 5846.883062338772, 5898.233229680157, 5903.616264942808, 5926.0, 5956.970215148896, 4224.9155016899485, 4325.134973005349, 4328.78793937698, 4357.455318085239, 4380.167768124046, 4323.924516232226, 4371.754050095771, 4381.737095774264, 4402.669799765031, 4452.4211852458975, 4860.222364421676, 4877.024804953929, 4890.437599676012, 4900.803967841318, 4912.631552633567, 5416.647917885403, 5625.240592520039, 5633.155026746021, 5659.377468478464, 5674.2339784129435, 1459.8175228096488, 1469.3241109089938, 1483.7180935622198, 1487.3564710994197, 1489.2181604657426, 1017.2167431078176, 1048.9737756556146, 1064.3613831700918, 1065.7815147894662, 1070.2722148938797, 5243.501867322674, 5305.8938821223355, 5328.200769884471, 5346.705931173823, 5357.098230128327, 5594.776208951596, 5627.943720562742, 5629.605927585111, 5634.267545219095, 5644.689542075225, 4971.950280497221, 5237.764300606482, 5278.228929811803, 5318.994767956093, 5380.542653874464, 2933.9315782373374, 2954.4386566552284, 2964.836933968609, 3014.4724673182104, 3040.3767227718263, 5759.396256392417, 5872.207252021008, 5879.617824841372, 5919.67441790697, 5970.791022314285, 5585.832425027255, 5622.522085622768, 5655.4344565543115, 5658.01969310738, 5674.290713660793, 5825.776586916711, 5844.6785426801935, 5862.208601838697, 5888.646681199141, 5916.319623243296, 5418.701971391612, 5521.503064724194, 5549.556035517068, 5584.609077364546, 5606.215129881738, 5461.481159289861, 5485.204645326485, 5506.191842160607, 5517.44825517445, 5612.270404847343, 5855.414458554281, 5861.706914654335, 5862.853428664317, 5889.499392551681, 5890.786653811322, 3991.900202494961, 4163.937540936828, 4178.140727376288, 4189.601987811153, 4197.601227883346, 5560.944390556043, 5562.833115006556, 5563.610547261653, 5599.608027438203, 5640.85897852557, 5314.893702125859, 5462.945370546403, 5472.945270547243, 5479.137093388267, 5497.845452455029, 5384.219288203267, 5411.864703382447, 5423.566114710892, 5428.430014848542, 5434.64674796137, 5448.809291674773, 5450.536704380013, 5474.479924407175, 5483.587221622473, 5493.285872836505, 5253.395859476056, 5381.596380271573, 5414.3232095988005, 5423.755930983037, 5498.285222920995, 5232.189010703347, 5261.342332208474, 5274.072889546339, 5294.708791016377, 5324.550706674973], "value": 4308.549923357003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5057083043965, 694.1219357512747, 754.6943487887407, 824.1717074340289, 843.0978852627688, 15224.847751522482, 16765.161741912918, 16798.076105814205, 16798.160091995396, 16817.49547513575, 15184.468543600977, 15366.69266614667, 15382.769258461132, 15382.923085384557, 15476.071435713837, 682.3619915379112, 698.4517154034085, 788.5268838696793, 793.9483933544316, 812.5327936436543, 786.6931896560346, 794.9761507154794, 807.4065561812092, 813.2396209544065, 831.9542425166613, 858.8582883824192, 868.4615538366222, 883.4346018548134, 884.8805411269493, 887.4586502233632, 848.5926755157498, 874.2044739287281, 874.8425283448945, 876.5617191404298, 877.5436772878099, 626.8965620672584, 630.8296759874854, 635.463033736492, 638.2213699286863, 638.5817289675224, 15943.123128227942, 16026.118563479004, 16254.83745162545, 16475.093869837154, 16801.41195058167, 15437.0, 15621.218939053002, 15775.921120394325, 15890.36438542457, 15895.841041589552, 12134.878651213463, 12233.632991010283, 12288.877111228952, 13204.207747535083, 13295.867041329657, 3910.804459777028, 3949.723519353646, 3969.920601587981, 4132.690048246393, 4135.193637240739, 81967.59016204941, 82747.17252827455, 83348.58325708333, 84561.0, 85931.14068859293, 11327.886721132849, 11373.37446440437, 11705.0, 12013.579524716768, 12140.514379424723, 23316.883415583154, 23893.52212955731, 23928.0, 23936.640950385758, 23983.880080599487, 8654.6970856021, 8978.191962723386, 9069.54652267384, 9081.54592270384, 9287.315815263326, 4519.209138400766, 4607.9308810367875, 4611.792469338888, 4623.86128416148, 4786.593139583105, 3601.7838929664304, 3655.0862284428895, 3658.213484100931, 3701.1117331840164, 3709.3323201823932, 585.1749033862234, 586.5102639296235, 595.9046552551571, 596.6628854697169, 608.9208402907682, 754.3701009656997, 761.672480833247, 767.5087943716027, 767.9884801727978, 793.7896457438783, 9771.0, 9873.0, 9972.750681233027, 10033.0, 10227.28409011362, 727.9053723015974, 748.7603966730594, 750.512167091395, 762.9433234969496, 777.2965466252937, 4710.528947105262, 4975.278584605212, 4981.950180498221, 4982.90034199314, 4995.550400463903, 69749.65125174342, 70975.64512177509, 81791.0, 84239.15760842436, 86192.13807861967, 70650.0, 71429.64285178541, 71860.0, 72643.0, 74869.0, 52589.47410525922, 65907.34092659011, 66352.6682366585, 66424.66787666127, 66671.0, 9842.65550705722, 10640.308379955279, 10958.616448424267, 11047.392393418439, 11072.11423086135, 40521.59478405296, 40908.18183636251, 41178.38232426456, 41667.0, 42123.0, 53087.0, 53116.0, 53610.73194634002, 54144.45855541394, 56514.0, 12816.0, 12902.354882255662, 13018.479260829461, 13273.933630332172, 13456.932715336361, 14744.189069601274, 14751.852481475047, 14773.482928097465, 14985.925070374578, 14994.400223991355, 12837.229766214057, 12988.480460781462, 13326.533571324957, 13362.732745345233, 13369.131006484547, 12872.292023938773, 13171.868281317447, 13371.395432547768, 13560.186388816308, 13595.796063058864, 13767.862321376659, 13838.0, 14202.644933876323, 14567.41730330775, 14588.85411145875, 11352.716182095184, 11583.8262426062, 11651.359175245443, 11684.298942063504, 11866.584669536525, 11669.59156429521, 11694.181407301407, 11769.52921883115, 11798.351090690094, 11947.402629868646], "value": 7456.2209832131775, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 114.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 128.0, 178.0, 121.0, 122.0, 120.0, 123.0, 141.0, 125.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 163.0, 165.0, 165.0, 166.0, 163.0, 163.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 112.0, 80.0, 78.0, 78.0, 79.0, 99.0, 77.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 107.0, 85.0, 90.0, 84.0, 93.0, 83.0, 83.0, 111.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 120.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 149.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 56.0, 59.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 68.0, 70.0, 68.0, 68.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 83.0, 82.0, 89.0, 80.0, 83.0, 86.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 137.0, 128.0, 126.0, 127.0, 130.0, 128.0, 169.0, 130.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 167.0, 169.0, 164.0, 173.0, 169.0, 164.0, 165.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 63.0, 63.0, 64.0, 66.0, 63.0, 62.0, 63.0, 61.0, 63.0], "value": 63.8, "unit": "score"}]}]} 02:36:32 INFO - Return code: 0 02:36:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:36:32 ERROR - # TBPL SUCCESS # 02:36:32 INFO - Running post-action listener: _resource_record_post_action 02:36:32 INFO - [mozharness: 2016-12-29 10:36:32.560304Z] Finished run-tests step (success) 02:36:32 INFO - Running post-run listener: _resource_record_post_run 02:36:32 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 12288; Write bytes: 411303936; Read time: 472; Write time: 441620 02:36:32 INFO - TinderboxPrint: CPU usage
13.5% 02:36:32 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 472 02:36:32 INFO - TinderboxPrint: I/O write bytes / time
411,303,936 / 441,620 02:36:32 INFO - TinderboxPrint: CPU idle
8,234.9 (86.5%) 02:36:32 INFO - TinderboxPrint: CPU user
1,222.4 (12.8%) 02:36:32 INFO - TinderboxPrint: Swap in / out
0 / 0 02:36:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117153792; Read time: 280; Write time: 135408 02:36:32 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 294150144; Read time: 192; Write time: 306212 02:36:33 INFO - Running post-run listener: _upload_blobber_files 02:36:33 INFO - Blob upload gear active. 02:36:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:36:33 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:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:36:33 INFO - (blobuploader) - INFO - Open directory for files ... 02:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:34 INFO - (blobuploader) - INFO - Done attempting. 02:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:37 INFO - (blobuploader) - INFO - Done attempting. 02:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:38 INFO - (blobuploader) - INFO - Done attempting. 02:36:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:36:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:39 INFO - (blobuploader) - INFO - Done attempting. 02:36:39 INFO - (blobuploader) - INFO - Iteration through files over. 02:36:39 INFO - Return code: 0 02:36:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:36:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:36:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0e3d2f05ffbceeccc37ce8b585131528b3e4a46000d869027f88b5361ec9d3f4f4a9154137a8c6a9c5e0d78ba910c77f3cdc7af98c06f5f12c4c4161f2ad3ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/20f5b864afbdb3200f25d6c30136328803558acb18a94dcd06faee55b68a8520d874cb4945fbd59a2201311cbb227442b4cfc0f1df8fae3b5deaa54cec64ace7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e72fff78eed6d3c8d43742bb1e2a1e7b5f9ea38461d359cf890aaf22fc7dfaf2f085079f237888e853c736b0a7904512a8c2a3dd0dccc62301eaff2c8e07a8cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38493056a185c3b5c779475c27d34ceb9e8ff2a8fdd2a99e9d6b4671ed5347f0e8f45eaf57409ebaa11bffe2feea96225ad2185ef6e3fce4468ae23fd65d1030"} 02:36:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:36:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:36:39 INFO - Contents: 02:36:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0e3d2f05ffbceeccc37ce8b585131528b3e4a46000d869027f88b5361ec9d3f4f4a9154137a8c6a9c5e0d78ba910c77f3cdc7af98c06f5f12c4c4161f2ad3ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/20f5b864afbdb3200f25d6c30136328803558acb18a94dcd06faee55b68a8520d874cb4945fbd59a2201311cbb227442b4cfc0f1df8fae3b5deaa54cec64ace7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e72fff78eed6d3c8d43742bb1e2a1e7b5f9ea38461d359cf890aaf22fc7dfaf2f085079f237888e853c736b0a7904512a8c2a3dd0dccc62301eaff2c8e07a8cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38493056a185c3b5c779475c27d34ceb9e8ff2a8fdd2a99e9d6b4671ed5347f0e8f45eaf57409ebaa11bffe2feea96225ad2185ef6e3fce4468ae23fd65d1030"} 02:36:39 INFO - Running post-run listener: copy_logs_to_upload_dir 02:36:39 INFO - Copying logs to upload dir... 02:36:39 INFO - mkdir: /builds/slave/test/build/upload/logs 02:36:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.709462 ========= master_lag: 0.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-12-29 02:36:36.967444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 02:36:36.972410) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0e3d2f05ffbceeccc37ce8b585131528b3e4a46000d869027f88b5361ec9d3f4f4a9154137a8c6a9c5e0d78ba910c77f3cdc7af98c06f5f12c4c4161f2ad3ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/20f5b864afbdb3200f25d6c30136328803558acb18a94dcd06faee55b68a8520d874cb4945fbd59a2201311cbb227442b4cfc0f1df8fae3b5deaa54cec64ace7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e72fff78eed6d3c8d43742bb1e2a1e7b5f9ea38461d359cf890aaf22fc7dfaf2f085079f237888e853c736b0a7904512a8c2a3dd0dccc62301eaff2c8e07a8cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38493056a185c3b5c779475c27d34ceb9e8ff2a8fdd2a99e9d6b4671ed5347f0e8f45eaf57409ebaa11bffe2feea96225ad2185ef6e3fce4468ae23fd65d1030"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483000203/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0e3d2f05ffbceeccc37ce8b585131528b3e4a46000d869027f88b5361ec9d3f4f4a9154137a8c6a9c5e0d78ba910c77f3cdc7af98c06f5f12c4c4161f2ad3ad8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/20f5b864afbdb3200f25d6c30136328803558acb18a94dcd06faee55b68a8520d874cb4945fbd59a2201311cbb227442b4cfc0f1df8fae3b5deaa54cec64ace7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e72fff78eed6d3c8d43742bb1e2a1e7b5f9ea38461d359cf890aaf22fc7dfaf2f085079f237888e853c736b0a7904512a8c2a3dd0dccc62301eaff2c8e07a8cd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38493056a185c3b5c779475c27d34ceb9e8ff2a8fdd2a99e9d6b4671ed5347f0e8f45eaf57409ebaa11bffe2feea96225ad2185ef6e3fce4468ae23fd65d1030"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 02:36:37.000100) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:36:37.000443) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1483002503.456988-1168406252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004586 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:36:37.053049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 02:36:37.053460) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 02:36:37.053793) ========= ========= Total master_lag: 0.42 =========