builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1482470158.33 results: success (0) buildid: 20161222193138 builduid: 6058b98cf165449e865919a1c56d8680 revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.327574) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.327964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.328233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.351022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.351281) ========= 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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 21:15:58-- 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% 42.8M=0s 2016-12-22 21:15:58 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108753 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.473105) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.473378) ========= 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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015481 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.524612) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.524914) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 21:15:58,734 truncating revision to first 12 chars 2016-12-22 21:15:58,734 Setting DEBUG logging. 2016-12-22 21:15:58,735 attempt 1/10 2016-12-22 21:15:58,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2785aaf276ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 21:15:59,374 unpacking tar archive at: mozilla-central-2785aaf276ba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.962204 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:59.500810) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:59.501204) ========= script_repo_revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:59.501614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:59.501880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:59.511332) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-22 21:15:59.511705) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:15:59 INFO - MultiFileLogger online at 20161222 21:15:59 in /builds/slave/test 21:15:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:15:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:15:59 INFO - {'append_to_log': False, 21:15:59 INFO - 'base_work_dir': '/builds/slave/test', 21:15:59 INFO - 'blob_upload_branch': 'Firefox', 21:15:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:15:59 INFO - 'branch': 'Firefox', 21:15:59 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:59 INFO - 'config_files': ('talos/linux_config.py',), 21:15:59 INFO - 'default_actions': ('clobber', 21:15:59 INFO - 'read-buildbot-config', 21:15:59 INFO - 'download-and-extract', 21:15:59 INFO - 'populate-webroot', 21:15:59 INFO - 'create-virtualenv', 21:15:59 INFO - 'install', 21:15:59 INFO - 'run-tests'), 21:15:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:59 INFO - 'download_minidump_stackwalk': True, 21:15:59 INFO - 'download_symbols': 'ondemand', 21:15:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:15:59 INFO - 'tooltool.py': '/tools/tooltool.py', 21:15:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:15:59 INFO - '/tools/misc-python/virtualenv.py')}, 21:15:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:59 INFO - 'installer_path': 'installer.exe', 21:15:59 INFO - 'log_level': 'info', 21:15:59 INFO - 'log_name': 'talos', 21:15:59 INFO - 'log_to_console': True, 21:15:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:15:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:15:59 INFO - 'opt_config_files': (), 21:15:59 INFO - 'pip_index': False, 21:15:59 INFO - 'sps_profile': False, 21:15:59 INFO - 'sps_profile_interval': 0, 21:15:59 INFO - 'suite': 'dromaeojs', 21:15:59 INFO - 'system_bits': '32', 21:15:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:59 INFO - 'title': 'talos-linux64-ix-038', 21:15:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:15:59 INFO - 'use_talos_json': True, 21:15:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:15:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:59 INFO - 'work_dir': 'build'} 21:15:59 INFO - [mozharness: 2016-12-23 05:15:59.820989Z] Running clobber step. 21:15:59 INFO - Running pre-action listener: _resource_record_pre_action 21:15:59 INFO - Running main action method: clobber 21:15:59 INFO - rmtree: /builds/slave/test/build 21:15:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:16:00 INFO - Running post-action listener: _resource_record_post_action 21:16:00 INFO - [mozharness: 2016-12-23 05:16:00.798981Z] Finished clobber step (success) 21:16:00 INFO - [mozharness: 2016-12-23 05:16:00.799082Z] Running read-buildbot-config step. 21:16:00 INFO - Running pre-action listener: _resource_record_pre_action 21:16:00 INFO - Running main action method: read_buildbot_config 21:16:00 INFO - Using buildbot properties: 21:16:00 INFO - { 21:16:00 INFO - "project": "", 21:16:00 INFO - "product": "firefox", 21:16:00 INFO - "script_repo_revision": "production", 21:16:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:16:00 INFO - "repository": "", 21:16:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:16:00 INFO - "buildid": "20161222193138", 21:16:00 INFO - "pgo_build": "True", 21:16:00 INFO - "basedir": "/builds/slave/test", 21:16:00 INFO - "buildnumber": 126, 21:16:00 INFO - "slavename": "talos-linux64-ix-038", 21:16:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:16:00 INFO - "platform": "ubuntu64_hw", 21:16:00 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:16:00 INFO - "branch": "mozilla-central", 21:16:00 INFO - "repo_path": "mozilla-central", 21:16:00 INFO - "revision": "2785aaf276ba29fb2e1f5607d90d441fee42efb4", 21:16:00 INFO - "stage_platform": "linux64-pgo", 21:16:00 INFO - "builduid": "6058b98cf165449e865919a1c56d8680", 21:16:00 INFO - "slavebuilddir": "test" 21:16:00 INFO - } 21:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:16:00 INFO - Running post-action listener: _resource_record_post_action 21:16:00 INFO - [mozharness: 2016-12-23 05:16:00.801551Z] Finished read-buildbot-config step (success) 21:16:00 INFO - [mozharness: 2016-12-23 05:16:00.801641Z] Running download-and-extract step. 21:16:00 INFO - Running pre-action listener: _resource_record_pre_action 21:16:00 INFO - Running main action method: download_and_extract 21:16:00 INFO - mkdir: /builds/slave/test/build/tests 21:16:00 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')]} 21:16:00 INFO - trying https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/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 21:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/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 21:16:01 INFO - Downloaded 1361 bytes. 21:16:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:16:01 INFO - Using the following test package requirements: 21:16:01 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:16:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:16:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:16:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'jsshell-linux-x86_64.zip'], 21:16:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:16:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:16:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:16:01 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:16:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:16:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:16:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:16:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:16:02 INFO - Expected file size: 21827161 21:16:02 INFO - Obtained file size: 21827161 21:16:06 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 21:16:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:16:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:16:06 INFO - Fetch https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:16:07 INFO - Expected file size: 21827161 21:16:07 INFO - Obtained file size: 21827161 21:16:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:16:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:16:11 INFO - Fetch https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:16:12 INFO - Expected file size: 14409776 21:16:12 INFO - Obtained file size: 14409776 21:16: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')]} 21:16:12 INFO - trying https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:12 INFO - Downloading https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/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 21:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/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 21:16:13 INFO - Downloaded 61393625 bytes. 21:16:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:13 INFO - mkdir: /builds/slave/test/properties 21:16:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:16:13 INFO - Writing to file /builds/slave/test/properties/build_url 21:16:13 INFO - Contents: 21:16:13 INFO - build_url:https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:14 INFO - Running post-action listener: _resource_record_post_action 21:16:14 INFO - Running post-action listener: set_extra_try_arguments 21:16:14 INFO - [mozharness: 2016-12-23 05:16:14.362501Z] Finished download-and-extract step (success) 21:16:14 INFO - [mozharness: 2016-12-23 05:16:14.362730Z] Running populate-webroot step. 21:16:14 INFO - Running pre-action listener: _resource_record_pre_action 21:16:14 INFO - Running main action method: populate_webroot 21:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:16:14 INFO - '--tppagecycles', 21:16:14 INFO - '7'], 21:16:14 INFO - 'tests': ['tsvgm']}}, 21:16:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:16:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:16:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:16:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:16:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['damp', 'tps']}, 21:16:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'tests': ['damp', 'tps']}, 21:16:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['dromaeo_dom']}, 21:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:16:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['basic_compositor_video']}, 21:16:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:16:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'other-e10s': {'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['a11yr', 21:16:14 INFO - 'ts_paint', 21:16:14 INFO - 'tpaint', 21:16:14 INFO - 'sessionrestore', 21:16:14 INFO - 'sessionrestore_no_auto_restore', 21:16:14 INFO - 'tabpaint']}, 21:16:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['tsvgx', 21:16:14 INFO - 'tsvgr_opacity', 21:16:14 INFO - 'tart', 21:16:14 INFO - 'tscrollx', 21:16:14 INFO - 'cart', 21:16:14 INFO - 'tsvg_static']}, 21:16:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:16:14 INFO - 'tsvgr_opacity', 21:16:14 INFO - 'tart', 21:16:14 INFO - 'tscrollx', 21:16:14 INFO - 'cart', 21:16:14 INFO - 'tsvg_static']}, 21:16:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'talos_options': ['--disable-e10s'], 21:16:14 INFO - 'tests': ['tp5o']}, 21:16:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:16:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'talos_options': ['--disable-e10s', 21:16:14 INFO - '--xperf_path', 21:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:14 INFO - 'tests': ['tp5n']}, 21:16:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:16:14 INFO - 'talos_options': ['--xperf_path', 21:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:14 INFO - 'tests': ['tp5n']}}, 21:16:14 INFO - 'talos.zip': {'path': '', 21:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:16:14 INFO - Running post-action listener: _resource_record_post_action 21:16:14 INFO - [mozharness: 2016-12-23 05:16:14.382208Z] Finished populate-webroot step (success) 21:16:14 INFO - [mozharness: 2016-12-23 05:16:14.382297Z] Running create-virtualenv step. 21:16:14 INFO - Running pre-action listener: _resource_record_pre_action 21:16:14 INFO - Running main action method: create_virtualenv 21:16:14 INFO - Creating virtualenv /builds/slave/test/build/venv 21:16:14 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 21:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:16:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:16:14 INFO - Using real prefix '/usr' 21:16:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:16:14 INFO - Installing distribute.............................................................................................................................................................................................done. 21:16:16 INFO - Installing pip.................done. 21:16:16 INFO - Return code: 0 21:16:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:16:17 INFO - Reading from file tmpfile_stdout 21:16:17 INFO - Output received: 21:16:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:16:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:16:17 INFO - Using env: {'DISPLAY': ':0', 21:16:17 INFO - 'HOME': '/home/cltbld', 21:16:17 INFO - 'LANG': 'en_US.UTF-8', 21:16:17 INFO - 'LANGUAGE': 'en_US:en', 21:16:17 INFO - 'LOGNAME': 'cltbld', 21:16:17 INFO - 'MAIL': '/var/mail/cltbld', 21:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:17 INFO - 'MOZ_NO_REMOTE': '1', 21:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:17 INFO - 'NO_EM_RESTART': '1', 21:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:17 INFO - 'PWD': '/builds/slave/test', 21:16:17 INFO - 'SHELL': '/bin/bash', 21:16:17 INFO - 'SHLVL': '1', 21:16:17 INFO - 'TERM': 'linux', 21:16:17 INFO - 'TMOUT': '86400', 21:16:17 INFO - 'USER': 'cltbld', 21:16:17 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:17 INFO - '_': '/tools/buildbot/bin/python'} 21:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:17 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 21:16:17 INFO - Cleaning up... 21:16:17 INFO - Return code: 0 21:16:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:16:17 INFO - Using env: {'DISPLAY': ':0', 21:16:17 INFO - 'HOME': '/home/cltbld', 21:16:17 INFO - 'LANG': 'en_US.UTF-8', 21:16:17 INFO - 'LANGUAGE': 'en_US:en', 21:16:17 INFO - 'LOGNAME': 'cltbld', 21:16:17 INFO - 'MAIL': '/var/mail/cltbld', 21:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:17 INFO - 'MOZ_NO_REMOTE': '1', 21:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:17 INFO - 'NO_EM_RESTART': '1', 21:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:17 INFO - 'PWD': '/builds/slave/test', 21:16:17 INFO - 'SHELL': '/bin/bash', 21:16:17 INFO - 'SHLVL': '1', 21:16:17 INFO - 'TERM': 'linux', 21:16:17 INFO - 'TMOUT': '86400', 21:16:17 INFO - 'USER': 'cltbld', 21:16:17 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:17 INFO - '_': '/tools/buildbot/bin/python'} 21:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:17 INFO - Downloading/unpacking psutil>=3.1.1 21:16:17 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 21:16:17 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 21:16:17 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 21:16:17 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 21:16:17 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 21:16:17 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 21:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:19 INFO - Installing collected packages: psutil 21:16:19 INFO - Running setup.py install for psutil 21:16:19 INFO - building 'psutil._psutil_linux' extension 21:16:19 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 21:16:19 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 21:16:19 INFO - building 'psutil._psutil_posix' extension 21:16:19 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 21:16:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:16:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:20 INFO - Successfully installed psutil 21:16:20 INFO - Cleaning up... 21:16:20 INFO - Return code: 0 21:16:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:16:20 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')]} 21:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:20 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')]} 21:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:20 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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:20 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 21:16:20 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 21:16:20 INFO - Using env: {'DISPLAY': ':0', 21:16:20 INFO - 'HOME': '/home/cltbld', 21:16:20 INFO - 'LANG': 'en_US.UTF-8', 21:16:20 INFO - 'LANGUAGE': 'en_US:en', 21:16:20 INFO - 'LOGNAME': 'cltbld', 21:16:20 INFO - 'MAIL': '/var/mail/cltbld', 21:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:20 INFO - 'MOZ_NO_REMOTE': '1', 21:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:20 INFO - 'NO_EM_RESTART': '1', 21:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:20 INFO - 'PWD': '/builds/slave/test', 21:16:20 INFO - 'SHELL': '/bin/bash', 21:16:20 INFO - 'SHLVL': '1', 21:16:20 INFO - 'TERM': 'linux', 21:16:20 INFO - 'TMOUT': '86400', 21:16:20 INFO - 'USER': 'cltbld', 21:16:20 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:20 INFO - '_': '/tools/buildbot/bin/python'} 21:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:16:20 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 21:16:20 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 21:16:20 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 21:16:20 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 21:16:20 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 21:16:20 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 21:16:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:16:22 INFO - Installing collected packages: mozsystemmonitor 21:16:22 INFO - Running setup.py install for mozsystemmonitor 21:16:22 INFO - Successfully installed mozsystemmonitor 21:16:22 INFO - Cleaning up... 21:16:22 INFO - Return code: 0 21:16:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:16:22 INFO - Using env: {'DISPLAY': ':0', 21:16:22 INFO - 'HOME': '/home/cltbld', 21:16:22 INFO - 'LANG': 'en_US.UTF-8', 21:16:22 INFO - 'LANGUAGE': 'en_US:en', 21:16:22 INFO - 'LOGNAME': 'cltbld', 21:16:22 INFO - 'MAIL': '/var/mail/cltbld', 21:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:22 INFO - 'MOZ_NO_REMOTE': '1', 21:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:22 INFO - 'NO_EM_RESTART': '1', 21:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:22 INFO - 'PWD': '/builds/slave/test', 21:16:22 INFO - 'SHELL': '/bin/bash', 21:16:22 INFO - 'SHLVL': '1', 21:16:22 INFO - 'TERM': 'linux', 21:16:22 INFO - 'TMOUT': '86400', 21:16:22 INFO - 'USER': 'cltbld', 21:16:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:22 INFO - '_': '/tools/buildbot/bin/python'} 21:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:22 INFO - Downloading/unpacking jsonschema==2.5.1 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:16:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:16:24 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 21:16:24 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 21:16:24 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 21:16:24 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 21:16:24 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 21:16:24 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 21:16:25 INFO - Downloading functools32-3.2.3-2.tar.gz 21:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:16:25 INFO - warning: no files found matching '*.txt' 21:16:25 INFO - no previously-included directories found matching 'build' 21:16:25 INFO - no previously-included directories found matching 'dist' 21:16:25 INFO - no previously-included directories found matching '.git*' 21:16:25 INFO - Installing collected packages: jsonschema, functools32 21:16:25 INFO - Running setup.py install for functools32 21:16:25 INFO - warning: no files found matching '*.txt' 21:16:25 INFO - no previously-included directories found matching 'build' 21:16:25 INFO - no previously-included directories found matching 'dist' 21:16:25 INFO - no previously-included directories found matching '.git*' 21:16:25 INFO - Successfully installed jsonschema functools32 21:16:25 INFO - Cleaning up... 21:16:25 INFO - Return code: 0 21:16:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:16:25 INFO - Using env: {'DISPLAY': ':0', 21:16:25 INFO - 'HOME': '/home/cltbld', 21:16:25 INFO - 'LANG': 'en_US.UTF-8', 21:16:25 INFO - 'LANGUAGE': 'en_US:en', 21:16:25 INFO - 'LOGNAME': 'cltbld', 21:16:25 INFO - 'MAIL': '/var/mail/cltbld', 21:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:25 INFO - 'MOZ_NO_REMOTE': '1', 21:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:25 INFO - 'NO_EM_RESTART': '1', 21:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:25 INFO - 'PWD': '/builds/slave/test', 21:16:25 INFO - 'SHELL': '/bin/bash', 21:16:25 INFO - 'SHLVL': '1', 21:16:25 INFO - 'TERM': 'linux', 21:16:25 INFO - 'TMOUT': '86400', 21:16:25 INFO - 'USER': 'cltbld', 21:16:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:25 INFO - '_': '/tools/buildbot/bin/python'} 21:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:16:25 INFO - Cleaning up... 21:16:25 INFO - Return code: 0 21:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:16:25 INFO - Using env: {'DISPLAY': ':0', 21:16:25 INFO - 'HOME': '/home/cltbld', 21:16:25 INFO - 'LANG': 'en_US.UTF-8', 21:16:25 INFO - 'LANGUAGE': 'en_US:en', 21:16:25 INFO - 'LOGNAME': 'cltbld', 21:16:25 INFO - 'MAIL': '/var/mail/cltbld', 21:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:25 INFO - 'MOZ_NO_REMOTE': '1', 21:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:25 INFO - 'NO_EM_RESTART': '1', 21:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:25 INFO - 'PWD': '/builds/slave/test', 21:16:25 INFO - 'SHELL': '/bin/bash', 21:16:25 INFO - 'SHLVL': '1', 21:16:25 INFO - 'TERM': 'linux', 21:16:25 INFO - 'TMOUT': '86400', 21:16:25 INFO - 'USER': 'cltbld', 21:16:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:25 INFO - '_': '/tools/buildbot/bin/python'} 21:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 21:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:28 INFO - Downloading docopt-0.6.1.tar.gz 21:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:16:28 INFO - Installing collected packages: blobuploader, requests, docopt 21:16:28 INFO - Running setup.py install for blobuploader 21:16:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:16:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:16:28 INFO - Running setup.py install for requests 21:16:29 INFO - Running setup.py install for docopt 21:16:29 INFO - Successfully installed blobuploader requests docopt 21:16:29 INFO - Cleaning up... 21:16:29 INFO - Return code: 0 21:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:29 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')]} 21:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:29 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')]} 21:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:29 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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:29 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 21:16:29 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 21:16:29 INFO - Using env: {'DISPLAY': ':0', 21:16:29 INFO - 'HOME': '/home/cltbld', 21:16:29 INFO - 'LANG': 'en_US.UTF-8', 21:16:29 INFO - 'LANGUAGE': 'en_US:en', 21:16:29 INFO - 'LOGNAME': 'cltbld', 21:16:29 INFO - 'MAIL': '/var/mail/cltbld', 21:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:29 INFO - 'MOZ_NO_REMOTE': '1', 21:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:29 INFO - 'NO_EM_RESTART': '1', 21:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:29 INFO - 'PWD': '/builds/slave/test', 21:16:29 INFO - 'SHELL': '/bin/bash', 21:16:29 INFO - 'SHLVL': '1', 21:16:29 INFO - 'TERM': 'linux', 21:16:29 INFO - 'TMOUT': '86400', 21:16:29 INFO - 'USER': 'cltbld', 21:16:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:29 INFO - '_': '/tools/buildbot/bin/python'} 21:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:29 INFO - Running setup.py (path:/tmp/pip-TOdXLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:29 INFO - Running setup.py (path:/tmp/pip-v7EehY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:29 INFO - Running setup.py (path:/tmp/pip-3KOGHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:29 INFO - Running setup.py (path:/tmp/pip-N_Cw7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:29 INFO - Running setup.py (path:/tmp/pip-c8O_PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:29 INFO - Running setup.py (path:/tmp/pip-Vse1YM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:29 INFO - Running setup.py (path:/tmp/pip-5E_ZBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:29 INFO - Running setup.py (path:/tmp/pip-4GwTi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:30 INFO - Running setup.py (path:/tmp/pip-NqCThO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:30 INFO - Running setup.py (path:/tmp/pip-daE1Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:30 INFO - Running setup.py (path:/tmp/pip-Z4Ro6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:30 INFO - Running setup.py (path:/tmp/pip-VHrbUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:30 INFO - Running setup.py (path:/tmp/pip-tVa6Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:30 INFO - Running setup.py (path:/tmp/pip-4p6yZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:30 INFO - Running setup.py (path:/tmp/pip-hqY83w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:30 INFO - Running setup.py (path:/tmp/pip-B9rIiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:30 INFO - Running setup.py (path:/tmp/pip-Lo8Pxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:16:30 INFO - Running setup.py install for manifestparser 21:16:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozcrash 21:16:30 INFO - Running setup.py install for mozdebug 21:16:30 INFO - Running setup.py install for mozdevice 21:16:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozfile 21:16:31 INFO - Running setup.py install for mozhttpd 21:16:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozinfo 21:16:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozInstall 21:16:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozleak 21:16:31 INFO - Running setup.py install for mozlog 21:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for moznetwork 21:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozprocess 21:16:31 INFO - Running setup.py install for mozprofile 21:16:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Running setup.py install for mozrunner 21:16:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Running setup.py install for mozscreenshot 21:16:32 INFO - Running setup.py install for moztest 21:16:32 INFO - Running setup.py install for mozversion 21:16:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:16:32 INFO - Cleaning up... 21:16:32 INFO - Return code: 0 21:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:32 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')]} 21:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 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')]} 21:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:32 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 21:16:32 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 21:16:32 INFO - Using env: {'DISPLAY': ':0', 21:16:32 INFO - 'HOME': '/home/cltbld', 21:16:32 INFO - 'LANG': 'en_US.UTF-8', 21:16:32 INFO - 'LANGUAGE': 'en_US:en', 21:16:32 INFO - 'LOGNAME': 'cltbld', 21:16:32 INFO - 'MAIL': '/var/mail/cltbld', 21:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:32 INFO - 'MOZ_NO_REMOTE': '1', 21:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:32 INFO - 'NO_EM_RESTART': '1', 21:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:32 INFO - 'PWD': '/builds/slave/test', 21:16:32 INFO - 'SHELL': '/bin/bash', 21:16:32 INFO - 'SHLVL': '1', 21:16:32 INFO - 'TERM': 'linux', 21:16:32 INFO - 'TMOUT': '86400', 21:16:32 INFO - 'USER': 'cltbld', 21:16:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:32 INFO - '_': '/tools/buildbot/bin/python'} 21:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:32 INFO - Running setup.py (path:/tmp/pip-HQmrR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:32 INFO - Running setup.py (path:/tmp/pip-3daLT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:32 INFO - Running setup.py (path:/tmp/pip-sPMy0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:32 INFO - Running setup.py (path:/tmp/pip-Pix5hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:33 INFO - Running setup.py (path:/tmp/pip-B9930p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:33 INFO - Running setup.py (path:/tmp/pip-ZORiie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:33 INFO - Running setup.py (path:/tmp/pip-X6GzLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:33 INFO - Running setup.py (path:/tmp/pip-WRlEQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:33 INFO - Running setup.py (path:/tmp/pip-N1SXpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:33 INFO - Running setup.py (path:/tmp/pip-vbUt5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:33 INFO - Running setup.py (path:/tmp/pip-FgV2hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:33 INFO - Running setup.py (path:/tmp/pip-w0apyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:33 INFO - Running setup.py (path:/tmp/pip-k9YnB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:33 INFO - Running setup.py (path:/tmp/pip-HbVzln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:33 INFO - Running setup.py (path:/tmp/pip-uEy8ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:33 INFO - Running setup.py (path:/tmp/pip-5JeAb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:33 INFO - Running setup.py (path:/tmp/pip-QyEVP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:33 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)) 21:16:33 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)) 21:16:33 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)) 21:16:33 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)) 21:16:33 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)) 21:16:33 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)) 21:16:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:16: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 21:16: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 21:16: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 21:16: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 21:16: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 21:16: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 21:16:35 INFO - Downloading blessings-1.6.tar.gz 21:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:16:36 INFO - Installing collected packages: blessings 21:16:36 INFO - Running setup.py install for blessings 21:16:36 INFO - Successfully installed blessings 21:16:36 INFO - Cleaning up... 21:16:36 INFO - Return code: 0 21:16:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:36 INFO - Reading from file tmpfile_stdout 21:16:36 INFO - Current package versions: 21:16:36 INFO - argparse == 1.2.1 21:16:36 INFO - blessings == 1.6 21:16:36 INFO - blobuploader == 1.2.4 21:16:36 INFO - docopt == 0.6.1 21:16:36 INFO - functools32 == 3.2.3-2 21:16:36 INFO - jsonschema == 2.5.1 21:16:36 INFO - manifestparser == 1.1 21:16:36 INFO - mozInstall == 1.12 21:16:36 INFO - mozcrash == 1.0 21:16:36 INFO - mozdebug == 0.1 21:16:36 INFO - mozdevice == 0.49 21:16:36 INFO - mozfile == 1.2 21:16:36 INFO - mozhttpd == 0.7 21:16:36 INFO - mozinfo == 0.9 21:16:36 INFO - mozleak == 0.1 21:16:36 INFO - mozlog == 3.4 21:16:36 INFO - moznetwork == 0.27 21:16:36 INFO - mozprocess == 0.23 21:16:36 INFO - mozprofile == 0.28 21:16:36 INFO - mozrunner == 6.13 21:16:36 INFO - mozscreenshot == 0.1 21:16:36 INFO - mozsystemmonitor == 0.3 21:16:36 INFO - moztest == 0.7 21:16:36 INFO - mozversion == 1.4 21:16:36 INFO - psutil == 3.1.1 21:16:36 INFO - requests == 1.2.3 21:16:36 INFO - wsgiref == 0.1.2 21:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:36 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')]} 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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')]} 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:36 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 21:16:36 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 21:16:36 INFO - Using env: {'DISPLAY': ':0', 21:16:36 INFO - 'HOME': '/home/cltbld', 21:16:36 INFO - 'LANG': 'en_US.UTF-8', 21:16:36 INFO - 'LANGUAGE': 'en_US:en', 21:16:36 INFO - 'LOGNAME': 'cltbld', 21:16:36 INFO - 'MAIL': '/var/mail/cltbld', 21:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:36 INFO - 'MOZ_NO_REMOTE': '1', 21:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:36 INFO - 'NO_EM_RESTART': '1', 21:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:36 INFO - 'PWD': '/builds/slave/test', 21:16:36 INFO - 'SHELL': '/bin/bash', 21:16:36 INFO - 'SHLVL': '1', 21:16:36 INFO - 'TERM': 'linux', 21:16:36 INFO - 'TMOUT': '86400', 21:16:36 INFO - 'USER': 'cltbld', 21:16:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:36 INFO - '_': '/tools/buildbot/bin/python'} 21:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:16:36 INFO - Cleaning up... 21:16:36 INFO - Return code: 0 21:16:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:16:36 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')]} 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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')]} 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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 0x1ec11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2232c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, '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': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', '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 21:16:36 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 21:16:36 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 21:16:36 INFO - Using env: {'DISPLAY': ':0', 21:16:36 INFO - 'HOME': '/home/cltbld', 21:16:36 INFO - 'LANG': 'en_US.UTF-8', 21:16:36 INFO - 'LANGUAGE': 'en_US:en', 21:16:36 INFO - 'LOGNAME': 'cltbld', 21:16:36 INFO - 'MAIL': '/var/mail/cltbld', 21:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:36 INFO - 'MOZ_NO_REMOTE': '1', 21:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:36 INFO - 'NO_EM_RESTART': '1', 21:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:36 INFO - 'PWD': '/builds/slave/test', 21:16:36 INFO - 'SHELL': '/bin/bash', 21:16:36 INFO - 'SHLVL': '1', 21:16:36 INFO - 'TERM': 'linux', 21:16:36 INFO - 'TMOUT': '86400', 21:16:36 INFO - 'USER': 'cltbld', 21:16:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:36 INFO - '_': '/tools/buildbot/bin/python'} 21:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:16:36 INFO - Cleaning up... 21:16:36 INFO - Return code: 0 21:16:36 INFO - Running post-action listener: _resource_record_post_action 21:16:36 INFO - Running post-action listener: _start_resource_monitoring 21:16:36 INFO - Starting resource monitoring. 21:16:36 INFO - [mozharness: 2016-12-23 05:16:36.937408Z] Finished create-virtualenv step (success) 21:16:36 INFO - [mozharness: 2016-12-23 05:16:36.937930Z] Running install step. 21:16:36 INFO - Running pre-action listener: _resource_record_pre_action 21:16:36 INFO - Running main action method: install 21:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:37 INFO - Reading from file tmpfile_stdout 21:16:37 INFO - Detecting whether we're running mozinstall >=1.0... 21:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:16:37 INFO - Reading from file tmpfile_stdout 21:16:37 INFO - Output received: 21:16:37 INFO - Usage: mozinstall [options] installer 21:16:37 INFO - Options: 21:16:37 INFO - -h, --help show this help message and exit 21:16:37 INFO - -d DEST, --destination=DEST 21:16:37 INFO - Directory to install application into. [default: 21:16:37 INFO - "/builds/slave/test"] 21:16:37 INFO - --app=APP Application being installed. [default: firefox] 21:16:37 INFO - mkdir: /builds/slave/test/build/application 21:16:37 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'] 21:16:37 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 21:16:54 INFO - Reading from file tmpfile_stdout 21:16:54 INFO - Output received: 21:16:54 INFO - /builds/slave/test/build/application/firefox/firefox 21:16:54 INFO - Running post-action listener: _resource_record_post_action 21:16:54 INFO - [mozharness: 2016-12-23 05:16:54.579463Z] Finished install step (success) 21:16:54 INFO - [mozharness: 2016-12-23 05:16:54.579709Z] Running run-tests step. 21:16:54 INFO - Running pre-action listener: _resource_record_pre_action 21:16:54 INFO - Running main action method: run_tests 21:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:54 INFO - Python 2.7.3 21:16:54 INFO - Return code: 0 21:16:54 INFO - grabbing minidump binary from tooltool 21:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:54 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22303f0>, '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 0x2230cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22311a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:16:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:16:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:16:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:54 INFO - Return code: 0 21:16:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:16:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:16:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 21:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 21:16:54 INFO - Using env: {'DISPLAY': ':0', 21:16:54 INFO - 'HOME': '/home/cltbld', 21:16:54 INFO - 'LANG': 'en_US.UTF-8', 21:16:54 INFO - 'LANGUAGE': 'en_US:en', 21:16:54 INFO - 'LOGNAME': 'cltbld', 21:16:54 INFO - 'MAIL': '/var/mail/cltbld', 21:16:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:54 INFO - 'MOZ_NO_REMOTE': '1', 21:16:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:54 INFO - 'NO_EM_RESTART': '1', 21:16:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:54 INFO - 'PWD': '/builds/slave/test', 21:16:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:54 INFO - 'SHELL': '/bin/bash', 21:16:54 INFO - 'SHLVL': '1', 21:16:54 INFO - 'TERM': 'linux', 21:16:54 INFO - 'TMOUT': '86400', 21:16:54 INFO - 'USER': 'cltbld', 21:16:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614', 21:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:54 INFO - '_': '/tools/buildbot/bin/python'} 21:16:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 21:16:54 INFO - mozversion application_buildid: 20161222193138 21:16:54 INFO - mozversion application_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 21:16:54 INFO - mozversion application_display_name: Nightly 21:16:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:54 INFO - mozversion application_name: Firefox 21:16:54 INFO - mozversion application_remotingname: firefox 21:16:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:16:54 INFO - mozversion application_vendor: Mozilla 21:16:54 INFO - mozversion application_version: 53.0a1 21:16:54 INFO - mozversion platform_buildid: 20161222193138 21:16:54 INFO - mozversion platform_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 21:16:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:16:54 INFO - mozversion platform_version: 53.0a1 21:16:54 INFO - using testdate: 1482470214 21:16:54 INFO - actual date: 1482470214 21:16:54 INFO - starting webserver on 'localhost:47338' 21:16:54 INFO - SUITE-START | Running 2 tests 21:16:54 INFO - TEST-START | dromaeo_css 21:16:54 INFO - operating with platform_type : linux_ 21:16:54 INFO - Initialising browser for dromaeo_css test... 21:16:54 INFO - TEST-INFO | started process 10027 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx1IgNS/profile http://localhost:47338/getInfo.html) 21:16:55 INFO - PROCESS | 10027 | 1482470215344 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 21:16:58 INFO - PROCESS | 10027 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:58 INFO - PROCESS | 10027 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:59 INFO - PROCESS | 10027 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:59 INFO - PROCESS | 10027 | __metrics Screen width/height:1600/1200 21:16:59 INFO - PROCESS | 10027 | colorDepth:24 21:16:59 INFO - PROCESS | 10027 | Browser inner width/height: 1024/697 21:16:59 INFO - PROCESS | 10027 | __metrics 21:17:00 INFO - PROCESS | 10027 | 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" 21:17:00 INFO - PROCESS | 10027 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:17:01 INFO - PROCESS | 10027 | console.error: PushService: 21:17:01 INFO - PROCESS | 10027 | stateChangeProcessEnqueue: Error transitioning state 21:17:01 INFO - PROCESS | 10027 | UnknownError 21:17:02 INFO - TEST-INFO | 10027: exit 0 21:17:02 INFO - Browser initialized. 21:17:02 INFO - Running cycle 1/1 for dromaeo_css test... 21:17:02 INFO - TEST-INFO | started process 10115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx1IgNS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:17:13 INFO - PROCESS | 10115 | RSS: Main: 146886656 21:17:13 INFO - PROCESS | 10115 | 21:18:05 INFO - PROCESS | 10115 | 1482470285080 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:20:14 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47338/tests/dromaeo/cssquery-ext.html) 21:20:17 INFO - PROCESS | 10115 | RSS: Main: 247771136 21:20:17 INFO - PROCESS | 10115 | 21:22:02 INFO - PROCESS | 10115 | ************************* 21:22:02 INFO - PROCESS | 10115 | A coding exception was thrown and uncaught in a Task. 21:22:02 INFO - PROCESS | 10115 | 21:22:02 INFO - PROCESS | 10115 | Full message: ReferenceError: fetch is not defined 21:22:02 INFO - PROCESS | 10115 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:22:02 INFO - PROCESS | 10115 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:22:02 INFO - PROCESS | 10115 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:22:02 INFO - PROCESS | 10115 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:22:02 INFO - PROCESS | 10115 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:22:02 INFO - PROCESS | 10115 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:22:02 INFO - PROCESS | 10115 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:22:02 INFO - PROCESS | 10115 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:22:02 INFO - PROCESS | 10115 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:22:02 INFO - PROCESS | 10115 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:22:02 INFO - PROCESS | 10115 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:22:02 INFO - PROCESS | 10115 | 21:22:02 INFO - PROCESS | 10115 | ************************* 21:23:19 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-ext.html (next: http://localhost:47338/tests/dromaeo/cssquery-jquery.html) 21:23:20 INFO - PROCESS | 10115 | RSS: Main: 251052032 21:23:20 INFO - PROCESS | 10115 | 21:26:22 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47338/tests/dromaeo/cssquery-mootools.html) 21:26:23 INFO - PROCESS | 10115 | RSS: Main: 183681024 21:26:23 INFO - PROCESS | 10115 | 21:29:24 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47338/tests/dromaeo/cssquery-prototype.html) 21:29:25 INFO - PROCESS | 10115 | RSS: Main: 235122688 21:29:25 INFO - PROCESS | 10115 | 21:32:28 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47338/tests/dromaeo/cssquery-yui.html) 21:32:28 INFO - PROCESS | 10115 | RSS: Main: 186265600 21:32:28 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | Cycle 1(1): loaded http://localhost:47338/tests/dromaeo/cssquery-yui.html (next: http://localhost:47338/tests/dromaeo/cssquery-dojo.html) 21:35:32 INFO - PROCESS | 10115 | RSS: Main: 279060480 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | __start_tp_report 21:35:32 INFO - PROCESS | 10115 | _x_x_mozilla_page_load 21:35:32 INFO - PROCESS | 10115 | _x_x_mozilla_page_load_details 21:35:32 INFO - PROCESS | 10115 | |i|pagename|runs| 21:35:32 INFO - PROCESS | 10115 | |0;dojo.html;3127.3588914272577;3135.7021082997117;3176.8093914365127;3177.348643528076;3187.012026271856;5124.8975020499565;5190.273361729361;5213.3743950725875;5321.973390133049;5336.38631557371;5207.244949482323;5219.8434046978655;5256.369235691713;5316.946830531693;5345.1447768357075;4769.260764581478;4791.401074865642;4812.494688057738;4831.589314908246;4869.732164730938;4747.572718455338;4847.394075740533;4870.6103511718975;4916.606671466274;4947.604191664676;4897.632677549198;4930.482882344154;4931.654784165092;4945.332380128691;4982.402111746595;4715.528447155291;4734.384530011076;4772.952270477285;4799.400074990626;4833.709977401332;4807.302941073524;4812.470628230927;4851.248056551241;4865.124277630025;4870.488598697157;5226.268322434862;5259.184826351921;5291.708956007418;5302.416734159239;5344.893102137974;5501.724913754297;5535.308086489189;5681.914771278434;5686.744096515667;5692.971535142339;5609.046462101453;5631.155326701028;5633.521150702195;5667.744951477193;5710.714464276811;5254.133068043769;5256.737163141827;5258.6581872178185;5261.184516399964;5263.342082239758;5605.943940560624;5769.826905192851;5773.913391299134;5782.7686892523825;5827.3007239131575;5455.781768729285;5495.862603434866;5572.58205634579;5585.078462053716;5671.886562268732;5669.34802497718;5697.088465845445;5704.71476426177;5707.029804933089;5711.143328500675;5518.33779946409;5563;5563.5271001964475;5642.887142257132;5689.516391106719;5556.083246264402;5572.916406253909;5585.357683866326;5648.971755141198;5700.486956173964;4236.724612900151;4238.385434112098;4238.682098842599;4240.851570195028;4273.0385663225525;3771.86798462058;3776.527934008249;3781.2248489059675;3805.4101614249835;3822.235552889433;5224.686518808883;5318.680879147263;5350.652207606493;5378.784848606012;5463.180522921593;5744.310682718017;5744.4829965303325;5744.971275143597;5749.367569567345;5751.166080918243;5372.597055220874;5396.298481197419;5397.136458166675;5413.53984911287;5449.155380915964;4596.517365676623;4621.329907163443;4642.744649044267;4643.349931009661;4679.22792739201;5848.766049358063;5873.941260587425;5877.559183061373;5884.08796636522;5891.823245302648;5631.211630371669;5639.097744360883;5641.351244606923;5642.85892852682;5649.85875353103;5809.738561764646;5860.472557469848;5865.501432378295;5868.559858010752;5870.5010074142465;5555.916661250004;5557.777688892561;5561.971035358471;5563;5563.360213575329;5679.744411501575;5679.886402271849;5701.629394089371;5703.315602127771;5704.286964129484;5513.751881165437;5518.117101263779;5519.751611177427;5529.612927095065;5538.750756216061;5577.9442205577425;5585.357683866245;5585.916211256916;5589.776408943597;5590.105583106683;5373.543248823894;5394.838154855361;5488.094464413327;5583.41374155724;5634.802781902614;5555.388907220284;5600.775968961196;5614.29821272341;5656.179853921076;5657.066584013592;5462.426445223195;5468.808591699271;5469.671819690831;5469.972650136724;5476 21:35:32 INFO - PROCESS | 10115 | |1;ext.html;544.8392724146377;588.0913987888712;597.0834768630151;614.3610644929274;637.2480473041815;38439.23121537571;42468.78765606168;42711.57288427115;42729.872415310165;42753.35869961953;42188.78905605475;42512.362314565304;42700.78649606748;42716.78641606795;42735.57264427355;8384.455010424334;8576.614052367659;8614.741557753277;8849.911500885006;8850.822983540325;10377.117944974649;10638.042576168144;10645.84031239532;10975.2866063706;10981.945090274538;10784.622538211164;10831.187660925423;10844.403557804317;10849.783004339908;10856.565737370496;10748.193885458622;10806.675799725981;10814.188935829845;10819.78360432787;10833.891661083368;10370.792584148276;10451.63419280329;10458.895411045869;10460.215483838743;10463.633772817915;41395;41568;41701.1659766806;41741;41741.1651766966;3324.202191474053;3572;3707.3141468828085;3786.5645450772963;3851.345271303857;31510.842445787854;32159.6784032159;32425.189370265696;33245;33270.66729332724;4224.725392849455;4314.525402205755;4322.178786030644;4496.64026877849;4503.617192538638;341835;343038;343105;343682.9999999975;345339;56657.150142747894;56758.14862777061;56805.71597141988;57077.429225708045;57142.7142864283;60463.69768151131;60558;60624.09063864045;60627.393726063056;60703;15160;15676;15690.607734806494;15694.843051569338;15697.058176509447;9510.714678559654;9717.271204659679;10918.563257469612;11131.220814542905;12013.459394327101;16772;16791.832081679026;16848.32606695743;16888.15559222034;16922.153892305334;6404.7117879695525;6409.935900640934;6604.933950660528;6631.933680663132;6737.225219099866;914.7438717159206;1021.667957913681;1025.7794574166442;1117.5675073191685;1172.0682057764097;30280.84859575688;31564.210894727814;31805;31946.520802187984;32138.19654508609;944.5560586524323;983.4787562591838;1004.2769206171545;1072.404815327485;1075.7525769073;15631.06213627186;16576.419825306057;16593.585160371087;16758;16923.746143807854;535541;537387;538341;538976;540154;58524.707376462844;58841.70579147163;58955.11567326493;59013.70493147593;59107;1140.7743079499655;1173.3253379306761;1197.5808467036468;1211.5759484180642;1330.5875178694635;29556;30725.41397263794;31249.68750312468;31335.21661958469;31646.36707265796;51792.22311665252;52272.47727522676;52301.215481768566;52343;52384.47615523796;426802;432641;432785;433075;435870;40063.59936400599;40900;42127.789361053;42134;42136;46615.533844662474;47465.52534474749;47565.28652070153;47808.760956194994;48072;39178;39917.25246431848;40049;40813.591864080976;42141;36797.63202367942;39038.6096139035;39539.40690889696;41099.58900410958;41659.16681666289;35106.64893351034;36443;36471.63528364682;41011.58988410078;41171.7941410291;31096.689033110284;32931.67068329286;33176.83411582927;33651.3269734599;33872.830635846665;25543.87228063848;25979.48041039131;26110.477790444464;26171.345716357242;26240 21:35:32 INFO - PROCESS | 10115 | |2;jquery.html;386.25452875949406;393.72439292495244;403.2559926934805;408.7710881906133;424.42914280293013;13634;15459.072455652655;18598.163082661278;18899.433017009476;19394.418167455;18132;18160.18279177437;18200.362987295448;18614.255429782825;18625.161867715953;5505.532029777453;5507.421720719317;5708.857278568027;5711.742971566289;5719.942800572004;5547.1679248112905;5590.944090559104;5635.690037047961;5641.68970706611;5646.407127251641;5403.972980135094;5600.971995140018;5605.663660180382;5616.073347897592;5617.241672374218;5567.610267281292;5593.944060559383;5627.212190293362;5627.549796016309;5632.35227948785;4856.2958371036;5033.496650334974;5074.112030394666;5074.112030394703;5077.466865979093;22621.321360359216;22726.659100113513;22727.659085113737;22746.65880011801;22917.541649166924;2341.098677009347;2349.6710460535537;2371.6916800815957;2372.79831214347;2483.3543278747657;12262.693432664166;14233.28833558318;14298.928505357511;14310;14753.852461475355;1680.5378520906727;1731.7229243321135;1750.7198848184357;1786.4194136905558;1809.9185536650882;77465;79036.60481697555;79196.20803791947;80016;81864;10596.31123976939;10783.568657253621;10810.621628242974;11014.339139651647;11572.725249177685;14999.625009374853;15064.698706025818;15065.17141557225;15100.773488397683;15122.697546049018;7255.383292420203;7369.963150184215;7631.54210747357;7653.234676532302;7665.386769058462;6923.515353925278;6948.131483564554;7054.365107140279;7110;7129.7861064168155;4574.336721175478;4675.298705194248;4681;4704.694194877322;4772.689775164603;1943.5626983928785;1979;1987.8906660133541;2011.6580181369202;2022.2618744158276;884.6992022712309;953.6328513522344;966.8018056298442;989.5101924547395;994.7413672445216;17439.825601744073;18801.247950082117;18893.244270229035;18975.146118424705;19297.4210773677;902.7201567513961;956.9043095690514;960.2030314838668;977.4623956823718;979.9069088436587;10054;10106.898931010595;10582;10644.414557199429;10689.679309620724;349039;351161;421640;424975;426570;59944.70027649834;64574;80758.59620701859;85763.57118214369;87125;1010.7675234696061;1051.2431049644244;1056.9735756606146;1057.2229411382627;1059.502034043998;16171.919140404223;16620.83379166241;17130.48608541746;17383.304667813652;17675.911620441817;37905.43141852819;38185;38609.80695096507;39191.608083919935;39682.40476392914;54456.727716361165;54461.72769136129;54952;55169.172462412294;57066.14400783909;8479.830403391774;8501.447405918596;8541.829163416573;8562.957185214284;8846.867296990422;12627.87372126267;13210.471581136648;13235.536756213487;13290.069695121636;13344.532941347006;11300.773984520429;11480.138989575647;11655.533778649098;11662.183647144619;11678.474468648761;11204.215704900578;11245;11308.208425410143;11310.20828542027;11342.149338799458;11360.488778004845;11433.828492572451;11656.242344247597;11766.647000589996;11867.169298149047;7420.628968551443;7505.924940750522;7690.384769218337;8456.915430845613;8671.176238257356;8441.70454034106;10567.841482377617;10889.509972051119;11003.22977391575;11013.559457621606 21:35:32 INFO - PROCESS | 10115 | |3;mootools.html;1377.1736957825306;1379.2689874366586;1492.8805695544352;1713.2290469288823;1747.6417334446433;7732.65203065862;8222.547759873205;8226.383021273406;8226.876596851052;8227.382946279024;7418.777436676908;7633.580153091578;8004.119546849856;8238.217369349917;8345.165483451652;2256.7968882800546;2299.586074506588;2301.8043466305385;2303.1363238785457;2304.493011537459;2310.445493081665;2324.732655744594;2356.1046802215133;2357.9742811876804;2360.1975328388344;2270.5231901300745;2272.4432514034083;2290.2327720213666;2303.1017903017837;2303.2974942642486;2230.342049095521;2237.183428048767;2257.2325409360815;2263.2418139923147;2264.4225722440838;2261.5476904618977;2279.806216471602;2282.3038973113194;2287.885605719707;2287.942801429961;8384.203500667427;8447.972486603232;8485.787855303604;8495.702650407267;8495.74512764618;4876.146674332013;5004.149294619923;5004.699718016895;5005.57452616528;5007.349044624212;3118.7193152616146;3183.984080079608;3309.2223327518013;3325.1022223999385;3326.9500957485657;941.1247539787994;961.7737384834322;969.4086607169621;971.3006635222699;975.6536429567543;11568.53725850965;11607.709807254801;11634.650960471115;11639.243449175776;11648.12639052066;4264.466941632296;4581.427321584802;4597.195490789099;4611.746353950498;4623.8844028898875;9900.455474948947;9903.603855845686;9907.306488545733;9908.306418550632;9913.30606857513;5051.393832740021;5058.721770302595;5059.165237735806;5060.51925067118;5061.974690126526;3832.770033797981;4292.463442069741;4508.594226519629;4560.635149188057;4665.2302370108555;3705.6479634434686;3837.3092843288478;3872;3887.086534664352;3900.1614652849776;1188.8989435897872;1194.9820752688718;1195.9162858599814;1204.4038201090516;1217.8964787992943;839.47532792005;847.9915200848036;860.0496451421214;884.6284560484612;885.2563846369081;5607.943920560824;5636.9964403649055;5705.229793977843;5745.569082318842;5807.738651760681;913.2237598041611;935.6351023100996;965.285688590438;969.7914948286152;979.6767066867917;3742.9625703742613;3884.5532763731967;3890.902727431837;3894.6494815466745;3898.7270891037924;10130;10446.05985461297;10497.475126243657;10542.314749541256;10544.156467482582;10381.688549343531;10391.272610917164;10401.739956501147;10413.42726150069;10436.5825366986;7630.847383052308;7837.882431763528;7941.888135661012;7977.800554986172;7984.800379990546;5579.163125531203;5697.800576979786;5731.340895797037;5838.32859221178;5938.228030356199;9251.814963700554;9281.350305478552;9333.439993600407;9352.625894964125;9402.012788657092;9530.714078577654;9634.518274086127;9644.373115747734;9689.903100969183;9781.266405019795;6496.74013039492;6633.668316584248;7022.1924478685605;7282.089738782653;7395.112586489656;7738;7952.562609056557;7954.880676789969;7960.800979975547;7965.601719914097;6832.555883867532;6924.480663950123;6928.203256625351;6953.061336719478;7077.681504332214;6979.825504362431;7062.929370706227;7065.505414620927;7077.752278670222;7194;6488.188976377953;6521.152250207443;6581.407673309425;6584;6585.308542603149;4957.405111386656;4985.052839960541;5048.444671086104;5060.49395060491;5086.720230387216;4660.86017419478;4737.455192652889;4831.4202295724735;4850.1269771441475;4999.150144475375 21:35:32 INFO - PROCESS | 10115 | |4;prototype.html;365.23665539023443;377.6638791475587;382.76268713397695;399.8060940443886;402.3622558245183;5163.380394352678;5209.577546440904;5209.739513024352;5210.609204309682;5235.424103348629;5030.949690503094;5181.316072276516;5181.715005674687;5183.326167598207;5188.42927278;3864.343061679507;4011.1576568920414;4033.0320723026407;4158.771267580282;4167.937480937789;4096.938545921814;4114.547399786021;4119.299719047769;4128.855490057848;4138.48945135025;4078.551359350484;4097.402013214849;4101.502951422739;4106.5072191337085;4147.771872547009;3920.5099362579676;3990.501187351581;4097.180563887205;4123.835046598132;4131.752094874287;3701.5002974598356;3704.7962362070075;3713.405855063204;3729.366007778657;3736.8318425670636;4971.801127954877;4974.577160941324;5006.799728010875;5025.1708468102715;5075.796968121271;4653.511381304948;4756.429228492569;4764.880877978078;4784.712917224978;4798.208295631217;5359.919601205985;5413.2692086568595;5417.810376636839;5423.3491980962535;5444.406691583536;4080.1431699343175;4090.7545547266964;4096.32410652242;4097.3776782858995;4110.7328023678665;5435.722605187778;5564.666120032771;5617.410171931929;5669.971650141723;5695.775408287238;4228.23891699497;4229.555896630809;4230.619244267969;4236.788160591958;4240.406343111967;4306.569343065668;4310.4611923509565;4312.460942382202;4319.524852266248;4320.978395108004;4614.492405835355;4698.264879420869;4729.196036673774;4815.747905544514;4825.541573550507;5406.4591591396265;5423.240746295522;5435.7825686972865;5453.727313634303;5461.590380721383;1429.9857001429853;1431.2485944878813;1440.96397590059;1441.4738620403687;1445.7542217822995;989.9653512127041;1038.2401828900092;1043.9321444106108;1046.2467023742893;1053.2416660004938;4980.601551875841;4986.301917731521;4987.675801072919;5126;5140.794368225229;5529.751161197756;5558.833235002957;5567.192757050205;5576.33084029919;5619.847931174099;5274.654962984491;5274.841754747364;5275.261463395128;5278.5249327560705;5287.841364759063;2752.6464872534098;2871.5118429866975;2873.41095075509;2876.8273903565855;2892.8698208580663;5621.229312766465;5651.8304450866535;5653.802116925888;5669.433056694297;5697.800576979868;5239.869003274873;5513.8345849624575;5521.641093328921;5532.834014979557;5565.41563135873;5499.890002200013;5589.664620122806;5628.634138780966;5636.998865402887;5651.2935883014625;5421.481985044194;5447.645903016291;5450.454954504677;5460.31746031746;5475.5072043516275;5221.451747566451;5315.654482458628;5373;5389.892202156013;5415.458454154553;5591.021571224938;5622.2128901954575;5630.774769009357;5643.294588176478;5672.404397538199;4073.246449406869;4108.034611866149;4111.198316328288;4134.193832202692;4135.565765594569;5303.840884773463;5472.480114389126;5577;5595.496405323541;5627.43725627434;5462.180672899097;5469.632591852037;5472.370677372153;5476.835694929158;5490.780368785204;5296.702307934581;5480.506754392124;5489.58828087887;5523.8895222096135;5529.972350138384;5427.782888684408;5449.564034877121;5450.809221677381;5454.645448045864;5472.808451704172;5230.503102205284;5348.57211423093;5350.892982140257;5490.094134467928;5513.862153446196;5406.432324605859;5409.350877894678;5409.648372855751;5417.783288668409;5420.918686219631 21:35:32 INFO - PROCESS | 10115 | |5;yui.html;678.0338018323889;698.8287869471981;702.912135983002;721.0446158839538;751.4251597527896;13708.314584270784;15155.62110947227;15250.84749152508;15302.540923772276;15321.923390383046;13834;14005.859941400582;14018.369173387196;14039.157650540974;14060.92969535151;669.9296573859748;685.5132855672479;690.1097584116484;787.4173111897197;809.4050872608628;668.7258224128109;766.0768773627793;790.3598085550693;792.1840504280572;807.6769292283104;815.1155995744624;824.7938015496126;825.471698113208;825.8348330333927;826.1614461321764;826.0541679776682;827.2843989948681;828.0808302783935;828.6271177969908;828.7928017995501;516.3287725956242;528.0574175097471;531.6756778365194;609.5732986909181;611.5199568338877;10397.688069357931;14200.85799142016;14766.187859667714;14782.408703651841;14866.331015104346;14751;15060.774088388684;15099.471518496799;15102.622434439118;15602.921985390001;11266.718332041764;11555.711107222303;11600.593979210771;11712.824307635392;11783.731067014303;3701.7408781385316;3809.3714537101346;3815.1988082502708;3933.252681990413;3936.980315098406;72805;75740.62129689316;76283.61858190673;76502.85245721374;76987.61506192433;10926.945365273123;11071.391073491035;11230.719232019264;11336.319820810777;11529.365884876244;21845.67231491529;21964.89017554902;22250.221242256768;22318.442038949157;22664.660030099563;7223.133224013047;7240.891386629205;7303.196648368676;7345.742898998509;7598.962005189938;4333.414988976448;4354.172707185625;4396.208682437128;4544.272916333371;4549.499555048942;3459.4637831136215;3591.1560783216446;3596.8021758803516;3716.4239542870896;3835.5013848200115;635.4122436745992;644.3556443556444;649.1885143570537;658.5883822610868;663.5587334422555;608.9421504957022;622.1321256846674;653.1541653558653;663.9170103737033;747.4917056401699;8846.601902914384;9472.479013654316;9557.426554406758;9559.665411710557;9751.21990240779;589.2722487727652;607.2652090969888;633.4868756307405;641.4002907281706;646.3988490703597;4268.701190916669;4384.890377740582;4407.889802754893;4650.581447669726;4702.553257440606;76757;79160;81470.59264703757;83117.58441207877;84022;67873.3212667877;71924.2807571928;72098.63950680214;72983;74033;65186.67406662842;65969;66419.66790166018;67831;68767.31232687709;8770.210681038738;8821.735347939572;8892.73321800347;8932.831574849542;8943.499243895003;43679.78160109179;43817.56182438135;45153;45310.82824202406;45747.31379029384;49388.06119388033;53138;53669;54308.728456357465;60171;12633.747325053631;12687.936560317139;13187.80218296707;13813.447462101403;13958.09272397291;14359.712805744035;14425;14759.704805903608;14904.701905962036;15799.131047792482;12971.481140754642;13403.32983350809;13511.527096551574;13767.38046787877;13942.860571394156;13635.86364136346;13936.094153879965;14019.228942408265;14347.497837575635;14907.92546037263;13781.793273100711;14079.296035197995;14124.78812817788;14715.117092974457;14979.925100374865;10480.903819236031;10686.026998899319;11457.255278406878;11502.252353596989;11553.595624153115;11424.885751142383;11688.88311116878;12027.338496382783;12081.456334465145;12443.128980971245 21:35:32 INFO - PROCESS | 10115 | __end_tp_report 21:35:32 INFO - PROCESS | 10115 | __start_cc_report 21:35:32 INFO - PROCESS | 10115 | _x_x_mozilla_cycle_collect,2109 21:35:32 INFO - PROCESS | 10115 | __end_cc_report 21:35:32 INFO - PROCESS | 10115 | __startTimestamp1482471332390__endTimestamp 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | ------- Summary: start ------- 21:35:32 INFO - PROCESS | 10115 | Number of tests: 6 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#0] dojo.html Cycles:165 Average:5255.03 Median:5476.00 stddev:599.43 (10.9%) stddev-sans-first:577.56 21:35:32 INFO - PROCESS | 10115 | Values: 3127.4 3135.7 3176.8 3177.3 3187.0 5124.9 5190.3 5213.4 5322.0 5336.4 5207.2 5219.8 5256.4 5316.9 5345.1 4769.3 4791.4 4812.5 4831.6 4869.7 4747.6 4847.4 4870.6 4916.6 4947.6 4897.6 4930.5 4931.7 4945.3 4982.4 4715.5 4734.4 4773.0 4799.4 4833.7 4807.3 4812.5 4851.2 4865.1 4870.5 5226.3 5259.2 5291.7 5302.4 5344.9 5501.7 5535.3 5681.9 5686.7 5693.0 5609.0 5631.2 5633.5 5667.7 5710.7 5254.1 5256.7 5258.7 5261.2 5263.3 5605.9 5769.8 5773.9 5782.8 5827.3 5455.8 5495.9 5572.6 5585.1 5671.9 5669.3 5697.1 5704.7 5707.0 5711.1 5518.3 5563.0 5563.5 5642.9 5689.5 5556.1 5572.9 5585.4 5649.0 5700.5 4236.7 4238.4 4238.7 4240.9 4273.0 3771.9 3776.5 3781.2 3805.4 3822.2 5224.7 5318.7 5350.7 5378.8 5463.2 5744.3 5744.5 5745.0 5749.4 5751.2 5372.6 5396.3 5397.1 5413.5 5449.2 4596.5 4621.3 4642.7 4643.3 4679.2 5848.8 5873.9 5877.6 5884.1 5891.8 5631.2 5639.1 5641.4 5642.9 5649.9 5809.7 5860.5 5865.5 5868.6 5870.5 5555.9 5557.8 5562.0 5563.0 5563.4 5679.7 5679.9 5701.6 5703.3 5704.3 5513.8 5518.1 5519.8 5529.6 5538.8 5577.9 5585.4 5585.9 5589.8 5590.1 5373.5 5394.8 5488.1 5583.4 5634.8 5555.4 5600.8 5614.3 5656.2 5657.1 5462.4 5468.8 5469.7 5470.0 5476.0 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#1] ext.html Cycles:180 Average:59966.96 Median:39108.30 stddev:117963.74 (301.6%) stddev-sans-first:118210.29 21:35:32 INFO - PROCESS | 10115 | Values: 544.8 588.1 597.1 614.4 637.2 38439.2 42468.8 42711.6 42729.9 42753.4 42188.8 42512.4 42700.8 42716.8 42735.6 8384.5 8576.6 8614.7 8849.9 8850.8 10377.1 10638.0 10645.8 10975.3 10981.9 10784.6 10831.2 10844.4 10849.8 10856.6 10748.2 10806.7 10814.2 10819.8 10833.9 10370.8 10451.6 10458.9 10460.2 10463.6 41395.0 41568.0 41701.2 41741.0 41741.2 3324.2 3572.0 3707.3 3786.6 3851.3 31510.8 32159.7 32425.2 33245.0 33270.7 4224.7 4314.5 4322.2 4496.6 4503.6 341835.0 343038.0 343105.0 343683.0 345339.0 56657.2 56758.1 56805.7 57077.4 57142.7 60463.7 60558.0 60624.1 60627.4 60703.0 15160.0 15676.0 15690.6 15694.8 15697.1 9510.7 9717.3 10918.6 11131.2 12013.5 16772.0 16791.8 16848.3 16888.2 16922.2 6404.7 6409.9 6604.9 6631.9 6737.2 914.7 1021.7 1025.8 1117.6 1172.1 30280.8 31564.2 31805.0 31946.5 32138.2 944.6 983.5 1004.3 1072.4 1075.8 15631.1 16576.4 16593.6 16758.0 16923.7 535541.0 537387.0 538341.0 538976.0 540154.0 58524.7 58841.7 58955.1 59013.7 59107.0 1140.8 1173.3 1197.6 1211.6 1330.6 29556.0 30725.4 31249.7 31335.2 31646.4 51792.2 52272.5 52301.2 52343.0 52384.5 426802.0 432641.0 432785.0 433075.0 435870.0 40063.6 40900.0 42127.8 42134.0 42136.0 46615.5 47465.5 47565.3 47808.8 48072.0 39178.0 39917.3 40049.0 40813.6 42141.0 36797.6 39038.6 39539.4 41099.6 41659.2 35106.6 36443.0 36471.6 41011.6 41171.8 31096.7 32931.7 33176.8 33651.3 33872.8 25543.9 25979.5 26110.5 26171.3 26240.0 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#2] jquery.html Cycles:180 Average:25522.83 Median:194533.22 stddev:65570.52 (33.7%) stddev-sans-first:65727.30 21:35:32 INFO - PROCESS | 10115 | Values: 386.3 393.7 403.3 408.8 424.4 13634.0 15459.1 18598.2 18899.4 19394.4 18132.0 18160.2 18200.4 18614.3 18625.2 5505.5 5507.4 5708.9 5711.7 5719.9 5547.2 5590.9 5635.7 5641.7 5646.4 5404.0 5601.0 5605.7 5616.1 5617.2 5567.6 5593.9 5627.2 5627.5 5632.4 4856.3 5033.5 5074.1 5074.1 5077.5 22621.3 22726.7 22727.7 22746.7 22917.5 2341.1 2349.7 2371.7 2372.8 2483.4 12262.7 14233.3 14298.9 14310.0 14753.9 1680.5 1731.7 1750.7 1786.4 1809.9 77465.0 79036.6 79196.2 80016.0 81864.0 10596.3 10783.6 10810.6 11014.3 11572.7 14999.6 15064.7 15065.2 15100.8 15122.7 7255.4 7370.0 7631.5 7653.2 7665.4 6923.5 6948.1 7054.4 7110.0 7129.8 4574.3 4675.3 4681.0 4704.7 4772.7 1943.6 1979.0 1987.9 2011.7 2022.3 884.7 953.6 966.8 989.5 994.7 17439.8 18801.2 18893.2 18975.1 19297.4 902.7 956.9 960.2 977.5 979.9 10054.0 10106.9 10582.0 10644.4 10689.7 349039.0 351161.0 421640.0 424975.0 426570.0 59944.7 64574.0 80758.6 85763.6 87125.0 1010.8 1051.2 1057.0 1057.2 1059.5 16171.9 16620.8 17130.5 17383.3 17675.9 37905.4 38185.0 38609.8 39191.6 39682.4 54456.7 54461.7 54952.0 55169.2 57066.1 8479.8 8501.4 8541.8 8563.0 8846.9 12627.9 13210.5 13235.5 13290.1 13344.5 11300.8 11480.1 11655.5 11662.2 11678.5 11204.2 11245.0 11308.2 11310.2 11342.1 11360.5 11433.8 11656.2 11766.6 11867.2 7420.6 7505.9 7690.4 8456.9 8671.2 8441.7 10567.8 10889.5 11003.2 11013.6 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#3] mootools.html Cycles:180 Average:5482.36 Median:5059.83 stddev:3085.86 (61.0%) stddev-sans-first:3079.10 21:35:32 INFO - PROCESS | 10115 | Values: 1377.2 1379.3 1492.9 1713.2 1747.6 7732.7 8222.5 8226.4 8226.9 8227.4 7418.8 7633.6 8004.1 8238.2 8345.2 2256.8 2299.6 2301.8 2303.1 2304.5 2310.4 2324.7 2356.1 2358.0 2360.2 2270.5 2272.4 2290.2 2303.1 2303.3 2230.3 2237.2 2257.2 2263.2 2264.4 2261.5 2279.8 2282.3 2287.9 2287.9 8384.2 8448.0 8485.8 8495.7 8495.7 4876.1 5004.1 5004.7 5005.6 5007.3 3118.7 3184.0 3309.2 3325.1 3327.0 941.1 961.8 969.4 971.3 975.7 11568.5 11607.7 11634.7 11639.2 11648.1 4264.5 4581.4 4597.2 4611.7 4623.9 9900.5 9903.6 9907.3 9908.3 9913.3 5051.4 5058.7 5059.2 5060.5 5062.0 3832.8 4292.5 4508.6 4560.6 4665.2 3705.6 3837.3 3872.0 3887.1 3900.2 1188.9 1195.0 1195.9 1204.4 1217.9 839.5 848.0 860.0 884.6 885.3 5607.9 5637.0 5705.2 5745.6 5807.7 913.2 935.6 965.3 969.8 979.7 3743.0 3884.6 3890.9 3894.6 3898.7 10130.0 10446.1 10497.5 10542.3 10544.2 10381.7 10391.3 10401.7 10413.4 10436.6 7630.8 7837.9 7941.9 7977.8 7984.8 5579.2 5697.8 5731.3 5838.3 5938.2 9251.8 9281.4 9333.4 9352.6 9402.0 9530.7 9634.5 9644.4 9689.9 9781.3 6496.7 6633.7 7022.2 7282.1 7395.1 7738.0 7952.6 7954.9 7960.8 7965.6 6832.6 6924.5 6928.2 6953.1 7077.7 6979.8 7062.9 7065.5 7077.8 7194.0 6488.2 6521.2 6581.4 6584.0 6585.3 4957.4 4985.1 5048.4 5060.5 5086.7 4660.9 4737.5 4831.4 4850.1 4999.2 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#4] prototype.html Cycles:180 Average:4599.16 Median:5209.66 stddev:1301.27 (25.0%) stddev-sans-first:1265.53 21:35:32 INFO - PROCESS | 10115 | Values: 365.2 377.7 382.8 399.8 402.4 5163.4 5209.6 5209.7 5210.6 5235.4 5030.9 5181.3 5181.7 5183.3 5188.4 3864.3 4011.2 4033.0 4158.8 4167.9 4096.9 4114.5 4119.3 4128.9 4138.5 4078.6 4097.4 4101.5 4106.5 4147.8 3920.5 3990.5 4097.2 4123.8 4131.8 3701.5 3704.8 3713.4 3729.4 3736.8 4971.8 4974.6 5006.8 5025.2 5075.8 4653.5 4756.4 4764.9 4784.7 4798.2 5359.9 5413.3 5417.8 5423.3 5444.4 4080.1 4090.8 4096.3 4097.4 4110.7 5435.7 5564.7 5617.4 5670.0 5695.8 4228.2 4229.6 4230.6 4236.8 4240.4 4306.6 4310.5 4312.5 4319.5 4321.0 4614.5 4698.3 4729.2 4815.7 4825.5 5406.5 5423.2 5435.8 5453.7 5461.6 1430.0 1431.2 1441.0 1441.5 1445.8 990.0 1038.2 1043.9 1046.2 1053.2 4980.6 4986.3 4987.7 5126.0 5140.8 5529.8 5558.8 5567.2 5576.3 5619.8 5274.7 5274.8 5275.3 5278.5 5287.8 2752.6 2871.5 2873.4 2876.8 2892.9 5621.2 5651.8 5653.8 5669.4 5697.8 5239.9 5513.8 5521.6 5532.8 5565.4 5499.9 5589.7 5628.6 5637.0 5651.3 5421.5 5447.6 5450.5 5460.3 5475.5 5221.5 5315.7 5373.0 5389.9 5415.5 5591.0 5622.2 5630.8 5643.3 5672.4 4073.2 4108.0 4111.2 4134.2 4135.6 5303.8 5472.5 5577.0 5595.5 5627.4 5462.2 5469.6 5472.4 5476.8 5490.8 5296.7 5480.5 5489.6 5523.9 5530.0 5427.8 5449.6 5450.8 5454.6 5472.8 5230.5 5348.6 5350.9 5490.1 5513.9 5406.4 5409.4 5409.6 5417.8 5420.9 21:35:32 INFO - PROCESS | 10115 | 21:35:32 INFO - PROCESS | 10115 | [#5] yui.html Cycles:180 Average:17737.15 Median:4546.89 stddev:22835.05 (502.2%) stddev-sans-first:22863.17 21:35:32 INFO - PROCESS | 10115 | Values: 678.0 698.8 702.9 721.0 751.4 13708.3 15155.6 15250.8 15302.5 15321.9 13834.0 14005.9 14018.4 14039.2 14060.9 669.9 685.5 690.1 787.4 809.4 668.7 766.1 790.4 792.2 807.7 815.1 824.8 825.5 825.8 826.2 826.1 827.3 828.1 828.6 828.8 516.3 528.1 531.7 609.6 611.5 10397.7 14200.9 14766.2 14782.4 14866.3 14751.0 15060.8 15099.5 15102.6 15602.9 11266.7 11555.7 11600.6 11712.8 11783.7 3701.7 3809.4 3815.2 3933.3 3937.0 72805.0 75740.6 76283.6 76502.9 76987.6 10926.9 11071.4 11230.7 11336.3 11529.4 21845.7 21964.9 22250.2 22318.4 22664.7 7223.1 7240.9 7303.2 7345.7 7599.0 4333.4 4354.2 4396.2 4544.3 4549.5 3459.5 3591.2 3596.8 3716.4 3835.5 635.4 644.4 649.2 658.6 663.6 608.9 622.1 653.2 663.9 747.5 8846.6 9472.5 9557.4 9559.7 9751.2 589.3 607.3 633.5 641.4 646.4 4268.7 4384.9 4407.9 4650.6 4702.6 76757.0 79160.0 81470.6 83117.6 84022.0 67873.3 71924.3 72098.6 72983.0 74033.0 65186.7 65969.0 66419.7 67831.0 68767.3 8770.2 8821.7 8892.7 8932.8 8943.5 43679.8 43817.6 45153.0 45310.8 45747.3 49388.1 53138.0 53669.0 54308.7 60171.0 12633.7 12687.9 13187.8 13813.4 13958.1 14359.7 14425.0 14759.7 14904.7 15799.1 12971.5 13403.3 13511.5 13767.4 13942.9 13635.9 13936.1 14019.2 14347.5 14907.9 13781.8 14079.3 14124.8 14715.1 14979.9 10480.9 10686.0 11457.3 11502.3 11553.6 11424.9 11688.9 12027.3 12081.5 12443.1 21:35:32 INFO - PROCESS | 10115 | -------- Summary: end -------- 21:35:32 INFO - PROCESS | 10115 | 21:35:33 INFO - TEST-INFO | 10115: exit 0 21:35:33 INFO - TEST-OK | dromaeo_css | took 1118259ms 21:35:33 INFO - TEST-START | kraken 21:35:33 INFO - operating with platform_type : linux_ 21:35:33 INFO - Initialising browser for kraken test... 21:35:33 INFO - TEST-INFO | started process 10412 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyaALFN/profile http://localhost:47338/getInfo.html) 21:35:33 INFO - PROCESS | 10412 | 1482471333579 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 21:35:36 INFO - PROCESS | 10412 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:36 INFO - PROCESS | 10412 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:37 INFO - PROCESS | 10412 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:37 INFO - PROCESS | 10412 | __metrics Screen width/height:1600/1200 21:35:37 INFO - PROCESS | 10412 | colorDepth:24 21:35:37 INFO - PROCESS | 10412 | Browser inner width/height: 1024/697 21:35:37 INFO - PROCESS | 10412 | __metrics 21:35:38 INFO - PROCESS | 10412 | 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" 21:35:38 INFO - PROCESS | 10412 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:35:39 INFO - PROCESS | 10412 | console.error: PushService: 21:35:39 INFO - PROCESS | 10412 | stateChangeProcessEnqueue: Error transitioning state 21:35:39 INFO - PROCESS | 10412 | UnknownError 21:35:40 INFO - TEST-INFO | 10412: exit 0 21:35:40 INFO - Browser initialized. 21:35:40 INFO - Running cycle 1/1 for kraken test... 21:35:40 INFO - TEST-INFO | started process 10499 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyaALFN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:51 INFO - PROCESS | 10499 | RSS: Main: 146874368 21:35:51 INFO - PROCESS | 10499 | 21:35:54 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47338/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:55 INFO - PROCESS | 10499 | RSS: Main: 201654272 21:35:55 INFO - PROCESS | 10499 | 21:35:57 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47338/tests/kraken/driver.html?testName=audio-dft) 21:35:58 INFO - PROCESS | 10499 | RSS: Main: 180867072 21:35:58 INFO - PROCESS | 10499 | 21:36:01 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47338/tests/kraken/driver.html?testName=audio-fft) 21:36:02 INFO - PROCESS | 10499 | RSS: Main: 262287360 21:36:02 INFO - PROCESS | 10499 | 21:36:04 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47338/tests/kraken/driver.html?testName=audio-oscillator) 21:36:04 INFO - PROCESS | 10499 | RSS: Main: 173232128 21:36:04 INFO - PROCESS | 10499 | 21:36:06 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47338/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:36:07 INFO - PROCESS | 10499 | RSS: Main: 178188288 21:36:07 INFO - PROCESS | 10499 | 21:36:10 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47338/tests/kraken/driver.html?testName=imaging-darkroom) 21:36:11 INFO - PROCESS | 10499 | RSS: Main: 217604096 21:36:11 INFO - PROCESS | 10499 | 21:36:14 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47338/tests/kraken/driver.html?testName=imaging-desaturate) 21:36:15 INFO - PROCESS | 10499 | RSS: Main: 217247744 21:36:15 INFO - PROCESS | 10499 | 21:36:17 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47338/tests/kraken/driver.html?testName=json-parse-financial) 21:36:18 INFO - PROCESS | 10499 | RSS: Main: 215543808 21:36:18 INFO - PROCESS | 10499 | 21:36:19 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47338/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:36:20 INFO - PROCESS | 10499 | RSS: Main: 177647616 21:36:20 INFO - PROCESS | 10499 | 21:36:22 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:36:22 INFO - PROCESS | 10499 | RSS: Main: 185454592 21:36:22 INFO - PROCESS | 10499 | 21:36:24 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:36:25 INFO - PROCESS | 10499 | RSS: Main: 194117632 21:36:25 INFO - PROCESS | 10499 | 21:36:28 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:36:28 INFO - PROCESS | 10499 | RSS: Main: 202678272 21:36:28 INFO - PROCESS | 10499 | 21:36:31 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:36:32 INFO - PROCESS | 10499 | RSS: Main: 200007680 21:36:32 INFO - PROCESS | 10499 | 21:36:33 INFO - PROCESS | 10499 | Cycle 1(1): loaded http://localhost:47338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47338/tests/kraken/driver.html?testName=ai-astar) 21:36:34 INFO - PROCESS | 10499 | RSS: Main: 250875904 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | __start_tp_report 21:36:34 INFO - PROCESS | 10499 | _x_x_mozilla_page_load 21:36:34 INFO - PROCESS | 10499 | _x_x_mozilla_page_load_details 21:36:34 INFO - PROCESS | 10499 | |i|pagename|runs| 21:36:34 INFO - PROCESS | 10499 | |0;ai-astar;111;112;112;111;112;112;112;112;112;111 21:36:34 INFO - PROCESS | 10499 | |1;audio-beat-detection;145;118;117;161;121;124;120;120;146;119 21:36:34 INFO - PROCESS | 10499 | |2;audio-dft;162;161;161;162;161;161;160;161;163;161 21:36:34 INFO - PROCESS | 10499 | |3;audio-fft;75;77;76;118;77;76;75;77;98;75 21:36:34 INFO - PROCESS | 10499 | |4;audio-oscillator;83;82;110;84;84;83;94;83;84;105 21:36:34 INFO - PROCESS | 10499 | |5;imaging-gaussian-blur;117;117;117;117;118;118;116;116;117;116 21:36:34 INFO - PROCESS | 10499 | |6;imaging-darkroom;146;146;146;147;146;147;147;146;147;146 21:36:34 INFO - PROCESS | 10499 | |7;imaging-desaturate;96;95;95;95;95;96;95;96;95;95 21:36:34 INFO - PROCESS | 10499 | |8;json-parse-financial;49;50;51;49;50;50;52;51;51;50 21:36:34 INFO - PROCESS | 10499 | |9;json-stringify-tinderbox;64;64;69;65;65;68;63;64;65;65 21:36:34 INFO - PROCESS | 10499 | |10;stanford-crypto-aes;90;83;81;80;81;81;81;81;80;87 21:36:34 INFO - PROCESS | 10499 | |11;stanford-crypto-ccm;136;126;141;133;127;129;125;131;161;147 21:36:34 INFO - PROCESS | 10499 | |12;stanford-crypto-pbkdf2;166;167;166;170;167;179;170;170;165;168 21:36:34 INFO - PROCESS | 10499 | |13;stanford-crypto-sha256-iterative;61;63;62;62;61;62;61;65;60;65 21:36:34 INFO - PROCESS | 10499 | __end_tp_report 21:36:34 INFO - PROCESS | 10499 | __start_cc_report 21:36:34 INFO - PROCESS | 10499 | _x_x_mozilla_cycle_collect,1561 21:36:34 INFO - PROCESS | 10499 | __end_cc_report 21:36:34 INFO - PROCESS | 10499 | __startTimestamp1482471394121__endTimestamp 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | ------- Summary: start ------- 21:36:34 INFO - PROCESS | 10499 | Number of tests: 14 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 21:36:34 INFO - PROCESS | 10499 | Values: 111.0 112.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 111.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:122.50 stddev:15.58 (12.7%) stddev-sans-first:15.43 21:36:34 INFO - PROCESS | 10499 | Values: 145.0 118.0 117.0 161.0 121.0 124.0 120.0 120.0 146.0 119.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 21:36:34 INFO - PROCESS | 10499 | Values: 162.0 161.0 161.0 162.0 161.0 161.0 160.0 161.0 163.0 161.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.50 stddev:14.32 (18.7%) stddev-sans-first:14.93 21:36:34 INFO - PROCESS | 10499 | Values: 75.0 77.0 76.0 118.0 77.0 76.0 75.0 77.0 98.0 75.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:83.50 stddev:10.29 (12.3%) stddev-sans-first:10.67 21:36:34 INFO - PROCESS | 10499 | Values: 83.0 82.0 110.0 84.0 84.0 83.0 94.0 83.0 84.0 105.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 21:36:34 INFO - PROCESS | 10499 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 116.0 116.0 117.0 116.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 21:36:34 INFO - PROCESS | 10499 | Values: 146.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 147.0 146.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 21:36:34 INFO - PROCESS | 10499 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:0.95 (1.9%) stddev-sans-first:0.88 21:36:34 INFO - PROCESS | 10499 | Values: 49.0 50.0 51.0 49.0 50.0 50.0 52.0 51.0 51.0 50.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:1.87 (2.9%) stddev-sans-first:1.94 21:36:34 INFO - PROCESS | 10499 | Values: 64.0 64.0 69.0 65.0 65.0 68.0 63.0 64.0 65.0 65.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:81.00 stddev:3.34 (4.1%) stddev-sans-first:2.18 21:36:34 INFO - PROCESS | 10499 | Values: 90.0 83.0 81.0 80.0 81.0 81.0 81.0 81.0 80.0 87.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:134.50 stddev:11.33 (8.4%) stddev-sans-first:12.01 21:36:34 INFO - PROCESS | 10499 | Values: 136.0 126.0 141.0 133.0 127.0 129.0 125.0 131.0 161.0 147.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:169.00 stddev:4.02 (2.4%) stddev-sans-first:4.14 21:36:34 INFO - PROCESS | 10499 | Values: 166.0 167.0 166.0 170.0 167.0 179.0 170.0 170.0 165.0 168.0 21:36:34 INFO - PROCESS | 10499 | 21:36:34 INFO - PROCESS | 10499 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.69 (2.7%) stddev-sans-first:1.73 21:36:34 INFO - PROCESS | 10499 | Values: 61.0 63.0 62.0 62.0 61.0 62.0 61.0 65.0 60.0 65.0 21:36:34 INFO - PROCESS | 10499 | -------- Summary: end -------- 21:36:34 INFO - PROCESS | 10499 | 21:36:35 INFO - TEST-INFO | 10499: exit 0 21:36:35 INFO - TEST-OK | kraken | took 62109ms 21:36:35 INFO - SUITE-END | took 1180s 21:36:35 INFO - Completed test suite (00:19:40) 21:36:35 INFO - javascript benchmark 21:36:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.3588914272577, 3135.7021082997117, 3176.8093914365127, 3177.348643528076, 3187.012026271856, 5124.8975020499565, 5190.273361729361, 5213.3743950725875, 5321.973390133049, 5336.38631557371, 5207.244949482323, 5219.8434046978655, 5256.369235691713, 5316.946830531693, 5345.1447768357075, 4769.260764581478, 4791.401074865642, 4812.494688057738, 4831.589314908246, 4869.732164730938, 4747.572718455338, 4847.394075740533, 4870.6103511718975, 4916.606671466274, 4947.604191664676, 4897.632677549198, 4930.482882344154, 4931.654784165092, 4945.332380128691, 4982.402111746595, 4715.528447155291, 4734.384530011076, 4772.952270477285, 4799.400074990626, 4833.709977401332, 4807.302941073524, 4812.470628230927, 4851.248056551241, 4865.124277630025, 4870.488598697157, 5226.268322434862, 5259.184826351921, 5291.708956007418, 5302.416734159239, 5344.893102137974, 5501.724913754297, 5535.308086489189, 5681.914771278434, 5686.744096515667, 5692.971535142339, 5609.046462101453, 5631.155326701028, 5633.521150702195, 5667.744951477193, 5710.714464276811, 5254.133068043769, 5256.737163141827, 5258.6581872178185, 5261.184516399964, 5263.342082239758, 5605.943940560624, 5769.826905192851, 5773.913391299134, 5782.7686892523825, 5827.3007239131575, 5455.781768729285, 5495.862603434866, 5572.58205634579, 5585.078462053716, 5671.886562268732, 5669.34802497718, 5697.088465845445, 5704.71476426177, 5707.029804933089, 5711.143328500675, 5518.33779946409, 5563.0, 5563.5271001964475, 5642.887142257132, 5689.516391106719, 5556.083246264402, 5572.916406253909, 5585.357683866326, 5648.971755141198, 5700.486956173964, 4236.724612900151, 4238.385434112098, 4238.682098842599, 4240.851570195028, 4273.0385663225525, 3771.86798462058, 3776.527934008249, 3781.2248489059675, 3805.4101614249835, 3822.235552889433, 5224.686518808883, 5318.680879147263, 5350.652207606493, 5378.784848606012, 5463.180522921593, 5744.310682718017, 5744.4829965303325, 5744.971275143597, 5749.367569567345, 5751.166080918243, 5372.597055220874, 5396.298481197419, 5397.136458166675, 5413.53984911287, 5449.155380915964, 4596.517365676623, 4621.329907163443, 4642.744649044267, 4643.349931009661, 4679.22792739201, 5848.766049358063, 5873.941260587425, 5877.559183061373, 5884.08796636522, 5891.823245302648, 5631.211630371669, 5639.097744360883, 5641.351244606923, 5642.85892852682, 5649.85875353103, 5809.738561764646, 5860.472557469848, 5865.501432378295, 5868.559858010752, 5870.5010074142465, 5555.916661250004, 5557.777688892561, 5561.971035358471, 5563.0, 5563.360213575329, 5679.744411501575, 5679.886402271849, 5701.629394089371, 5703.315602127771, 5704.286964129484, 5513.751881165437, 5518.117101263779, 5519.751611177427, 5529.612927095065, 5538.750756216061, 5577.9442205577425, 5585.357683866245, 5585.916211256916, 5589.776408943597, 5590.105583106683, 5373.543248823894, 5394.838154855361, 5488.094464413327, 5583.41374155724, 5634.802781902614, 5555.388907220284, 5600.775968961196, 5614.29821272341, 5656.179853921076, 5657.066584013592, 5462.426445223195, 5468.808591699271, 5469.671819690831, 5469.972650136724, 5476.0], "value": 5214.877958022284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.8392724146377, 588.0913987888712, 597.0834768630151, 614.3610644929274, 637.2480473041815, 38439.23121537571, 42468.78765606168, 42711.57288427115, 42729.872415310165, 42753.35869961953, 42188.78905605475, 42512.362314565304, 42700.78649606748, 42716.78641606795, 42735.57264427355, 8384.455010424334, 8576.614052367659, 8614.741557753277, 8849.911500885006, 8850.822983540325, 10377.117944974649, 10638.042576168144, 10645.84031239532, 10975.2866063706, 10981.945090274538, 10784.622538211164, 10831.187660925423, 10844.403557804317, 10849.783004339908, 10856.565737370496, 10748.193885458622, 10806.675799725981, 10814.188935829845, 10819.78360432787, 10833.891661083368, 10370.792584148276, 10451.63419280329, 10458.895411045869, 10460.215483838743, 10463.633772817915, 41395.0, 41568.0, 41701.1659766806, 41741.0, 41741.1651766966, 3324.202191474053, 3572.0, 3707.3141468828085, 3786.5645450772963, 3851.345271303857, 31510.842445787854, 32159.6784032159, 32425.189370265696, 33245.0, 33270.66729332724, 4224.725392849455, 4314.525402205755, 4322.178786030644, 4496.64026877849, 4503.617192538638, 341835.0, 343038.0, 343105.0, 343682.9999999975, 345339.0, 56657.150142747894, 56758.14862777061, 56805.71597141988, 57077.429225708045, 57142.7142864283, 60463.69768151131, 60558.0, 60624.09063864045, 60627.393726063056, 60703.0, 15160.0, 15676.0, 15690.607734806494, 15694.843051569338, 15697.058176509447, 9510.714678559654, 9717.271204659679, 10918.563257469612, 11131.220814542905, 12013.459394327101, 16772.0, 16791.832081679026, 16848.32606695743, 16888.15559222034, 16922.153892305334, 6404.7117879695525, 6409.935900640934, 6604.933950660528, 6631.933680663132, 6737.225219099866, 914.7438717159206, 1021.667957913681, 1025.7794574166442, 1117.5675073191685, 1172.0682057764097, 30280.84859575688, 31564.210894727814, 31805.0, 31946.520802187984, 32138.19654508609, 944.5560586524323, 983.4787562591838, 1004.2769206171545, 1072.404815327485, 1075.7525769073, 15631.06213627186, 16576.419825306057, 16593.585160371087, 16758.0, 16923.746143807854, 535541.0, 537387.0, 538341.0, 538976.0, 540154.0, 58524.707376462844, 58841.70579147163, 58955.11567326493, 59013.70493147593, 59107.0, 1140.7743079499655, 1173.3253379306761, 1197.5808467036468, 1211.5759484180642, 1330.5875178694635, 29556.0, 30725.41397263794, 31249.68750312468, 31335.21661958469, 31646.36707265796, 51792.22311665252, 52272.47727522676, 52301.215481768566, 52343.0, 52384.47615523796, 426802.0, 432641.0, 432785.0, 433075.0, 435870.0, 40063.59936400599, 40900.0, 42127.789361053, 42134.0, 42136.0, 46615.533844662474, 47465.52534474749, 47565.28652070153, 47808.760956194994, 48072.0, 39178.0, 39917.25246431848, 40049.0, 40813.591864080976, 42141.0, 36797.63202367942, 39038.6096139035, 39539.40690889696, 41099.58900410958, 41659.16681666289, 35106.64893351034, 36443.0, 36471.63528364682, 41011.58988410078, 41171.7941410291, 31096.689033110284, 32931.67068329286, 33176.83411582927, 33651.3269734599, 33872.830635846665, 25543.87228063848, 25979.48041039131, 26110.477790444464, 26171.345716357242, 26240.0], "value": 20129.843010688764, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.25452875949406, 393.72439292495244, 403.2559926934805, 408.7710881906133, 424.42914280293013, 13634.0, 15459.072455652655, 18598.163082661278, 18899.433017009476, 19394.418167455, 18132.0, 18160.18279177437, 18200.362987295448, 18614.255429782825, 18625.161867715953, 5505.532029777453, 5507.421720719317, 5708.857278568027, 5711.742971566289, 5719.942800572004, 5547.1679248112905, 5590.944090559104, 5635.690037047961, 5641.68970706611, 5646.407127251641, 5403.972980135094, 5600.971995140018, 5605.663660180382, 5616.073347897592, 5617.241672374218, 5567.610267281292, 5593.944060559383, 5627.212190293362, 5627.549796016309, 5632.35227948785, 4856.2958371036, 5033.496650334974, 5074.112030394666, 5074.112030394703, 5077.466865979093, 22621.321360359216, 22726.659100113513, 22727.659085113737, 22746.65880011801, 22917.541649166924, 2341.098677009347, 2349.6710460535537, 2371.6916800815957, 2372.79831214347, 2483.3543278747657, 12262.693432664166, 14233.28833558318, 14298.928505357511, 14310.0, 14753.852461475355, 1680.5378520906727, 1731.7229243321135, 1750.7198848184357, 1786.4194136905558, 1809.9185536650882, 77465.0, 79036.60481697555, 79196.20803791947, 80016.0, 81864.0, 10596.31123976939, 10783.568657253621, 10810.621628242974, 11014.339139651647, 11572.725249177685, 14999.625009374853, 15064.698706025818, 15065.17141557225, 15100.773488397683, 15122.697546049018, 7255.383292420203, 7369.963150184215, 7631.54210747357, 7653.234676532302, 7665.386769058462, 6923.515353925278, 6948.131483564554, 7054.365107140279, 7110.0, 7129.7861064168155, 4574.336721175478, 4675.298705194248, 4681.0, 4704.694194877322, 4772.689775164603, 1943.5626983928785, 1979.0, 1987.8906660133541, 2011.6580181369202, 2022.2618744158276, 884.6992022712309, 953.6328513522344, 966.8018056298442, 989.5101924547395, 994.7413672445216, 17439.825601744073, 18801.247950082117, 18893.244270229035, 18975.146118424705, 19297.4210773677, 902.7201567513961, 956.9043095690514, 960.2030314838668, 977.4623956823718, 979.9069088436587, 10054.0, 10106.898931010595, 10582.0, 10644.414557199429, 10689.679309620724, 349039.0, 351161.0, 421640.0, 424975.0, 426570.0, 59944.70027649834, 64574.0, 80758.59620701859, 85763.57118214369, 87125.0, 1010.7675234696061, 1051.2431049644244, 1056.9735756606146, 1057.2229411382627, 1059.502034043998, 16171.919140404223, 16620.83379166241, 17130.48608541746, 17383.304667813652, 17675.911620441817, 37905.43141852819, 38185.0, 38609.80695096507, 39191.608083919935, 39682.40476392914, 54456.727716361165, 54461.72769136129, 54952.0, 55169.172462412294, 57066.14400783909, 8479.830403391774, 8501.447405918596, 8541.829163416573, 8562.957185214284, 8846.867296990422, 12627.87372126267, 13210.471581136648, 13235.536756213487, 13290.069695121636, 13344.532941347006, 11300.773984520429, 11480.138989575647, 11655.533778649098, 11662.183647144619, 11678.474468648761, 11204.215704900578, 11245.0, 11308.208425410143, 11310.20828542027, 11342.149338799458, 11360.488778004845, 11433.828492572451, 11656.242344247597, 11766.647000589996, 11867.169298149047, 7420.628968551443, 7505.924940750522, 7690.384769218337, 8456.915430845613, 8671.176238257356, 8441.70454034106, 10567.841482377617, 10889.509972051119, 11003.22977391575, 11013.559457621606], "value": 8907.386171315342, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.1736957825306, 1379.2689874366586, 1492.8805695544352, 1713.2290469288823, 1747.6417334446433, 7732.65203065862, 8222.547759873205, 8226.383021273406, 8226.876596851052, 8227.382946279024, 7418.777436676908, 7633.580153091578, 8004.119546849856, 8238.217369349917, 8345.165483451652, 2256.7968882800546, 2299.586074506588, 2301.8043466305385, 2303.1363238785457, 2304.493011537459, 2310.445493081665, 2324.732655744594, 2356.1046802215133, 2357.9742811876804, 2360.1975328388344, 2270.5231901300745, 2272.4432514034083, 2290.2327720213666, 2303.1017903017837, 2303.2974942642486, 2230.342049095521, 2237.183428048767, 2257.2325409360815, 2263.2418139923147, 2264.4225722440838, 2261.5476904618977, 2279.806216471602, 2282.3038973113194, 2287.885605719707, 2287.942801429961, 8384.203500667427, 8447.972486603232, 8485.787855303604, 8495.702650407267, 8495.74512764618, 4876.146674332013, 5004.149294619923, 5004.699718016895, 5005.57452616528, 5007.349044624212, 3118.7193152616146, 3183.984080079608, 3309.2223327518013, 3325.1022223999385, 3326.9500957485657, 941.1247539787994, 961.7737384834322, 969.4086607169621, 971.3006635222699, 975.6536429567543, 11568.53725850965, 11607.709807254801, 11634.650960471115, 11639.243449175776, 11648.12639052066, 4264.466941632296, 4581.427321584802, 4597.195490789099, 4611.746353950498, 4623.8844028898875, 9900.455474948947, 9903.603855845686, 9907.306488545733, 9908.306418550632, 9913.30606857513, 5051.393832740021, 5058.721770302595, 5059.165237735806, 5060.51925067118, 5061.974690126526, 3832.770033797981, 4292.463442069741, 4508.594226519629, 4560.635149188057, 4665.2302370108555, 3705.6479634434686, 3837.3092843288478, 3872.0, 3887.086534664352, 3900.1614652849776, 1188.8989435897872, 1194.9820752688718, 1195.9162858599814, 1204.4038201090516, 1217.8964787992943, 839.47532792005, 847.9915200848036, 860.0496451421214, 884.6284560484612, 885.2563846369081, 5607.943920560824, 5636.9964403649055, 5705.229793977843, 5745.569082318842, 5807.738651760681, 913.2237598041611, 935.6351023100996, 965.285688590438, 969.7914948286152, 979.6767066867917, 3742.9625703742613, 3884.5532763731967, 3890.902727431837, 3894.6494815466745, 3898.7270891037924, 10130.0, 10446.05985461297, 10497.475126243657, 10542.314749541256, 10544.156467482582, 10381.688549343531, 10391.272610917164, 10401.739956501147, 10413.42726150069, 10436.5825366986, 7630.847383052308, 7837.882431763528, 7941.888135661012, 7977.800554986172, 7984.800379990546, 5579.163125531203, 5697.800576979786, 5731.340895797037, 5838.32859221178, 5938.228030356199, 9251.814963700554, 9281.350305478552, 9333.439993600407, 9352.625894964125, 9402.012788657092, 9530.714078577654, 9634.518274086127, 9644.373115747734, 9689.903100969183, 9781.266405019795, 6496.74013039492, 6633.668316584248, 7022.1924478685605, 7282.089738782653, 7395.112586489656, 7738.0, 7952.562609056557, 7954.880676789969, 7960.800979975547, 7965.601719914097, 6832.555883867532, 6924.480663950123, 6928.203256625351, 6953.061336719478, 7077.681504332214, 6979.825504362431, 7062.929370706227, 7065.505414620927, 7077.752278670222, 7194.0, 6488.188976377953, 6521.152250207443, 6581.407673309425, 6584.0, 6585.308542603149, 4957.405111386656, 4985.052839960541, 5048.444671086104, 5060.49395060491, 5086.720230387216, 4660.86017419478, 4737.455192652889, 4831.4202295724735, 4850.1269771441475, 4999.150144475375], "value": 4405.21232561242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.23665539023443, 377.6638791475587, 382.76268713397695, 399.8060940443886, 402.3622558245183, 5163.380394352678, 5209.577546440904, 5209.739513024352, 5210.609204309682, 5235.424103348629, 5030.949690503094, 5181.316072276516, 5181.715005674687, 5183.326167598207, 5188.42927278, 3864.343061679507, 4011.1576568920414, 4033.0320723026407, 4158.771267580282, 4167.937480937789, 4096.938545921814, 4114.547399786021, 4119.299719047769, 4128.855490057848, 4138.48945135025, 4078.551359350484, 4097.402013214849, 4101.502951422739, 4106.5072191337085, 4147.771872547009, 3920.5099362579676, 3990.501187351581, 4097.180563887205, 4123.835046598132, 4131.752094874287, 3701.5002974598356, 3704.7962362070075, 3713.405855063204, 3729.366007778657, 3736.8318425670636, 4971.801127954877, 4974.577160941324, 5006.799728010875, 5025.1708468102715, 5075.796968121271, 4653.511381304948, 4756.429228492569, 4764.880877978078, 4784.712917224978, 4798.208295631217, 5359.919601205985, 5413.2692086568595, 5417.810376636839, 5423.3491980962535, 5444.406691583536, 4080.1431699343175, 4090.7545547266964, 4096.32410652242, 4097.3776782858995, 4110.7328023678665, 5435.722605187778, 5564.666120032771, 5617.410171931929, 5669.971650141723, 5695.775408287238, 4228.23891699497, 4229.555896630809, 4230.619244267969, 4236.788160591958, 4240.406343111967, 4306.569343065668, 4310.4611923509565, 4312.460942382202, 4319.524852266248, 4320.978395108004, 4614.492405835355, 4698.264879420869, 4729.196036673774, 4815.747905544514, 4825.541573550507, 5406.4591591396265, 5423.240746295522, 5435.7825686972865, 5453.727313634303, 5461.590380721383, 1429.9857001429853, 1431.2485944878813, 1440.96397590059, 1441.4738620403687, 1445.7542217822995, 989.9653512127041, 1038.2401828900092, 1043.9321444106108, 1046.2467023742893, 1053.2416660004938, 4980.601551875841, 4986.301917731521, 4987.675801072919, 5126.0, 5140.794368225229, 5529.751161197756, 5558.833235002957, 5567.192757050205, 5576.33084029919, 5619.847931174099, 5274.654962984491, 5274.841754747364, 5275.261463395128, 5278.5249327560705, 5287.841364759063, 2752.6464872534098, 2871.5118429866975, 2873.41095075509, 2876.8273903565855, 2892.8698208580663, 5621.229312766465, 5651.8304450866535, 5653.802116925888, 5669.433056694297, 5697.800576979868, 5239.869003274873, 5513.8345849624575, 5521.641093328921, 5532.834014979557, 5565.41563135873, 5499.890002200013, 5589.664620122806, 5628.634138780966, 5636.998865402887, 5651.2935883014625, 5421.481985044194, 5447.645903016291, 5450.454954504677, 5460.31746031746, 5475.5072043516275, 5221.451747566451, 5315.654482458628, 5373.0, 5389.892202156013, 5415.458454154553, 5591.021571224938, 5622.2128901954575, 5630.774769009357, 5643.294588176478, 5672.404397538199, 4073.246449406869, 4108.034611866149, 4111.198316328288, 4134.193832202692, 4135.565765594569, 5303.840884773463, 5472.480114389126, 5577.0, 5595.496405323541, 5627.43725627434, 5462.180672899097, 5469.632591852037, 5472.370677372153, 5476.835694929158, 5490.780368785204, 5296.702307934581, 5480.506754392124, 5489.58828087887, 5523.8895222096135, 5529.972350138384, 5427.782888684408, 5449.564034877121, 5450.809221677381, 5454.645448045864, 5472.808451704172, 5230.503102205284, 5348.57211423093, 5350.892982140257, 5490.094134467928, 5513.862153446196, 5406.432324605859, 5409.350877894678, 5409.648372855751, 5417.783288668409, 5420.918686219631], "value": 4205.586047096868, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.0338018323889, 698.8287869471981, 702.912135983002, 721.0446158839538, 751.4251597527896, 13708.314584270784, 15155.62110947227, 15250.84749152508, 15302.540923772276, 15321.923390383046, 13834.0, 14005.859941400582, 14018.369173387196, 14039.157650540974, 14060.92969535151, 669.9296573859748, 685.5132855672479, 690.1097584116484, 787.4173111897197, 809.4050872608628, 668.7258224128109, 766.0768773627793, 790.3598085550693, 792.1840504280572, 807.6769292283104, 815.1155995744624, 824.7938015496126, 825.471698113208, 825.8348330333927, 826.1614461321764, 826.0541679776682, 827.2843989948681, 828.0808302783935, 828.6271177969908, 828.7928017995501, 516.3287725956242, 528.0574175097471, 531.6756778365194, 609.5732986909181, 611.5199568338877, 10397.688069357931, 14200.85799142016, 14766.187859667714, 14782.408703651841, 14866.331015104346, 14751.0, 15060.774088388684, 15099.471518496799, 15102.622434439118, 15602.921985390001, 11266.718332041764, 11555.711107222303, 11600.593979210771, 11712.824307635392, 11783.731067014303, 3701.7408781385316, 3809.3714537101346, 3815.1988082502708, 3933.252681990413, 3936.980315098406, 72805.0, 75740.62129689316, 76283.61858190673, 76502.85245721374, 76987.61506192433, 10926.945365273123, 11071.391073491035, 11230.719232019264, 11336.319820810777, 11529.365884876244, 21845.67231491529, 21964.89017554902, 22250.221242256768, 22318.442038949157, 22664.660030099563, 7223.133224013047, 7240.891386629205, 7303.196648368676, 7345.742898998509, 7598.962005189938, 4333.414988976448, 4354.172707185625, 4396.208682437128, 4544.272916333371, 4549.499555048942, 3459.4637831136215, 3591.1560783216446, 3596.8021758803516, 3716.4239542870896, 3835.5013848200115, 635.4122436745992, 644.3556443556444, 649.1885143570537, 658.5883822610868, 663.5587334422555, 608.9421504957022, 622.1321256846674, 653.1541653558653, 663.9170103737033, 747.4917056401699, 8846.601902914384, 9472.479013654316, 9557.426554406758, 9559.665411710557, 9751.21990240779, 589.2722487727652, 607.2652090969888, 633.4868756307405, 641.4002907281706, 646.3988490703597, 4268.701190916669, 4384.890377740582, 4407.889802754893, 4650.581447669726, 4702.553257440606, 76757.0, 79160.0, 81470.59264703757, 83117.58441207877, 84022.0, 67873.3212667877, 71924.2807571928, 72098.63950680214, 72983.0, 74033.0, 65186.67406662842, 65969.0, 66419.66790166018, 67831.0, 68767.31232687709, 8770.210681038738, 8821.735347939572, 8892.73321800347, 8932.831574849542, 8943.499243895003, 43679.78160109179, 43817.56182438135, 45153.0, 45310.82824202406, 45747.31379029384, 49388.06119388033, 53138.0, 53669.0, 54308.728456357465, 60171.0, 12633.747325053631, 12687.936560317139, 13187.80218296707, 13813.447462101403, 13958.09272397291, 14359.712805744035, 14425.0, 14759.704805903608, 14904.701905962036, 15799.131047792482, 12971.481140754642, 13403.32983350809, 13511.527096551574, 13767.38046787877, 13942.860571394156, 13635.86364136346, 13936.094153879965, 14019.228942408265, 14347.497837575635, 14907.92546037263, 13781.793273100711, 14079.296035197995, 14124.78812817788, 14715.117092974457, 14979.925100374865, 10480.903819236031, 10686.026998899319, 11457.255278406878, 11502.252353596989, 11553.595624153115, 11424.885751142383, 11688.88311116878, 12027.338496382783, 12081.456334465145, 12443.128980971245], "value": 7102.913671183101, "unit": "score"}], "name": "dromaeo_css", "value": 7052.644189717492, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 117.0, 161.0, 121.0, 124.0, 120.0, 120.0, 146.0, 119.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0, 161.0, 163.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 118.0, 77.0, 76.0, 75.0, 77.0, 98.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 110.0, 84.0, 84.0, 83.0, 94.0, 83.0, 84.0, 105.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 116.0, 116.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 49.0, 50.0, 50.0, 52.0, 51.0, 51.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 69.0, 65.0, 65.0, 68.0, 63.0, 64.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 81.0, 80.0, 81.0, 81.0, 81.0, 81.0, 80.0, 87.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 126.0, 141.0, 133.0, 127.0, 129.0, 125.0, 131.0, 161.0, 147.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 170.0, 167.0, 179.0, 170.0, 170.0, 165.0, 168.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 61.0, 62.0, 61.0, 65.0, 60.0, 65.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1496.8999999999999, "alertThreshold": 2.0}]} 21:36:35 INFO - Return code: 0 21:36:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:36:35 INFO - # TBPL SUCCESS # 21:36:35 INFO - Running post-action listener: _resource_record_post_action 21:36:35 INFO - [mozharness: 2016-12-23 05:36:35.414777Z] Finished run-tests step (success) 21:36:35 INFO - Running post-run listener: _resource_record_post_run 21:36:35 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 16384; Write bytes: 415723520; Read time: 1832; Write time: 1314176 21:36:35 INFO - TinderboxPrint: CPU usage
13.2% 21:36:35 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 1,832 21:36:35 INFO - TinderboxPrint: I/O write bytes / time
415,723,520 / 1,314,176 21:36:35 INFO - TinderboxPrint: CPU idle
8,299.5 (86.8%) 21:36:35 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 21:36:35 INFO - TinderboxPrint: Swap in / out
0 / 0 21:36:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 122384384; Read time: 684; Write time: 276520 21:36:35 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292724736; Read time: 1136; Write time: 1037656 21:36:36 INFO - Running post-run listener: _upload_blobber_files 21:36:36 INFO - Blob upload gear active. 21:36:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:36:36 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 21:36:36 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:36:36 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:36:36 INFO - (blobuploader) - INFO - Open directory for files ... 21:36:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:36:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:37 INFO - (blobuploader) - INFO - Done attempting. 21:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:36:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:40 INFO - (blobuploader) - INFO - Done attempting. 21:36:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:36:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:36:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:41 INFO - (blobuploader) - INFO - Done attempting. 21:36:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:36:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:36:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:42 INFO - (blobuploader) - INFO - Done attempting. 21:36:42 INFO - (blobuploader) - INFO - Iteration through files over. 21:36:42 INFO - Return code: 0 21:36:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:36:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:36:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f41c011aa009d975bf314039422b09022b4b7de1b2ba5a24ba2dfdeab5b6f47bab848052604706e456e0934faf2bfabec4ade7ed3f6ee2e8a864e8785112c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f659f8d062f9b8a66e637a8409a459055c1c849396ed5730ef0c867d3aac4c092b06945a5f7e8ce1b7b9180be6f649ad1fc6952ddce628625b200a9d5dc5f34d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff9dfc44baaa4e99b121d1c310266207d94c360e64d984199ad1ba88342e65f333dd416958343527c6be9c7133741ff187d95335b712d34384b0a36e52a7f8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d328f2cd893fcf7f6d2706ee3919079ede6665c3d292d4ce6c16a551688f48f8197518cbfa4c4056dde634ec975f1f261c9605c7b8742fb049e13fcdfcabde"} 21:36:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:36:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:36:42 INFO - Contents: 21:36:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f41c011aa009d975bf314039422b09022b4b7de1b2ba5a24ba2dfdeab5b6f47bab848052604706e456e0934faf2bfabec4ade7ed3f6ee2e8a864e8785112c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f659f8d062f9b8a66e637a8409a459055c1c849396ed5730ef0c867d3aac4c092b06945a5f7e8ce1b7b9180be6f649ad1fc6952ddce628625b200a9d5dc5f34d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff9dfc44baaa4e99b121d1c310266207d94c360e64d984199ad1ba88342e65f333dd416958343527c6be9c7133741ff187d95335b712d34384b0a36e52a7f8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d328f2cd893fcf7f6d2706ee3919079ede6665c3d292d4ce6c16a551688f48f8197518cbfa4c4056dde634ec975f1f261c9605c7b8742fb049e13fcdfcabde"} 21:36:42 INFO - Running post-run listener: copy_logs_to_upload_dir 21:36:42 INFO - Copying logs to upload dir... 21:36:42 INFO - mkdir: /builds/slave/test/build/upload/logs 21:36:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.126760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-22 21:36:42.678705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:42.683044) ========= 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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f41c011aa009d975bf314039422b09022b4b7de1b2ba5a24ba2dfdeab5b6f47bab848052604706e456e0934faf2bfabec4ade7ed3f6ee2e8a864e8785112c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f659f8d062f9b8a66e637a8409a459055c1c849396ed5730ef0c867d3aac4c092b06945a5f7e8ce1b7b9180be6f649ad1fc6952ddce628625b200a9d5dc5f34d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff9dfc44baaa4e99b121d1c310266207d94c360e64d984199ad1ba88342e65f333dd416958343527c6be9c7133741ff187d95335b712d34384b0a36e52a7f8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d328f2cd893fcf7f6d2706ee3919079ede6665c3d292d4ce6c16a551688f48f8197518cbfa4c4056dde634ec975f1f261c9605c7b8742fb049e13fcdfcabde"} build_url:https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f41c011aa009d975bf314039422b09022b4b7de1b2ba5a24ba2dfdeab5b6f47bab848052604706e456e0934faf2bfabec4ade7ed3f6ee2e8a864e8785112c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f659f8d062f9b8a66e637a8409a459055c1c849396ed5730ef0c867d3aac4c092b06945a5f7e8ce1b7b9180be6f649ad1fc6952ddce628625b200a9d5dc5f34d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff9dfc44baaa4e99b121d1c310266207d94c360e64d984199ad1ba88342e65f333dd416958343527c6be9c7133741ff187d95335b712d34384b0a36e52a7f8d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d328f2cd893fcf7f6d2706ee3919079ede6665c3d292d4ce6c16a551688f48f8197518cbfa4c4056dde634ec975f1f261c9605c7b8742fb049e13fcdfcabde"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:42.716179) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:42.716500) ========= 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=8783db0840cc980e80bb0bd400000236-1482469009.870995-216471614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:42.795427) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 21:36:42.798871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 21:36:42.799167) ========= ========= Total master_lag: 0.20 =========