builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-061 starttime: 1482470157.19 results: success (0) buildid: 20161222193138 builduid: 6058b98cf165449e865919a1c56d8680 revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.197543) ========= 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:57.198011) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.198303) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.212922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.213182) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 21:15:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.2M=0s 2016-12-22 21:15:57 (89.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081396 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.308271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.308599) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018975 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.362546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:57.362865) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 21:15:57,902 truncating revision to first 12 chars 2016-12-22 21:15:57,902 Setting DEBUG logging. 2016-12-22 21:15:57,902 attempt 1/10 2016-12-22 21:15:57,902 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:58,266 unpacking tar archive at: mozilla-central-2785aaf276ba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.705366 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.081494) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.081807) ========= script_repo_revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.082187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.082462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 21:15:58.091777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-22 21:15:58.092141) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:15:58 INFO - MultiFileLogger online at 20161222 21:15:58 in /builds/slave/test 21:15:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:15:58 INFO - {'append_to_log': False, 21:15:58 INFO - 'base_work_dir': '/builds/slave/test', 21:15:58 INFO - 'blob_upload_branch': 'Firefox', 21:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:15:58 INFO - 'branch': 'Firefox', 21:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:58 INFO - 'config_files': ('talos/linux_config.py',), 21:15:58 INFO - 'default_actions': ('clobber', 21:15:58 INFO - 'read-buildbot-config', 21:15:58 INFO - 'download-and-extract', 21:15:58 INFO - 'populate-webroot', 21:15:58 INFO - 'create-virtualenv', 21:15:58 INFO - 'install', 21:15:58 INFO - 'run-tests'), 21:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:58 INFO - 'download_minidump_stackwalk': True, 21:15:58 INFO - 'download_symbols': 'ondemand', 21:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:58 INFO - 'installer_path': 'installer.exe', 21:15:58 INFO - 'log_level': 'info', 21:15:58 INFO - 'log_name': 'talos', 21:15:58 INFO - 'log_to_console': True, 21:15:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:15:58 INFO - 'opt_config_files': (), 21:15:58 INFO - 'pip_index': False, 21:15:58 INFO - 'sps_profile': False, 21:15:58 INFO - 'sps_profile_interval': 0, 21:15:58 INFO - 'suite': 'dromaeojs-e10s', 21:15:58 INFO - 'system_bits': '32', 21:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:58 INFO - 'title': 'talos-linux64-ix-061', 21:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:15:58 INFO - 'use_talos_json': True, 21:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:58 INFO - 'work_dir': 'build'} 21:15:58 INFO - [mozharness: 2016-12-23 05:15:58.733376Z] Running clobber step. 21:15:58 INFO - Running pre-action listener: _resource_record_pre_action 21:15:58 INFO - Running main action method: clobber 21:15:58 INFO - rmtree: /builds/slave/test/build 21:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:15:59 INFO - Running post-action listener: _resource_record_post_action 21:15:59 INFO - [mozharness: 2016-12-23 05:15:59.191952Z] Finished clobber step (success) 21:15:59 INFO - [mozharness: 2016-12-23 05:15:59.192053Z] Running read-buildbot-config step. 21:15:59 INFO - Running pre-action listener: _resource_record_pre_action 21:15:59 INFO - Running main action method: read_buildbot_config 21:15:59 INFO - Using buildbot properties: 21:15:59 INFO - { 21:15:59 INFO - "project": "", 21:15:59 INFO - "product": "firefox", 21:15:59 INFO - "script_repo_revision": "production", 21:15:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:15:59 INFO - "repository": "", 21:15:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 21:15:59 INFO - "buildid": "20161222193138", 21:15:59 INFO - "pgo_build": "True", 21:15:59 INFO - "basedir": "/builds/slave/test", 21:15:59 INFO - "buildnumber": 491, 21:15:59 INFO - "slavename": "talos-linux64-ix-061", 21:15:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:15:59 INFO - "platform": "ubuntu64_hw", 21:15:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:15:59 INFO - "branch": "mozilla-central", 21:15:59 INFO - "repo_path": "mozilla-central", 21:15:59 INFO - "revision": "2785aaf276ba29fb2e1f5607d90d441fee42efb4", 21:15:59 INFO - "stage_platform": "linux64-pgo", 21:15:59 INFO - "builduid": "6058b98cf165449e865919a1c56d8680", 21:15:59 INFO - "slavebuilddir": "test" 21:15:59 INFO - } 21:15:59 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:15:59 INFO - Running post-action listener: _resource_record_post_action 21:15:59 INFO - [mozharness: 2016-12-23 05:15:59.194493Z] Finished read-buildbot-config step (success) 21:15:59 INFO - [mozharness: 2016-12-23 05:15:59.194583Z] Running download-and-extract step. 21:15:59 INFO - Running pre-action listener: _resource_record_pre_action 21:15:59 INFO - Running main action method: download_and_extract 21:15:59 INFO - mkdir: /builds/slave/test/build/tests 21:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:59 INFO - trying https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:15:59 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:15:59 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:15:59 INFO - Downloaded 1361 bytes. 21:15:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:15:59 INFO - Using the following test package requirements: 21:15:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:15:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:15:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:15:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'jsshell-linux-x86_64.zip'], 21:15:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:15:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:15:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:15:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:15:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:15:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:15:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:15:59 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:15:59 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:15:59 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:00 INFO - Expected file size: 21827161 21:16:00 INFO - Obtained file size: 21827161 21:16:04 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:04 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:04 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:04 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:05 INFO - Expected file size: 21827161 21:16:05 INFO - Obtained file size: 21827161 21:16:09 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:09 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:09 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:11 INFO - Expected file size: 14409776 21:16:11 INFO - Obtained file size: 14409776 21:16:11 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:11 INFO - trying https://queue.taskcluster.net/v1/task/C9WDacv2RB61Wgt8HlAMOA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:11 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:11 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:12 INFO - Downloaded 61393625 bytes. 21:16:12 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:12 INFO - mkdir: /builds/slave/test/properties 21:16:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:16:12 INFO - Writing to file /builds/slave/test/properties/build_url 21:16:12 INFO - Contents: 21:16:12 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:13 INFO - Running post-action listener: _resource_record_post_action 21:16:13 INFO - Running post-action listener: set_extra_try_arguments 21:16:13 INFO - [mozharness: 2016-12-23 05:16:13.037083Z] Finished download-and-extract step (success) 21:16:13 INFO - [mozharness: 2016-12-23 05:16:13.037383Z] Running populate-webroot step. 21:16:13 INFO - Running pre-action listener: _resource_record_pre_action 21:16:13 INFO - Running main action method: populate_webroot 21:16:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:16:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:16:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:16:13 INFO - '--tppagecycles', 21:16:13 INFO - '7'], 21:16:13 INFO - 'tests': ['tsvgm']}}, 21:16:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:16:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:16:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:16:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:16:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['damp', 'tps']}, 21:16:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'tests': ['damp', 'tps']}, 21:16:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['dromaeo_dom']}, 21:16:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:16:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['basic_compositor_video']}, 21:16:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:16:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tsvgx', 21:16:13 INFO - 'tsvgr_opacity', 21:16:13 INFO - 'tart', 21:16:13 INFO - 'tscrollx', 21:16:13 INFO - 'cart', 21:16:13 INFO - 'tsvg_static']}, 21:16:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:16:13 INFO - 'tsvgr_opacity', 21:16:13 INFO - 'tart', 21:16:13 INFO - 'tscrollx', 21:16:13 INFO - 'cart', 21:16:13 INFO - 'tsvg_static']}, 21:16:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tp5o']}, 21:16:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:16:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s', 21:16:13 INFO - '--xperf_path', 21:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:13 INFO - 'tests': ['tp5n']}, 21:16:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--xperf_path', 21:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:13 INFO - 'tests': ['tp5n']}}, 21:16:13 INFO - 'talos.zip': {'path': '', 21:16:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:16:13 INFO - Running post-action listener: _resource_record_post_action 21:16:13 INFO - [mozharness: 2016-12-23 05:16:13.057502Z] Finished populate-webroot step (success) 21:16:13 INFO - [mozharness: 2016-12-23 05:16:13.057595Z] Running create-virtualenv step. 21:16:13 INFO - Running pre-action listener: _resource_record_pre_action 21:16:13 INFO - Running main action method: create_virtualenv 21:16:13 INFO - Creating virtualenv /builds/slave/test/build/venv 21:16:13 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:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:16:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:16:13 INFO - Using real prefix '/usr' 21:16:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:16:13 INFO - Installing distribute.............................................................................................................................................................................................done. 21:16:15 INFO - Installing pip.................done. 21:16:15 INFO - Return code: 0 21:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:16:15 INFO - Reading from file tmpfile_stdout 21:16:15 INFO - Output received: 21:16:15 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:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:15 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:15 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:15 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:15 INFO - Using env: {'DISPLAY': ':0', 21:16:15 INFO - 'HOME': '/home/cltbld', 21:16:15 INFO - 'LANG': 'en_US.UTF-8', 21:16:15 INFO - 'LANGUAGE': 'en_US:en', 21:16:15 INFO - 'LOGNAME': 'cltbld', 21:16:15 INFO - 'MAIL': '/var/mail/cltbld', 21:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:15 INFO - 'MOZ_NO_REMOTE': '1', 21:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:15 INFO - 'NO_EM_RESTART': '1', 21:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:15 INFO - 'PWD': '/builds/slave/test', 21:16:15 INFO - 'SHELL': '/bin/bash', 21:16:15 INFO - 'SHLVL': '1', 21:16:15 INFO - 'TERM': 'linux', 21:16:15 INFO - 'TMOUT': '86400', 21:16:15 INFO - 'USER': 'cltbld', 21:16:15 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:15 INFO - '_': '/tools/buildbot/bin/python'} 21:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:16 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:16 INFO - Cleaning up... 21:16:16 INFO - Return code: 0 21:16:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:16:16 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:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 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:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:16 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:16 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:16 INFO - Using env: {'DISPLAY': ':0', 21:16:16 INFO - 'HOME': '/home/cltbld', 21:16:16 INFO - 'LANG': 'en_US.UTF-8', 21:16:16 INFO - 'LANGUAGE': 'en_US:en', 21:16:16 INFO - 'LOGNAME': 'cltbld', 21:16:16 INFO - 'MAIL': '/var/mail/cltbld', 21:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:16 INFO - 'MOZ_NO_REMOTE': '1', 21:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:16 INFO - 'NO_EM_RESTART': '1', 21:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:16 INFO - 'PWD': '/builds/slave/test', 21:16:16 INFO - 'SHELL': '/bin/bash', 21:16:16 INFO - 'SHLVL': '1', 21:16:16 INFO - 'TERM': 'linux', 21:16:16 INFO - 'TMOUT': '86400', 21:16:16 INFO - 'USER': 'cltbld', 21:16:16 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:16 INFO - '_': '/tools/buildbot/bin/python'} 21:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:16 INFO - Downloading/unpacking psutil>=3.1.1 21:16:16 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:16 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:16 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:16 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:16 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:16 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:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:18 INFO - Installing collected packages: psutil 21:16:18 INFO - Running setup.py install for psutil 21:16:18 INFO - building 'psutil._psutil_linux' extension 21:16:18 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:18 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:18 INFO - building 'psutil._psutil_posix' extension 21:16:18 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:18 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:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:18 INFO - Successfully installed psutil 21:16:18 INFO - Cleaning up... 21:16:18 INFO - Return code: 0 21:16:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:16:18 INFO - Using env: {'DISPLAY': ':0', 21:16:18 INFO - 'HOME': '/home/cltbld', 21:16:18 INFO - 'LANG': 'en_US.UTF-8', 21:16:18 INFO - 'LANGUAGE': 'en_US:en', 21:16:18 INFO - 'LOGNAME': 'cltbld', 21:16:18 INFO - 'MAIL': '/var/mail/cltbld', 21:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:18 INFO - 'MOZ_NO_REMOTE': '1', 21:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:18 INFO - 'NO_EM_RESTART': '1', 21:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:18 INFO - 'PWD': '/builds/slave/test', 21:16:18 INFO - 'SHELL': '/bin/bash', 21:16:18 INFO - 'SHLVL': '1', 21:16:18 INFO - 'TERM': 'linux', 21:16:18 INFO - 'TMOUT': '86400', 21:16:18 INFO - 'USER': 'cltbld', 21:16:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:18 INFO - '_': '/tools/buildbot/bin/python'} 21:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:16:21 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:21 INFO - Installing collected packages: mozsystemmonitor 21:16:21 INFO - Running setup.py install for mozsystemmonitor 21:16:21 INFO - Successfully installed mozsystemmonitor 21:16:21 INFO - Cleaning up... 21:16:21 INFO - Return code: 0 21:16:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:16:21 INFO - Using env: {'DISPLAY': ':0', 21:16:21 INFO - 'HOME': '/home/cltbld', 21:16:21 INFO - 'LANG': 'en_US.UTF-8', 21:16:21 INFO - 'LANGUAGE': 'en_US:en', 21:16:21 INFO - 'LOGNAME': 'cltbld', 21:16:21 INFO - 'MAIL': '/var/mail/cltbld', 21:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:21 INFO - 'MOZ_NO_REMOTE': '1', 21:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:21 INFO - 'NO_EM_RESTART': '1', 21:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:21 INFO - 'PWD': '/builds/slave/test', 21:16:21 INFO - 'SHELL': '/bin/bash', 21:16:21 INFO - 'SHLVL': '1', 21:16:21 INFO - 'TERM': 'linux', 21:16:21 INFO - 'TMOUT': '86400', 21:16:21 INFO - 'USER': 'cltbld', 21:16:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:21 INFO - '_': '/tools/buildbot/bin/python'} 21:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:21 INFO - Downloading/unpacking jsonschema==2.5.1 21:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:16:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:23 INFO - Downloading functools32-3.2.3-2.tar.gz 21:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:16:23 INFO - warning: no files found matching '*.txt' 21:16:23 INFO - no previously-included directories found matching 'build' 21:16:23 INFO - no previously-included directories found matching 'dist' 21:16:23 INFO - no previously-included directories found matching '.git*' 21:16:23 INFO - Installing collected packages: jsonschema, functools32 21:16:23 INFO - Running setup.py install for functools32 21:16:23 INFO - warning: no files found matching '*.txt' 21:16:23 INFO - no previously-included directories found matching 'build' 21:16:23 INFO - no previously-included directories found matching 'dist' 21:16:23 INFO - no previously-included directories found matching '.git*' 21:16:23 INFO - Successfully installed jsonschema functools32 21:16:23 INFO - Cleaning up... 21:16:24 INFO - Return code: 0 21:16:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:24 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 21:16:24 INFO - 'HOME': '/home/cltbld', 21:16:24 INFO - 'LANG': 'en_US.UTF-8', 21:16:24 INFO - 'LANGUAGE': 'en_US:en', 21:16:24 INFO - 'LOGNAME': 'cltbld', 21:16:24 INFO - 'MAIL': '/var/mail/cltbld', 21:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:24 INFO - 'MOZ_NO_REMOTE': '1', 21:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:24 INFO - 'NO_EM_RESTART': '1', 21:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:24 INFO - 'PWD': '/builds/slave/test', 21:16:24 INFO - 'SHELL': '/bin/bash', 21:16:24 INFO - 'SHLVL': '1', 21:16:24 INFO - 'TERM': 'linux', 21:16:24 INFO - 'TMOUT': '86400', 21:16:24 INFO - 'USER': 'cltbld', 21:16:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:24 INFO - '_': '/tools/buildbot/bin/python'} 21:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:16:24 INFO - Cleaning up... 21:16:24 INFO - Return code: 0 21:16:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:24 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 21:16:24 INFO - 'HOME': '/home/cltbld', 21:16:24 INFO - 'LANG': 'en_US.UTF-8', 21:16:24 INFO - 'LANGUAGE': 'en_US:en', 21:16:24 INFO - 'LOGNAME': 'cltbld', 21:16:24 INFO - 'MAIL': '/var/mail/cltbld', 21:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:24 INFO - 'MOZ_NO_REMOTE': '1', 21:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:24 INFO - 'NO_EM_RESTART': '1', 21:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:24 INFO - 'PWD': '/builds/slave/test', 21:16:24 INFO - 'SHELL': '/bin/bash', 21:16:24 INFO - 'SHLVL': '1', 21:16:24 INFO - 'TERM': 'linux', 21:16:24 INFO - 'TMOUT': '86400', 21:16:24 INFO - 'USER': 'cltbld', 21:16:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:24 INFO - '_': '/tools/buildbot/bin/python'} 21:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:24 INFO - Downloading/unpacking blobuploader==1.2.4 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:26 INFO - Downloading blobuploader-1.2.4.tar.gz 21:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:16:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:16:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:27 INFO - Downloading docopt-0.6.1.tar.gz 21:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:16:27 INFO - Installing collected packages: blobuploader, requests, docopt 21:16:27 INFO - Running setup.py install for blobuploader 21:16:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:16:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:16:27 INFO - Running setup.py install for requests 21:16:27 INFO - Running setup.py install for docopt 21:16:27 INFO - Successfully installed blobuploader requests docopt 21:16:27 INFO - Cleaning up... 21:16:27 INFO - Return code: 0 21:16:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:27 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:27 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:27 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:27 INFO - Using env: {'DISPLAY': ':0', 21:16:27 INFO - 'HOME': '/home/cltbld', 21:16:27 INFO - 'LANG': 'en_US.UTF-8', 21:16:27 INFO - 'LANGUAGE': 'en_US:en', 21:16:27 INFO - 'LOGNAME': 'cltbld', 21:16:27 INFO - 'MAIL': '/var/mail/cltbld', 21:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:27 INFO - 'MOZ_NO_REMOTE': '1', 21:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:27 INFO - 'NO_EM_RESTART': '1', 21:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:27 INFO - 'PWD': '/builds/slave/test', 21:16:27 INFO - 'SHELL': '/bin/bash', 21:16:27 INFO - 'SHLVL': '1', 21:16:27 INFO - 'TERM': 'linux', 21:16:27 INFO - 'TMOUT': '86400', 21:16:27 INFO - 'USER': 'cltbld', 21:16:27 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:27 INFO - '_': '/tools/buildbot/bin/python'} 21:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:28 INFO - Running setup.py (path:/tmp/pip-o21du3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:28 INFO - Running setup.py (path:/tmp/pip-Y8Miw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:28 INFO - Running setup.py (path:/tmp/pip-NhSZQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:28 INFO - Running setup.py (path:/tmp/pip-FSq8Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:28 INFO - Running setup.py (path:/tmp/pip-JP9vpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:28 INFO - Running setup.py (path:/tmp/pip-Zwp6nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:28 INFO - Running setup.py (path:/tmp/pip-E_B8zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:28 INFO - Running setup.py (path:/tmp/pip-Mxuls6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:28 INFO - Running setup.py (path:/tmp/pip-4PIZwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:28 INFO - Running setup.py (path:/tmp/pip-BX8gs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:28 INFO - Running setup.py (path:/tmp/pip-QH8KTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:28 INFO - Running setup.py (path:/tmp/pip-VZbdKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:28 INFO - Running setup.py (path:/tmp/pip-q0XSyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:29 INFO - Running setup.py (path:/tmp/pip-40PROe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:29 INFO - Running setup.py (path:/tmp/pip-jaX7f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:29 INFO - Running setup.py (path:/tmp/pip-arLb_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:29 INFO - Running setup.py (path:/tmp/pip-y3ft2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:16:29 INFO - Running setup.py install for manifestparser 21:16:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:16:29 INFO - Running setup.py install for mozcrash 21:16:29 INFO - Running setup.py install for mozdebug 21:16:29 INFO - Running setup.py install for mozdevice 21:16:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:16:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:16:29 INFO - Running setup.py install for mozfile 21:16:29 INFO - Running setup.py install for mozhttpd 21:16:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:16:29 INFO - Running setup.py install for mozinfo 21:16:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozInstall 21:16:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozleak 21:16:30 INFO - Running setup.py install for mozlog 21:16:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for moznetwork 21:16:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozprocess 21:16:30 INFO - Running setup.py install for mozprofile 21:16:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozrunner 21:16:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozscreenshot 21:16:30 INFO - Running setup.py install for moztest 21:16:31 INFO - Running setup.py install for mozversion 21:16:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:16:31 INFO - Cleaning up... 21:16:31 INFO - Return code: 0 21:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:31 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:31 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:31 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:31 INFO - Using env: {'DISPLAY': ':0', 21:16:31 INFO - 'HOME': '/home/cltbld', 21:16:31 INFO - 'LANG': 'en_US.UTF-8', 21:16:31 INFO - 'LANGUAGE': 'en_US:en', 21:16:31 INFO - 'LOGNAME': 'cltbld', 21:16:31 INFO - 'MAIL': '/var/mail/cltbld', 21:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:31 INFO - 'MOZ_NO_REMOTE': '1', 21:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:31 INFO - 'NO_EM_RESTART': '1', 21:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:31 INFO - 'PWD': '/builds/slave/test', 21:16:31 INFO - 'SHELL': '/bin/bash', 21:16:31 INFO - 'SHLVL': '1', 21:16:31 INFO - 'TERM': 'linux', 21:16:31 INFO - 'TMOUT': '86400', 21:16:31 INFO - 'USER': 'cltbld', 21:16:31 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:31 INFO - '_': '/tools/buildbot/bin/python'} 21:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:31 INFO - Running setup.py (path:/tmp/pip-ITy9RP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:31 INFO - Running setup.py (path:/tmp/pip-RRzncU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:31 INFO - Running setup.py (path:/tmp/pip-MK9GdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:31 INFO - Running setup.py (path:/tmp/pip-i674R0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:31 INFO - Running setup.py (path:/tmp/pip-xUe1wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:31 INFO - Running setup.py (path:/tmp/pip-FOaZWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:31 INFO - Running setup.py (path:/tmp/pip-SB5Gjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:31 INFO - Running setup.py (path:/tmp/pip-c9bj3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:31 INFO - Running setup.py (path:/tmp/pip-nd1L3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:32 INFO - Running setup.py (path:/tmp/pip-wEPdFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:32 INFO - Running setup.py (path:/tmp/pip-QBs2Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:32 INFO - Running setup.py (path:/tmp/pip-erSlyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:32 INFO - Running setup.py (path:/tmp/pip-y7wyp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:32 INFO - Running setup.py (path:/tmp/pip-ZtQ_uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:32 INFO - Running setup.py (path:/tmp/pip-NNodxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:32 INFO - Running setup.py (path:/tmp/pip-ZLFxsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:32 INFO - Running setup.py (path:/tmp/pip-_tLMXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:16:32 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:32 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:32 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:32 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:32 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:32 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:34 INFO - Downloading blessings-1.6.tar.gz 21:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:16:34 INFO - Installing collected packages: blessings 21:16:34 INFO - Running setup.py install for blessings 21:16:34 INFO - Successfully installed blessings 21:16:34 INFO - Cleaning up... 21:16:34 INFO - Return code: 0 21:16:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:35 INFO - Reading from file tmpfile_stdout 21:16:35 INFO - Current package versions: 21:16:35 INFO - argparse == 1.2.1 21:16:35 INFO - blessings == 1.6 21:16:35 INFO - blobuploader == 1.2.4 21:16:35 INFO - docopt == 0.6.1 21:16:35 INFO - functools32 == 3.2.3-2 21:16:35 INFO - jsonschema == 2.5.1 21:16:35 INFO - manifestparser == 1.1 21:16:35 INFO - mozInstall == 1.12 21:16:35 INFO - mozcrash == 1.0 21:16:35 INFO - mozdebug == 0.1 21:16:35 INFO - mozdevice == 0.49 21:16:35 INFO - mozfile == 1.2 21:16:35 INFO - mozhttpd == 0.7 21:16:35 INFO - mozinfo == 0.9 21:16:35 INFO - mozleak == 0.1 21:16:35 INFO - mozlog == 3.4 21:16:35 INFO - moznetwork == 0.27 21:16:35 INFO - mozprocess == 0.23 21:16:35 INFO - mozprofile == 0.28 21:16:35 INFO - mozrunner == 6.13 21:16:35 INFO - mozscreenshot == 0.1 21:16:35 INFO - mozsystemmonitor == 0.3 21:16:35 INFO - moztest == 0.7 21:16:35 INFO - mozversion == 1.4 21:16:35 INFO - psutil == 3.1.1 21:16:35 INFO - requests == 1.2.3 21:16:35 INFO - wsgiref == 0.1.2 21:16:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:35 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:35 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:35 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:35 INFO - Using env: {'DISPLAY': ':0', 21:16:35 INFO - 'HOME': '/home/cltbld', 21:16:35 INFO - 'LANG': 'en_US.UTF-8', 21:16:35 INFO - 'LANGUAGE': 'en_US:en', 21:16:35 INFO - 'LOGNAME': 'cltbld', 21:16:35 INFO - 'MAIL': '/var/mail/cltbld', 21:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:35 INFO - 'MOZ_NO_REMOTE': '1', 21:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:35 INFO - 'NO_EM_RESTART': '1', 21:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:35 INFO - 'PWD': '/builds/slave/test', 21:16:35 INFO - 'SHELL': '/bin/bash', 21:16:35 INFO - 'SHLVL': '1', 21:16:35 INFO - 'TERM': 'linux', 21:16:35 INFO - 'TMOUT': '86400', 21:16:35 INFO - 'USER': 'cltbld', 21:16:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:35 INFO - '_': '/tools/buildbot/bin/python'} 21:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Cleaning up... 21:16:35 INFO - Return code: 0 21:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:16:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:35 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 0xc5e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe2aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfcfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', '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:35 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:35 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:35 INFO - Using env: {'DISPLAY': ':0', 21:16:35 INFO - 'HOME': '/home/cltbld', 21:16:35 INFO - 'LANG': 'en_US.UTF-8', 21:16:35 INFO - 'LANGUAGE': 'en_US:en', 21:16:35 INFO - 'LOGNAME': 'cltbld', 21:16:35 INFO - 'MAIL': '/var/mail/cltbld', 21:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:35 INFO - 'MOZ_NO_REMOTE': '1', 21:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:35 INFO - 'NO_EM_RESTART': '1', 21:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:35 INFO - 'PWD': '/builds/slave/test', 21:16:35 INFO - 'SHELL': '/bin/bash', 21:16:35 INFO - 'SHLVL': '1', 21:16:35 INFO - 'TERM': 'linux', 21:16:35 INFO - 'TMOUT': '86400', 21:16:35 INFO - 'USER': 'cltbld', 21:16:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:35 INFO - '_': '/tools/buildbot/bin/python'} 21:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:16:35 INFO - Cleaning up... 21:16:35 INFO - Return code: 0 21:16:35 INFO - Running post-action listener: _resource_record_post_action 21:16:35 INFO - Running post-action listener: _start_resource_monitoring 21:16:35 INFO - Starting resource monitoring. 21:16:35 INFO - [mozharness: 2016-12-23 05:16:35.555065Z] Finished create-virtualenv step (success) 21:16:35 INFO - [mozharness: 2016-12-23 05:16:35.555546Z] Running install step. 21:16:35 INFO - Running pre-action listener: _resource_record_pre_action 21:16:35 INFO - Running main action method: install 21:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:35 INFO - Reading from file tmpfile_stdout 21:16:35 INFO - Detecting whether we're running mozinstall >=1.0... 21:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:16:35 INFO - Reading from file tmpfile_stdout 21:16:35 INFO - Output received: 21:16:35 INFO - Usage: mozinstall [options] installer 21:16:35 INFO - Options: 21:16:35 INFO - -h, --help show this help message and exit 21:16:35 INFO - -d DEST, --destination=DEST 21:16:35 INFO - Directory to install application into. [default: 21:16:35 INFO - "/builds/slave/test"] 21:16:35 INFO - --app=APP Application being installed. [default: firefox] 21:16:35 INFO - mkdir: /builds/slave/test/build/application 21:16:35 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:35 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:53 INFO - Reading from file tmpfile_stdout 21:16:53 INFO - Output received: 21:16:53 INFO - /builds/slave/test/build/application/firefox/firefox 21:16:53 INFO - Running post-action listener: _resource_record_post_action 21:16:53 INFO - [mozharness: 2016-12-23 05:16:53.225823Z] Finished install step (success) 21:16:53 INFO - [mozharness: 2016-12-23 05:16:53.226133Z] Running run-tests step. 21:16:53 INFO - Running pre-action listener: _resource_record_pre_action 21:16:53 INFO - Running main action method: run_tests 21:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:53 INFO - Python 2.7.3 21:16:53 INFO - Return code: 0 21:16:53 INFO - grabbing minidump binary from tooltool 21:16:53 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:53 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 0xfcd3f0>, '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 0xfcdcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:53 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:53 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:53 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:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:53 INFO - Return code: 0 21:16:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:16:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:16:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --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-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:16:53 INFO - Using env: {'DISPLAY': ':0', 21:16:53 INFO - 'HOME': '/home/cltbld', 21:16:53 INFO - 'LANG': 'en_US.UTF-8', 21:16:53 INFO - 'LANGUAGE': 'en_US:en', 21:16:53 INFO - 'LOGNAME': 'cltbld', 21:16:53 INFO - 'MAIL': '/var/mail/cltbld', 21:16:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:53 INFO - 'MOZ_NO_REMOTE': '1', 21:16:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:53 INFO - 'NO_EM_RESTART': '1', 21:16:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:53 INFO - 'PWD': '/builds/slave/test', 21:16:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:53 INFO - 'SHELL': '/bin/bash', 21:16:53 INFO - 'SHLVL': '1', 21:16:53 INFO - 'TERM': 'linux', 21:16:53 INFO - 'TMOUT': '86400', 21:16:53 INFO - 'USER': 'cltbld', 21:16:53 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274', 21:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:53 INFO - '_': '/tools/buildbot/bin/python'} 21:16:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:16:53 INFO - mozversion application_buildid: 20161222193138 21:16:53 INFO - mozversion application_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 21:16:53 INFO - mozversion application_display_name: Nightly 21:16:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:53 INFO - mozversion application_name: Firefox 21:16:53 INFO - mozversion application_remotingname: firefox 21:16:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:16:53 INFO - mozversion application_vendor: Mozilla 21:16:53 INFO - mozversion application_version: 53.0a1 21:16:53 INFO - mozversion platform_buildid: 20161222193138 21:16:53 INFO - mozversion platform_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 21:16:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:16:53 INFO - mozversion platform_version: 53.0a1 21:16:53 INFO - using testdate: 1482470213 21:16:53 INFO - actual date: 1482470213 21:16:53 INFO - starting webserver on 'localhost:50803' 21:16:53 INFO - SUITE-START | Running 2 tests 21:16:53 INFO - TEST-START | dromaeo_css 21:16:53 INFO - operating with platform_type : linux_ 21:16:53 INFO - Initialising browser for dromaeo_css test... 21:16:53 INFO - TEST-INFO | started process 10999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgy_uPd/profile http://localhost:50803/getInfo.html) 21:16:53 INFO - PROCESS | 10999 | 1482470213963 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:56 INFO - PROCESS | 10999 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:57 INFO - PROCESS | 10999 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:57 INFO - PROCESS | 10999 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:16:57 INFO - PROCESS | 10999 | __metrics Screen width/height:1600/1200 21:16:57 INFO - PROCESS | 10999 | colorDepth:24 21:16:57 INFO - PROCESS | 10999 | Browser inner width/height: 1024/697 21:16:57 INFO - PROCESS | 10999 | __metrics 21:16:58 ERROR - PROCESS | 10999 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 21:16:59 INFO - TEST-INFO | 10999: exit 0 21:16:59 INFO - Browser initialized. 21:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:16:59 INFO - TEST-INFO | started process 11123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgy_uPd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:17:11 INFO - PROCESS | 11123 | RSS: Main: 154800128 21:17:11 INFO - PROCESS | 11123 | 21:18:00 INFO - PROCESS | 11123 | 1482470280940 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:11 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50803/tests/dromaeo/cssquery-ext.html) 21:20:12 INFO - PROCESS | 11123 | RSS: Main: 161992704 21:20:12 INFO - PROCESS | 11123 | 21:21:59 INFO - PROCESS | 11123 | ************************* 21:21:59 INFO - PROCESS | 11123 | A coding exception was thrown and uncaught in a Task. 21:21:59 INFO - PROCESS | 11123 | 21:21:59 INFO - PROCESS | 11123 | Full message: ReferenceError: fetch is not defined 21:21:59 INFO - PROCESS | 11123 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:21:59 INFO - PROCESS | 11123 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:21:59 INFO - PROCESS | 11123 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:21:59 INFO - PROCESS | 11123 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:21:59 INFO - PROCESS | 11123 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:21:59 INFO - PROCESS | 11123 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:21:59 INFO - PROCESS | 11123 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:21:59 INFO - PROCESS | 11123 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:21:59 INFO - PROCESS | 11123 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:21:59 INFO - PROCESS | 11123 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:21:59 INFO - PROCESS | 11123 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:21:59 INFO - PROCESS | 11123 | 21:21:59 INFO - PROCESS | 11123 | ************************* 21:23:14 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-ext.html (next: http://localhost:50803/tests/dromaeo/cssquery-jquery.html) 21:23:15 INFO - PROCESS | 11123 | RSS: Main: 159612928 21:23:15 INFO - PROCESS | 11123 | 21:26:17 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50803/tests/dromaeo/cssquery-mootools.html) 21:26:18 INFO - PROCESS | 11123 | RSS: Main: 164970496 21:26:18 INFO - PROCESS | 11123 | 21:29:20 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50803/tests/dromaeo/cssquery-prototype.html) 21:29:20 INFO - PROCESS | 11123 | RSS: Main: 159870976 21:29:20 INFO - PROCESS | 11123 | 21:32:23 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50803/tests/dromaeo/cssquery-yui.html) 21:32:24 INFO - PROCESS | 11123 | RSS: Main: 163631104 21:32:24 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | Cycle 1(1): loaded http://localhost:50803/tests/dromaeo/cssquery-yui.html (next: http://localhost:50803/tests/dromaeo/cssquery-dojo.html) 21:35:27 INFO - PROCESS | 11123 | RSS: Main: 163319808 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | __start_tp_report 21:35:27 INFO - PROCESS | 11123 | _x_x_mozilla_page_load 21:35:27 INFO - PROCESS | 11123 | _x_x_mozilla_page_load_details 21:35:27 INFO - PROCESS | 11123 | |i|pagename|runs| 21:35:27 INFO - PROCESS | 11123 | |0;dojo.html;3161.8975377521374;3177.571027911232;3179.459491886379;3196.5045417960196;3215.1158431431354;5240.2401651760565;5318.0693378658725;5350.438203988579;5364.731763411828;5379.67721936684;5259.631825772198;5280.551153151977;5347.6256662033675;5355.705436201008;5388.137897936331;4787.904241915159;4818.060478206751;4860.562549370556;4936.210206366982;4958.281049247857;4779.569838714515;4834.685745426536;4932.704037757746;4936.407631084275;4950.455449900526;4763.571278584944;4952.455229924705;4965.180745177077;4992.725400102993;5066.18940969445;4781.163296423112;4795.928061079087;4817.9518204818205;4836.733979631119;4943.6539442239055;4816.133096042711;4822.421309442854;4824.710517368934;4826.61387089032;4852.272159176152;5278.4721527846905;5288.206768984644;5305.0450918834595;5315.760790764425;5334.226537152092;5537.667739935617;5567.638103523299;5657.8019769308285;5709.65742055478;5720.71396430181;5777.682227477487;5778;5782.190493330937;5790.971045144789;5857.85355366115;5391.406945236021;5392.948375066824;5405.864853378657;5408.459154084639;5408.621396502246;5587.944120558824;5666.4050274721385;5703.885922281531;5723.6279641823985;5905.261842269716;5532.3914369419335;5557.444255574491;5559.082751346064;5584.91622625661;5596.888062238732;5600.215969764155;5614.129809879475;5630.8029218977135;5644;5653.180288858175;5549.19536667181;5577.470140336662;5621.578381621394;5625.212470254168;5660.943390566124;5713.857153571111;5719.227904232958;5723.112917497794;5732.197492351074;5774.354309021908;4293.74237545742;4308.9353659695125;4311.611954924072;4312.094460163338;4346.695731298779;3776.376897811885;3796.2976849282973;3805.9238815224094;3840.423936409561;3884.553276373253;5433.592480563973;5440.809571664972;5443.482869127427;5482.972585137049;5490.725463726798;5737.655740655574;5756.510696590753;5764.020116580192;5776.56675749312;5789.913151302734;5297.311349524537;5329.25390445338;5352.090144675414;5438.293021907206;5444.8094316698725;4715.552022557851;4780.521947805261;4784.138855006132;4817.807287708522;4832.106060378841;5985.431384018511;5989.221401217814;5991.101334799728;6008.66952317618;6020.668863212565;5606.523445507178;5668.2064510967675;5682.659040457585;5722.113072473773;5742.913856292075;5947.583669143119;5949.018411962151;5950.672713000827;5994.6403215807195;6070.028795392716;5609.635373700697;5642.097264437834;5652.773889044557;5660.20757093999;5667.659940403589;5567.331920169606;5764.07774756037;5770.076787713946;5779.335376431596;5784.50831679312;5469.234307197075;5508.256385388082;5569.860753481195;5592.133219351007;5669.603127781016;5649.435056494318;5650.519705824887;5653.773849045992;5663.32040155184;5666.15007748841;5426.891462170814;5450.400455949922;5569.498745112959;5602;5635.464630860061;5483.177523371526;5567.387587365468;5576.1356989666665;5634.154876768517;5675.31896172462;5443.156310771837;5484.1773733938635;5486.698231597141;5495.505404513613;5499.725013749377 21:35:27 INFO - PROCESS | 11123 | |1;ext.html;526.7366316841579;534.3507638219562;563.9774409023639;565.1579147070864;566.0659911146607;39782.60217397825;43021.56978430215;43066;43146.56853431465;46551.30173047407;44120.779396103055;44414;44485.332720009224;44618;44626.107477850426;8906.91093089069;9047.185753282203;9048.77378065547;9064.954675226647;10121.848172277421;10118.13995810357;10430.061294483496;10462.476876156199;10463.947680261588;10478.423686697228;10456.372617642966;10495.107915827164;10504.579816807318;10521.210909181804;10527.789444211112;10320.948395258014;10351.27541072129;10363.92721455712;10375.584976600927;10401.323913945645;10262.9486852566;10269;10272.640457583948;10322.690319290357;10368.222383321207;38462;38508.031255155496;38756.64351747704;38817.417738733944;38849.223015539814;3388.15296175956;3414.778039427454;3426.554547908781;3437.295354452331;3516.0682419158943;28567.857160714062;28617.427651447062;29164.562531562042;29318.560221596694;29461.41077178466;4296.269634162169;4328.523862375137;4352.412424322708;4479.529649386833;4487.259602165639;356544;358901;361372.19313903525;362439;362698;53335.19997200045;53489.732551336994;53607.19589206165;53781.27734033246;53871;55876.72061639747;55941.440585594435;56096;56408.43591564114;56488.71755641195;14372.209528475825;14487.85512144865;14623;14624.926875365554;14625.26873656313;8497.74506764798;8651.740447786577;10712.571497140027;10887.945560272148;11043.83434248487;16584.336626535045;16693.33226670944;16742.916285418738;16748.99506029642;16860.494185174466;6454.967725161344;6518.837029074311;6564.409203171642;6615.503837212228;6739.730410783513;930.5393830054167;974.8196583632051;1011.489197955028;1043.3218408034838;1046.638909576195;31695.52456713105;31865.362692746014;32072.839635801673;32133.839330803665;32783.180420489676;1064.350746044912;1070.4166229404993;1076.7240819628764;1101.730076131353;1113.7828123516;15507.703289512765;16387.918060409622;16508.33966641345;16566.917165414096;16580.450587201398;494641;495327;496065;499620;502395;60297.69851150716;60479;60646.69676651589;60708.392916071156;61231.6938415314;1345.2264947654942;1350.3113412159869;1379.35170469879;1388.9027768056342;1390.0755997261708;29692.40615187641;31237.68762312348;31304.373912521165;31431.52852707257;31978.68021319757;51616.74191629018;51791.48208518017;51980.48019519756;52051;52115.21827172519;413684;416122;416399;416715;417879;23824.52350953006;24457.75542244555;24524;27797.583036254877;28290;40272.44619038428;40402.39396409115;41132.38301425541;41288.58711412847;41757.58242417537;34752;34796.65203348036;35052.29895402027;35088.824555878076;35774.284514310086;34839;34910.65089349074;35341.46987795237;35344;35530.82234588914;34942;35406.645933540334;35497.645023549434;35580.82209588936;35852.8207358972;30215.395692086477;32617.18457038593;32683.509747354285;32745.50881736728;32813;24762.25713228534;24969.37576560528;25169.49661006733;25335;25358 21:35:27 INFO - PROCESS | 11123 | |2;jquery.html;382.3538220407511;388.7259482065144;389.41314144859706;393.17629566059105;409.823775776416;14645.853541464583;17194.39819606314;17645.470635880927;19192.616147677054;19219.23123075079;17137.314507419687;17487.650246995054;17878.91060544699;18552;18821.247150114013;5578;5602.1036634138545;5745.913811292834;5786.56600754943;5877.265341832271;5676.2620859288445;5801.680907550083;5808.651480911138;5817.127430885359;5823.708814559276;5488.4785945335125;5698.914516282259;5741.483266506014;5744.6840423776885;5765.106408506688;5726.255586773735;5780.71096445176;5782.392848750884;5789.739461724233;5795.1886735857015;4983.526564976321;5132.538071573539;5143.897122057575;5169.043726910496;5171.327727395452;21514.462138446506;22090.668639970412;22122.557548849094;22198.667019994715;22443;2208.1829723002593;2338.2166974063707;2656.3093595665073;2718.5242582547976;2912.0681381957784;9917.702468925943;9957.153641940444;10295.845562316572;10343.94828025855;10612.734681632945;1890.905454727258;1977.5451646121473;1997.8501612378984;2005.7793642699291;2011.336259034515;78059;78465.60767196184;80275.19724802794;80935.19064809335;81137.59431202806;10567;10765.416300087922;10774.515146818412;10779.461026948622;10922.781544369069;14294;14475.27623618836;14509.85490145106;14611.92694036523;14628.853711462962;7484.47608667399;7493.887591686129;7672.846543069108;7765.339946104531;7798.727044553413;7058.752943646948;7069.8939515907305;7164.534305270141;7295.234000429985;7337.449691273176;4556.3621093047;4564.52072532386;4609.492955774862;4651.511516314815;4734.834280800224;1978.8911609861595;1989.890556019404;1996.1815655581433;2030.7357296614168;2047.754269487671;868.769776009358;868.9919693155908;926.7497775600685;1000.799840031982;1055.5883205549842;17939.820601794076;18239.63520729578;18708.345207917657;18825.717614235797;18837.717434238497;922.6678395777515;956.9760755981156;972.7820569102457;1007.4106647611229;1009.3287963504334;7970.800729981681;10268.845967310497;10274.691759247235;10464.06414813262;10508;426952;429828;430386;433259;433955;60621.39378606246;74140;86548;87265.56367218123;89637.55181224183;1009.7677534167183;1025.3847691385108;1053.4995876958383;1066.8613080299667;1069.850220969065;16580.419685310957;16769.74845377345;16816.747748783535;17007.404740834012;17095.914520427817;37706.62293377031;38320.6167938317;38486.2302753949;38713.612863871;39238.80380598079;52763;53060;53830;55475.72262138664;56430.43569564416;8365.33077353798;8373.748787536384;8420.284275836622;8500.53247071417;8523.573821308786;11012;12810;12880.742385152433;12904.677383065498;13236.801447978482;10981.945090274497;11178.161637877029;11241.437928103398;11582.015528680156;11657.766844663229;10765.838512422477;10775.137988960707;10803.837942431028;10871.130309575374;11188.272762270426;10977.506012229629;11016.614418495636;11057.612983545538;11856.170068095496;11877.703057423634;8203.220694034057;8375.62309696053;8547.95726021366;8555.187257210555;8593.484390936565;8279.917200828155;11214;11223.821498742514;11228.775424491301;11461.140414468782 21:35:27 INFO - PROCESS | 11123 | |3;mootools.html;1392.470861072792;1426.0730525158644;1547.2650491016768;1697.906615136167;1751.4745576327098;7973.282404583586;8237.299829514499;8389.748307550783;8476.152384761528;8482.109378515259;8365.958170209156;8546.188112129354;8550.65797368106;8559.828803423929;8579.49858774715;2211.4250294923354;2276.8178545716305;2278.8063014643776;2284.5088306014204;2285.5771682238837;2299.4596269876565;2326.61610834213;2344.5229505411567;2352.0591763294733;2355.729091154522;2241.12596087526;2270.182734215681;2288.2791920544973;2294.19703103914;2339.122828939148;2201.768814274494;2225.688403623494;2231;2234.8770817605027;2245.113180293791;2289.473421113153;2299.1837897546466;2304.2165663674264;2305.0549274797254;2312.791848733605;8368.037675667316;8477.279431248351;8509.659613615448;8518.190771876723;8564.957175214084;4905.779239934212;5007.223880298559;5021.347224860781;5023.673461225045;5024.949750502485;3135.8588863501195;3203.423383790917;3394.100563350714;3431.4681224410256;3438.1232785639754;1008.3193844155165;1020.3316827478013;1044.5821671331453;1049.3336731175682;1051.9368837869677;10968.122550196045;11233.943830280878;11252.549898004152;11659.650210493699;11792.469338879857;4197.958020419818;4301.720388174758;4385.6710746694125;4446.42196514451;4464.218761716752;9126.680566180152;9145.81708365829;9149.634014639472;9165.58754856033;9170;4991.3511243538105;4993.925091123636;4994.250862370601;5000.524950129732;5002.424721157114;4451.221036318631;4564.269716845355;4611.077784443125;4633.62930965522;4712.835050773207;3537.9469307960403;3621.2395396966403;3634.5093412388987;3658.908527286839;3684.797336146538;1087.6465148826662;1106.1261603333437;1107.8423047914948;1112.8831472695413;1115.8716747573972;909.6361455417754;927.7077720518015;940.6032042417005;962.5043102802063;975.2539307429822;5424.6473979192015;5586.273784408001;5647.08916508781;5834.03738383162;5953.255843019622;417.68464809069565;422.11777385264946;422.88582082838076;429.3302448180848;439.5275079289776;3803.54357477099;3951.980240098781;4003.1193137510286;4019.256437559061;4064.9593504064583;9985.60057597688;10235.43705096212;10255.743606409751;10268.486575671186;10283.53724082418;10096.14182794456;10124.69625911224;10125.392476451289;10148.644797432054;10172.389656620626;7376.889346659805;7635.083789945242;7665.846683066307;7809.80475488106;7875.921240787518;5592.933771564437;5686.829395118153;5701.40135285789;5719.8856022880145;5813.738381772915;9174.128457796498;9207.67773127964;9211.631534738803;9275.76810579741;9286.860697089414;9440.858387124335;9467.337286389886;9530.714078577654;9605.711828645151;9621.134097931219;6841.3842754152365;6905.309469053054;7044.964775176091;7152.785416437515;7182.96408517954;7844.764657060297;7909.881351779843;7930.9603451982375;7931.524108553274;7932.04815422153;6876.828079298058;6885.51801373899;6920.238773734985;6938.132733408324;7084.866421372556;6471.4499267560905;7073.469489788389;7096.7516136934955;7099.396551292992;7199.424046076406;6123.203983482119;6183;6219.40915613016;6246;6246.437820596168;4564.315352697122;4577.313402989578;4645.767711614338;4687.343771872009;4725.125851717443;4751.574527641625;4751.786169622306;4761.666683332133;4803.543663351976;4880 21:35:27 INFO - PROCESS | 11123 | |4;prototype.html;369.73563901810206;371.6376532880442;377.6525596451265;378.23407599610766;383.3062157494936;5124.538791508773;5244.921326180106;5273.472652734725;5278.155495120776;5289.418164001966;5138.691678499284;5248.239005344223;5257.842264732055;5296.894062118756;5319.813806516772;4000.6599439047573;4110.671146308303;4126.595558664114;4247.638950689187;4253.1281087377165;4136.9864383226;4142.565030671782;4158.480189976253;4162.271602469571;4166.541680415167;4076.4916980717176;4094.7338423002557;4104.958950410502;4122.525909520398;4128.21563902859;3937.98031009846;4129.607687269705;4130.793460326987;4135.524414692319;4170.291050521419;3621.5292012038267;3631.564212294515;3631.963680363189;3644.6173151819207;3645.908852278688;5171.301874246966;5172.405173405069;5193.662411943249;5198.2722418861385;5199.168133098724;4709.364235828154;4724.244120940667;4732.337472753818;4761.428628564559;4770.284457331428;5318.6969192547685;5531.3915469298345;5547.833564993016;5551.639143455663;5565.220869078333;4007.7996100194873;4031.7623718836035;4040.636342729139;4045.3122969095325;4049.6962727795535;5583.553315734772;5828.184054232411;5837.737301821428;5844.590878638497;5855.8536036599;4066.186762647423;4099.959000410017;4116.259073366763;4117.526484454326;4121.340585506305;4159.854405095868;4168.708190426702;4188.392683060939;4199.874003779892;4207.558206388347;4703.764811759398;4733.9053218936115;4745.976270118696;4777.474477807438;4804.183288840905;5341.679499230058;5511.476409741068;5524.613277070566;5540.307461567304;5573.442655734394;1361.918284902909;1377.7175678985784;1379.2414172205247;1383.4120498787975;1385.9792103118461;940.4404379394249;970.6408628807245;973.1873885305849;976.4824642939253;986.5511192407438;5131.666441681279;5133.5379815816;5140.537351638371;5150.124478838607;5249.740062385076;5518.448155184449;5534.501894829485;5556.332820338557;5564.554835613141;5621.381648018714;4977.477364876709;5022.146235140035;5157.277981082577;5202.739863006835;5247.2129180623215;2928.633920759905;2940.4413161499247;2941.3234955960247;2949.896753613656;2953.7784666150123;5779.711014449261;5794.62334948227;5796.536277097822;5798.941375811611;5833.241678581842;5312.787488500417;5493.368262649767;5521.613487055948;5608.579356548275;5633.929508417922;5801.332846722512;5808.622439541501;5822.3304320003745;5833.562482813806;5858.238429004287;5272.7100009499845;5452.454754524516;5493.890122197614;5508.063629183129;5554.472325129106;5415.702136382379;5426.565874730091;5435.124881915733;5444.401115877171;5454.236406902957;5738.225339579103;5760.6255593386295;5765.94234057654;5771.394003629726;5823.388544202798;3914.2227750221173;3915.275674000319;3985.9636494511055;3991.5409727881665;4030.41558973953;5507.917381239365;5514.476124768141;5547.972260138674;5559.527440167631;5654.532918994113;5500.257465242301;5602.579806514446;5616.360310653986;5620.747066382104;5654.886902262014;5601.243832082617;5629.31120663791;5633.690147041952;5642.717864106696;5657.32112146545;5368.624196306221;5540.833774986757;5575.0208675921085;5581.488330251322;5608.943910561005;5624.85937851557;5640.210370548045;5642.266505354278;5650.23721797552;5727.713614319351;5425.728713564385;5469.535089517276;5566.248556444828;5574.86062848432;5586.301712285965 21:35:27 INFO - PROCESS | 11123 | |5;yui.html;737.0381651944214;768.4275214964848;784.6665167303895;792.1405275276325;833.804056046829;12058.216215945957;12181.025517958564;12490.250584964897;14439.927800360983;14711.852881471183;11764;11772.47023883925;11778.175527713041;11795.233309834855;11981.341026243554;785.4855069929191;804.5092493578912;808.0505406147782;809.550699361854;809.7975506123469;769.0502229746287;797.9800504987393;800.3197282310048;800.9479383840061;802.4022103532876;838.3460900497622;840.306746933782;840.3613253927028;841.9368547358941;848.0713618587667;835.4945258118811;837.8491871463134;839.4291881520563;840.3487297344576;841.2176675691604;620.1256228717532;624.2446639566131;624.8031869960947;625.5808608232495;628.8647940692773;13580.38888250031;13776.65558361049;13809.792853107212;13810.861891381059;14181.858181418156;14276;14480.782788258184;14814.111153330834;14817;15058.849411505855;11436.771264574661;11465.484053217626;11500.137489688306;11695.590654327058;11899.107566932427;3567.500549922987;3621.3300539400293;3640.053586067615;3809.942850857239;3817.8854634361014;77682.22317776806;78948;78997.21002789956;79038.60480697674;82583.58708206481;10233.9488302558;10522.526486308134;10690.78618427627;10877.238593298393;10979;20906.581868362548;21036.789632103788;21044.579108417747;21078.578428431345;21092.578148437253;7680.9615951921;7698;7739.148693643695;7747.961260193663;8079.4344395892895;4152.335626299778;4284.485861696617;4355.237833379146;4360.411344468456;4456.844010459683;3334.983325083359;3392.542006829096;3406.0803583032443;3409.931801363959;3422.19578399076;548.8737590354241;574.643720893049;582.7931084465039;586.3989410853824;609.7500024989822;570.6775671745451;601.2964831147568;620.2649859916014;635.0918186992552;637.1844039629282;7921.207879212033;8777.736667899973;9244.7688807779;9305.90694093064;9419;601.8675891303906;621.4003486635419;672.3847679373416;673.245964519741;703.8662654095808;4131.772752498641;4423.623991960655;4542.409486766699;4598.1033698429155;4624.768761561909;57465.42534574601;57518.7124064377;69093;74463;74615;51164.1394067563;56865;59612.70193649091;63958.6802065996;66311.66844165845;58395.70802145962;58658.41341586615;59083.70458147682;59348.10977835336;59374;8872;8998.730038098867;9055.80696806432;9063.093690630883;9332.253419726541;38702.22595548017;39351.80324098361;39771.2045759089;39830.402543962446;40855.1828963425;50245;50546.74726626343;54727;55174.17238741503;55837;10911.508982095665;11434.94282528582;11458.541658333574;11828.825391792185;12334.81497777516;12468.501259949864;12498.43757030954;12580.874191258337;12683;12720.300383478998;11475.540978360772;11797.941010295237;12076.21504602198;12548.372581370711;12625.810612840998;11547.653570392902;11662.685069756624;11874.228175168586;12340.629781106221;12426.440810163745;10457.372557646566;10457.372557646566;10487.475626218811;11045.668629941114;12350.567730129402;9575.664851730156;9604.183644390305;9769.755756106155;9784;10055.29612927088;9939.503024848584;10338.793224135625;10443.164546836388;10521.368717876645;10562.31344962575 21:35:27 INFO - PROCESS | 11123 | __end_tp_report 21:35:27 INFO - PROCESS | 11123 | __start_cc_report 21:35:27 INFO - PROCESS | 11123 | _x_x_mozilla_cycle_collect,321 21:35:27 INFO - PROCESS | 11123 | __end_cc_report 21:35:27 INFO - PROCESS | 11123 | __startTimestamp1482471327295__endTimestamp 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | ------- Summary: start ------- 21:35:27 INFO - PROCESS | 11123 | Number of tests: 6 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#0] dojo.html Cycles:165 Average:5302.84 Median:5495.51 stddev:602.94 (11.0%) stddev-sans-first:580.93 21:35:27 INFO - PROCESS | 11123 | Values: 3161.9 3177.6 3179.5 3196.5 3215.1 5240.2 5318.1 5350.4 5364.7 5379.7 5259.6 5280.6 5347.6 5355.7 5388.1 4787.9 4818.1 4860.6 4936.2 4958.3 4779.6 4834.7 4932.7 4936.4 4950.5 4763.6 4952.5 4965.2 4992.7 5066.2 4781.2 4795.9 4818.0 4836.7 4943.7 4816.1 4822.4 4824.7 4826.6 4852.3 5278.5 5288.2 5305.0 5315.8 5334.2 5537.7 5567.6 5657.8 5709.7 5720.7 5777.7 5778.0 5782.2 5791.0 5857.9 5391.4 5392.9 5405.9 5408.5 5408.6 5587.9 5666.4 5703.9 5723.6 5905.3 5532.4 5557.4 5559.1 5584.9 5596.9 5600.2 5614.1 5630.8 5644.0 5653.2 5549.2 5577.5 5621.6 5625.2 5660.9 5713.9 5719.2 5723.1 5732.2 5774.4 4293.7 4308.9 4311.6 4312.1 4346.7 3776.4 3796.3 3805.9 3840.4 3884.6 5433.6 5440.8 5443.5 5483.0 5490.7 5737.7 5756.5 5764.0 5776.6 5789.9 5297.3 5329.3 5352.1 5438.3 5444.8 4715.6 4780.5 4784.1 4817.8 4832.1 5985.4 5989.2 5991.1 6008.7 6020.7 5606.5 5668.2 5682.7 5722.1 5742.9 5947.6 5949.0 5950.7 5994.6 6070.0 5609.6 5642.1 5652.8 5660.2 5667.7 5567.3 5764.1 5770.1 5779.3 5784.5 5469.2 5508.3 5569.9 5592.1 5669.6 5649.4 5650.5 5653.8 5663.3 5666.2 5426.9 5450.4 5569.5 5602.0 5635.5 5483.2 5567.4 5576.1 5634.2 5675.3 5443.2 5484.2 5486.7 5495.5 5499.7 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#1] ext.html Cycles:180 Average:57544.56 Median:34997.15 stddev:113511.09 (324.3%) stddev-sans-first:113748.80 21:35:27 INFO - PROCESS | 11123 | Values: 526.7 534.4 564.0 565.2 566.1 39782.6 43021.6 43066.0 43146.6 46551.3 44120.8 44414.0 44485.3 44618.0 44626.1 8906.9 9047.2 9048.8 9065.0 10121.8 10118.1 10430.1 10462.5 10463.9 10478.4 10456.4 10495.1 10504.6 10521.2 10527.8 10320.9 10351.3 10363.9 10375.6 10401.3 10262.9 10269.0 10272.6 10322.7 10368.2 38462.0 38508.0 38756.6 38817.4 38849.2 3388.2 3414.8 3426.6 3437.3 3516.1 28567.9 28617.4 29164.6 29318.6 29461.4 4296.3 4328.5 4352.4 4479.5 4487.3 356544.0 358901.0 361372.2 362439.0 362698.0 53335.2 53489.7 53607.2 53781.3 53871.0 55876.7 55941.4 56096.0 56408.4 56488.7 14372.2 14487.9 14623.0 14624.9 14625.3 8497.7 8651.7 10712.6 10887.9 11043.8 16584.3 16693.3 16742.9 16749.0 16860.5 6455.0 6518.8 6564.4 6615.5 6739.7 930.5 974.8 1011.5 1043.3 1046.6 31695.5 31865.4 32072.8 32133.8 32783.2 1064.4 1070.4 1076.7 1101.7 1113.8 15507.7 16387.9 16508.3 16566.9 16580.5 494641.0 495327.0 496065.0 499620.0 502395.0 60297.7 60479.0 60646.7 60708.4 61231.7 1345.2 1350.3 1379.4 1388.9 1390.1 29692.4 31237.7 31304.4 31431.5 31978.7 51616.7 51791.5 51980.5 52051.0 52115.2 413684.0 416122.0 416399.0 416715.0 417879.0 23824.5 24457.8 24524.0 27797.6 28290.0 40272.4 40402.4 41132.4 41288.6 41757.6 34752.0 34796.7 35052.3 35088.8 35774.3 34839.0 34910.7 35341.5 35344.0 35530.8 34942.0 35406.6 35497.6 35580.8 35852.8 30215.4 32617.2 32683.5 32745.5 32813.0 24762.3 24969.4 25169.5 25335.0 25358.0 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#2] jquery.html Cycles:180 Average:26462.04 Median:19044.49 stddev:71122.89 (373.5%) stddev-sans-first:71295.45 21:35:27 INFO - PROCESS | 11123 | Values: 382.4 388.7 389.4 393.2 409.8 14645.9 17194.4 17645.5 19192.6 19219.2 17137.3 17487.7 17878.9 18552.0 18821.2 5578.0 5602.1 5745.9 5786.6 5877.3 5676.3 5801.7 5808.7 5817.1 5823.7 5488.5 5698.9 5741.5 5744.7 5765.1 5726.3 5780.7 5782.4 5789.7 5795.2 4983.5 5132.5 5143.9 5169.0 5171.3 21514.5 22090.7 22122.6 22198.7 22443.0 2208.2 2338.2 2656.3 2718.5 2912.1 9917.7 9957.2 10295.8 10343.9 10612.7 1890.9 1977.5 1997.9 2005.8 2011.3 78059.0 78465.6 80275.2 80935.2 81137.6 10567.0 10765.4 10774.5 10779.5 10922.8 14294.0 14475.3 14509.9 14611.9 14628.9 7484.5 7493.9 7672.8 7765.3 7798.7 7058.8 7069.9 7164.5 7295.2 7337.4 4556.4 4564.5 4609.5 4651.5 4734.8 1978.9 1989.9 1996.2 2030.7 2047.8 868.8 869.0 926.7 1000.8 1055.6 17939.8 18239.6 18708.3 18825.7 18837.7 922.7 957.0 972.8 1007.4 1009.3 7970.8 10268.8 10274.7 10464.1 10508.0 426952.0 429828.0 430386.0 433259.0 433955.0 60621.4 74140.0 86548.0 87265.6 89637.6 1009.8 1025.4 1053.5 1066.9 1069.9 16580.4 16769.7 16816.7 17007.4 17095.9 37706.6 38320.6 38486.2 38713.6 39238.8 52763.0 53060.0 53830.0 55475.7 56430.4 8365.3 8373.7 8420.3 8500.5 8523.6 11012.0 12810.0 12880.7 12904.7 13236.8 10981.9 11178.2 11241.4 11582.0 11657.8 10765.8 10775.1 10803.8 10871.1 11188.3 10977.5 11016.6 11057.6 11856.2 11877.7 8203.2 8375.6 8548.0 8555.2 8593.5 8279.9 11214.0 11223.8 11228.8 11461.1 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#3] mootools.html Cycles:180 Average:5413.20 Median:4756.73 stddev:3049.74 (64.1%) stddev-sans-first:3043.33 21:35:27 INFO - PROCESS | 11123 | Values: 1392.5 1426.1 1547.3 1697.9 1751.5 7973.3 8237.3 8389.7 8476.2 8482.1 8366.0 8546.2 8550.7 8559.8 8579.5 2211.4 2276.8 2278.8 2284.5 2285.6 2299.5 2326.6 2344.5 2352.1 2355.7 2241.1 2270.2 2288.3 2294.2 2339.1 2201.8 2225.7 2231.0 2234.9 2245.1 2289.5 2299.2 2304.2 2305.1 2312.8 8368.0 8477.3 8509.7 8518.2 8565.0 4905.8 5007.2 5021.3 5023.7 5024.9 3135.9 3203.4 3394.1 3431.5 3438.1 1008.3 1020.3 1044.6 1049.3 1051.9 10968.1 11233.9 11252.5 11659.7 11792.5 4198.0 4301.7 4385.7 4446.4 4464.2 9126.7 9145.8 9149.6 9165.6 9170.0 4991.4 4993.9 4994.3 5000.5 5002.4 4451.2 4564.3 4611.1 4633.6 4712.8 3537.9 3621.2 3634.5 3658.9 3684.8 1087.6 1106.1 1107.8 1112.9 1115.9 909.6 927.7 940.6 962.5 975.3 5424.6 5586.3 5647.1 5834.0 5953.3 417.7 422.1 422.9 429.3 439.5 3803.5 3952.0 4003.1 4019.3 4065.0 9985.6 10235.4 10255.7 10268.5 10283.5 10096.1 10124.7 10125.4 10148.6 10172.4 7376.9 7635.1 7665.8 7809.8 7875.9 5592.9 5686.8 5701.4 5719.9 5813.7 9174.1 9207.7 9211.6 9275.8 9286.9 9440.9 9467.3 9530.7 9605.7 9621.1 6841.4 6905.3 7045.0 7152.8 7183.0 7844.8 7909.9 7931.0 7931.5 7932.0 6876.8 6885.5 6920.2 6938.1 7084.9 6471.4 7073.5 7096.8 7099.4 7199.4 6123.2 6183.0 6219.4 6246.0 6246.4 4564.3 4577.3 4645.8 4687.3 4725.1 4751.6 4751.8 4761.7 4803.5 4880.0 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#4] prototype.html Cycles:180 Average:4644.86 Median:5273.09 stddev:1350.93 (25.6%) stddev-sans-first:1316.06 21:35:27 INFO - PROCESS | 11123 | Values: 369.7 371.6 377.7 378.2 383.3 5124.5 5244.9 5273.5 5278.2 5289.4 5138.7 5248.2 5257.8 5296.9 5319.8 4000.7 4110.7 4126.6 4247.6 4253.1 4137.0 4142.6 4158.5 4162.3 4166.5 4076.5 4094.7 4105.0 4122.5 4128.2 3938.0 4129.6 4130.8 4135.5 4170.3 3621.5 3631.6 3632.0 3644.6 3645.9 5171.3 5172.4 5193.7 5198.3 5199.2 4709.4 4724.2 4732.3 4761.4 4770.3 5318.7 5531.4 5547.8 5551.6 5565.2 4007.8 4031.8 4040.6 4045.3 4049.7 5583.6 5828.2 5837.7 5844.6 5855.9 4066.2 4100.0 4116.3 4117.5 4121.3 4159.9 4168.7 4188.4 4199.9 4207.6 4703.8 4733.9 4746.0 4777.5 4804.2 5341.7 5511.5 5524.6 5540.3 5573.4 1361.9 1377.7 1379.2 1383.4 1386.0 940.4 970.6 973.2 976.5 986.6 5131.7 5133.5 5140.5 5150.1 5249.7 5518.4 5534.5 5556.3 5564.6 5621.4 4977.5 5022.1 5157.3 5202.7 5247.2 2928.6 2940.4 2941.3 2949.9 2953.8 5779.7 5794.6 5796.5 5798.9 5833.2 5312.8 5493.4 5521.6 5608.6 5633.9 5801.3 5808.6 5822.3 5833.6 5858.2 5272.7 5452.5 5493.9 5508.1 5554.5 5415.7 5426.6 5435.1 5444.4 5454.2 5738.2 5760.6 5765.9 5771.4 5823.4 3914.2 3915.3 3986.0 3991.5 4030.4 5507.9 5514.5 5548.0 5559.5 5654.5 5500.3 5602.6 5616.4 5620.7 5654.9 5601.2 5629.3 5633.7 5642.7 5657.3 5368.6 5540.8 5575.0 5581.5 5608.9 5624.9 5640.2 5642.3 5650.2 5727.7 5425.7 5469.5 5566.2 5574.9 5586.3 21:35:27 INFO - PROCESS | 11123 | 21:35:27 INFO - PROCESS | 11123 | [#5] yui.html Cycles:180 Average:16166.13 Median:50395.87 stddev:20805.81 (41.3%) stddev-sans-first:20831.92 21:35:27 INFO - PROCESS | 11123 | Values: 737.0 768.4 784.7 792.1 833.8 12058.2 12181.0 12490.3 14439.9 14711.9 11764.0 11772.5 11778.2 11795.2 11981.3 785.5 804.5 808.1 809.6 809.8 769.1 798.0 800.3 800.9 802.4 838.3 840.3 840.4 841.9 848.1 835.5 837.8 839.4 840.3 841.2 620.1 624.2 624.8 625.6 628.9 13580.4 13776.7 13809.8 13810.9 14181.9 14276.0 14480.8 14814.1 14817.0 15058.8 11436.8 11465.5 11500.1 11695.6 11899.1 3567.5 3621.3 3640.1 3809.9 3817.9 77682.2 78948.0 78997.2 79038.6 82583.6 10233.9 10522.5 10690.8 10877.2 10979.0 20906.6 21036.8 21044.6 21078.6 21092.6 7681.0 7698.0 7739.1 7748.0 8079.4 4152.3 4284.5 4355.2 4360.4 4456.8 3335.0 3392.5 3406.1 3409.9 3422.2 548.9 574.6 582.8 586.4 609.8 570.7 601.3 620.3 635.1 637.2 7921.2 8777.7 9244.8 9305.9 9419.0 601.9 621.4 672.4 673.2 703.9 4131.8 4423.6 4542.4 4598.1 4624.8 57465.4 57518.7 69093.0 74463.0 74615.0 51164.1 56865.0 59612.7 63958.7 66311.7 58395.7 58658.4 59083.7 59348.1 59374.0 8872.0 8998.7 9055.8 9063.1 9332.3 38702.2 39351.8 39771.2 39830.4 40855.2 50245.0 50546.7 54727.0 55174.2 55837.0 10911.5 11434.9 11458.5 11828.8 12334.8 12468.5 12498.4 12580.9 12683.0 12720.3 11475.5 11797.9 12076.2 12548.4 12625.8 11547.7 11662.7 11874.2 12340.6 12426.4 10457.4 10457.4 10487.5 11045.7 12350.6 9575.7 9604.2 9769.8 9784.0 10055.3 9939.5 10338.8 10443.2 10521.4 10562.3 21:35:27 INFO - PROCESS | 11123 | -------- Summary: end -------- 21:35:27 INFO - PROCESS | 11123 | 21:35:28 INFO - TEST-INFO | 11123: exit 0 21:35:28 INFO - TEST-OK | dromaeo_css | took 1114589ms 21:35:28 INFO - TEST-START | kraken 21:35:28 INFO - operating with platform_type : linux_ 21:35:28 INFO - Initialising browser for kraken test... 21:35:28 INFO - TEST-INFO | started process 11491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZYJESF/profile http://localhost:50803/getInfo.html) 21:35:28 INFO - PROCESS | 11491 | 1482471328554 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:31 INFO - PROCESS | 11491 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:31 INFO - PROCESS | 11491 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:32 INFO - PROCESS | 11491 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 21:35:32 INFO - PROCESS | 11491 | __metrics Screen width/height:1600/1200 21:35:32 INFO - PROCESS | 11491 | colorDepth:24 21:35:32 INFO - PROCESS | 11491 | Browser inner width/height: 1024/697 21:35:32 INFO - PROCESS | 11491 | __metrics 21:35:33 ERROR - PROCESS | 11491 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 21:35:34 INFO - TEST-INFO | 11491: exit 0 21:35:34 INFO - Browser initialized. 21:35:34 INFO - Running cycle 1/1 for kraken test... 21:35:34 INFO - TEST-INFO | started process 11617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZYJESF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:46 INFO - PROCESS | 11617 | RSS: Main: 154939392 21:35:46 INFO - PROCESS | 11617 | 21:35:48 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50803/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:49 INFO - PROCESS | 11617 | RSS: Main: 161767424 21:35:49 INFO - PROCESS | 11617 | 21:35:52 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50803/tests/kraken/driver.html?testName=audio-dft) 21:35:52 INFO - PROCESS | 11617 | RSS: Main: 159817728 21:35:52 INFO - PROCESS | 11617 | 21:35:55 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50803/tests/kraken/driver.html?testName=audio-fft) 21:35:56 INFO - PROCESS | 11617 | RSS: Main: 156499968 21:35:56 INFO - PROCESS | 11617 | 21:35:58 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50803/tests/kraken/driver.html?testName=audio-oscillator) 21:35:58 INFO - PROCESS | 11617 | RSS: Main: 156983296 21:35:58 INFO - PROCESS | 11617 | 21:36:01 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50803/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:36:01 INFO - PROCESS | 11617 | RSS: Main: 157396992 21:36:01 INFO - PROCESS | 11617 | 21:36:04 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50803/tests/kraken/driver.html?testName=imaging-darkroom) 21:36:05 INFO - PROCESS | 11617 | RSS: Main: 161624064 21:36:05 INFO - PROCESS | 11617 | 21:36:08 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50803/tests/kraken/driver.html?testName=imaging-desaturate) 21:36:09 INFO - PROCESS | 11617 | RSS: Main: 159744000 21:36:09 INFO - PROCESS | 11617 | 21:36:12 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50803/tests/kraken/driver.html?testName=json-parse-financial) 21:36:12 INFO - PROCESS | 11617 | RSS: Main: 159883264 21:36:12 INFO - PROCESS | 11617 | 21:36:14 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50803/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:36:15 INFO - PROCESS | 11617 | RSS: Main: 159920128 21:36:15 INFO - PROCESS | 11617 | 21:36:16 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:36:17 INFO - PROCESS | 11617 | RSS: Main: 160391168 21:36:17 INFO - PROCESS | 11617 | 21:36:19 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:36:20 INFO - PROCESS | 11617 | RSS: Main: 160292864 21:36:20 INFO - PROCESS | 11617 | 21:36:22 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:36:23 INFO - PROCESS | 11617 | RSS: Main: 160972800 21:36:23 INFO - PROCESS | 11617 | 21:36:26 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:36:27 INFO - PROCESS | 11617 | RSS: Main: 160903168 21:36:27 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:50803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50803/tests/kraken/driver.html?testName=ai-astar) 21:36:28 INFO - PROCESS | 11617 | RSS: Main: 162086912 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | __start_tp_report 21:36:28 INFO - PROCESS | 11617 | _x_x_mozilla_page_load 21:36:28 INFO - PROCESS | 11617 | _x_x_mozilla_page_load_details 21:36:28 INFO - PROCESS | 11617 | |i|pagename|runs| 21:36:28 INFO - PROCESS | 11617 | |0;ai-astar;104;114;112;112;112;114;112;111;112;117 21:36:28 INFO - PROCESS | 11617 | |1;audio-beat-detection;149;121;123;166;122;120;121;126;157;121 21:36:28 INFO - PROCESS | 11617 | |2;audio-dft;164;164;167;163;162;167;175;165;162;162 21:36:28 INFO - PROCESS | 11617 | |3;audio-fft;76;77;75;92;76;76;75;76;97;76 21:36:28 INFO - PROCESS | 11617 | |4;audio-oscillator;85;84;109;85;84;83;92;85;84;109 21:36:28 INFO - PROCESS | 11617 | |5;imaging-gaussian-blur;118;118;119;118;118;117;117;117;118;124 21:36:28 INFO - PROCESS | 11617 | |6;imaging-darkroom;149;150;147;148;148;148;148;149;152;148 21:36:28 INFO - PROCESS | 11617 | |7;imaging-desaturate;96;97;96;96;96;96;96;96;97;95 21:36:28 INFO - PROCESS | 11617 | |8;json-parse-financial;54;54;57;55;55;58;55;54;55;55 21:36:28 INFO - PROCESS | 11617 | |9;json-stringify-tinderbox;65;66;65;66;67;66;66;66;67;66 21:36:28 INFO - PROCESS | 11617 | |10;stanford-crypto-aes;90;81;82;83;91;81;82;81;81;84 21:36:28 INFO - PROCESS | 11617 | |11;stanford-crypto-ccm;131;143;127;130;126;132;137;129;177;127 21:36:28 INFO - PROCESS | 11617 | |12;stanford-crypto-pbkdf2;172;172;174;167;166;171;165;165;169;171 21:36:28 INFO - PROCESS | 11617 | |13;stanford-crypto-sha256-iterative;65;66;63;63;67;64;64;63;62;67 21:36:28 INFO - PROCESS | 11617 | __end_tp_report 21:36:28 INFO - PROCESS | 11617 | __start_cc_report 21:36:28 INFO - PROCESS | 11617 | _x_x_mozilla_cycle_collect,712 21:36:28 INFO - PROCESS | 11617 | __end_cc_report 21:36:28 INFO - PROCESS | 11617 | __startTimestamp1482471388981__endTimestamp 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | ------- Summary: start ------- 21:36:28 INFO - PROCESS | 11617 | Number of tests: 14 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:3.30 (2.9%) stddev-sans-first:1.83 21:36:28 INFO - PROCESS | 11617 | Values: 104.0 114.0 112.0 112.0 112.0 114.0 112.0 111.0 112.0 117.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:124.50 stddev:17.61 (14.1%) stddev-sans-first:17.65 21:36:28 INFO - PROCESS | 11617 | Values: 149.0 121.0 123.0 166.0 122.0 120.0 121.0 126.0 157.0 121.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.50 stddev:3.96 (2.4%) stddev-sans-first:4.18 21:36:28 INFO - PROCESS | 11617 | Values: 164.0 164.0 167.0 163.0 162.0 167.0 175.0 165.0 162.0 162.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#3] audio-fft Cycles:10 Average:79.60 Median:76.00 stddev:7.96 (10.5%) stddev-sans-first:8.34 21:36:28 INFO - PROCESS | 11617 | Values: 76.0 77.0 75.0 92.0 76.0 76.0 75.0 76.0 97.0 76.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:84.50 stddev:10.32 (12.2%) stddev-sans-first:10.78 21:36:28 INFO - PROCESS | 11617 | Values: 85.0 84.0 109.0 85.0 84.0 83.0 92.0 85.0 84.0 109.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#5] imaging-gaussian-blur Cycles:10 Average:118.40 Median:118.00 stddev:2.07 (1.8%) stddev-sans-first:2.19 21:36:28 INFO - PROCESS | 11617 | Values: 118.0 118.0 119.0 118.0 118.0 117.0 117.0 117.0 118.0 124.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:148.50 stddev:1.42 (1.0%) stddev-sans-first:1.50 21:36:28 INFO - PROCESS | 11617 | Values: 149.0 150.0 147.0 148.0 148.0 148.0 148.0 149.0 152.0 148.0 21:36:28 INFO - PROCESS | 11617 | 21:36:28 INFO - PROCESS | 11617 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 21:36:28 INFO - PROCESS | 11617 | Values: 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 95.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.32 (2.4%) stddev-sans-first:1.32 21:36:29 INFO - PROCESS | 11617 | Values: 54.0 54.0 57.0 55.0 55.0 58.0 55.0 54.0 55.0 55.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 21:36:29 INFO - PROCESS | 11617 | Values: 65.0 66.0 65.0 66.0 67.0 66.0 66.0 66.0 67.0 66.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:82.50 stddev:3.78 (4.6%) stddev-sans-first:3.22 21:36:29 INFO - PROCESS | 11617 | Values: 90.0 81.0 82.0 83.0 91.0 81.0 82.0 81.0 81.0 84.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:131.50 stddev:15.34 (11.7%) stddev-sans-first:16.17 21:36:29 INFO - PROCESS | 11617 | Values: 131.0 143.0 127.0 130.0 126.0 132.0 137.0 129.0 177.0 127.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:171.00 stddev:3.26 (1.9%) stddev-sans-first:3.30 21:36:29 INFO - PROCESS | 11617 | Values: 172.0 172.0 174.0 167.0 166.0 171.0 165.0 165.0 169.0 171.0 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - PROCESS | 11617 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.78 (2.8%) stddev-sans-first:1.87 21:36:29 INFO - PROCESS | 11617 | Values: 65.0 66.0 63.0 63.0 67.0 64.0 64.0 63.0 62.0 67.0 21:36:29 INFO - PROCESS | 11617 | -------- Summary: end -------- 21:36:29 INFO - PROCESS | 11617 | 21:36:29 INFO - TEST-INFO | 11617: exit 0 21:36:30 INFO - TEST-OK | kraken | took 61919ms 21:36:30 INFO - SUITE-END | took 1176s 21:36:30 INFO - Completed test suite (00:19:36) 21:36:30 INFO - javascript benchmark 21:36:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6921.835756615286, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3161.8975377521374, 3177.571027911232, 3179.459491886379, 3196.5045417960196, 3215.1158431431354, 5240.2401651760565, 5318.0693378658725, 5350.438203988579, 5364.731763411828, 5379.67721936684, 5259.631825772198, 5280.551153151977, 5347.6256662033675, 5355.705436201008, 5388.137897936331, 4787.904241915159, 4818.060478206751, 4860.562549370556, 4936.210206366982, 4958.281049247857, 4779.569838714515, 4834.685745426536, 4932.704037757746, 4936.407631084275, 4950.455449900526, 4763.571278584944, 4952.455229924705, 4965.180745177077, 4992.725400102993, 5066.18940969445, 4781.163296423112, 4795.928061079087, 4817.9518204818205, 4836.733979631119, 4943.6539442239055, 4816.133096042711, 4822.421309442854, 4824.710517368934, 4826.61387089032, 4852.272159176152, 5278.4721527846905, 5288.206768984644, 5305.0450918834595, 5315.760790764425, 5334.226537152092, 5537.667739935617, 5567.638103523299, 5657.8019769308285, 5709.65742055478, 5720.71396430181, 5777.682227477487, 5778.0, 5782.190493330937, 5790.971045144789, 5857.85355366115, 5391.406945236021, 5392.948375066824, 5405.864853378657, 5408.459154084639, 5408.621396502246, 5587.944120558824, 5666.4050274721385, 5703.885922281531, 5723.6279641823985, 5905.261842269716, 5532.3914369419335, 5557.444255574491, 5559.082751346064, 5584.91622625661, 5596.888062238732, 5600.215969764155, 5614.129809879475, 5630.8029218977135, 5644.0, 5653.180288858175, 5549.19536667181, 5577.470140336662, 5621.578381621394, 5625.212470254168, 5660.943390566124, 5713.857153571111, 5719.227904232958, 5723.112917497794, 5732.197492351074, 5774.354309021908, 4293.74237545742, 4308.9353659695125, 4311.611954924072, 4312.094460163338, 4346.695731298779, 3776.376897811885, 3796.2976849282973, 3805.9238815224094, 3840.423936409561, 3884.553276373253, 5433.592480563973, 5440.809571664972, 5443.482869127427, 5482.972585137049, 5490.725463726798, 5737.655740655574, 5756.510696590753, 5764.020116580192, 5776.56675749312, 5789.913151302734, 5297.311349524537, 5329.25390445338, 5352.090144675414, 5438.293021907206, 5444.8094316698725, 4715.552022557851, 4780.521947805261, 4784.138855006132, 4817.807287708522, 4832.106060378841, 5985.431384018511, 5989.221401217814, 5991.101334799728, 6008.66952317618, 6020.668863212565, 5606.523445507178, 5668.2064510967675, 5682.659040457585, 5722.113072473773, 5742.913856292075, 5947.583669143119, 5949.018411962151, 5950.672713000827, 5994.6403215807195, 6070.028795392716, 5609.635373700697, 5642.097264437834, 5652.773889044557, 5660.20757093999, 5667.659940403589, 5567.331920169606, 5764.07774756037, 5770.076787713946, 5779.335376431596, 5784.50831679312, 5469.234307197075, 5508.256385388082, 5569.860753481195, 5592.133219351007, 5669.603127781016, 5649.435056494318, 5650.519705824887, 5653.773849045992, 5663.32040155184, 5666.15007748841, 5426.891462170814, 5450.400455949922, 5569.498745112959, 5602.0, 5635.464630860061, 5483.177523371526, 5567.387587365468, 5576.1356989666665, 5634.154876768517, 5675.31896172462, 5443.156310771837, 5484.1773733938635, 5486.698231597141, 5495.505404513613, 5499.725013749377], "value": 5262.631442100126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.7366316841579, 534.3507638219562, 563.9774409023639, 565.1579147070864, 566.0659911146607, 39782.60217397825, 43021.56978430215, 43066.0, 43146.56853431465, 46551.30173047407, 44120.779396103055, 44414.0, 44485.332720009224, 44618.0, 44626.107477850426, 8906.91093089069, 9047.185753282203, 9048.77378065547, 9064.954675226647, 10121.848172277421, 10118.13995810357, 10430.061294483496, 10462.476876156199, 10463.947680261588, 10478.423686697228, 10456.372617642966, 10495.107915827164, 10504.579816807318, 10521.210909181804, 10527.789444211112, 10320.948395258014, 10351.27541072129, 10363.92721455712, 10375.584976600927, 10401.323913945645, 10262.9486852566, 10269.0, 10272.640457583948, 10322.690319290357, 10368.222383321207, 38462.0, 38508.031255155496, 38756.64351747704, 38817.417738733944, 38849.223015539814, 3388.15296175956, 3414.778039427454, 3426.554547908781, 3437.295354452331, 3516.0682419158943, 28567.857160714062, 28617.427651447062, 29164.562531562042, 29318.560221596694, 29461.41077178466, 4296.269634162169, 4328.523862375137, 4352.412424322708, 4479.529649386833, 4487.259602165639, 356544.0, 358901.0, 361372.19313903525, 362439.0, 362698.0, 53335.19997200045, 53489.732551336994, 53607.19589206165, 53781.27734033246, 53871.0, 55876.72061639747, 55941.440585594435, 56096.0, 56408.43591564114, 56488.71755641195, 14372.209528475825, 14487.85512144865, 14623.0, 14624.926875365554, 14625.26873656313, 8497.74506764798, 8651.740447786577, 10712.571497140027, 10887.945560272148, 11043.83434248487, 16584.336626535045, 16693.33226670944, 16742.916285418738, 16748.99506029642, 16860.494185174466, 6454.967725161344, 6518.837029074311, 6564.409203171642, 6615.503837212228, 6739.730410783513, 930.5393830054167, 974.8196583632051, 1011.489197955028, 1043.3218408034838, 1046.638909576195, 31695.52456713105, 31865.362692746014, 32072.839635801673, 32133.839330803665, 32783.180420489676, 1064.350746044912, 1070.4166229404993, 1076.7240819628764, 1101.730076131353, 1113.7828123516, 15507.703289512765, 16387.918060409622, 16508.33966641345, 16566.917165414096, 16580.450587201398, 494641.0, 495327.0, 496065.0, 499620.0, 502395.0, 60297.69851150716, 60479.0, 60646.69676651589, 60708.392916071156, 61231.6938415314, 1345.2264947654942, 1350.3113412159869, 1379.35170469879, 1388.9027768056342, 1390.0755997261708, 29692.40615187641, 31237.68762312348, 31304.373912521165, 31431.52852707257, 31978.68021319757, 51616.74191629018, 51791.48208518017, 51980.48019519756, 52051.0, 52115.21827172519, 413684.0, 416122.0, 416399.0, 416715.0, 417879.0, 23824.52350953006, 24457.75542244555, 24524.0, 27797.583036254877, 28290.0, 40272.44619038428, 40402.39396409115, 41132.38301425541, 41288.58711412847, 41757.58242417537, 34752.0, 34796.65203348036, 35052.29895402027, 35088.824555878076, 35774.284514310086, 34839.0, 34910.65089349074, 35341.46987795237, 35344.0, 35530.82234588914, 34942.0, 35406.645933540334, 35497.645023549434, 35580.82209588936, 35852.8207358972, 30215.395692086477, 32617.18457038593, 32683.509747354285, 32745.50881736728, 32813.0, 24762.25713228534, 24969.37576560528, 25169.49661006733, 25335.0, 25358.0], "value": 19321.253289080905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.3538220407511, 388.7259482065144, 389.41314144859706, 393.17629566059105, 409.823775776416, 14645.853541464583, 17194.39819606314, 17645.470635880927, 19192.616147677054, 19219.23123075079, 17137.314507419687, 17487.650246995054, 17878.91060544699, 18552.0, 18821.247150114013, 5578.0, 5602.1036634138545, 5745.913811292834, 5786.56600754943, 5877.265341832271, 5676.2620859288445, 5801.680907550083, 5808.651480911138, 5817.127430885359, 5823.708814559276, 5488.4785945335125, 5698.914516282259, 5741.483266506014, 5744.6840423776885, 5765.106408506688, 5726.255586773735, 5780.71096445176, 5782.392848750884, 5789.739461724233, 5795.1886735857015, 4983.526564976321, 5132.538071573539, 5143.897122057575, 5169.043726910496, 5171.327727395452, 21514.462138446506, 22090.668639970412, 22122.557548849094, 22198.667019994715, 22443.0, 2208.1829723002593, 2338.2166974063707, 2656.3093595665073, 2718.5242582547976, 2912.0681381957784, 9917.702468925943, 9957.153641940444, 10295.845562316572, 10343.94828025855, 10612.734681632945, 1890.905454727258, 1977.5451646121473, 1997.8501612378984, 2005.7793642699291, 2011.336259034515, 78059.0, 78465.60767196184, 80275.19724802794, 80935.19064809335, 81137.59431202806, 10567.0, 10765.416300087922, 10774.515146818412, 10779.461026948622, 10922.781544369069, 14294.0, 14475.27623618836, 14509.85490145106, 14611.92694036523, 14628.853711462962, 7484.47608667399, 7493.887591686129, 7672.846543069108, 7765.339946104531, 7798.727044553413, 7058.752943646948, 7069.8939515907305, 7164.534305270141, 7295.234000429985, 7337.449691273176, 4556.3621093047, 4564.52072532386, 4609.492955774862, 4651.511516314815, 4734.834280800224, 1978.8911609861595, 1989.890556019404, 1996.1815655581433, 2030.7357296614168, 2047.754269487671, 868.769776009358, 868.9919693155908, 926.7497775600685, 1000.799840031982, 1055.5883205549842, 17939.820601794076, 18239.63520729578, 18708.345207917657, 18825.717614235797, 18837.717434238497, 922.6678395777515, 956.9760755981156, 972.7820569102457, 1007.4106647611229, 1009.3287963504334, 7970.800729981681, 10268.845967310497, 10274.691759247235, 10464.06414813262, 10508.0, 426952.0, 429828.0, 430386.0, 433259.0, 433955.0, 60621.39378606246, 74140.0, 86548.0, 87265.56367218123, 89637.55181224183, 1009.7677534167183, 1025.3847691385108, 1053.4995876958383, 1066.8613080299667, 1069.850220969065, 16580.419685310957, 16769.74845377345, 16816.747748783535, 17007.404740834012, 17095.914520427817, 37706.62293377031, 38320.6167938317, 38486.2302753949, 38713.612863871, 39238.80380598079, 52763.0, 53060.0, 53830.0, 55475.72262138664, 56430.43569564416, 8365.33077353798, 8373.748787536384, 8420.284275836622, 8500.53247071417, 8523.573821308786, 11012.0, 12810.0, 12880.742385152433, 12904.677383065498, 13236.801447978482, 10981.945090274497, 11178.161637877029, 11241.437928103398, 11582.015528680156, 11657.766844663229, 10765.838512422477, 10775.137988960707, 10803.837942431028, 10871.130309575374, 11188.272762270426, 10977.506012229629, 11016.614418495636, 11057.612983545538, 11856.170068095496, 11877.703057423634, 8203.220694034057, 8375.62309696053, 8547.95726021366, 8555.187257210555, 8593.484390936565, 8279.917200828155, 11214.0, 11223.821498742514, 11228.775424491301, 11461.140414468782], "value": 8888.96305937097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.470861072792, 1426.0730525158644, 1547.2650491016768, 1697.906615136167, 1751.4745576327098, 7973.282404583586, 8237.299829514499, 8389.748307550783, 8476.152384761528, 8482.109378515259, 8365.958170209156, 8546.188112129354, 8550.65797368106, 8559.828803423929, 8579.49858774715, 2211.4250294923354, 2276.8178545716305, 2278.8063014643776, 2284.5088306014204, 2285.5771682238837, 2299.4596269876565, 2326.61610834213, 2344.5229505411567, 2352.0591763294733, 2355.729091154522, 2241.12596087526, 2270.182734215681, 2288.2791920544973, 2294.19703103914, 2339.122828939148, 2201.768814274494, 2225.688403623494, 2231.0, 2234.8770817605027, 2245.113180293791, 2289.473421113153, 2299.1837897546466, 2304.2165663674264, 2305.0549274797254, 2312.791848733605, 8368.037675667316, 8477.279431248351, 8509.659613615448, 8518.190771876723, 8564.957175214084, 4905.779239934212, 5007.223880298559, 5021.347224860781, 5023.673461225045, 5024.949750502485, 3135.8588863501195, 3203.423383790917, 3394.100563350714, 3431.4681224410256, 3438.1232785639754, 1008.3193844155165, 1020.3316827478013, 1044.5821671331453, 1049.3336731175682, 1051.9368837869677, 10968.122550196045, 11233.943830280878, 11252.549898004152, 11659.650210493699, 11792.469338879857, 4197.958020419818, 4301.720388174758, 4385.6710746694125, 4446.42196514451, 4464.218761716752, 9126.680566180152, 9145.81708365829, 9149.634014639472, 9165.58754856033, 9170.0, 4991.3511243538105, 4993.925091123636, 4994.250862370601, 5000.524950129732, 5002.424721157114, 4451.221036318631, 4564.269716845355, 4611.077784443125, 4633.62930965522, 4712.835050773207, 3537.9469307960403, 3621.2395396966403, 3634.5093412388987, 3658.908527286839, 3684.797336146538, 1087.6465148826662, 1106.1261603333437, 1107.8423047914948, 1112.8831472695413, 1115.8716747573972, 909.6361455417754, 927.7077720518015, 940.6032042417005, 962.5043102802063, 975.2539307429822, 5424.6473979192015, 5586.273784408001, 5647.08916508781, 5834.03738383162, 5953.255843019622, 417.68464809069565, 422.11777385264946, 422.88582082838076, 429.3302448180848, 439.5275079289776, 3803.54357477099, 3951.980240098781, 4003.1193137510286, 4019.256437559061, 4064.9593504064583, 9985.60057597688, 10235.43705096212, 10255.743606409751, 10268.486575671186, 10283.53724082418, 10096.14182794456, 10124.69625911224, 10125.392476451289, 10148.644797432054, 10172.389656620626, 7376.889346659805, 7635.083789945242, 7665.846683066307, 7809.80475488106, 7875.921240787518, 5592.933771564437, 5686.829395118153, 5701.40135285789, 5719.8856022880145, 5813.738381772915, 9174.128457796498, 9207.67773127964, 9211.631534738803, 9275.76810579741, 9286.860697089414, 9440.858387124335, 9467.337286389886, 9530.714078577654, 9605.711828645151, 9621.134097931219, 6841.3842754152365, 6905.309469053054, 7044.964775176091, 7152.785416437515, 7182.96408517954, 7844.764657060297, 7909.881351779843, 7930.9603451982375, 7931.524108553274, 7932.04815422153, 6876.828079298058, 6885.51801373899, 6920.238773734985, 6938.132733408324, 7084.866421372556, 6471.4499267560905, 7073.469489788389, 7096.7516136934955, 7099.396551292992, 7199.424046076406, 6123.203983482119, 6183.0, 6219.40915613016, 6246.0, 6246.437820596168, 4564.315352697122, 4577.313402989578, 4645.767711614338, 4687.343771872009, 4725.125851717443, 4751.574527641625, 4751.786169622306, 4761.666683332133, 4803.543663351976, 4880.0], "value": 4285.529067061949, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.73563901810206, 371.6376532880442, 377.6525596451265, 378.23407599610766, 383.3062157494936, 5124.538791508773, 5244.921326180106, 5273.472652734725, 5278.155495120776, 5289.418164001966, 5138.691678499284, 5248.239005344223, 5257.842264732055, 5296.894062118756, 5319.813806516772, 4000.6599439047573, 4110.671146308303, 4126.595558664114, 4247.638950689187, 4253.1281087377165, 4136.9864383226, 4142.565030671782, 4158.480189976253, 4162.271602469571, 4166.541680415167, 4076.4916980717176, 4094.7338423002557, 4104.958950410502, 4122.525909520398, 4128.21563902859, 3937.98031009846, 4129.607687269705, 4130.793460326987, 4135.524414692319, 4170.291050521419, 3621.5292012038267, 3631.564212294515, 3631.963680363189, 3644.6173151819207, 3645.908852278688, 5171.301874246966, 5172.405173405069, 5193.662411943249, 5198.2722418861385, 5199.168133098724, 4709.364235828154, 4724.244120940667, 4732.337472753818, 4761.428628564559, 4770.284457331428, 5318.6969192547685, 5531.3915469298345, 5547.833564993016, 5551.639143455663, 5565.220869078333, 4007.7996100194873, 4031.7623718836035, 4040.636342729139, 4045.3122969095325, 4049.6962727795535, 5583.553315734772, 5828.184054232411, 5837.737301821428, 5844.590878638497, 5855.8536036599, 4066.186762647423, 4099.959000410017, 4116.259073366763, 4117.526484454326, 4121.340585506305, 4159.854405095868, 4168.708190426702, 4188.392683060939, 4199.874003779892, 4207.558206388347, 4703.764811759398, 4733.9053218936115, 4745.976270118696, 4777.474477807438, 4804.183288840905, 5341.679499230058, 5511.476409741068, 5524.613277070566, 5540.307461567304, 5573.442655734394, 1361.918284902909, 1377.7175678985784, 1379.2414172205247, 1383.4120498787975, 1385.9792103118461, 940.4404379394249, 970.6408628807245, 973.1873885305849, 976.4824642939253, 986.5511192407438, 5131.666441681279, 5133.5379815816, 5140.537351638371, 5150.124478838607, 5249.740062385076, 5518.448155184449, 5534.501894829485, 5556.332820338557, 5564.554835613141, 5621.381648018714, 4977.477364876709, 5022.146235140035, 5157.277981082577, 5202.739863006835, 5247.2129180623215, 2928.633920759905, 2940.4413161499247, 2941.3234955960247, 2949.896753613656, 2953.7784666150123, 5779.711014449261, 5794.62334948227, 5796.536277097822, 5798.941375811611, 5833.241678581842, 5312.787488500417, 5493.368262649767, 5521.613487055948, 5608.579356548275, 5633.929508417922, 5801.332846722512, 5808.622439541501, 5822.3304320003745, 5833.562482813806, 5858.238429004287, 5272.7100009499845, 5452.454754524516, 5493.890122197614, 5508.063629183129, 5554.472325129106, 5415.702136382379, 5426.565874730091, 5435.124881915733, 5444.401115877171, 5454.236406902957, 5738.225339579103, 5760.6255593386295, 5765.94234057654, 5771.394003629726, 5823.388544202798, 3914.2227750221173, 3915.275674000319, 3985.9636494511055, 3991.5409727881665, 4030.41558973953, 5507.917381239365, 5514.476124768141, 5547.972260138674, 5559.527440167631, 5654.532918994113, 5500.257465242301, 5602.579806514446, 5616.360310653986, 5620.747066382104, 5654.886902262014, 5601.243832082617, 5629.31120663791, 5633.690147041952, 5642.717864106696, 5657.32112146545, 5368.624196306221, 5540.833774986757, 5575.0208675921085, 5581.488330251322, 5608.943910561005, 5624.85937851557, 5640.210370548045, 5642.266505354278, 5650.23721797552, 5727.713614319351, 5425.728713564385, 5469.535089517276, 5566.248556444828, 5574.86062848432, 5586.301712285965], "value": 4226.849709168745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.0381651944214, 768.4275214964848, 784.6665167303895, 792.1405275276325, 833.804056046829, 12058.216215945957, 12181.025517958564, 12490.250584964897, 14439.927800360983, 14711.852881471183, 11764.0, 11772.47023883925, 11778.175527713041, 11795.233309834855, 11981.341026243554, 785.4855069929191, 804.5092493578912, 808.0505406147782, 809.550699361854, 809.7975506123469, 769.0502229746287, 797.9800504987393, 800.3197282310048, 800.9479383840061, 802.4022103532876, 838.3460900497622, 840.306746933782, 840.3613253927028, 841.9368547358941, 848.0713618587667, 835.4945258118811, 837.8491871463134, 839.4291881520563, 840.3487297344576, 841.2176675691604, 620.1256228717532, 624.2446639566131, 624.8031869960947, 625.5808608232495, 628.8647940692773, 13580.38888250031, 13776.65558361049, 13809.792853107212, 13810.861891381059, 14181.858181418156, 14276.0, 14480.782788258184, 14814.111153330834, 14817.0, 15058.849411505855, 11436.771264574661, 11465.484053217626, 11500.137489688306, 11695.590654327058, 11899.107566932427, 3567.500549922987, 3621.3300539400293, 3640.053586067615, 3809.942850857239, 3817.8854634361014, 77682.22317776806, 78948.0, 78997.21002789956, 79038.60480697674, 82583.58708206481, 10233.9488302558, 10522.526486308134, 10690.78618427627, 10877.238593298393, 10979.0, 20906.581868362548, 21036.789632103788, 21044.579108417747, 21078.578428431345, 21092.578148437253, 7680.9615951921, 7698.0, 7739.148693643695, 7747.961260193663, 8079.4344395892895, 4152.335626299778, 4284.485861696617, 4355.237833379146, 4360.411344468456, 4456.844010459683, 3334.983325083359, 3392.542006829096, 3406.0803583032443, 3409.931801363959, 3422.19578399076, 548.8737590354241, 574.643720893049, 582.7931084465039, 586.3989410853824, 609.7500024989822, 570.6775671745451, 601.2964831147568, 620.2649859916014, 635.0918186992552, 637.1844039629282, 7921.207879212033, 8777.736667899973, 9244.7688807779, 9305.90694093064, 9419.0, 601.8675891303906, 621.4003486635419, 672.3847679373416, 673.245964519741, 703.8662654095808, 4131.772752498641, 4423.623991960655, 4542.409486766699, 4598.1033698429155, 4624.768761561909, 57465.42534574601, 57518.7124064377, 69093.0, 74463.0, 74615.0, 51164.1394067563, 56865.0, 59612.70193649091, 63958.6802065996, 66311.66844165845, 58395.70802145962, 58658.41341586615, 59083.70458147682, 59348.10977835336, 59374.0, 8872.0, 8998.730038098867, 9055.80696806432, 9063.093690630883, 9332.253419726541, 38702.22595548017, 39351.80324098361, 39771.2045759089, 39830.402543962446, 40855.1828963425, 50245.0, 50546.74726626343, 54727.0, 55174.17238741503, 55837.0, 10911.508982095665, 11434.94282528582, 11458.541658333574, 11828.825391792185, 12334.81497777516, 12468.501259949864, 12498.43757030954, 12580.874191258337, 12683.0, 12720.300383478998, 11475.540978360772, 11797.941010295237, 12076.21504602198, 12548.372581370711, 12625.810612840998, 11547.653570392902, 11662.685069756624, 11874.228175168586, 12340.629781106221, 12426.440810163745, 10457.372557646566, 10457.372557646566, 10487.475626218811, 11045.668629941114, 12350.567730129402, 9575.664851730156, 9604.183644390305, 9769.755756106155, 9784.0, 10055.29612927088, 9939.503024848584, 10338.793224135625, 10443.164546836388, 10521.368717876645, 10562.31344962575], "value": 6716.932804206507, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 112.0, 112.0, 112.0, 114.0, 112.0, 111.0, 112.0, 117.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 123.0, 166.0, 122.0, 120.0, 121.0, 126.0, 157.0, 121.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 167.0, 163.0, 162.0, 167.0, 175.0, 165.0, 162.0, 162.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 92.0, 76.0, 76.0, 75.0, 76.0, 97.0, 76.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 109.0, 85.0, 84.0, 83.0, 92.0, 85.0, 84.0, 109.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 124.0], "value": 118.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 147.0, 148.0, 148.0, 148.0, 148.0, 149.0, 152.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 55.0, 55.0, 58.0, 55.0, 54.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 81.0, 82.0, 83.0, 91.0, 81.0, 82.0, 81.0, 81.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 143.0, 127.0, 130.0, 126.0, 132.0, 137.0, 129.0, 177.0, 127.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 174.0, 167.0, 166.0, 171.0, 165.0, 165.0, 169.0, 171.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 63.0, 67.0, 64.0, 64.0, 63.0, 62.0, 67.0], "value": 64.4, "unit": "score"}]}]} 21:36:30 INFO - Return code: 0 21:36:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:36:30 ERROR - # TBPL SUCCESS # 21:36:30 INFO - Running post-action listener: _resource_record_post_action 21:36:30 INFO - [mozharness: 2016-12-23 05:36:30.267274Z] Finished run-tests step (success) 21:36:30 INFO - Running post-run listener: _resource_record_post_run 21:36:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 409890816; Read time: 16; Write time: 346864 21:36:30 INFO - TinderboxPrint: CPU usage
13.4% 21:36:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 21:36:30 INFO - TinderboxPrint: I/O write bytes / time
409,890,816 / 346,864 21:36:30 INFO - TinderboxPrint: CPU idle
8,245.4 (86.5%) 21:36:30 INFO - TinderboxPrint: CPU user
1,220.0 (12.8%) 21:36:30 INFO - TinderboxPrint: Swap in / out
0 / 0 21:36:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116064256; Read time: 0; Write time: 83840 21:36:30 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 292933632; Read time: 0; Write time: 263000 21:36:30 INFO - Running post-run listener: _upload_blobber_files 21:36:30 INFO - Blob upload gear active. 21:36:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:36:30 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:30 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:30 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:31 INFO - (blobuploader) - INFO - Open directory for files ... 21:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:31 INFO - (blobuploader) - INFO - Done attempting. 21:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:32 INFO - (blobuploader) - INFO - Done attempting. 21:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:36:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:36 INFO - (blobuploader) - INFO - Done attempting. 21:36:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.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-e10s_raw.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 - Iteration through files over. 21:36:37 INFO - Return code: 0 21:36:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:36:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:36:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f1db0881ee924fd1d5a55bc711e2bf96859afde508cda310968c15d22619240e0494a8da0649436f83a7ae8a5d43e9d1d2caf267d9b923f6fb8226c81fbd93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7f35f681dfb1b56d1a8096673fa9a5b9d9deefa04c4bcbed7d864f3eb5a0c68f1f27389a126e4557b27f15819e23d8849eca87c27ba28b9b8404bc5367cae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01cbef8ca3e95759689729abd728988b64a888031ef2551e3f41f79772b949ab5421b8abca429a5bf15a67e8123b27aaeaaf4673d5206589207c9e44b5354c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe81f1c20b49d1f838a85e1f9f9bda96b73b6173af19da379f0e1805e391c549f4c3de30dbe2469276c0b856ff8323b1b4f726295c8cb57e1f0dadf8a7926d5"} 21:36:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:36:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:36:37 INFO - Contents: 21:36:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f1db0881ee924fd1d5a55bc711e2bf96859afde508cda310968c15d22619240e0494a8da0649436f83a7ae8a5d43e9d1d2caf267d9b923f6fb8226c81fbd93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7f35f681dfb1b56d1a8096673fa9a5b9d9deefa04c4bcbed7d864f3eb5a0c68f1f27389a126e4557b27f15819e23d8849eca87c27ba28b9b8404bc5367cae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01cbef8ca3e95759689729abd728988b64a888031ef2551e3f41f79772b949ab5421b8abca429a5bf15a67e8123b27aaeaaf4673d5206589207c9e44b5354c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe81f1c20b49d1f838a85e1f9f9bda96b73b6173af19da379f0e1805e391c549f4c3de30dbe2469276c0b856ff8323b1b4f726295c8cb57e1f0dadf8a7926d5"} 21:36:37 INFO - Running post-run listener: copy_logs_to_upload_dir 21:36:37 INFO - Copying logs to upload dir... 21:36:37 INFO - mkdir: /builds/slave/test/build/upload/logs 21:36:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.495064 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-22 21:36:36.591599) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:36.594956) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f1db0881ee924fd1d5a55bc711e2bf96859afde508cda310968c15d22619240e0494a8da0649436f83a7ae8a5d43e9d1d2caf267d9b923f6fb8226c81fbd93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7f35f681dfb1b56d1a8096673fa9a5b9d9deefa04c4bcbed7d864f3eb5a0c68f1f27389a126e4557b27f15819e23d8849eca87c27ba28b9b8404bc5367cae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01cbef8ca3e95759689729abd728988b64a888031ef2551e3f41f79772b949ab5421b8abca429a5bf15a67e8123b27aaeaaf4673d5206589207c9e44b5354c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe81f1c20b49d1f838a85e1f9f9bda96b73b6173af19da379f0e1805e391c549f4c3de30dbe2469276c0b856ff8323b1b4f726295c8cb57e1f0dadf8a7926d5"} 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.011157 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f1db0881ee924fd1d5a55bc711e2bf96859afde508cda310968c15d22619240e0494a8da0649436f83a7ae8a5d43e9d1d2caf267d9b923f6fb8226c81fbd93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7f35f681dfb1b56d1a8096673fa9a5b9d9deefa04c4bcbed7d864f3eb5a0c68f1f27389a126e4557b27f15819e23d8849eca87c27ba28b9b8404bc5367cae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01cbef8ca3e95759689729abd728988b64a888031ef2551e3f41f79772b949ab5421b8abca429a5bf15a67e8123b27aaeaaf4673d5206589207c9e44b5354c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebe81f1c20b49d1f838a85e1f9f9bda96b73b6173af19da379f0e1805e391c549f4c3de30dbe2469276c0b856ff8323b1b4f726295c8cb57e1f0dadf8a7926d5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:36.620087) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:36.620384) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482468604.335007-1152601274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 21:36:36.705714) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 21:36:36.706035) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 21:36:36.706318) ========= ========= Total master_lag: 0.16 =========