builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1482964567.82 results: success (0) buildid: 20161228141815 builduid: 1cdad23b58bf41708713bc91a01b600b revision: fce32b66735c5e444f6e2025789ffab1aa1663e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:07.819241) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:07.819875) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:07.820167) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:07.835537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:07.835951) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 14:36:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.7M=0s 2016-12-28 14:36:09 (32.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178361 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:08.028378) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:08.028715) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020020 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:08.078478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 14:36:08.078831) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev fce32b66735c5e444f6e2025789ffab1aa1663e3 --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 fce32b66735c5e444f6e2025789ffab1aa1663e3 --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=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 14:36:09,316 truncating revision to first 12 chars 2016-12-28 14:36:09,316 Setting DEBUG logging. 2016-12-28 14:36:09,316 attempt 1/10 2016-12-28 14:36:09,316 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/fce32b66735c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 14:36:10,615 unpacking tar archive at: mozilla-inbound-fce32b66735c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.640423 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 14:36:09.732552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:09.732912) ========= script_repo_revision: fce32b66735c5e444f6e2025789ffab1aa1663e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:09.733338) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:09.733609) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 14:36:09.741836) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-12-28 14:36:09.742153) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:36:11 INFO - MultiFileLogger online at 20161228 14:36:11 in /builds/slave/test 14:36:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 14:36:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:36:11 INFO - {'append_to_log': False, 14:36:11 INFO - 'base_work_dir': '/builds/slave/test', 14:36:11 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 14:36:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:36:11 INFO - 'branch': 'Mozilla-Inbound', 14:36:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:11 INFO - 'config_files': ('talos/linux_config.py',), 14:36:11 INFO - 'default_actions': ('clobber', 14:36:11 INFO - 'read-buildbot-config', 14:36:11 INFO - 'download-and-extract', 14:36:11 INFO - 'populate-webroot', 14:36:11 INFO - 'create-virtualenv', 14:36:11 INFO - 'install', 14:36:11 INFO - 'run-tests'), 14:36:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:11 INFO - 'download_minidump_stackwalk': True, 14:36:11 INFO - 'download_symbols': 'ondemand', 14:36:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:11 INFO - 'installer_path': 'installer.exe', 14:36:11 INFO - 'log_level': 'info', 14:36:11 INFO - 'log_name': 'talos', 14:36:11 INFO - 'log_to_console': True, 14:36:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:36:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:36:11 INFO - 'opt_config_files': (), 14:36:11 INFO - 'pip_index': False, 14:36:11 INFO - 'sps_profile': False, 14:36:11 INFO - 'sps_profile_interval': 0, 14:36:11 INFO - 'suite': 'dromaeojs', 14:36:11 INFO - 'system_bits': '32', 14:36:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:11 INFO - 'title': 'talos-linux64-ix-037', 14:36:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:11 INFO - 'use_talos_json': True, 14:36:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:36:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:11 INFO - 'work_dir': 'build'} 14:36:11 INFO - [mozharness: 2016-12-28 22:36:11.073426Z] Running clobber step. 14:36:11 INFO - Running pre-action listener: _resource_record_pre_action 14:36:11 INFO - Running main action method: clobber 14:36:11 INFO - rmtree: /builds/slave/test/build 14:36:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:36:12 INFO - Running post-action listener: _resource_record_post_action 14:36:12 INFO - [mozharness: 2016-12-28 22:36:12.079094Z] Finished clobber step (success) 14:36:12 INFO - [mozharness: 2016-12-28 22:36:12.079193Z] Running read-buildbot-config step. 14:36:12 INFO - Running pre-action listener: _resource_record_pre_action 14:36:12 INFO - Running main action method: read_buildbot_config 14:36:12 INFO - Using buildbot properties: 14:36:12 INFO - { 14:36:12 INFO - "project": "", 14:36:12 INFO - "product": "firefox", 14:36:12 INFO - "script_repo_revision": "production", 14:36:12 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 14:36:12 INFO - "repository": "", 14:36:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 14:36:12 INFO - "buildid": "20161228141815", 14:36:12 INFO - "stage_platform": "linux64-pgo", 14:36:12 INFO - "basedir": "/builds/slave/test", 14:36:12 INFO - "buildnumber": 3140, 14:36:12 INFO - "platform": "ubuntu64_hw", 14:36:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:36:12 INFO - "slavebuilddir": "test", 14:36:12 INFO - "branch": "mozilla-inbound", 14:36:12 INFO - "revision": "fce32b66735c5e444f6e2025789ffab1aa1663e3", 14:36:12 INFO - "slavename": "talos-linux64-ix-037", 14:36:12 INFO - "builduid": "1cdad23b58bf41708713bc91a01b600b", 14:36:12 INFO - "repo_path": "integration/mozilla-inbound" 14:36:12 INFO - } 14:36:12 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:36:12 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 14:36:12 INFO - Running post-action listener: _resource_record_post_action 14:36:12 INFO - [mozharness: 2016-12-28 22:36:12.081601Z] Finished read-buildbot-config step (success) 14:36:12 INFO - [mozharness: 2016-12-28 22:36:12.081689Z] Running download-and-extract step. 14:36:12 INFO - Running pre-action listener: _resource_record_pre_action 14:36:12 INFO - Running main action method: download_and_extract 14:36:12 INFO - mkdir: /builds/slave/test/build/tests 14:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:36:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/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 14:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/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 14:36:12 INFO - Downloaded 1361 bytes. 14:36:12 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:36:12 INFO - Using the following test package requirements: 14:36:12 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:36:12 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:36:12 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:36:12 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'jsshell-linux-x86_64.zip'], 14:36:12 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:36:12 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:36:12 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:36:12 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:36:12 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:36:12 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:36:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:36:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:36:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:36:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:36:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:36:12 INFO - Expected file size: 21833444 14:36:12 INFO - Obtained file size: 21833444 14:36:16 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 14:36:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:36:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:36:16 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:36:16 INFO - Expected file size: 21833444 14:36:16 INFO - Obtained file size: 21833444 14:36:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:36:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:36:21 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:36:21 INFO - Expected file size: 14409776 14:36:21 INFO - Obtained file size: 14409776 14:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:21 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:36:21 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/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 14:36:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/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 14:36:22 INFO - Downloaded 61376469 bytes. 14:36:22 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:36:22 INFO - mkdir: /builds/slave/test/properties 14:36:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:36:22 INFO - Writing to file /builds/slave/test/properties/build_url 14:36:22 INFO - Contents: 14:36:22 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:36:22 INFO - Running post-action listener: _resource_record_post_action 14:36:22 INFO - Running post-action listener: set_extra_try_arguments 14:36:22 INFO - [mozharness: 2016-12-28 22:36:22.841332Z] Finished download-and-extract step (success) 14:36:22 INFO - [mozharness: 2016-12-28 22:36:22.841561Z] Running populate-webroot step. 14:36:22 INFO - Running pre-action listener: _resource_record_pre_action 14:36:22 INFO - Running main action method: populate_webroot 14:36:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:36:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:36:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:36:22 INFO - '--tppagecycles', 14:36:22 INFO - '7'], 14:36:22 INFO - 'tests': ['tsvgm']}}, 14:36:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:36:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:36:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:36:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:36:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['damp', 'tps']}, 14:36:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'tests': ['damp', 'tps']}, 14:36:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['dromaeo_dom']}, 14:36:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:36:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['basic_compositor_video']}, 14:36:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:36:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'other-e10s': {'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['a11yr', 14:36:22 INFO - 'ts_paint', 14:36:22 INFO - 'tpaint', 14:36:22 INFO - 'sessionrestore', 14:36:22 INFO - 'sessionrestore_no_auto_restore', 14:36:22 INFO - 'tabpaint']}, 14:36:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['tsvgx', 14:36:22 INFO - 'tsvgr_opacity', 14:36:22 INFO - 'tart', 14:36:22 INFO - 'tscrollx', 14:36:22 INFO - 'cart', 14:36:22 INFO - 'tsvg_static']}, 14:36:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:36:22 INFO - 'tsvgr_opacity', 14:36:22 INFO - 'tart', 14:36:22 INFO - 'tscrollx', 14:36:22 INFO - 'cart', 14:36:22 INFO - 'tsvg_static']}, 14:36:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'talos_options': ['--disable-e10s'], 14:36:22 INFO - 'tests': ['tp5o']}, 14:36:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:36:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'talos_options': ['--disable-e10s', 14:36:22 INFO - '--xperf_path', 14:36:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:22 INFO - 'tests': ['tp5n']}, 14:36:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:36:22 INFO - 'talos_options': ['--xperf_path', 14:36:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:22 INFO - 'tests': ['tp5n']}}, 14:36:22 INFO - 'talos.zip': {'path': '', 14:36:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:36:22 INFO - Running post-action listener: _resource_record_post_action 14:36:22 INFO - [mozharness: 2016-12-28 22:36:22.861853Z] Finished populate-webroot step (success) 14:36:22 INFO - [mozharness: 2016-12-28 22:36:22.861943Z] Running create-virtualenv step. 14:36:22 INFO - Running pre-action listener: _resource_record_pre_action 14:36:22 INFO - Running main action method: create_virtualenv 14:36:22 INFO - Creating virtualenv /builds/slave/test/build/venv 14:36:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:36:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:36:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:36:22 INFO - Using real prefix '/usr' 14:36:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:36:23 INFO - Installing distribute.............................................................................................................................................................................................done. 14:36:25 INFO - Installing pip.................done. 14:36:25 INFO - Return code: 0 14:36:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:36:25 INFO - Reading from file tmpfile_stdout 14:36:25 INFO - Output received: 14:36:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:36:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:36: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')]} 14:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36: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')]} 14:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:36:25 INFO - Using env: {'DISPLAY': ':0', 14:36:25 INFO - 'HOME': '/home/cltbld', 14:36:25 INFO - 'LANG': 'en_US.UTF-8', 14:36:25 INFO - 'LANGUAGE': 'en_US:en', 14:36:25 INFO - 'LOGNAME': 'cltbld', 14:36:25 INFO - 'MAIL': '/var/mail/cltbld', 14:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:25 INFO - 'MOZ_NO_REMOTE': '1', 14:36:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:25 INFO - 'NO_EM_RESTART': '1', 14:36:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:25 INFO - 'PWD': '/builds/slave/test', 14:36:25 INFO - 'SHELL': '/bin/bash', 14:36:25 INFO - 'SHLVL': '1', 14:36:25 INFO - 'TERM': 'linux', 14:36:25 INFO - 'TMOUT': '86400', 14:36:25 INFO - 'USER': 'cltbld', 14:36:25 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:25 INFO - '_': '/tools/buildbot/bin/python'} 14:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:36:25 INFO - Cleaning up... 14:36:25 INFO - Return code: 0 14:36:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:36: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')]} 14:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36: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')]} 14:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:36:25 INFO - Using env: {'DISPLAY': ':0', 14:36:25 INFO - 'HOME': '/home/cltbld', 14:36:25 INFO - 'LANG': 'en_US.UTF-8', 14:36:25 INFO - 'LANGUAGE': 'en_US:en', 14:36:25 INFO - 'LOGNAME': 'cltbld', 14:36:25 INFO - 'MAIL': '/var/mail/cltbld', 14:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:25 INFO - 'MOZ_NO_REMOTE': '1', 14:36:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:25 INFO - 'NO_EM_RESTART': '1', 14:36:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:25 INFO - 'PWD': '/builds/slave/test', 14:36:25 INFO - 'SHELL': '/bin/bash', 14:36:25 INFO - 'SHLVL': '1', 14:36:25 INFO - 'TERM': 'linux', 14:36:25 INFO - 'TMOUT': '86400', 14:36:25 INFO - 'USER': 'cltbld', 14:36:25 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:25 INFO - '_': '/tools/buildbot/bin/python'} 14:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:26 INFO - Downloading/unpacking psutil>=3.1.1 14:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:36:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:28 INFO - Installing collected packages: psutil 14:36:28 INFO - Running setup.py install for psutil 14:36:28 INFO - building 'psutil._psutil_linux' extension 14:36:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:36:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:36:28 INFO - building 'psutil._psutil_posix' extension 14:36:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:36:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:36:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:28 INFO - Successfully installed psutil 14:36:28 INFO - Cleaning up... 14:36:28 INFO - Return code: 0 14:36:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:36:28 INFO - Using env: {'DISPLAY': ':0', 14:36:28 INFO - 'HOME': '/home/cltbld', 14:36:28 INFO - 'LANG': 'en_US.UTF-8', 14:36:28 INFO - 'LANGUAGE': 'en_US:en', 14:36:28 INFO - 'LOGNAME': 'cltbld', 14:36:28 INFO - 'MAIL': '/var/mail/cltbld', 14:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:28 INFO - 'MOZ_NO_REMOTE': '1', 14:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:28 INFO - 'NO_EM_RESTART': '1', 14:36:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:28 INFO - 'PWD': '/builds/slave/test', 14:36:28 INFO - 'SHELL': '/bin/bash', 14:36:28 INFO - 'SHLVL': '1', 14:36:28 INFO - 'TERM': 'linux', 14:36:28 INFO - 'TMOUT': '86400', 14:36:28 INFO - 'USER': 'cltbld', 14:36:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:28 INFO - '_': '/tools/buildbot/bin/python'} 14:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:36:31 INFO - Installing collected packages: mozsystemmonitor 14:36:31 INFO - Running setup.py install for mozsystemmonitor 14:36:31 INFO - Successfully installed mozsystemmonitor 14:36:31 INFO - Cleaning up... 14:36:31 INFO - Return code: 0 14:36:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:36: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')]} 14:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36: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')]} 14:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:36:31 INFO - Using env: {'DISPLAY': ':0', 14:36:31 INFO - 'HOME': '/home/cltbld', 14:36:31 INFO - 'LANG': 'en_US.UTF-8', 14:36:31 INFO - 'LANGUAGE': 'en_US:en', 14:36:31 INFO - 'LOGNAME': 'cltbld', 14:36:31 INFO - 'MAIL': '/var/mail/cltbld', 14:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:31 INFO - 'MOZ_NO_REMOTE': '1', 14:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:31 INFO - 'NO_EM_RESTART': '1', 14:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:31 INFO - 'PWD': '/builds/slave/test', 14:36:31 INFO - 'SHELL': '/bin/bash', 14:36:31 INFO - 'SHLVL': '1', 14:36:31 INFO - 'TERM': 'linux', 14:36:31 INFO - 'TMOUT': '86400', 14:36:31 INFO - 'USER': 'cltbld', 14:36:31 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:31 INFO - '_': '/tools/buildbot/bin/python'} 14:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:31 INFO - Downloading/unpacking jsonschema==2.5.1 14:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:36:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:33 INFO - Downloading functools32-3.2.3-2.tar.gz 14:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:36:33 INFO - warning: no files found matching '*.txt' 14:36:33 INFO - no previously-included directories found matching 'build' 14:36:33 INFO - no previously-included directories found matching 'dist' 14:36:33 INFO - no previously-included directories found matching '.git*' 14:36:33 INFO - Installing collected packages: jsonschema, functools32 14:36:33 INFO - Running setup.py install for functools32 14:36:33 INFO - warning: no files found matching '*.txt' 14:36:33 INFO - no previously-included directories found matching 'build' 14:36:33 INFO - no previously-included directories found matching 'dist' 14:36:33 INFO - no previously-included directories found matching '.git*' 14:36:33 INFO - Successfully installed jsonschema functools32 14:36:33 INFO - Cleaning up... 14:36:33 INFO - Return code: 0 14:36:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:36:33 INFO - Using env: {'DISPLAY': ':0', 14:36:33 INFO - 'HOME': '/home/cltbld', 14:36:33 INFO - 'LANG': 'en_US.UTF-8', 14:36:33 INFO - 'LANGUAGE': 'en_US:en', 14:36:33 INFO - 'LOGNAME': 'cltbld', 14:36:33 INFO - 'MAIL': '/var/mail/cltbld', 14:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:33 INFO - 'MOZ_NO_REMOTE': '1', 14:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:33 INFO - 'NO_EM_RESTART': '1', 14:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:33 INFO - 'PWD': '/builds/slave/test', 14:36:33 INFO - 'SHELL': '/bin/bash', 14:36:33 INFO - 'SHLVL': '1', 14:36:33 INFO - 'TERM': 'linux', 14:36:33 INFO - 'TMOUT': '86400', 14:36:33 INFO - 'USER': 'cltbld', 14:36:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:33 INFO - '_': '/tools/buildbot/bin/python'} 14:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:36:34 INFO - Cleaning up... 14:36:34 INFO - Return code: 0 14:36:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:36: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')]} 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36: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')]} 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:34 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 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:34 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 14:36:34 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 14:36:34 INFO - Using env: {'DISPLAY': ':0', 14:36:34 INFO - 'HOME': '/home/cltbld', 14:36:34 INFO - 'LANG': 'en_US.UTF-8', 14:36:34 INFO - 'LANGUAGE': 'en_US:en', 14:36:34 INFO - 'LOGNAME': 'cltbld', 14:36:34 INFO - 'MAIL': '/var/mail/cltbld', 14:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:34 INFO - 'MOZ_NO_REMOTE': '1', 14:36:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:34 INFO - 'NO_EM_RESTART': '1', 14:36:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:34 INFO - 'PWD': '/builds/slave/test', 14:36:34 INFO - 'SHELL': '/bin/bash', 14:36:34 INFO - 'SHLVL': '1', 14:36:34 INFO - 'TERM': 'linux', 14:36:34 INFO - 'TMOUT': '86400', 14:36:34 INFO - 'USER': 'cltbld', 14:36:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:34 INFO - '_': '/tools/buildbot/bin/python'} 14:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:34 INFO - Downloading/unpacking blobuploader==1.2.4 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:36 INFO - Downloading blobuploader-1.2.4.tar.gz 14:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:36:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:36:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36:37 INFO - Downloading docopt-0.6.1.tar.gz 14:36:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:36:37 INFO - Installing collected packages: blobuploader, requests, docopt 14:36:37 INFO - Running setup.py install for blobuploader 14:36:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:36:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:36:37 INFO - Running setup.py install for requests 14:36:37 INFO - Running setup.py install for docopt 14:36:37 INFO - Successfully installed blobuploader requests docopt 14:36:37 INFO - Cleaning up... 14:36:37 INFO - Return code: 0 14:36:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:36:37 INFO - Using env: {'DISPLAY': ':0', 14:36:37 INFO - 'HOME': '/home/cltbld', 14:36:37 INFO - 'LANG': 'en_US.UTF-8', 14:36:37 INFO - 'LANGUAGE': 'en_US:en', 14:36:37 INFO - 'LOGNAME': 'cltbld', 14:36:37 INFO - 'MAIL': '/var/mail/cltbld', 14:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:37 INFO - 'MOZ_NO_REMOTE': '1', 14:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:37 INFO - 'NO_EM_RESTART': '1', 14:36:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:37 INFO - 'PWD': '/builds/slave/test', 14:36:37 INFO - 'SHELL': '/bin/bash', 14:36:37 INFO - 'SHLVL': '1', 14:36:37 INFO - 'TERM': 'linux', 14:36:37 INFO - 'TMOUT': '86400', 14:36:37 INFO - 'USER': 'cltbld', 14:36:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:37 INFO - '_': '/tools/buildbot/bin/python'} 14:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:37 INFO - Running setup.py (path:/tmp/pip-Qe6eFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:38 INFO - Running setup.py (path:/tmp/pip-pSfGgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:38 INFO - Running setup.py (path:/tmp/pip-p385Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:38 INFO - Running setup.py (path:/tmp/pip-1jGeIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:38 INFO - Running setup.py (path:/tmp/pip-S_PmC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:38 INFO - Running setup.py (path:/tmp/pip-Bm6Tsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:38 INFO - Running setup.py (path:/tmp/pip-Fm0Q8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:38 INFO - Running setup.py (path:/tmp/pip-kobsc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:38 INFO - Running setup.py (path:/tmp/pip-WciSRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:38 INFO - Running setup.py (path:/tmp/pip-YUt9uK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:38 INFO - Running setup.py (path:/tmp/pip-HgNcl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:38 INFO - Running setup.py (path:/tmp/pip-MCIKGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:38 INFO - Running setup.py (path:/tmp/pip-NuQhnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:38 INFO - Running setup.py (path:/tmp/pip-mIIeyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:38 INFO - Running setup.py (path:/tmp/pip-o_TijT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:39 INFO - Running setup.py (path:/tmp/pip-jUR4SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:39 INFO - Running setup.py (path:/tmp/pip-qKMlC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:36:39 INFO - Running setup.py install for manifestparser 14:36:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Running setup.py install for mozcrash 14:36:39 INFO - Running setup.py install for mozdebug 14:36:39 INFO - Running setup.py install for mozdevice 14:36:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Running setup.py install for mozfile 14:36:39 INFO - Running setup.py install for mozhttpd 14:36:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Running setup.py install for mozinfo 14:36:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Running setup.py install for mozInstall 14:36:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Running setup.py install for mozleak 14:36:40 INFO - Running setup.py install for mozlog 14:36:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Running setup.py install for moznetwork 14:36:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Running setup.py install for mozprocess 14:36:40 INFO - Running setup.py install for mozprofile 14:36:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Running setup.py install for mozrunner 14:36:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Running setup.py install for mozscreenshot 14:36:40 INFO - Running setup.py install for moztest 14:36:40 INFO - Running setup.py install for mozversion 14:36:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:36:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:36:40 INFO - Cleaning up... 14:36:41 INFO - Return code: 0 14:36:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:41 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')]} 14:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:41 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')]} 14:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:41 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 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:41 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 14:36:41 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 14:36:41 INFO - Using env: {'DISPLAY': ':0', 14:36:41 INFO - 'HOME': '/home/cltbld', 14:36:41 INFO - 'LANG': 'en_US.UTF-8', 14:36:41 INFO - 'LANGUAGE': 'en_US:en', 14:36:41 INFO - 'LOGNAME': 'cltbld', 14:36:41 INFO - 'MAIL': '/var/mail/cltbld', 14:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:41 INFO - 'MOZ_NO_REMOTE': '1', 14:36:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:41 INFO - 'NO_EM_RESTART': '1', 14:36:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:41 INFO - 'PWD': '/builds/slave/test', 14:36:41 INFO - 'SHELL': '/bin/bash', 14:36:41 INFO - 'SHLVL': '1', 14:36:41 INFO - 'TERM': 'linux', 14:36:41 INFO - 'TMOUT': '86400', 14:36:41 INFO - 'USER': 'cltbld', 14:36:41 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:41 INFO - '_': '/tools/buildbot/bin/python'} 14:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:41 INFO - Running setup.py (path:/tmp/pip-TBB6kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:41 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)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:41 INFO - Running setup.py (path:/tmp/pip-H7_FLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:41 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)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:41 INFO - Running setup.py (path:/tmp/pip-WwCPU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:41 INFO - Running setup.py (path:/tmp/pip-h3pGhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:41 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)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:41 INFO - Running setup.py (path:/tmp/pip-D2JyP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:41 INFO - Running setup.py (path:/tmp/pip-QPO7zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:41 INFO - Running setup.py (path:/tmp/pip-wdLcaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:41 INFO - Running setup.py (path:/tmp/pip-vCWVUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:41 INFO - Running setup.py (path:/tmp/pip-WHeNmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:41 INFO - Running setup.py (path:/tmp/pip-Po0Mui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:41 INFO - Running setup.py (path:/tmp/pip-espnKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:42 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)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:42 INFO - Running setup.py (path:/tmp/pip-fiX3fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:42 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)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:42 INFO - Running setup.py (path:/tmp/pip-bKfMD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:42 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)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:42 INFO - Running setup.py (path:/tmp/pip-2Fin5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:42 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)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:42 INFO - Running setup.py (path:/tmp/pip-BPuXRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:42 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)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:42 INFO - Running setup.py (path:/tmp/pip-foina2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:42 INFO - Running setup.py (path:/tmp/pip-Hn_Kgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:44 INFO - Downloading blessings-1.6.tar.gz 14:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:36:44 INFO - Installing collected packages: blessings 14:36:44 INFO - Running setup.py install for blessings 14:36:44 INFO - Successfully installed blessings 14:36:44 INFO - Cleaning up... 14:36:44 INFO - Return code: 0 14:36:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:44 INFO - Reading from file tmpfile_stdout 14:36:44 INFO - Current package versions: 14:36:44 INFO - argparse == 1.2.1 14:36:44 INFO - blessings == 1.6 14:36:44 INFO - blobuploader == 1.2.4 14:36:44 INFO - docopt == 0.6.1 14:36:44 INFO - functools32 == 3.2.3-2 14:36:44 INFO - jsonschema == 2.5.1 14:36:44 INFO - manifestparser == 1.1 14:36:44 INFO - mozInstall == 1.12 14:36:44 INFO - mozcrash == 1.0 14:36:44 INFO - mozdebug == 0.1 14:36:44 INFO - mozdevice == 0.49 14:36:44 INFO - mozfile == 1.2 14:36:44 INFO - mozhttpd == 0.7 14:36:44 INFO - mozinfo == 0.9 14:36:44 INFO - mozleak == 0.1 14:36:44 INFO - mozlog == 3.4 14:36:44 INFO - moznetwork == 0.27 14:36:44 INFO - mozprocess == 0.23 14:36:44 INFO - mozprofile == 0.28 14:36:44 INFO - mozrunner == 6.13 14:36:44 INFO - mozscreenshot == 0.1 14:36:44 INFO - mozsystemmonitor == 0.3 14:36:44 INFO - moztest == 0.7 14:36:44 INFO - mozversion == 1.4 14:36:44 INFO - psutil == 3.1.1 14:36:44 INFO - requests == 1.2.3 14:36:44 INFO - wsgiref == 0.1.2 14:36:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:45 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 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:45 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 14:36:45 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 14:36:45 INFO - Using env: {'DISPLAY': ':0', 14:36:45 INFO - 'HOME': '/home/cltbld', 14:36:45 INFO - 'LANG': 'en_US.UTF-8', 14:36:45 INFO - 'LANGUAGE': 'en_US:en', 14:36:45 INFO - 'LOGNAME': 'cltbld', 14:36:45 INFO - 'MAIL': '/var/mail/cltbld', 14:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:45 INFO - 'MOZ_NO_REMOTE': '1', 14:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:45 INFO - 'NO_EM_RESTART': '1', 14:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:45 INFO - 'PWD': '/builds/slave/test', 14:36:45 INFO - 'SHELL': '/bin/bash', 14:36:45 INFO - 'SHLVL': '1', 14:36:45 INFO - 'TERM': 'linux', 14:36:45 INFO - 'TMOUT': '86400', 14:36:45 INFO - 'USER': 'cltbld', 14:36:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:45 INFO - '_': '/tools/buildbot/bin/python'} 14:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 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)) 14:36:45 INFO - Cleaning up... 14:36:45 INFO - Return code: 0 14:36:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:36:45 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')]} 14:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:45 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')]} 14:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:45 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 0x25191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', '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 14:36:45 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 14:36:45 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 14:36:45 INFO - Using env: {'DISPLAY': ':0', 14:36:45 INFO - 'HOME': '/home/cltbld', 14:36:45 INFO - 'LANG': 'en_US.UTF-8', 14:36:45 INFO - 'LANGUAGE': 'en_US:en', 14:36:45 INFO - 'LOGNAME': 'cltbld', 14:36:45 INFO - 'MAIL': '/var/mail/cltbld', 14:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:45 INFO - 'MOZ_NO_REMOTE': '1', 14:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:45 INFO - 'NO_EM_RESTART': '1', 14:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:45 INFO - 'PWD': '/builds/slave/test', 14:36:45 INFO - 'SHELL': '/bin/bash', 14:36:45 INFO - 'SHLVL': '1', 14:36:45 INFO - 'TERM': 'linux', 14:36:45 INFO - 'TMOUT': '86400', 14:36:45 INFO - 'USER': 'cltbld', 14:36:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:45 INFO - '_': '/tools/buildbot/bin/python'} 14:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:36:45 INFO - Cleaning up... 14:36:45 INFO - Return code: 0 14:36:45 INFO - Running post-action listener: _resource_record_post_action 14:36:45 INFO - Running post-action listener: _start_resource_monitoring 14:36:45 INFO - Starting resource monitoring. 14:36:45 INFO - [mozharness: 2016-12-28 22:36:45.492750Z] Finished create-virtualenv step (success) 14:36:45 INFO - [mozharness: 2016-12-28 22:36:45.493334Z] Running install step. 14:36:45 INFO - Running pre-action listener: _resource_record_pre_action 14:36:45 INFO - Running main action method: install 14:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:45 INFO - Reading from file tmpfile_stdout 14:36:45 INFO - Detecting whether we're running mozinstall >=1.0... 14:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:36:45 INFO - Reading from file tmpfile_stdout 14:36:45 INFO - Output received: 14:36:45 INFO - Usage: mozinstall [options] installer 14:36:45 INFO - Options: 14:36:45 INFO - -h, --help show this help message and exit 14:36:45 INFO - -d DEST, --destination=DEST 14:36:45 INFO - Directory to install application into. [default: 14:36:45 INFO - "/builds/slave/test"] 14:36:45 INFO - --app=APP Application being installed. [default: firefox] 14:36:45 INFO - mkdir: /builds/slave/test/build/application 14:36:45 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'] 14:36:45 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 14:37:03 INFO - Reading from file tmpfile_stdout 14:37:03 INFO - Output received: 14:37:03 INFO - /builds/slave/test/build/application/firefox/firefox 14:37:03 INFO - Running post-action listener: _resource_record_post_action 14:37:03 INFO - [mozharness: 2016-12-28 22:37:03.160857Z] Finished install step (success) 14:37:03 INFO - [mozharness: 2016-12-28 22:37:03.161136Z] Running run-tests step. 14:37:03 INFO - Running pre-action listener: _resource_record_pre_action 14:37:03 INFO - Running main action method: run_tests 14:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:37:03 INFO - Python 2.7.3 14:37:03 INFO - Return code: 0 14:37:03 INFO - grabbing minidump binary from tooltool 14:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:03 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 0x2914850>, '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 0x28896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2889b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:37:03 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 14:37:03 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 14:37:03 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 14:37:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:37:03 INFO - Return code: 0 14:37:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:37:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:37:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:37:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:37:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:37:03 INFO - Using env: {'DISPLAY': ':0', 14:37:03 INFO - 'HOME': '/home/cltbld', 14:37:03 INFO - 'LANG': 'en_US.UTF-8', 14:37:03 INFO - 'LANGUAGE': 'en_US:en', 14:37:03 INFO - 'LOGNAME': 'cltbld', 14:37:03 INFO - 'MAIL': '/var/mail/cltbld', 14:37:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:37:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:03 INFO - 'MOZ_NO_REMOTE': '1', 14:37:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:37:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:03 INFO - 'NO_EM_RESTART': '1', 14:37:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:03 INFO - 'PWD': '/builds/slave/test', 14:37:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:37:03 INFO - 'SHELL': '/bin/bash', 14:37:03 INFO - 'SHLVL': '1', 14:37:03 INFO - 'TERM': 'linux', 14:37:03 INFO - 'TMOUT': '86400', 14:37:03 INFO - 'USER': 'cltbld', 14:37:03 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906', 14:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:03 INFO - '_': '/tools/buildbot/bin/python'} 14:37:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:37:03 INFO - mozversion application_buildid: 20161228123003 14:37:03 INFO - mozversion application_changeset: fce32b66735c5e444f6e2025789ffab1aa1663e3 14:37:03 INFO - mozversion application_display_name: Nightly 14:37:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:37:03 INFO - mozversion application_name: Firefox 14:37:03 INFO - mozversion application_remotingname: firefox 14:37:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:37:03 INFO - mozversion application_vendor: Mozilla 14:37:03 INFO - mozversion application_version: 53.0a1 14:37:03 INFO - mozversion platform_buildid: 20161228123003 14:37:03 INFO - mozversion platform_changeset: fce32b66735c5e444f6e2025789ffab1aa1663e3 14:37:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:37:03 INFO - mozversion platform_version: 53.0a1 14:37:03 INFO - using testdate: 1482964623 14:37:03 INFO - actual date: 1482964623 14:37:03 INFO - starting webserver on 'localhost:35621' 14:37:03 INFO - SUITE-START | Running 2 tests 14:37:03 INFO - TEST-START | dromaeo_css 14:37:03 INFO - operating with platform_type : linux_ 14:37:03 INFO - Initialising browser for dromaeo_css test... 14:37:03 INFO - TEST-INFO | started process 12277 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwJnkzm/profile http://localhost:35621/getInfo.html) 14:37:03 INFO - PROCESS | 12277 | 1482964623890 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 14:37:07 INFO - PROCESS | 12277 | __metrics Screen width/height:1600/1200 14:37:07 INFO - PROCESS | 12277 | colorDepth:24 14:37:07 INFO - PROCESS | 12277 | Browser inner width/height: 1024/697 14:37:07 INFO - PROCESS | 12277 | __metrics 14:37:07 INFO - PROCESS | 12277 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:37:07 INFO - PROCESS | 12277 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:37:08 INFO - PROCESS | 12277 | console.error: PushService: 14:37:08 INFO - PROCESS | 12277 | stateChangeProcessEnqueue: Error transitioning state 14:37:08 INFO - PROCESS | 12277 | UnknownError 14:37:08 INFO - TEST-INFO | 12277: exit 0 14:37:08 INFO - Browser initialized. 14:37:08 INFO - Running cycle 1/1 for dromaeo_css test... 14:37:08 INFO - TEST-INFO | started process 12360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwJnkzm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:20 INFO - PROCESS | 12360 | RSS: Main: 147009536 14:37:20 INFO - PROCESS | 12360 | 14:40:22 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35621/tests/dromaeo/cssquery-ext.html) 14:40:23 INFO - PROCESS | 12360 | RSS: Main: 250179584 14:40:23 INFO - PROCESS | 12360 | 14:42:09 INFO - PROCESS | 12360 | ************************* 14:42:09 INFO - PROCESS | 12360 | A coding exception was thrown and uncaught in a Task. 14:42:09 INFO - PROCESS | 12360 | 14:42:09 INFO - PROCESS | 12360 | Full message: ReferenceError: fetch is not defined 14:42:09 INFO - PROCESS | 12360 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 14:42:09 INFO - PROCESS | 12360 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 14:42:09 INFO - PROCESS | 12360 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 14:42:09 INFO - PROCESS | 12360 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:42:09 INFO - PROCESS | 12360 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:42:09 INFO - PROCESS | 12360 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:42:09 INFO - PROCESS | 12360 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 14:42:09 INFO - PROCESS | 12360 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:42:09 INFO - PROCESS | 12360 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:42:09 INFO - PROCESS | 12360 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:42:09 INFO - PROCESS | 12360 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 14:42:09 INFO - PROCESS | 12360 | 14:42:09 INFO - PROCESS | 12360 | ************************* 14:43:26 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-ext.html (next: http://localhost:35621/tests/dromaeo/cssquery-jquery.html) 14:43:26 INFO - PROCESS | 12360 | RSS: Main: 246525952 14:43:26 INFO - PROCESS | 12360 | 14:46:28 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35621/tests/dromaeo/cssquery-mootools.html) 14:46:29 INFO - PROCESS | 12360 | RSS: Main: 183681024 14:46:29 INFO - PROCESS | 12360 | 14:49:31 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35621/tests/dromaeo/cssquery-prototype.html) 14:49:31 INFO - PROCESS | 12360 | RSS: Main: 193159168 14:49:31 INFO - PROCESS | 12360 | 14:52:34 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35621/tests/dromaeo/cssquery-yui.html) 14:52:35 INFO - PROCESS | 12360 | RSS: Main: 184324096 14:52:35 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | Cycle 1(1): loaded http://localhost:35621/tests/dromaeo/cssquery-yui.html (next: http://localhost:35621/tests/dromaeo/cssquery-dojo.html) 14:55:37 INFO - PROCESS | 12360 | RSS: Main: 258830336 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | __start_tp_report 14:55:37 INFO - PROCESS | 12360 | _x_x_mozilla_page_load 14:55:37 INFO - PROCESS | 12360 | _x_x_mozilla_page_load_details 14:55:37 INFO - PROCESS | 12360 | |i|pagename|runs| 14:55:37 INFO - PROCESS | 12360 | |0;dojo.html;3137.435261652902;3155.731762800162;3174.4127336442743;3183.602049743782;3185.2673885006448;5146.125158723016;5180.093483640362;5238.842834714955;5259.868503287415;5289.682619042856;5250.6587071840395;5275.208718692198;5298.23175639532;5313.07021271277;5318.707471089088;4786.875084355173;4821.879453013686;4825.975870120653;4843.975780121094;4844.733539655317;4794.161021821167;4847.733374664392;4855.247436647325;4877.146499362615;4927.334809800667;4899.265110233476;4908.116539022974;4915.164422048242;4916.065947469987;4918.754062296889;4698.530146985308;4727.267273572602;4750.334953106568;4794.736289504076;4842.733649649268;4791.041791641685;4809.807607695723;4827.155247831615;4849.127157111719;4866.78099485524;5289.497497737709;5301.814436494704;5313.123334649778;5334.439883812182;5357.4374690657305;5596.664200148004;5632.267805185341;5638.943610563924;5686.289213848269;5692.943070569283;5727;5737.454941780524;5742.253507044099;5747.770089196427;5752.597318187729;5350.438203988564;5352.625316227865;5352.732363381893;5354.812581559705;5355.250264962909;5762.971185144047;5783.508401785811;5823.068309070529;5828.475437210586;5834.416558344132;5581.665100094007;5619.325680918316;5634.746436410372;5634.85912852182;5644.774209031675;5574.470425309671;5581.358143813432;5589.35722391922;5598.048331783607;5600.635958662755;5555.666660000413;5651.954388438152;5659.518940889988;5665.801696940587;5666.121751128581;5622.803201887996;5628.127640215773;5633.070543360382;5677.3471050829685;5679.914801277984;4169.812358443877;4266.743995360288;4266.914661706748;4274.337477690963;4305.48334199898;3723.2181241938956;3725.0873535983314;3748.4189950557707;3749.8125093745202;3818.6563209311294;5384.192371144281;5394.9460505394445;5412.810551630674;5417.620766546304;5426.375966763872;5697.487226149584;5700.9429905701245;5709.7716091356715;5711.771529138871;5734.741936612779;5263.868403289872;5313.0967735485065;5356.732163391893;5395.325584301962;5430.321209848769;4727.149113159664;4727.976360118178;4728.71627702339;4755.025219829996;4767.308740232646;5968.970155149196;5993.61041532299;6000.819975400745;6002.489788368038;6004.54965877561;5479.479449452296;5504.669719816824;5626.127950167731;5663.688497132533;5695.288088988877;5963.582549221513;5968.22413086296;5968.76124954997;5992.041273396236;5997.940020599738;5583.469570390806;5590.217369568345;5631.183478395692;5633.38032816398;5645.774169033192;5492.203630473638;5537.640053396516;5611.971940140274;5692.743826527733;5703.258576385043;5535.861603459784;5559.471850174227;5563.443655634404;5593.1889875966945;5594.216809646571;5535.557155427637;5546.694931778791;5548.944510554843;5549.889002219852;5596.440355964371;5361.705106219196;5400.756965936621;5408.972955135199;5473.31583552056;5526.806561770319;5478.315210598676;5508.614396992172;5520.530754885719;5591.133374326986;5594.0769772987005;5367.980083784225;5403.891922161613;5417.539509141767;5419.268398766274;5422.132458806572 14:55:37 INFO - PROCESS | 12360 | |1;ext.html;559.6921693068813;582.0919365789372;593.6111846740384;595.5503594785936;598.5092224376011;32414.513782293256;42147.57852421479;42859.35710964338;42894.14211715764;46150;43506.564934350645;43904.3414348785;44047.77976110123;44055.559444405546;44130.338044929355;8692.47845129291;8917.152870477294;8918.464892106462;8922.420042697204;8924.643014279422;8420.789480263013;8619.95690021549;10377.377357358544;10716.41059741714;10724.78550428991;10621.5220315086;10687.625933092344;10688.465576721133;10689.786204275948;10690.51892664832;10731;10769.569217231301;10794.190435717353;10805.405702686348;10806.675799726021;10488;10512.159027277876;10566.101881340095;10567.365958042543;10577.206709496819;41222.793886030675;41313.79343103295;41430.37854432186;41464.79267603643;41548.169036619096;3983.860564880244;4145.150244199932;4153.584641535822;4183.790810459464;4202.768847713374;28213.07891075341;29719;29871.32444835496;30009.399812003638;30086.097417077523;4020.4974378202724;4042.1309418474875;4092.6521245694153;4256.936145957813;4262.211490874198;315711;317158.9999999977;319531;321615;321804;50038.74980625074;50164;50277.24584131241;50332;50407.74796126069;48705.5129448708;48749.51250487521;48817.51182488201;48843.5115648839;48857.5114248853;14651.413943442356;14933.253337333308;14935.178565178801;14956.252187390586;14964.156810206026;10952.616658416917;11229;11680.357580333164;11792.056635469142;11803.763924721628;18208.362586422627;20262.594748104955;20299.797002030085;20896.79103208979;20969.475763106046;7257.52826066304;7414.814629634195;7512.586807725518;7765.650545725456;7829;875.2035647560689;892.9151730585584;909.2816674826945;916.5737931861584;950.1401231885155;30047.849760751495;31241.68758312388;31498.842505787325;31595.84202078975;32211.51682724761;992.6128809764198;1044.738815296176;1061.6761887624348;1065.3554599467366;1090.705509512443;15822.367105315889;16419;16562.58593535171;16578.17109144538;16655.25051372667;489711;519002;524101.035958565;529876;756146;53476.19785703217;53664.46335536673;53920.460795392326;54019.72990135024;54077.72961135248;1339.7052648417332;1374.3265799758046;1388.3127851713466;1388.562602780121;1402.9719405611831;27872.721272787014;28862.567061494516;28991;29675.703242967294;29820.254493637833;44951;45510.772446137555;45550.31674524951;45631.54368456406;45639;428545;431820;432385;432496;432693.05762481526;22878.199263025715;24513;25906.48187036211;26353;26693.7330626699;40415.797921010206;40547.797261013504;40555.39166912558;41600.79199603983;42079;33855.49216761701;34909.65090349064;35204.119897002776;35401.46897796483;35515.644843551236;33223;33502;34023.65976340304;34044.148896277795;34449;34109.65890341164;34406.311873761886;34504.309913802084;34780.478292826134;34804.82597587097;29754.95857644972;30040;30623.387532248784;30750;31129.37741245208;23941.64087538723;24011.75988240165;24214.152504662252;24284.150054748;24351.26946191617 14:55:37 INFO - PROCESS | 12360 | |2;jquery.html;390.159206909111;392.854643781801;404.4176386004153;404.8400881651748;416.58965918570203;14294.78557821631;16830.410935617256;17553.47339579813;19065.428037158872;19068.427947161606;18072.81927180728;18939;18974.62050758984;19195.904020479913;19423;5759.424057594239;5929.614575052629;5954.434328738763;5965.284165900098;5967.791127310545;5746.138079288119;5835.795747148851;5840.299164100314;5845.824625261249;5846.21076154718;5495.340559132909;5739.081746920494;5802.39074897136;5820.738066786984;5826.825195244128;5866.354700982888;5893.882122357529;5894.793682221145;5900.704964751788;5904.468597826195;5424.457554244584;5549.500544950974;5597.720113994284;5601.439856014406;5733.082706766939;13634.863651363557;13846.376913038937;13852.16886986784;19526.51183720404;21889.67165492519;2758.2690586994463;3074.6156730408698;3075.415671022521;3132.9843350783326;3137.294108825519;9597.760055998657;9939.254555908336;11334.829977550344;13802.861971380258;14173.503927362493;1912.3784769949823;1916.6741653918866;1918.2231196365494;1923.7210604462414;1972.9444747060254;73306.50201234929;74748;75301.62349188329;76353;77231.61384193099;10674.46627668629;10712.625058122929;10756.300840445347;10908.836367454494;11163.553457861757;15100.697986040437;15243.71344298364;15289.923550382176;15328.693426131415;15337.85573957336;7650.081990161105;7653.425993050432;7836.412269079728;7958.840823183504;7971.920280797118;7282.963585182146;7336.706531738778;7363.926360736324;7495.737649182252;7506.549607023596;4613.66203800894;4750.857474275777;4762.476127625959;4791.113643975876;4873.074115918035;1926.7109933510085;1952.1508143957424;1975.4666240115089;1978.0801927103967;1983.4644645945514;841.6086519768337;866.7789713622985;873.7509809704204;874.2568816506047;876.6099085906837;17756.55608609769;18036.549086272687;18397.632047358977;18467;18769.62299058092;889.0620395482698;890.4568213389826;930.9767255818658;990.2375171118199;997.2022382094295;9586.185174260127;9919.80160396788;9956.253281003954;10006.149477294366;10052.246081543768;448858;457555;458660.4272594098;464493;466821;68025.65987170032;69593.65203173952;84479;85537.57231213928;86852;1077.762892163707;1096.8848270931596;1121.9719507012226;1124.707576030238;1128.898399144081;15299.235038248265;15389.538313850155;15706.607334816721;15958.68082638364;16050.919745401197;37719.62280377271;38355;38614.80692596519;39407.43851649634;40121.79939100286;54418.72790636022;55633.165502516684;56677.716611416676;56690;57111.714441427524;5604.355499117653;6780.288069752605;6813.591184528944;7494.213107623839;8313.958430207811;8611.48331100136;10438.11276041545;11427.885721142682;13218.206907585576;13257.403416846459;11235.887641123483;11573.710657233636;11693.298402095901;11832.645020649394;11982.64052078439;11407.657770266906;11422.428878556206;11429.371384573928;11790.587329443428;11974.64076077719;10675.57297708083;10817.891821081688;11419.657410277705;11765.352905589933;11903.345316007702;7531.510451820614;7540.396768258636;7665.42509311815;7708.152103268524;7806.258405451473;8549.700760473354;10769.58309685259;10817.296875703054;10858.45707714627;10881.673549793519 14:55:37 INFO - PROCESS | 12360 | |3;mootools.html;1360.7754720471123;1369.048511284657;1369.479597752854;1371.060823336015;1380.0891411668301;7925.801854953622;8390.202930721587;8394.202550757669;8398.370122240833;8399.11809260028;7286.380657644107;7530.246975302467;7546.358559522433;7749.30256276935;8247.505149691022;1973.9506512337198;2142.442964829148;2200.2959053103014;2208.116753298685;2213.424509627492;2153.052656830997;2172.1311475409802;2189.179057853305;2189.3650841256017;2194.5172062146303;1915.1477592471356;2192.123150739701;2217.4899773052207;2221.3002904085242;2222.6666000099954;1931.9323823666175;1939.6896496560553;1943.523836660013;2083.8853863037525;2160.9783902161093;2129.0419311309975;2131.296672098204;2134.4343748906554;2135.263334149716;2135.4341099608614;8250.711225107152;8302.460340077936;8325.875111873327;8333.583320833995;8347.707830225974;4834.202356611155;4997.525235102659;5000.149974504307;5004.274380214885;5004.774785134678;3092.6443459002276;3202.2794871154038;3244.8377581121085;3270.2314955985325;3287.96712032879;1245.7072587941827;1302.4920281090383;1325.56919001324;1364.8976326775432;1389.1873254146337;10911.563537458493;10925.453727313603;10968.23222374434;10985.176111791568;10989.61536346232;4250.447441832542;4412.24991751403;4451.376807246988;4509.503954564995;4523.208438523311;9118.224950879117;9124.361294709306;9134.177923986874;9138.497382644018;9148.313876459291;5035.219540971156;5048.899022019613;5109.514596113363;5219.321488206509;5227.843164705065;4741.2651039089;4747.525247475225;4787;4791.616670666338;4818.975905120522;3439.8108104054018;3443.9655603443643;3446.069561218457;3461.5846098468346;3472;1213.2720367779439;1247.6257122863105;1253.216739537789;1269.282855186835;1275.09468277524;918.3938600523622;920.7375897869075;932.6689025395887;937.634322614181;948.1656142594472;5477.452254774491;5484.91772623411;5666.121751128581;5706.144078388192;5840.386759390288;901.8917729872326;923.6951805904035;925.6528801699362;929.4051806843626;937.3532262738688;3887.5476524506776;3919.392494163409;3959.4558122331737;4015.959840401617;4018.537868145142;9599.616015359308;9828.410295382157;9864.358816677037;9868.30921835479;9869.506524673738;9722.562484688206;9729.270304727173;9749.658761943298;9796.65711700087;9816.116549510436;7689.077310722637;7814.33578145864;7818.4136189785995;7822.452428330076;7832.0601527817025;5582.637128586629;5597.860053498695;5675.489205971482;5737.454941780524;5821.330546987151;9218.400803947721;9244.95377523135;9278.350515463853;9281.814363712823;9336.11306925841;8783.824323513621;9012.053734357798;9101.135392137736;9334.579943902405;9340.34617576763;7280.289132054069;7427.8143046424275;7436.516626419266;7440.702371905063;7454.3663788576405;6861.210960739579;7819.100803407681;7821.178776228414;7830.2952734254195;7843.254890785366;6697.430718388797;6958.930410695828;7081.18566364854;7142.392896603847;7250.927490725026;6799.966000169968;6818.18181818185;6848;6855.383015528626;6866.416354609914;6137.1101190328045;6248.4688801452385;6256.499480041677;6256.906146407717;6257.906131407942;4554.27131658933;4667.953320466888;4723.456802467623;4735.029318989667;4739.21802902531;4632.073585282889;4726.440274709269;4748.548887855648;4826.107170173529;4925.9014819704125 14:55:37 INFO - PROCESS | 12360 | |4;prototype.html;376.76640482900586;391.33473095737247;393.264595206963;395.31610314156507;396.78177002648533;5073.847784566459;5096.41391240007;5121.155009423441;5131;5184.948150518503;4878.85346943468;4950.876228094299;5003.424606170292;5029.421616514102;5122.436531981479;4086.816093275795;4113.526944401402;4121.9175616487655;4123.340265557511;4128.649064829493;4110.054687421894;4132.235536425771;4135.8759237222785;4140.254754144268;4141.565135660758;3899.3760998240286;3927.8625248116323;4048.089179934506;4068.206699693561;4072.9185416291657;4048.5748996355255;4072.3687828386646;4076.2255171517318;4078.0017791282353;4095.5290141633795;3706.8517259309865;3707.6477734615437;3708.554973403182;3715.5541335039698;3718.925621487555;4821.662483626147;4932.569554829095;4939.160342741707;5009.820727983302;5038.168702164138;4631.722096674211;4646.883827904296;4649.372334734801;4659.091477161955;4712.481627021025;5299.311089558333;5306.814261500829;5312.734363281859;5328.573714102862;5330.626856120073;3950.960490395088;4026.8187931543152;4046.7571945683353;4048.898777530556;4069.1658210066867;5492.890142197173;5494.928488944657;5495.890082198373;5509.338879334466;5510.008198524264;4161.583841615814;4168.353905144708;4176.394422808676;4180.724878911961;4185.455890734185;4057.208844275338;4097.545371393172;4252.808623611945;4271.10306835568;4287.464066991626;4611.469680986729;4624.260118381043;4636.698614590041;4641.512641172628;4660.603848672833;5219.686818790885;5279.155335146435;5292.814751483679;5294.92057619136;5299.814506492255;1308.8429388473444;1421.644588852787;1428.8854693339213;1430.706705125447;1431.4632012995128;936.2229349639782;936.4849332866896;941.3081385181886;945.1304799584399;955.1690029674228;4986.326845875833;5003.774830132653;5045.041442125985;5046.571041461443;5061.506855477569;4965.138073222542;5284.894302114013;5303.995483410001;5335.919961200585;5337.759800808973;4971.850844474671;4974.303597496353;5022.74886255693;5023.572996295283;5064.215046667772;2810.0726760169096;2811.451766905434;2841.2328671258642;2841.9005334813182;2862.8568571571336;5461.344638643388;5548.223248745179;5754.424557544212;5816.82549523515;5821.7380217890295;5615.859603509945;5665.971670141623;5678.4605462480995;5700.116481945222;5727.5131613812455;5628.268325117627;5697.7436015380235;5731.484166424958;5857.297124345106;5862.94137058624;5503.697296648723;5503.7798488061635;5522.282103326542;5524.640898341595;5526.392096869422;5452.509274165344;5468.726563671721;5484.972575137099;5512.586556008396;5555;5676.517496012885;5708.181927085407;5731.369549349652;5748.827535173951;5806.65160090396;4195.370694395865;4204.936925946175;4208.180519052304;4210.915781684411;4221.585768767374;5260.579153667621;5383.9192412114635;5442.238086667949;5451.618386712892;5460.372057213472;5087.64386492942;5325.2810870531985;5388.491222457718;5399.433059528693;5409.188621706776;5263.105272103828;5330.706811125426;5364.7049412282695;5395.730213489388;5415.458454154553;5333.626646134733;5378.704171270618;5380.3274590676165;5406.432324605859;5410.404855465974;5364.222187782828;5366.409694933633;5408.242846001484;5441.7279136043835;5455.809046683347;5132.461091585479;5200.349956255468;5224.686518808883;5224.791008359623;5260.947390526198 14:55:37 INFO - PROCESS | 12360 | |5;yui.html;641.6535071061627;650.7462089784988;718.8993540904271;732.673960087761;774.5081873010638;16142.273597688103;16739.91630041848;16845.326186952523;16863.988160710367;16884.15579221037;12884.742305153892;12939.223646581211;12948.093633445662;13029.478820847178;14451.421943122288;813.9104698483162;815.1155995744609;817.1052697296451;825.4964471672275;827.5903427803215;843.2242337049929;846.7375113714737;846.8941382327209;847.2120927537388;847.7711018025129;863.8920134983127;864.2481041493909;864.9827003459958;866.4194989357114;866.4974314897345;848.5333066813259;854.4830377621512;855.2858363266648;855.4525103933425;857.2456238510102;626.5081910700125;627.078195053271;627.1564745417406;627.42903957399;627.5575719118057;13476.25880576568;13492.797608035888;13858.376373063136;14956.700865982728;15083.924580377137;11810.645680629596;12019.699507512294;12031.819522707166;12158.148929574845;12518.561850335285;10733.570657173781;10788.51451684676;10822.404767737771;10856.511456984455;10911.890881091167;3442.122258824009;3450.1719587299117;3451.7928924264374;3464.2378676691087;3485.6165821759587;72708.27291727067;73654;73861.26138738652;74501.62749186273;75233.62383188104;8847.867281990775;8965.775855603531;9036.954815225881;9058.682946096855;9101.271898248124;19664.311749088713;19669.704954425695;19730.90134549318;19852.503687407643;19914.90042549807;7468.10382754073;7645.503042302233;7653.808654783675;7711.884321735066;7809.101953275333;3468.2196505785996;3540.097275194809;3559.04507592364;4051.2505186540584;4162.229987452331;3565.982170089133;3674.761140525857;3675.5405574303213;3685.889423317251;3693.741438099361;602.8251806976016;621.7109044294425;622.4553515673786;626.123427201921;631.0723236841835;594.6075590110506;602.57518449493;615.7075389190188;623.6258245053023;624.2883113250898;8128.9593552031865;8795.516246606498;8822.51476168817;8865.379423440298;9097.590608422637;621.8569728962274;625.4246093593905;631.1164369882201;642.2870613618973;667.4626925325063;4189.790510474464;4231.42875711775;4250.086231460251;4289.742615443083;4295.312749959991;76816.61591692005;79700.20299797044;80540.59729701314;81104.18895810966;81932;44921.55078449239;55405.1689224662;58103.418965809804;71120;71481.92777108347;57639.71180144156;58058.419415806144;58148;58350.41649583535;58717.70641146767;9559.378640388353;9790.902090979283;9905.455199964072;9989.850152247867;10014.799704006025;40295.3955690659;40302.59697402988;40483.59516404916;40748.59251407448;41809.37285940774;48442.757786210845;50225.24662130144;50293.49706502888;50342.748286258335;52514.737426312626;12794.360281985677;12913.09608327408;13016.28410437435;13051.804222936853;13081.542146025224;14359.569212923629;14518.782218266946;14701.632459188178;14918;15701.764473532678;13839.584812455643;13895.166290022631;14023.719525609635;14156.292185390483;14517.927410363303;14148.883374689827;14160.150390976574;14358.784618230526;14368.784468233194;14775.482858099915;13866.861331386557;13922.802008515644;14318.212498312692;14355.497557585435;15005.32476038559;10640.678218929344;10786;11331.603393881174;11420.71448213768;11570;10912.508937097691;11009.669709908716;11151.944240279072;11221;11256.774864502828 14:55:37 INFO - PROCESS | 12360 | __end_tp_report 14:55:37 INFO - PROCESS | 12360 | __start_cc_report 14:55:37 INFO - PROCESS | 12360 | _x_x_mozilla_cycle_collect,1295 14:55:37 INFO - PROCESS | 12360 | __end_cc_report 14:55:37 INFO - PROCESS | 12360 | __startTimestamp1482965737796__endTimestamp 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | ------- Summary: start ------- 14:55:37 INFO - PROCESS | 12360 | Number of tests: 6 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#0] dojo.html Cycles:165 Average:5265.28 Median:5430.32 stddev:606.51 (11.2%) stddev-sans-first:584.94 14:55:37 INFO - PROCESS | 12360 | Values: 3137.4 3155.7 3174.4 3183.6 3185.3 5146.1 5180.1 5238.8 5259.9 5289.7 5250.7 5275.2 5298.2 5313.1 5318.7 4786.9 4821.9 4826.0 4844.0 4844.7 4794.2 4847.7 4855.2 4877.1 4927.3 4899.3 4908.1 4915.2 4916.1 4918.8 4698.5 4727.3 4750.3 4794.7 4842.7 4791.0 4809.8 4827.2 4849.1 4866.8 5289.5 5301.8 5313.1 5334.4 5357.4 5596.7 5632.3 5638.9 5686.3 5692.9 5727.0 5737.5 5742.3 5747.8 5752.6 5350.4 5352.6 5352.7 5354.8 5355.3 5763.0 5783.5 5823.1 5828.5 5834.4 5581.7 5619.3 5634.7 5634.9 5644.8 5574.5 5581.4 5589.4 5598.0 5600.6 5555.7 5652.0 5659.5 5665.8 5666.1 5622.8 5628.1 5633.1 5677.3 5679.9 4169.8 4266.7 4266.9 4274.3 4305.5 3723.2 3725.1 3748.4 3749.8 3818.7 5384.2 5394.9 5412.8 5417.6 5426.4 5697.5 5700.9 5709.8 5711.8 5734.7 5263.9 5313.1 5356.7 5395.3 5430.3 4727.1 4728.0 4728.7 4755.0 4767.3 5969.0 5993.6 6000.8 6002.5 6004.5 5479.5 5504.7 5626.1 5663.7 5695.3 5963.6 5968.2 5968.8 5992.0 5997.9 5583.5 5590.2 5631.2 5633.4 5645.8 5492.2 5537.6 5612.0 5692.7 5703.3 5535.9 5559.5 5563.4 5593.2 5594.2 5535.6 5546.7 5548.9 5549.9 5596.4 5361.7 5400.8 5409.0 5473.3 5526.8 5478.3 5508.6 5520.5 5591.1 5594.1 5368.0 5403.9 5417.5 5419.3 5422.1 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#1] ext.html Cycles:180 Average:57972.01 Median:34476.65 stddev:120683.90 (350.0%) stddev-sans-first:120945.47 14:55:37 INFO - PROCESS | 12360 | Values: 559.7 582.1 593.6 595.6 598.5 32414.5 42147.6 42859.4 42894.1 46150.0 43506.6 43904.3 44047.8 44055.6 44130.3 8692.5 8917.2 8918.5 8922.4 8924.6 8420.8 8620.0 10377.4 10716.4 10724.8 10621.5 10687.6 10688.5 10689.8 10690.5 10731.0 10769.6 10794.2 10805.4 10806.7 10488.0 10512.2 10566.1 10567.4 10577.2 41222.8 41313.8 41430.4 41464.8 41548.2 3983.9 4145.2 4153.6 4183.8 4202.8 28213.1 29719.0 29871.3 30009.4 30086.1 4020.5 4042.1 4092.7 4256.9 4262.2 315711.0 317159.0 319531.0 321615.0 321804.0 50038.7 50164.0 50277.2 50332.0 50407.7 48705.5 48749.5 48817.5 48843.5 48857.5 14651.4 14933.3 14935.2 14956.3 14964.2 10952.6 11229.0 11680.4 11792.1 11803.8 18208.4 20262.6 20299.8 20896.8 20969.5 7257.5 7414.8 7512.6 7765.7 7829.0 875.2 892.9 909.3 916.6 950.1 30047.8 31241.7 31498.8 31595.8 32211.5 992.6 1044.7 1061.7 1065.4 1090.7 15822.4 16419.0 16562.6 16578.2 16655.3 489711.0 519002.0 524101.0 529876.0 756146.0 53476.2 53664.5 53920.5 54019.7 54077.7 1339.7 1374.3 1388.3 1388.6 1403.0 27872.7 28862.6 28991.0 29675.7 29820.3 44951.0 45510.8 45550.3 45631.5 45639.0 428545.0 431820.0 432385.0 432496.0 432693.1 22878.2 24513.0 25906.5 26353.0 26693.7 40415.8 40547.8 40555.4 41600.8 42079.0 33855.5 34909.7 35204.1 35401.5 35515.6 33223.0 33502.0 34023.7 34044.1 34449.0 34109.7 34406.3 34504.3 34780.5 34804.8 29755.0 30040.0 30623.4 30750.0 31129.4 23941.6 24011.8 24214.2 24284.2 24351.3 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#2] jquery.html Cycles:180 Average:27034.90 Median:404.63 stddev:75578.78 (18678.5%) stddev-sans-first:75764.31 14:55:37 INFO - PROCESS | 12360 | Values: 390.2 392.9 404.4 404.8 416.6 14294.8 16830.4 17553.5 19065.4 19068.4 18072.8 18939.0 18974.6 19195.9 19423.0 5759.4 5929.6 5954.4 5965.3 5967.8 5746.1 5835.8 5840.3 5845.8 5846.2 5495.3 5739.1 5802.4 5820.7 5826.8 5866.4 5893.9 5894.8 5900.7 5904.5 5424.5 5549.5 5597.7 5601.4 5733.1 13634.9 13846.4 13852.2 19526.5 21889.7 2758.3 3074.6 3075.4 3133.0 3137.3 9597.8 9939.3 11334.8 13802.9 14173.5 1912.4 1916.7 1918.2 1923.7 1972.9 73306.5 74748.0 75301.6 76353.0 77231.6 10674.5 10712.6 10756.3 10908.8 11163.6 15100.7 15243.7 15289.9 15328.7 15337.9 7650.1 7653.4 7836.4 7958.8 7971.9 7283.0 7336.7 7363.9 7495.7 7506.5 4613.7 4750.9 4762.5 4791.1 4873.1 1926.7 1952.2 1975.5 1978.1 1983.5 841.6 866.8 873.8 874.3 876.6 17756.6 18036.5 18397.6 18467.0 18769.6 889.1 890.5 931.0 990.2 997.2 9586.2 9919.8 9956.3 10006.1 10052.2 448858.0 457555.0 458660.4 464493.0 466821.0 68025.7 69593.7 84479.0 85537.6 86852.0 1077.8 1096.9 1122.0 1124.7 1128.9 15299.2 15389.5 15706.6 15958.7 16050.9 37719.6 38355.0 38614.8 39407.4 40121.8 54418.7 55633.2 56677.7 56690.0 57111.7 5604.4 6780.3 6813.6 7494.2 8314.0 8611.5 10438.1 11427.9 13218.2 13257.4 11235.9 11573.7 11693.3 11832.6 11982.6 11407.7 11422.4 11429.4 11790.6 11974.6 10675.6 10817.9 11419.7 11765.4 11903.3 7531.5 7540.4 7665.4 7708.2 7806.3 8549.7 10769.6 10817.3 10858.5 10881.7 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#3] mootools.html Cycles:180 Average:5344.46 Median:5481.18 stddev:2950.24 (53.8%) stddev-sans-first:2943.33 14:55:37 INFO - PROCESS | 12360 | Values: 1360.8 1369.0 1369.5 1371.1 1380.1 7925.8 8390.2 8394.2 8398.4 8399.1 7286.4 7530.2 7546.4 7749.3 8247.5 1974.0 2142.4 2200.3 2208.1 2213.4 2153.1 2172.1 2189.2 2189.4 2194.5 1915.1 2192.1 2217.5 2221.3 2222.7 1931.9 1939.7 1943.5 2083.9 2161.0 2129.0 2131.3 2134.4 2135.3 2135.4 8250.7 8302.5 8325.9 8333.6 8347.7 4834.2 4997.5 5000.1 5004.3 5004.8 3092.6 3202.3 3244.8 3270.2 3288.0 1245.7 1302.5 1325.6 1364.9 1389.2 10911.6 10925.5 10968.2 10985.2 10989.6 4250.4 4412.2 4451.4 4509.5 4523.2 9118.2 9124.4 9134.2 9138.5 9148.3 5035.2 5048.9 5109.5 5219.3 5227.8 4741.3 4747.5 4787.0 4791.6 4819.0 3439.8 3444.0 3446.1 3461.6 3472.0 1213.3 1247.6 1253.2 1269.3 1275.1 918.4 920.7 932.7 937.6 948.2 5477.5 5484.9 5666.1 5706.1 5840.4 901.9 923.7 925.7 929.4 937.4 3887.5 3919.4 3959.5 4016.0 4018.5 9599.6 9828.4 9864.4 9868.3 9869.5 9722.6 9729.3 9749.7 9796.7 9816.1 7689.1 7814.3 7818.4 7822.5 7832.1 5582.6 5597.9 5675.5 5737.5 5821.3 9218.4 9245.0 9278.4 9281.8 9336.1 8783.8 9012.1 9101.1 9334.6 9340.3 7280.3 7427.8 7436.5 7440.7 7454.4 6861.2 7819.1 7821.2 7830.3 7843.3 6697.4 6958.9 7081.2 7142.4 7250.9 6800.0 6818.2 6848.0 6855.4 6866.4 6137.1 6248.5 6256.5 6256.9 6257.9 4554.3 4668.0 4723.5 4735.0 4739.2 4632.1 4726.4 4748.5 4826.1 4925.9 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#4] prototype.html Cycles:180 Average:4552.35 Median:5080.75 stddev:1297.05 (25.5%) stddev-sans-first:1262.25 14:55:37 INFO - PROCESS | 12360 | Values: 376.8 391.3 393.3 395.3 396.8 5073.8 5096.4 5121.2 5131.0 5184.9 4878.9 4950.9 5003.4 5029.4 5122.4 4086.8 4113.5 4121.9 4123.3 4128.6 4110.1 4132.2 4135.9 4140.3 4141.6 3899.4 3927.9 4048.1 4068.2 4072.9 4048.6 4072.4 4076.2 4078.0 4095.5 3706.9 3707.6 3708.6 3715.6 3718.9 4821.7 4932.6 4939.2 5009.8 5038.2 4631.7 4646.9 4649.4 4659.1 4712.5 5299.3 5306.8 5312.7 5328.6 5330.6 3951.0 4026.8 4046.8 4048.9 4069.2 5492.9 5494.9 5495.9 5509.3 5510.0 4161.6 4168.4 4176.4 4180.7 4185.5 4057.2 4097.5 4252.8 4271.1 4287.5 4611.5 4624.3 4636.7 4641.5 4660.6 5219.7 5279.2 5292.8 5294.9 5299.8 1308.8 1421.6 1428.9 1430.7 1431.5 936.2 936.5 941.3 945.1 955.2 4986.3 5003.8 5045.0 5046.6 5061.5 4965.1 5284.9 5304.0 5335.9 5337.8 4971.9 4974.3 5022.7 5023.6 5064.2 2810.1 2811.5 2841.2 2841.9 2862.9 5461.3 5548.2 5754.4 5816.8 5821.7 5615.9 5666.0 5678.5 5700.1 5727.5 5628.3 5697.7 5731.5 5857.3 5862.9 5503.7 5503.8 5522.3 5524.6 5526.4 5452.5 5468.7 5485.0 5512.6 5555.0 5676.5 5708.2 5731.4 5748.8 5806.7 4195.4 4204.9 4208.2 4210.9 4221.6 5260.6 5383.9 5442.2 5451.6 5460.4 5087.6 5325.3 5388.5 5399.4 5409.2 5263.1 5330.7 5364.7 5395.7 5415.5 5333.6 5378.7 5380.3 5406.4 5410.4 5364.2 5366.4 5408.2 5441.7 5455.8 5132.5 5200.3 5224.7 5224.8 5260.9 14:55:37 INFO - PROCESS | 12360 | 14:55:37 INFO - PROCESS | 12360 | [#5] yui.html Cycles:180 Average:16669.12 Median:24608.43 stddev:21219.78 (86.2%) stddev-sans-first:21245.17 14:55:37 INFO - PROCESS | 12360 | Values: 641.7 650.7 718.9 732.7 774.5 16142.3 16739.9 16845.3 16864.0 16884.2 12884.7 12939.2 12948.1 13029.5 14451.4 813.9 815.1 817.1 825.5 827.6 843.2 846.7 846.9 847.2 847.8 863.9 864.2 865.0 866.4 866.5 848.5 854.5 855.3 855.5 857.2 626.5 627.1 627.2 627.4 627.6 13476.3 13492.8 13858.4 14956.7 15083.9 11810.6 12019.7 12031.8 12158.1 12518.6 10733.6 10788.5 10822.4 10856.5 10911.9 3442.1 3450.2 3451.8 3464.2 3485.6 72708.3 73654.0 73861.3 74501.6 75233.6 8847.9 8965.8 9037.0 9058.7 9101.3 19664.3 19669.7 19730.9 19852.5 19914.9 7468.1 7645.5 7653.8 7711.9 7809.1 3468.2 3540.1 3559.0 4051.3 4162.2 3566.0 3674.8 3675.5 3685.9 3693.7 602.8 621.7 622.5 626.1 631.1 594.6 602.6 615.7 623.6 624.3 8129.0 8795.5 8822.5 8865.4 9097.6 621.9 625.4 631.1 642.3 667.5 4189.8 4231.4 4250.1 4289.7 4295.3 76816.6 79700.2 80540.6 81104.2 81932.0 44921.6 55405.2 58103.4 71120.0 71481.9 57639.7 58058.4 58148.0 58350.4 58717.7 9559.4 9790.9 9905.5 9989.9 10014.8 40295.4 40302.6 40483.6 40748.6 41809.4 48442.8 50225.2 50293.5 50342.7 52514.7 12794.4 12913.1 13016.3 13051.8 13081.5 14359.6 14518.8 14701.6 14918.0 15701.8 13839.6 13895.2 14023.7 14156.3 14517.9 14148.9 14160.2 14358.8 14368.8 14775.5 13866.9 13922.8 14318.2 14355.5 15005.3 10640.7 10786.0 11331.6 11420.7 11570.0 10912.5 11009.7 11151.9 11221.0 11256.8 14:55:37 INFO - PROCESS | 12360 | -------- Summary: end -------- 14:55:37 INFO - PROCESS | 12360 | 14:55:38 INFO - TEST-INFO | 12360: exit 0 14:55:38 INFO - TEST-OK | dromaeo_css | took 1115135ms 14:55:38 INFO - TEST-START | kraken 14:55:38 INFO - operating with platform_type : linux_ 14:55:38 INFO - Initialising browser for kraken test... 14:55:38 INFO - TEST-INFO | started process 12661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwqWXxh/profile http://localhost:35621/getInfo.html) 14:55:39 INFO - PROCESS | 12661 | 1482965739021 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 14:55:42 INFO - PROCESS | 12661 | __metrics Screen width/height:1600/1200 14:55:42 INFO - PROCESS | 12661 | colorDepth:24 14:55:42 INFO - PROCESS | 12661 | Browser inner width/height: 1024/697 14:55:42 INFO - PROCESS | 12661 | __metrics 14:55:42 INFO - PROCESS | 12661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:55:42 INFO - PROCESS | 12661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:55:43 INFO - PROCESS | 12661 | console.error: PushService: 14:55:43 INFO - PROCESS | 12661 | stateChangeProcessEnqueue: Error transitioning state 14:55:43 INFO - PROCESS | 12661 | UnknownError 14:55:43 INFO - TEST-INFO | 12661: exit 0 14:55:43 INFO - Browser initialized. 14:55:43 INFO - Running cycle 1/1 for kraken test... 14:55:43 INFO - TEST-INFO | started process 12748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwqWXxh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:55 INFO - PROCESS | 12748 | RSS: Main: 147013632 14:55:55 INFO - PROCESS | 12748 | 14:55:58 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35621/tests/kraken/driver.html?testName=audio-beat-detection) 14:55:59 INFO - PROCESS | 12748 | RSS: Main: 201433088 14:55:59 INFO - PROCESS | 12748 | 14:56:01 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35621/tests/kraken/driver.html?testName=audio-dft) 14:56:02 INFO - PROCESS | 12748 | RSS: Main: 181006336 14:56:02 INFO - PROCESS | 12748 | 14:56:05 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35621/tests/kraken/driver.html?testName=audio-fft) 14:56:05 INFO - PROCESS | 12748 | RSS: Main: 263106560 14:56:05 INFO - PROCESS | 12748 | 14:56:08 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35621/tests/kraken/driver.html?testName=audio-oscillator) 14:56:08 INFO - PROCESS | 12748 | RSS: Main: 175677440 14:56:08 INFO - PROCESS | 12748 | 14:56:10 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35621/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:56:11 INFO - PROCESS | 12748 | RSS: Main: 178302976 14:56:11 INFO - PROCESS | 12748 | 14:56:14 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35621/tests/kraken/driver.html?testName=imaging-darkroom) 14:56:14 INFO - PROCESS | 12748 | RSS: Main: 217808896 14:56:14 INFO - PROCESS | 12748 | 14:56:18 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35621/tests/kraken/driver.html?testName=imaging-desaturate) 14:56:18 INFO - PROCESS | 12748 | RSS: Main: 217137152 14:56:18 INFO - PROCESS | 12748 | 14:56:21 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35621/tests/kraken/driver.html?testName=json-parse-financial) 14:56:22 INFO - PROCESS | 12748 | RSS: Main: 215490560 14:56:22 INFO - PROCESS | 12748 | 14:56:23 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35621/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:56:24 INFO - PROCESS | 12748 | RSS: Main: 178237440 14:56:24 INFO - PROCESS | 12748 | 14:56:26 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:56:26 INFO - PROCESS | 12748 | RSS: Main: 185507840 14:56:26 INFO - PROCESS | 12748 | 14:56:28 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:56:29 INFO - PROCESS | 12748 | RSS: Main: 193572864 14:56:29 INFO - PROCESS | 12748 | 14:56:32 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:56:32 INFO - PROCESS | 12748 | RSS: Main: 202838016 14:56:32 INFO - PROCESS | 12748 | 14:56:35 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:56:36 INFO - PROCESS | 12748 | RSS: Main: 200146944 14:56:36 INFO - PROCESS | 12748 | 14:56:37 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:35621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35621/tests/kraken/driver.html?testName=ai-astar) 14:56:38 INFO - PROCESS | 12748 | RSS: Main: 251031552 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | __start_tp_report 14:56:38 INFO - PROCESS | 12748 | _x_x_mozilla_page_load 14:56:38 INFO - PROCESS | 12748 | _x_x_mozilla_page_load_details 14:56:38 INFO - PROCESS | 12748 | |i|pagename|runs| 14:56:38 INFO - PROCESS | 12748 | |0;ai-astar;108;110;110;110;111;109;109;110;110;109 14:56:38 INFO - PROCESS | 12748 | |1;audio-beat-detection;145;117;125;158;123;119;117;120;140;124 14:56:38 INFO - PROCESS | 12748 | |2;audio-dft;160;161;160;160;160;160;160;162;160;160 14:56:38 INFO - PROCESS | 12748 | |3;audio-fft;75;75;76;111;77;78;76;76;105;75 14:56:38 INFO - PROCESS | 12748 | |4;audio-oscillator;84;82;103;84;83;83;93;83;85;113 14:56:38 INFO - PROCESS | 12748 | |5;imaging-gaussian-blur;117;118;116;116;117;117;117;117;117;117 14:56:38 INFO - PROCESS | 12748 | |6;imaging-darkroom;146;146;147;146;147;146;147;146;146;147 14:56:38 INFO - PROCESS | 12748 | |7;imaging-desaturate;95;96;95;96;96;96;96;96;95;96 14:56:38 INFO - PROCESS | 12748 | |8;json-parse-financial;58;51;51;52;54;55;53;53;53;54 14:56:38 INFO - PROCESS | 12748 | |9;json-stringify-tinderbox;67;68;66;67;68;67;67;68;67;68 14:56:38 INFO - PROCESS | 12748 | |10;stanford-crypto-aes;81;82;81;82;82;81;81;93;82;82 14:56:38 INFO - PROCESS | 12748 | |11;stanford-crypto-ccm;133;133;127;128;133;125;127;134;167;144 14:56:38 INFO - PROCESS | 12748 | |12;stanford-crypto-pbkdf2;165;164;166;171;167;169;164;166;170;167 14:56:38 INFO - PROCESS | 12748 | |13;stanford-crypto-sha256-iterative;64;67;62;62;62;63;63;68;62;62 14:56:38 INFO - PROCESS | 12748 | __end_tp_report 14:56:38 INFO - PROCESS | 12748 | __start_cc_report 14:56:38 INFO - PROCESS | 12748 | _x_x_mozilla_cycle_collect,1534 14:56:38 INFO - PROCESS | 12748 | __end_cc_report 14:56:38 INFO - PROCESS | 12748 | __startTimestamp1482965798067__endTimestamp 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | ------- Summary: start ------- 14:56:38 INFO - PROCESS | 12748 | Number of tests: 14 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:0.84 (0.8%) stddev-sans-first:0.67 14:56:38 INFO - PROCESS | 12748 | Values: 108.0 110.0 110.0 110.0 111.0 109.0 109.0 110.0 110.0 109.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:124.50 stddev:14.00 (11.2%) stddev-sans-first:13.56 14:56:38 INFO - PROCESS | 12748 | Values: 145.0 117.0 125.0 158.0 123.0 119.0 117.0 120.0 140.0 124.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 14:56:38 INFO - PROCESS | 12748 | Values: 160.0 161.0 160.0 160.0 160.0 160.0 160.0 162.0 160.0 160.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:13.60 (17.9%) stddev-sans-first:14.16 14:56:38 INFO - PROCESS | 12748 | Values: 75.0 75.0 76.0 111.0 77.0 78.0 76.0 76.0 105.0 75.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:83.50 stddev:10.59 (12.7%) stddev-sans-first:11.06 14:56:38 INFO - PROCESS | 12748 | Values: 84.0 82.0 103.0 84.0 83.0 83.0 93.0 83.0 85.0 113.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:56:38 INFO - PROCESS | 12748 | Values: 117.0 118.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 14:56:38 INFO - PROCESS | 12748 | Values: 146.0 146.0 147.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 14:56:38 INFO - PROCESS | 12748 | Values: 95.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:2.07 (3.9%) stddev-sans-first:1.36 14:56:38 INFO - PROCESS | 12748 | Values: 58.0 51.0 51.0 52.0 54.0 55.0 53.0 53.0 53.0 54.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 14:56:38 INFO - PROCESS | 12748 | Values: 67.0 68.0 66.0 67.0 68.0 67.0 67.0 68.0 67.0 68.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.00 stddev:3.65 (4.5%) stddev-sans-first:3.82 14:56:38 INFO - PROCESS | 12748 | Values: 81.0 82.0 81.0 82.0 82.0 81.0 81.0 93.0 82.0 82.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:133.00 stddev:12.45 (9.4%) stddev-sans-first:13.18 14:56:38 INFO - PROCESS | 12748 | Values: 133.0 133.0 127.0 128.0 133.0 125.0 127.0 134.0 167.0 144.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:2.42 (1.5%) stddev-sans-first:2.47 14:56:38 INFO - PROCESS | 12748 | Values: 165.0 164.0 166.0 171.0 167.0 169.0 164.0 166.0 170.0 167.0 14:56:38 INFO - PROCESS | 12748 | 14:56:38 INFO - PROCESS | 12748 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:2.22 (3.5%) stddev-sans-first:2.35 14:56:38 INFO - PROCESS | 12748 | Values: 64.0 67.0 62.0 62.0 62.0 63.0 63.0 68.0 62.0 62.0 14:56:38 INFO - PROCESS | 12748 | -------- Summary: end -------- 14:56:38 INFO - PROCESS | 12748 | 14:56:39 INFO - TEST-INFO | 12748: exit 0 14:56:39 INFO - TEST-OK | kraken | took 60547ms 14:56:39 INFO - SUITE-END | took 1175s 14:56:39 INFO - Completed test suite (00:19:35) 14:56:39 INFO - javascript benchmark 14:56:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.435261652902, 3155.731762800162, 3174.4127336442743, 3183.602049743782, 3185.2673885006448, 5146.125158723016, 5180.093483640362, 5238.842834714955, 5259.868503287415, 5289.682619042856, 5250.6587071840395, 5275.208718692198, 5298.23175639532, 5313.07021271277, 5318.707471089088, 4786.875084355173, 4821.879453013686, 4825.975870120653, 4843.975780121094, 4844.733539655317, 4794.161021821167, 4847.733374664392, 4855.247436647325, 4877.146499362615, 4927.334809800667, 4899.265110233476, 4908.116539022974, 4915.164422048242, 4916.065947469987, 4918.754062296889, 4698.530146985308, 4727.267273572602, 4750.334953106568, 4794.736289504076, 4842.733649649268, 4791.041791641685, 4809.807607695723, 4827.155247831615, 4849.127157111719, 4866.78099485524, 5289.497497737709, 5301.814436494704, 5313.123334649778, 5334.439883812182, 5357.4374690657305, 5596.664200148004, 5632.267805185341, 5638.943610563924, 5686.289213848269, 5692.943070569283, 5727.0, 5737.454941780524, 5742.253507044099, 5747.770089196427, 5752.597318187729, 5350.438203988564, 5352.625316227865, 5352.732363381893, 5354.812581559705, 5355.250264962909, 5762.971185144047, 5783.508401785811, 5823.068309070529, 5828.475437210586, 5834.416558344132, 5581.665100094007, 5619.325680918316, 5634.746436410372, 5634.85912852182, 5644.774209031675, 5574.470425309671, 5581.358143813432, 5589.35722391922, 5598.048331783607, 5600.635958662755, 5555.666660000413, 5651.954388438152, 5659.518940889988, 5665.801696940587, 5666.121751128581, 5622.803201887996, 5628.127640215773, 5633.070543360382, 5677.3471050829685, 5679.914801277984, 4169.812358443877, 4266.743995360288, 4266.914661706748, 4274.337477690963, 4305.48334199898, 3723.2181241938956, 3725.0873535983314, 3748.4189950557707, 3749.8125093745202, 3818.6563209311294, 5384.192371144281, 5394.9460505394445, 5412.810551630674, 5417.620766546304, 5426.375966763872, 5697.487226149584, 5700.9429905701245, 5709.7716091356715, 5711.771529138871, 5734.741936612779, 5263.868403289872, 5313.0967735485065, 5356.732163391893, 5395.325584301962, 5430.321209848769, 4727.149113159664, 4727.976360118178, 4728.71627702339, 4755.025219829996, 4767.308740232646, 5968.970155149196, 5993.61041532299, 6000.819975400745, 6002.489788368038, 6004.54965877561, 5479.479449452296, 5504.669719816824, 5626.127950167731, 5663.688497132533, 5695.288088988877, 5963.582549221513, 5968.22413086296, 5968.76124954997, 5992.041273396236, 5997.940020599738, 5583.469570390806, 5590.217369568345, 5631.183478395692, 5633.38032816398, 5645.774169033192, 5492.203630473638, 5537.640053396516, 5611.971940140274, 5692.743826527733, 5703.258576385043, 5535.861603459784, 5559.471850174227, 5563.443655634404, 5593.1889875966945, 5594.216809646571, 5535.557155427637, 5546.694931778791, 5548.944510554843, 5549.889002219852, 5596.440355964371, 5361.705106219196, 5400.756965936621, 5408.972955135199, 5473.31583552056, 5526.806561770319, 5478.315210598676, 5508.614396992172, 5520.530754885719, 5591.133374326986, 5594.0769772987005, 5367.980083784225, 5403.891922161613, 5417.539509141767, 5419.268398766274, 5422.132458806572], "value": 5224.262307308367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.6921693068813, 582.0919365789372, 593.6111846740384, 595.5503594785936, 598.5092224376011, 32414.513782293256, 42147.57852421479, 42859.35710964338, 42894.14211715764, 46150.0, 43506.564934350645, 43904.3414348785, 44047.77976110123, 44055.559444405546, 44130.338044929355, 8692.47845129291, 8917.152870477294, 8918.464892106462, 8922.420042697204, 8924.643014279422, 8420.789480263013, 8619.95690021549, 10377.377357358544, 10716.41059741714, 10724.78550428991, 10621.5220315086, 10687.625933092344, 10688.465576721133, 10689.786204275948, 10690.51892664832, 10731.0, 10769.569217231301, 10794.190435717353, 10805.405702686348, 10806.675799726021, 10488.0, 10512.159027277876, 10566.101881340095, 10567.365958042543, 10577.206709496819, 41222.793886030675, 41313.79343103295, 41430.37854432186, 41464.79267603643, 41548.169036619096, 3983.860564880244, 4145.150244199932, 4153.584641535822, 4183.790810459464, 4202.768847713374, 28213.07891075341, 29719.0, 29871.32444835496, 30009.399812003638, 30086.097417077523, 4020.4974378202724, 4042.1309418474875, 4092.6521245694153, 4256.936145957813, 4262.211490874198, 315711.0, 317158.9999999977, 319531.0, 321615.0, 321804.0, 50038.74980625074, 50164.0, 50277.24584131241, 50332.0, 50407.74796126069, 48705.5129448708, 48749.51250487521, 48817.51182488201, 48843.5115648839, 48857.5114248853, 14651.413943442356, 14933.253337333308, 14935.178565178801, 14956.252187390586, 14964.156810206026, 10952.616658416917, 11229.0, 11680.357580333164, 11792.056635469142, 11803.763924721628, 18208.362586422627, 20262.594748104955, 20299.797002030085, 20896.79103208979, 20969.475763106046, 7257.52826066304, 7414.814629634195, 7512.586807725518, 7765.650545725456, 7829.0, 875.2035647560689, 892.9151730585584, 909.2816674826945, 916.5737931861584, 950.1401231885155, 30047.849760751495, 31241.68758312388, 31498.842505787325, 31595.84202078975, 32211.51682724761, 992.6128809764198, 1044.738815296176, 1061.6761887624348, 1065.3554599467366, 1090.705509512443, 15822.367105315889, 16419.0, 16562.58593535171, 16578.17109144538, 16655.25051372667, 489711.0, 519002.0, 524101.035958565, 529876.0, 756146.0, 53476.19785703217, 53664.46335536673, 53920.460795392326, 54019.72990135024, 54077.72961135248, 1339.7052648417332, 1374.3265799758046, 1388.3127851713466, 1388.562602780121, 1402.9719405611831, 27872.721272787014, 28862.567061494516, 28991.0, 29675.703242967294, 29820.254493637833, 44951.0, 45510.772446137555, 45550.31674524951, 45631.54368456406, 45639.0, 428545.0, 431820.0, 432385.0, 432496.0, 432693.05762481526, 22878.199263025715, 24513.0, 25906.48187036211, 26353.0, 26693.7330626699, 40415.797921010206, 40547.797261013504, 40555.39166912558, 41600.79199603983, 42079.0, 33855.49216761701, 34909.65090349064, 35204.119897002776, 35401.46897796483, 35515.644843551236, 33223.0, 33502.0, 34023.65976340304, 34044.148896277795, 34449.0, 34109.65890341164, 34406.311873761886, 34504.309913802084, 34780.478292826134, 34804.82597587097, 29754.95857644972, 30040.0, 30623.387532248784, 30750.0, 31129.37741245208, 23941.64087538723, 24011.75988240165, 24214.152504662252, 24284.150054748, 24351.26946191617], "value": 19253.554975573814, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.159206909111, 392.854643781801, 404.4176386004153, 404.8400881651748, 416.58965918570203, 14294.78557821631, 16830.410935617256, 17553.47339579813, 19065.428037158872, 19068.427947161606, 18072.81927180728, 18939.0, 18974.62050758984, 19195.904020479913, 19423.0, 5759.424057594239, 5929.614575052629, 5954.434328738763, 5965.284165900098, 5967.791127310545, 5746.138079288119, 5835.795747148851, 5840.299164100314, 5845.824625261249, 5846.21076154718, 5495.340559132909, 5739.081746920494, 5802.39074897136, 5820.738066786984, 5826.825195244128, 5866.354700982888, 5893.882122357529, 5894.793682221145, 5900.704964751788, 5904.468597826195, 5424.457554244584, 5549.500544950974, 5597.720113994284, 5601.439856014406, 5733.082706766939, 13634.863651363557, 13846.376913038937, 13852.16886986784, 19526.51183720404, 21889.67165492519, 2758.2690586994463, 3074.6156730408698, 3075.415671022521, 3132.9843350783326, 3137.294108825519, 9597.760055998657, 9939.254555908336, 11334.829977550344, 13802.861971380258, 14173.503927362493, 1912.3784769949823, 1916.6741653918866, 1918.2231196365494, 1923.7210604462414, 1972.9444747060254, 73306.50201234929, 74748.0, 75301.62349188329, 76353.0, 77231.61384193099, 10674.46627668629, 10712.625058122929, 10756.300840445347, 10908.836367454494, 11163.553457861757, 15100.697986040437, 15243.71344298364, 15289.923550382176, 15328.693426131415, 15337.85573957336, 7650.081990161105, 7653.425993050432, 7836.412269079728, 7958.840823183504, 7971.920280797118, 7282.963585182146, 7336.706531738778, 7363.926360736324, 7495.737649182252, 7506.549607023596, 4613.66203800894, 4750.857474275777, 4762.476127625959, 4791.113643975876, 4873.074115918035, 1926.7109933510085, 1952.1508143957424, 1975.4666240115089, 1978.0801927103967, 1983.4644645945514, 841.6086519768337, 866.7789713622985, 873.7509809704204, 874.2568816506047, 876.6099085906837, 17756.55608609769, 18036.549086272687, 18397.632047358977, 18467.0, 18769.62299058092, 889.0620395482698, 890.4568213389826, 930.9767255818658, 990.2375171118199, 997.2022382094295, 9586.185174260127, 9919.80160396788, 9956.253281003954, 10006.149477294366, 10052.246081543768, 448858.0, 457555.0, 458660.4272594098, 464493.0, 466821.0, 68025.65987170032, 69593.65203173952, 84479.0, 85537.57231213928, 86852.0, 1077.762892163707, 1096.8848270931596, 1121.9719507012226, 1124.707576030238, 1128.898399144081, 15299.235038248265, 15389.538313850155, 15706.607334816721, 15958.68082638364, 16050.919745401197, 37719.62280377271, 38355.0, 38614.80692596519, 39407.43851649634, 40121.79939100286, 54418.72790636022, 55633.165502516684, 56677.716611416676, 56690.0, 57111.714441427524, 5604.355499117653, 6780.288069752605, 6813.591184528944, 7494.213107623839, 8313.958430207811, 8611.48331100136, 10438.11276041545, 11427.885721142682, 13218.206907585576, 13257.403416846459, 11235.887641123483, 11573.710657233636, 11693.298402095901, 11832.645020649394, 11982.64052078439, 11407.657770266906, 11422.428878556206, 11429.371384573928, 11790.587329443428, 11974.64076077719, 10675.57297708083, 10817.891821081688, 11419.657410277705, 11765.352905589933, 11903.345316007702, 7531.510451820614, 7540.396768258636, 7665.42509311815, 7708.152103268524, 7806.258405451473, 8549.700760473354, 10769.58309685259, 10817.296875703054, 10858.45707714627, 10881.673549793519], "value": 8864.02340780331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.7754720471123, 1369.048511284657, 1369.479597752854, 1371.060823336015, 1380.0891411668301, 7925.801854953622, 8390.202930721587, 8394.202550757669, 8398.370122240833, 8399.11809260028, 7286.380657644107, 7530.246975302467, 7546.358559522433, 7749.30256276935, 8247.505149691022, 1973.9506512337198, 2142.442964829148, 2200.2959053103014, 2208.116753298685, 2213.424509627492, 2153.052656830997, 2172.1311475409802, 2189.179057853305, 2189.3650841256017, 2194.5172062146303, 1915.1477592471356, 2192.123150739701, 2217.4899773052207, 2221.3002904085242, 2222.6666000099954, 1931.9323823666175, 1939.6896496560553, 1943.523836660013, 2083.8853863037525, 2160.9783902161093, 2129.0419311309975, 2131.296672098204, 2134.4343748906554, 2135.263334149716, 2135.4341099608614, 8250.711225107152, 8302.460340077936, 8325.875111873327, 8333.583320833995, 8347.707830225974, 4834.202356611155, 4997.525235102659, 5000.149974504307, 5004.274380214885, 5004.774785134678, 3092.6443459002276, 3202.2794871154038, 3244.8377581121085, 3270.2314955985325, 3287.96712032879, 1245.7072587941827, 1302.4920281090383, 1325.56919001324, 1364.8976326775432, 1389.1873254146337, 10911.563537458493, 10925.453727313603, 10968.23222374434, 10985.176111791568, 10989.61536346232, 4250.447441832542, 4412.24991751403, 4451.376807246988, 4509.503954564995, 4523.208438523311, 9118.224950879117, 9124.361294709306, 9134.177923986874, 9138.497382644018, 9148.313876459291, 5035.219540971156, 5048.899022019613, 5109.514596113363, 5219.321488206509, 5227.843164705065, 4741.2651039089, 4747.525247475225, 4787.0, 4791.616670666338, 4818.975905120522, 3439.8108104054018, 3443.9655603443643, 3446.069561218457, 3461.5846098468346, 3472.0, 1213.2720367779439, 1247.6257122863105, 1253.216739537789, 1269.282855186835, 1275.09468277524, 918.3938600523622, 920.7375897869075, 932.6689025395887, 937.634322614181, 948.1656142594472, 5477.452254774491, 5484.91772623411, 5666.121751128581, 5706.144078388192, 5840.386759390288, 901.8917729872326, 923.6951805904035, 925.6528801699362, 929.4051806843626, 937.3532262738688, 3887.5476524506776, 3919.392494163409, 3959.4558122331737, 4015.959840401617, 4018.537868145142, 9599.616015359308, 9828.410295382157, 9864.358816677037, 9868.30921835479, 9869.506524673738, 9722.562484688206, 9729.270304727173, 9749.658761943298, 9796.65711700087, 9816.116549510436, 7689.077310722637, 7814.33578145864, 7818.4136189785995, 7822.452428330076, 7832.0601527817025, 5582.637128586629, 5597.860053498695, 5675.489205971482, 5737.454941780524, 5821.330546987151, 9218.400803947721, 9244.95377523135, 9278.350515463853, 9281.814363712823, 9336.11306925841, 8783.824323513621, 9012.053734357798, 9101.135392137736, 9334.579943902405, 9340.34617576763, 7280.289132054069, 7427.8143046424275, 7436.516626419266, 7440.702371905063, 7454.3663788576405, 6861.210960739579, 7819.100803407681, 7821.178776228414, 7830.2952734254195, 7843.254890785366, 6697.430718388797, 6958.930410695828, 7081.18566364854, 7142.392896603847, 7250.927490725026, 6799.966000169968, 6818.18181818185, 6848.0, 6855.383015528626, 6866.416354609914, 6137.1101190328045, 6248.4688801452385, 6256.499480041677, 6256.906146407717, 6257.906131407942, 4554.27131658933, 4667.953320466888, 4723.456802467623, 4735.029318989667, 4739.21802902531, 4632.073585282889, 4726.440274709269, 4748.548887855648, 4826.107170173529, 4925.9014819704125], "value": 4329.393327919419, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.76640482900586, 391.33473095737247, 393.264595206963, 395.31610314156507, 396.78177002648533, 5073.847784566459, 5096.41391240007, 5121.155009423441, 5131.0, 5184.948150518503, 4878.85346943468, 4950.876228094299, 5003.424606170292, 5029.421616514102, 5122.436531981479, 4086.816093275795, 4113.526944401402, 4121.9175616487655, 4123.340265557511, 4128.649064829493, 4110.054687421894, 4132.235536425771, 4135.8759237222785, 4140.254754144268, 4141.565135660758, 3899.3760998240286, 3927.8625248116323, 4048.089179934506, 4068.206699693561, 4072.9185416291657, 4048.5748996355255, 4072.3687828386646, 4076.2255171517318, 4078.0017791282353, 4095.5290141633795, 3706.8517259309865, 3707.6477734615437, 3708.554973403182, 3715.5541335039698, 3718.925621487555, 4821.662483626147, 4932.569554829095, 4939.160342741707, 5009.820727983302, 5038.168702164138, 4631.722096674211, 4646.883827904296, 4649.372334734801, 4659.091477161955, 4712.481627021025, 5299.311089558333, 5306.814261500829, 5312.734363281859, 5328.573714102862, 5330.626856120073, 3950.960490395088, 4026.8187931543152, 4046.7571945683353, 4048.898777530556, 4069.1658210066867, 5492.890142197173, 5494.928488944657, 5495.890082198373, 5509.338879334466, 5510.008198524264, 4161.583841615814, 4168.353905144708, 4176.394422808676, 4180.724878911961, 4185.455890734185, 4057.208844275338, 4097.545371393172, 4252.808623611945, 4271.10306835568, 4287.464066991626, 4611.469680986729, 4624.260118381043, 4636.698614590041, 4641.512641172628, 4660.603848672833, 5219.686818790885, 5279.155335146435, 5292.814751483679, 5294.92057619136, 5299.814506492255, 1308.8429388473444, 1421.644588852787, 1428.8854693339213, 1430.706705125447, 1431.4632012995128, 936.2229349639782, 936.4849332866896, 941.3081385181886, 945.1304799584399, 955.1690029674228, 4986.326845875833, 5003.774830132653, 5045.041442125985, 5046.571041461443, 5061.506855477569, 4965.138073222542, 5284.894302114013, 5303.995483410001, 5335.919961200585, 5337.759800808973, 4971.850844474671, 4974.303597496353, 5022.74886255693, 5023.572996295283, 5064.215046667772, 2810.0726760169096, 2811.451766905434, 2841.2328671258642, 2841.9005334813182, 2862.8568571571336, 5461.344638643388, 5548.223248745179, 5754.424557544212, 5816.82549523515, 5821.7380217890295, 5615.859603509945, 5665.971670141623, 5678.4605462480995, 5700.116481945222, 5727.5131613812455, 5628.268325117627, 5697.7436015380235, 5731.484166424958, 5857.297124345106, 5862.94137058624, 5503.697296648723, 5503.7798488061635, 5522.282103326542, 5524.640898341595, 5526.392096869422, 5452.509274165344, 5468.726563671721, 5484.972575137099, 5512.586556008396, 5555.0, 5676.517496012885, 5708.181927085407, 5731.369549349652, 5748.827535173951, 5806.65160090396, 4195.370694395865, 4204.936925946175, 4208.180519052304, 4210.915781684411, 4221.585768767374, 5260.579153667621, 5383.9192412114635, 5442.238086667949, 5451.618386712892, 5460.372057213472, 5087.64386492942, 5325.2810870531985, 5388.491222457718, 5399.433059528693, 5409.188621706776, 5263.105272103828, 5330.706811125426, 5364.7049412282695, 5395.730213489388, 5415.458454154553, 5333.626646134733, 5378.704171270618, 5380.3274590676165, 5406.432324605859, 5410.404855465974, 5364.222187782828, 5366.409694933633, 5408.242846001484, 5441.7279136043835, 5455.809046683347, 5132.461091585479, 5200.349956255468, 5224.686518808883, 5224.791008359623, 5260.947390526198], "value": 4156.745164356451, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.6535071061627, 650.7462089784988, 718.8993540904271, 732.673960087761, 774.5081873010638, 16142.273597688103, 16739.91630041848, 16845.326186952523, 16863.988160710367, 16884.15579221037, 12884.742305153892, 12939.223646581211, 12948.093633445662, 13029.478820847178, 14451.421943122288, 813.9104698483162, 815.1155995744609, 817.1052697296451, 825.4964471672275, 827.5903427803215, 843.2242337049929, 846.7375113714737, 846.8941382327209, 847.2120927537388, 847.7711018025129, 863.8920134983127, 864.2481041493909, 864.9827003459958, 866.4194989357114, 866.4974314897345, 848.5333066813259, 854.4830377621512, 855.2858363266648, 855.4525103933425, 857.2456238510102, 626.5081910700125, 627.078195053271, 627.1564745417406, 627.42903957399, 627.5575719118057, 13476.25880576568, 13492.797608035888, 13858.376373063136, 14956.700865982728, 15083.924580377137, 11810.645680629596, 12019.699507512294, 12031.819522707166, 12158.148929574845, 12518.561850335285, 10733.570657173781, 10788.51451684676, 10822.404767737771, 10856.511456984455, 10911.890881091167, 3442.122258824009, 3450.1719587299117, 3451.7928924264374, 3464.2378676691087, 3485.6165821759587, 72708.27291727067, 73654.0, 73861.26138738652, 74501.62749186273, 75233.62383188104, 8847.867281990775, 8965.775855603531, 9036.954815225881, 9058.682946096855, 9101.271898248124, 19664.311749088713, 19669.704954425695, 19730.90134549318, 19852.503687407643, 19914.90042549807, 7468.10382754073, 7645.503042302233, 7653.808654783675, 7711.884321735066, 7809.101953275333, 3468.2196505785996, 3540.097275194809, 3559.04507592364, 4051.2505186540584, 4162.229987452331, 3565.982170089133, 3674.761140525857, 3675.5405574303213, 3685.889423317251, 3693.741438099361, 602.8251806976016, 621.7109044294425, 622.4553515673786, 626.123427201921, 631.0723236841835, 594.6075590110506, 602.57518449493, 615.7075389190188, 623.6258245053023, 624.2883113250898, 8128.9593552031865, 8795.516246606498, 8822.51476168817, 8865.379423440298, 9097.590608422637, 621.8569728962274, 625.4246093593905, 631.1164369882201, 642.2870613618973, 667.4626925325063, 4189.790510474464, 4231.42875711775, 4250.086231460251, 4289.742615443083, 4295.312749959991, 76816.61591692005, 79700.20299797044, 80540.59729701314, 81104.18895810966, 81932.0, 44921.55078449239, 55405.1689224662, 58103.418965809804, 71120.0, 71481.92777108347, 57639.71180144156, 58058.419415806144, 58148.0, 58350.41649583535, 58717.70641146767, 9559.378640388353, 9790.902090979283, 9905.455199964072, 9989.850152247867, 10014.799704006025, 40295.3955690659, 40302.59697402988, 40483.59516404916, 40748.59251407448, 41809.37285940774, 48442.757786210845, 50225.24662130144, 50293.49706502888, 50342.748286258335, 52514.737426312626, 12794.360281985677, 12913.09608327408, 13016.28410437435, 13051.804222936853, 13081.542146025224, 14359.569212923629, 14518.782218266946, 14701.632459188178, 14918.0, 15701.764473532678, 13839.584812455643, 13895.166290022631, 14023.719525609635, 14156.292185390483, 14517.927410363303, 14148.883374689827, 14160.150390976574, 14358.784618230526, 14368.784468233194, 14775.482858099915, 13866.861331386557, 13922.802008515644, 14318.212498312692, 14355.497557585435, 15005.32476038559, 10640.678218929344, 10786.0, 11331.603393881174, 11420.71448213768, 11570.0, 10912.508937097691, 11009.669709908716, 11151.944240279072, 11221.0, 11256.774864502828], "value": 6900.474979840194, "unit": "score"}], "name": "dromaeo_css", "value": 6929.662751985004, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 110.0, 110.0, 111.0, 109.0, 109.0, 110.0, 110.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 125.0, 158.0, 123.0, 119.0, 117.0, 120.0, 140.0, 124.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 160.0, 162.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 111.0, 77.0, 78.0, 76.0, 76.0, 105.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 103.0, 84.0, 83.0, 83.0, 93.0, 83.0, 85.0, 113.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 51.0, 51.0, 52.0, 54.0, 55.0, 53.0, 53.0, 53.0, 54.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 82.0, 82.0, 81.0, 81.0, 93.0, 82.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 127.0, 128.0, 133.0, 125.0, 127.0, 134.0, 167.0, 144.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 166.0, 171.0, 167.0, 169.0, 164.0, 166.0, 170.0, 167.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 62.0, 62.0, 62.0, 63.0, 63.0, 68.0, 62.0, 62.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1498.3, "alertThreshold": 2.0}]} 14:56:39 INFO - Return code: 0 14:56:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:56:39 INFO - # TBPL SUCCESS # 14:56:39 INFO - Running post-action listener: _resource_record_post_action 14:56:39 INFO - [mozharness: 2016-12-28 22:56:39.396270Z] Finished run-tests step (success) 14:56:39 INFO - Running post-run listener: _resource_record_post_run 14:56:39 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 407949312; Read time: 0; Write time: 285516 14:56:39 INFO - TinderboxPrint: CPU usage
13.0% 14:56:39 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:56:39 INFO - TinderboxPrint: I/O write bytes / time
407,949,312 / 285,516 14:56:39 INFO - TinderboxPrint: CPU idle
8,281.6 (87.0%) 14:56:39 INFO - TinderboxPrint: CPU user
1,196.8 (12.6%) 14:56:39 INFO - TinderboxPrint: Swap in / out
0 / 0 14:56:39 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 111820800; Read time: 0; Write time: 104608 14:56:39 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 296124416; Read time: 0; Write time: 180908 14:56:40 INFO - Running post-run listener: _upload_blobber_files 14:56:40 INFO - Blob upload gear active. 14:56:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:56:40 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 14:56:40 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'] 14:56:40 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 14:56:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:56:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:56:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:41 INFO - (blobuploader) - INFO - Done attempting. 14:56:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:56:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:42 INFO - (blobuploader) - INFO - Done attempting. 14:56:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:56:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:56:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:45 INFO - (blobuploader) - INFO - Done attempting. 14:56:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:56:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:56:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:46 INFO - (blobuploader) - INFO - Done attempting. 14:56:46 INFO - (blobuploader) - INFO - Iteration through files over. 14:56:46 INFO - Return code: 0 14:56:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:56:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:56:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8c03500137de4aac7c0cdb0a62e7a1c9ea2263ca9ddccf853c0aa032509a163fe43d1e717988266e70d04d1569637b2aef92eac1ce47fc957276463a1def416", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/331b6bbbdeec60da209b6a1e0b2796e9c6f6243ea94c5a0042bc4db03c837528e5d62901637d60ee92910d8ec15adb46f6fe24b3544f1c2f487880a5ee967614", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d33617852d2edbd16e0f3a5cd23b8e1dfe2fa7a2c1376196c301c9747ac1eadb8d26a72ae3f0199690987c05f35678a51fabd715c01036ea89a760e9c56ba6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1e242ef4e2af1f1156984e53f5aff9101854184054f3f384aca2cc0a65a0adfcfd54e1bec96a3cda0fc4d05885851204796e90617425df9ba69d7062ed463fcf"} 14:56:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:56:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:56:46 INFO - Contents: 14:56:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8c03500137de4aac7c0cdb0a62e7a1c9ea2263ca9ddccf853c0aa032509a163fe43d1e717988266e70d04d1569637b2aef92eac1ce47fc957276463a1def416", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/331b6bbbdeec60da209b6a1e0b2796e9c6f6243ea94c5a0042bc4db03c837528e5d62901637d60ee92910d8ec15adb46f6fe24b3544f1c2f487880a5ee967614", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d33617852d2edbd16e0f3a5cd23b8e1dfe2fa7a2c1376196c301c9747ac1eadb8d26a72ae3f0199690987c05f35678a51fabd715c01036ea89a760e9c56ba6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1e242ef4e2af1f1156984e53f5aff9101854184054f3f384aca2cc0a65a0adfcfd54e1bec96a3cda0fc4d05885851204796e90617425df9ba69d7062ed463fcf"} 14:56:46 INFO - Running post-run listener: copy_logs_to_upload_dir 14:56:46 INFO - Copying logs to upload dir... 14:56:46 INFO - mkdir: /builds/slave/test/build/upload/logs 14:56:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.973307 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-12-28 14:56:45.734000) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 14:56:45.738044) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8c03500137de4aac7c0cdb0a62e7a1c9ea2263ca9ddccf853c0aa032509a163fe43d1e717988266e70d04d1569637b2aef92eac1ce47fc957276463a1def416", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/331b6bbbdeec60da209b6a1e0b2796e9c6f6243ea94c5a0042bc4db03c837528e5d62901637d60ee92910d8ec15adb46f6fe24b3544f1c2f487880a5ee967614", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d33617852d2edbd16e0f3a5cd23b8e1dfe2fa7a2c1376196c301c9747ac1eadb8d26a72ae3f0199690987c05f35678a51fabd715c01036ea89a760e9c56ba6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1e242ef4e2af1f1156984e53f5aff9101854184054f3f384aca2cc0a65a0adfcfd54e1bec96a3cda0fc4d05885851204796e90617425df9ba69d7062ed463fcf"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011877 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482957003/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8c03500137de4aac7c0cdb0a62e7a1c9ea2263ca9ddccf853c0aa032509a163fe43d1e717988266e70d04d1569637b2aef92eac1ce47fc957276463a1def416", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/331b6bbbdeec60da209b6a1e0b2796e9c6f6243ea94c5a0042bc4db03c837528e5d62901637d60ee92910d8ec15adb46f6fe24b3544f1c2f487880a5ee967614", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d33617852d2edbd16e0f3a5cd23b8e1dfe2fa7a2c1376196c301c9747ac1eadb8d26a72ae3f0199690987c05f35678a51fabd715c01036ea89a760e9c56ba6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1e242ef4e2af1f1156984e53f5aff9101854184054f3f384aca2cc0a65a0adfcfd54e1bec96a3cda0fc4d05885851204796e90617425df9ba69d7062ed463fcf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 14:56:45.765339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:56:45.765739) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1482964568.332620-50608906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:56:45.847273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 14:56:45.847593) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 14:56:45.847922) ========= ========= Total master_lag: 0.17 =========