builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1478808190.68 results: success (0) buildid: 20161110073622 builduid: b0834b6311774e34a57f7281a0cd2555 revision: fa5bb25eb05ff73bb23c06cde372db389d8eb030 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.678281) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.678731) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.679006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.707018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.707423) ========= 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-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-10 12:03:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-11-10 12:03:11 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151695 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.872875) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.873154) ========= 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-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020335 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.923634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:10.923938) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa5bb25eb05ff73bb23c06cde372db389d8eb030 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa5bb25eb05ff73bb23c06cde372db389d8eb030 --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-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-10 12:03:11,443 truncating revision to first 12 chars 2016-11-10 12:03:11,443 Setting DEBUG logging. 2016-11-10 12:03:11,443 attempt 1/10 2016-11-10 12:03:11,443 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa5bb25eb05f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 12:03:11,752 unpacking tar archive at: mozilla-aurora-fa5bb25eb05f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.635499 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:11.573435) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:11.573707) ========= script_repo_revision: fa5bb25eb05ff73bb23c06cde372db389d8eb030 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:11.574065) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:11.574343) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 12:03:11.583858) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-10 12:03:11.584142) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:03:12 INFO - MultiFileLogger online at 20161110 12:03:12 in /builds/slave/test 12:03:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:03:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:03:12 INFO - {'append_to_log': False, 12:03:12 INFO - 'base_work_dir': '/builds/slave/test', 12:03:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:03:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:03:12 INFO - 'branch': 'Mozilla-Aurora', 12:03:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:03:12 INFO - 'config_files': ('talos/linux_config.py',), 12:03:12 INFO - 'default_actions': ('clobber', 12:03:12 INFO - 'read-buildbot-config', 12:03:12 INFO - 'download-and-extract', 12:03:12 INFO - 'populate-webroot', 12:03:12 INFO - 'create-virtualenv', 12:03:12 INFO - 'install', 12:03:12 INFO - 'run-tests'), 12:03:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:03:12 INFO - 'download_minidump_stackwalk': True, 12:03:12 INFO - 'download_symbols': 'ondemand', 12:03:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:03:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:03:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:03:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:03:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:03:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:03:12 INFO - 'installer_path': 'installer.exe', 12:03:12 INFO - 'log_level': 'info', 12:03:12 INFO - 'log_name': 'talos', 12:03:12 INFO - 'log_to_console': True, 12:03:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:03:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:03:12 INFO - 'opt_config_files': (), 12:03:12 INFO - 'pip_index': False, 12:03:12 INFO - 'sps_profile': False, 12:03:12 INFO - 'sps_profile_interval': 0, 12:03:12 INFO - 'suite': 'dromaeojs', 12:03:12 INFO - 'system_bits': '32', 12:03:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:03:12 INFO - 'title': 'talos-linux64-ix-061', 12:03:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:03:12 INFO - 'use_talos_json': True, 12:03:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:03:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:03:12 INFO - 'work_dir': 'build'} 12:03:12 INFO - [mozharness: 2016-11-10 20:03:12.202304Z] Running clobber step. 12:03:12 INFO - Running pre-action listener: _resource_record_pre_action 12:03:12 INFO - Running main action method: clobber 12:03:12 INFO - rmtree: /builds/slave/test/build 12:03:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:03:13 INFO - Running post-action listener: _resource_record_post_action 12:03:13 INFO - [mozharness: 2016-11-10 20:03:13.172998Z] Finished clobber step (success) 12:03:13 INFO - [mozharness: 2016-11-10 20:03:13.173096Z] Running read-buildbot-config step. 12:03:13 INFO - Running pre-action listener: _resource_record_pre_action 12:03:13 INFO - Running main action method: read_buildbot_config 12:03:13 INFO - Using buildbot properties: 12:03:13 INFO - { 12:03:13 INFO - "project": "", 12:03:13 INFO - "product": "firefox", 12:03:13 INFO - "script_repo_revision": "production", 12:03:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:03:13 INFO - "repository": "", 12:03:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:03:13 INFO - "buildid": "20161110073622", 12:03:13 INFO - "pgo_build": "True", 12:03:13 INFO - "basedir": "/builds/slave/test", 12:03:13 INFO - "buildnumber": 880, 12:03:13 INFO - "slavename": "talos-linux64-ix-061", 12:03:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:03:13 INFO - "platform": "ubuntu64_hw", 12:03:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:03:13 INFO - "branch": "mozilla-aurora", 12:03:13 INFO - "repo_path": "releases/mozilla-aurora", 12:03:13 INFO - "revision": "fa5bb25eb05ff73bb23c06cde372db389d8eb030", 12:03:13 INFO - "stage_platform": "linux64-pgo", 12:03:13 INFO - "builduid": "b0834b6311774e34a57f7281a0cd2555", 12:03:13 INFO - "slavebuilddir": "test" 12:03:13 INFO - } 12:03:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 12:03:13 INFO - Running post-action listener: _resource_record_post_action 12:03:13 INFO - [mozharness: 2016-11-10 20:03:13.175552Z] Finished read-buildbot-config step (success) 12:03:13 INFO - [mozharness: 2016-11-10 20:03:13.175641Z] Running download-and-extract step. 12:03:13 INFO - Running pre-action listener: _resource_record_pre_action 12:03:13 INFO - Running main action method: download_and_extract 12:03:13 INFO - mkdir: /builds/slave/test/build/tests 12:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:13 INFO - trying https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:03:13 INFO - Downloading https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:03:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:03:13 INFO - Downloaded 1361 bytes. 12:03:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:03:13 INFO - Using the following test package requirements: 12:03:13 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:03:13 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:03:13 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:03:13 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'jsshell-linux-x86_64.zip'], 12:03:13 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:03:13 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:03:13 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:03:13 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:03:13 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:03:13 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:03:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:03:13 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:03:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:03:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:03:13 INFO - Fetch https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:03:14 INFO - Expected file size: 21897528 12:03:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:03:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:03:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:03:18 INFO - Fetch https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:03:19 INFO - Expected file size: 21897528 12:03:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 12:03:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:03:23 INFO - Fetch https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 12:03:24 INFO - Expected file size: 14414089 12:03: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')]} 12:03:24 INFO - trying https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:03:24 INFO - Downloading https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:03:26 INFO - Downloaded 64041448 bytes. 12:03:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:03:26 INFO - mkdir: /builds/slave/test/properties 12:03:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:03:26 INFO - Writing to file /builds/slave/test/properties/build_url 12:03:26 INFO - Contents: 12:03:26 INFO - build_url:https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:03:26 INFO - Running post-action listener: _resource_record_post_action 12:03:26 INFO - Running post-action listener: set_extra_try_arguments 12:03:26 INFO - [mozharness: 2016-11-10 20:03:26.997103Z] Finished download-and-extract step (success) 12:03:26 INFO - [mozharness: 2016-11-10 20:03:26.997331Z] Running populate-webroot step. 12:03:26 INFO - Running pre-action listener: _resource_record_pre_action 12:03:26 INFO - Running main action method: populate_webroot 12:03:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:03:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:03:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:03:27 INFO - '--tppagecycles', 12:03:27 INFO - '7'], 12:03:27 INFO - 'tests': ['tsvgm']}}, 12:03:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:03:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:03:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:03:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:03:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['damp', 'tps']}, 12:03:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'tests': ['damp', 'tps']}, 12:03:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['dromaeo_dom']}, 12:03:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:03:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['basic_compositor_video']}, 12:03:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:03:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'other-e10s': {'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['a11yr', 12:03:27 INFO - 'ts_paint', 12:03:27 INFO - 'tpaint', 12:03:27 INFO - 'sessionrestore', 12:03:27 INFO - 'sessionrestore_no_auto_restore', 12:03:27 INFO - 'tabpaint']}, 12:03:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['tsvgx', 12:03:27 INFO - 'tsvgr_opacity', 12:03:27 INFO - 'tart', 12:03:27 INFO - 'tscrollx', 12:03:27 INFO - 'cart']}, 12:03:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:03:27 INFO - 'tsvgr_opacity', 12:03:27 INFO - 'tart', 12:03:27 INFO - 'tscrollx', 12:03:27 INFO - 'cart']}, 12:03:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'talos_options': ['--disable-e10s'], 12:03:27 INFO - 'tests': ['tp5o']}, 12:03:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'tests': ['tp5o']}, 12:03:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'talos_options': ['--disable-e10s', 12:03:27 INFO - '--xperf_path', 12:03:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:27 INFO - 'tests': ['tp5n']}, 12:03:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:27 INFO - 'talos_options': ['--xperf_path', 12:03:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:27 INFO - 'tests': ['tp5n']}}, 12:03:27 INFO - 'talos.zip': {'path': '', 12:03:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:03:27 INFO - Running post-action listener: _resource_record_post_action 12:03:27 INFO - [mozharness: 2016-11-10 20:03:27.017319Z] Finished populate-webroot step (success) 12:03:27 INFO - [mozharness: 2016-11-10 20:03:27.017406Z] Running create-virtualenv step. 12:03:27 INFO - Running pre-action listener: _resource_record_pre_action 12:03:27 INFO - Running main action method: create_virtualenv 12:03:27 INFO - Creating virtualenv /builds/slave/test/build/venv 12:03:27 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 12:03:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:03:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:03:27 INFO - Using real prefix '/usr' 12:03:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:03:27 INFO - Installing distribute.............................................................................................................................................................................................done. 12:03:29 INFO - Installing pip.................done. 12:03:29 INFO - Return code: 0 12:03:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:29 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:29 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 12:03:29 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 12:03:29 INFO - Using env: {'DISPLAY': ':0', 12:03:29 INFO - 'HOME': '/home/cltbld', 12:03:29 INFO - 'LANG': 'en_US.UTF-8', 12:03:29 INFO - 'LANGUAGE': 'en_US:en', 12:03:29 INFO - 'LOGNAME': 'cltbld', 12:03:29 INFO - 'MAIL': '/var/mail/cltbld', 12:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:29 INFO - 'MOZ_NO_REMOTE': '1', 12:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:29 INFO - 'NO_EM_RESTART': '1', 12:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:29 INFO - 'PWD': '/builds/slave/test', 12:03:29 INFO - 'SHELL': '/bin/bash', 12:03:29 INFO - 'SHLVL': '1', 12:03:29 INFO - 'TERM': 'linux', 12:03:29 INFO - 'TMOUT': '86400', 12:03:29 INFO - 'USER': 'cltbld', 12:03:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:29 INFO - '_': '/tools/buildbot/bin/python'} 12:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:29 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 12:03:29 INFO - Cleaning up... 12:03:29 INFO - Return code: 0 12:03:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:29 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:29 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 12:03:29 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 12:03:29 INFO - Using env: {'DISPLAY': ':0', 12:03:29 INFO - 'HOME': '/home/cltbld', 12:03:29 INFO - 'LANG': 'en_US.UTF-8', 12:03:29 INFO - 'LANGUAGE': 'en_US:en', 12:03:29 INFO - 'LOGNAME': 'cltbld', 12:03:29 INFO - 'MAIL': '/var/mail/cltbld', 12:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:29 INFO - 'MOZ_NO_REMOTE': '1', 12:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:29 INFO - 'NO_EM_RESTART': '1', 12:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:29 INFO - 'PWD': '/builds/slave/test', 12:03:29 INFO - 'SHELL': '/bin/bash', 12:03:29 INFO - 'SHLVL': '1', 12:03:29 INFO - 'TERM': 'linux', 12:03:29 INFO - 'TMOUT': '86400', 12:03:29 INFO - 'USER': 'cltbld', 12:03:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:29 INFO - '_': '/tools/buildbot/bin/python'} 12:03:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:30 INFO - Downloading/unpacking psutil>=3.1.1 12:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:03:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:32 INFO - Installing collected packages: psutil 12:03:32 INFO - Running setup.py install for psutil 12:03:32 INFO - building 'psutil._psutil_linux' extension 12:03:32 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 12:03:32 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 12:03:32 INFO - building 'psutil._psutil_posix' extension 12:03:32 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 12:03:32 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 12:03:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:32 INFO - Successfully installed psutil 12:03:32 INFO - Cleaning up... 12:03:32 INFO - Return code: 0 12:03:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:32 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 12:03:32 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 12:03:32 INFO - Using env: {'DISPLAY': ':0', 12:03:32 INFO - 'HOME': '/home/cltbld', 12:03:32 INFO - 'LANG': 'en_US.UTF-8', 12:03:32 INFO - 'LANGUAGE': 'en_US:en', 12:03:32 INFO - 'LOGNAME': 'cltbld', 12:03:32 INFO - 'MAIL': '/var/mail/cltbld', 12:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:32 INFO - 'MOZ_NO_REMOTE': '1', 12:03:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:32 INFO - 'NO_EM_RESTART': '1', 12:03:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:32 INFO - 'PWD': '/builds/slave/test', 12:03:32 INFO - 'SHELL': '/bin/bash', 12:03:32 INFO - 'SHLVL': '1', 12:03:32 INFO - 'TERM': 'linux', 12:03:32 INFO - 'TMOUT': '86400', 12:03:32 INFO - 'USER': 'cltbld', 12:03:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:32 INFO - '_': '/tools/buildbot/bin/python'} 12:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:03:34 INFO - Installing collected packages: mozsystemmonitor 12:03:34 INFO - Running setup.py install for mozsystemmonitor 12:03:34 INFO - Successfully installed mozsystemmonitor 12:03:34 INFO - Cleaning up... 12:03:34 INFO - Return code: 0 12:03:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:03:34 INFO - Using env: {'DISPLAY': ':0', 12:03:34 INFO - 'HOME': '/home/cltbld', 12:03:34 INFO - 'LANG': 'en_US.UTF-8', 12:03:34 INFO - 'LANGUAGE': 'en_US:en', 12:03:34 INFO - 'LOGNAME': 'cltbld', 12:03:34 INFO - 'MAIL': '/var/mail/cltbld', 12:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:34 INFO - 'MOZ_NO_REMOTE': '1', 12:03:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:34 INFO - 'NO_EM_RESTART': '1', 12:03:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:34 INFO - 'PWD': '/builds/slave/test', 12:03:34 INFO - 'SHELL': '/bin/bash', 12:03:34 INFO - 'SHLVL': '1', 12:03:34 INFO - 'TERM': 'linux', 12:03:34 INFO - 'TMOUT': '86400', 12:03:34 INFO - 'USER': 'cltbld', 12:03:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:34 INFO - '_': '/tools/buildbot/bin/python'} 12:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:35 INFO - Downloading/unpacking jsonschema==2.5.1 12:03:35 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 12:03:35 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 12:03:35 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 12:03:35 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 12:03:35 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 12:03:35 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 12:03:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:03:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 INFO - Downloading functools32-3.2.3-2.tar.gz 12:03:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:03:37 INFO - warning: no files found matching '*.txt' 12:03:37 INFO - no previously-included directories found matching 'build' 12:03:37 INFO - no previously-included directories found matching 'dist' 12:03:37 INFO - no previously-included directories found matching '.git*' 12:03:37 INFO - Installing collected packages: jsonschema, functools32 12:03:37 INFO - Running setup.py install for functools32 12:03:37 INFO - warning: no files found matching '*.txt' 12:03:37 INFO - no previously-included directories found matching 'build' 12:03:37 INFO - no previously-included directories found matching 'dist' 12:03:37 INFO - no previously-included directories found matching '.git*' 12:03:37 INFO - Successfully installed jsonschema functools32 12:03:37 INFO - Cleaning up... 12:03:37 INFO - Return code: 0 12:03:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:03:37 INFO - Using env: {'DISPLAY': ':0', 12:03:37 INFO - 'HOME': '/home/cltbld', 12:03:37 INFO - 'LANG': 'en_US.UTF-8', 12:03:37 INFO - 'LANGUAGE': 'en_US:en', 12:03:37 INFO - 'LOGNAME': 'cltbld', 12:03:37 INFO - 'MAIL': '/var/mail/cltbld', 12:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:37 INFO - 'MOZ_NO_REMOTE': '1', 12:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:37 INFO - 'NO_EM_RESTART': '1', 12:03:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:37 INFO - 'PWD': '/builds/slave/test', 12:03:37 INFO - 'SHELL': '/bin/bash', 12:03:37 INFO - 'SHLVL': '1', 12:03:37 INFO - 'TERM': 'linux', 12:03:37 INFO - 'TMOUT': '86400', 12:03:37 INFO - 'USER': 'cltbld', 12:03:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:37 INFO - '_': '/tools/buildbot/bin/python'} 12:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:03:37 INFO - Cleaning up... 12:03:37 INFO - Return code: 0 12:03:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:03:37 INFO - Using env: {'DISPLAY': ':0', 12:03:37 INFO - 'HOME': '/home/cltbld', 12:03:37 INFO - 'LANG': 'en_US.UTF-8', 12:03:37 INFO - 'LANGUAGE': 'en_US:en', 12:03:37 INFO - 'LOGNAME': 'cltbld', 12:03:37 INFO - 'MAIL': '/var/mail/cltbld', 12:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:37 INFO - 'MOZ_NO_REMOTE': '1', 12:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:37 INFO - 'NO_EM_RESTART': '1', 12:03:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:37 INFO - 'PWD': '/builds/slave/test', 12:03:37 INFO - 'SHELL': '/bin/bash', 12:03:37 INFO - 'SHLVL': '1', 12:03:37 INFO - 'TERM': 'linux', 12:03:37 INFO - 'TMOUT': '86400', 12:03:37 INFO - 'USER': 'cltbld', 12:03:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:37 INFO - '_': '/tools/buildbot/bin/python'} 12:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:37 INFO - Downloading/unpacking blobuploader==1.2.4 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:37 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 12:03:39 INFO - Downloading blobuploader-1.2.4.tar.gz 12:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:03:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:03:39 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 12:03:39 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 12:03:39 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 12:03:39 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 12:03:39 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 12:03:39 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 12:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:03:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:03:40 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 12:03:40 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 12:03:40 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 12:03:40 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 12:03:40 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 12:03:40 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 12:03:40 INFO - Downloading docopt-0.6.1.tar.gz 12:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:03:40 INFO - Installing collected packages: blobuploader, requests, docopt 12:03:40 INFO - Running setup.py install for blobuploader 12:03:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:03:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:03:40 INFO - Running setup.py install for requests 12:03:41 INFO - Running setup.py install for docopt 12:03:41 INFO - Successfully installed blobuploader requests docopt 12:03:41 INFO - Cleaning up... 12:03:41 INFO - Return code: 0 12:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:41 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:41 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 12:03:41 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 12:03:41 INFO - Using env: {'DISPLAY': ':0', 12:03:41 INFO - 'HOME': '/home/cltbld', 12:03:41 INFO - 'LANG': 'en_US.UTF-8', 12:03:41 INFO - 'LANGUAGE': 'en_US:en', 12:03:41 INFO - 'LOGNAME': 'cltbld', 12:03:41 INFO - 'MAIL': '/var/mail/cltbld', 12:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:41 INFO - 'MOZ_NO_REMOTE': '1', 12:03:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:41 INFO - 'NO_EM_RESTART': '1', 12:03:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:41 INFO - 'PWD': '/builds/slave/test', 12:03:41 INFO - 'SHELL': '/bin/bash', 12:03:41 INFO - 'SHLVL': '1', 12:03:41 INFO - 'TERM': 'linux', 12:03:41 INFO - 'TMOUT': '86400', 12:03:41 INFO - 'USER': 'cltbld', 12:03:41 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:41 INFO - '_': '/tools/buildbot/bin/python'} 12:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:41 INFO - Running setup.py (path:/tmp/pip-zgfmO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:41 INFO - Running setup.py (path:/tmp/pip-NglMjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:41 INFO - Running setup.py (path:/tmp/pip-wTn7kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:41 INFO - Running setup.py (path:/tmp/pip-CUh98o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:41 INFO - Running setup.py (path:/tmp/pip-Q39hyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:41 INFO - Running setup.py (path:/tmp/pip-wtOtWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:41 INFO - Running setup.py (path:/tmp/pip-fP41sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:42 INFO - Running setup.py (path:/tmp/pip-jq0Kyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:42 INFO - Running setup.py (path:/tmp/pip-4b2lBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:42 INFO - Running setup.py (path:/tmp/pip-RKkw7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:42 INFO - Running setup.py (path:/tmp/pip-wEIT__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:42 INFO - Running setup.py (path:/tmp/pip-u1Xc7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:42 INFO - Running setup.py (path:/tmp/pip-DDOoR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:42 INFO - Running setup.py (path:/tmp/pip-CFRtjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:42 INFO - Running setup.py (path:/tmp/pip-sVnzF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:42 INFO - Running setup.py (path:/tmp/pip-42dEb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:42 INFO - Running setup.py (path:/tmp/pip-ZiJTtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:03:42 INFO - Running setup.py install for manifestparser 12:03:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:03:42 INFO - Running setup.py install for mozcrash 12:03:42 INFO - Running setup.py install for mozdebug 12:03:43 INFO - Running setup.py install for mozdevice 12:03:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for mozfile 12:03:43 INFO - Running setup.py install for mozhttpd 12:03:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for mozinfo 12:03:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for mozInstall 12:03:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for mozleak 12:03:43 INFO - Running setup.py install for mozlog 12:03:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for moznetwork 12:03:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:03:43 INFO - Running setup.py install for mozprocess 12:03:43 INFO - Running setup.py install for mozprofile 12:03:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:03:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:03:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:03:44 INFO - Running setup.py install for mozrunner 12:03:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:03:44 INFO - Running setup.py install for mozscreenshot 12:03:44 INFO - Running setup.py install for moztest 12:03:44 INFO - Running setup.py install for mozversion 12:03:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:03:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:03:44 INFO - Cleaning up... 12:03:44 INFO - Return code: 0 12:03:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:44 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:44 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 12:03:44 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 12:03:44 INFO - Using env: {'DISPLAY': ':0', 12:03:44 INFO - 'HOME': '/home/cltbld', 12:03:44 INFO - 'LANG': 'en_US.UTF-8', 12:03:44 INFO - 'LANGUAGE': 'en_US:en', 12:03:44 INFO - 'LOGNAME': 'cltbld', 12:03:44 INFO - 'MAIL': '/var/mail/cltbld', 12:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:44 INFO - 'MOZ_NO_REMOTE': '1', 12:03:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:44 INFO - 'NO_EM_RESTART': '1', 12:03:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:44 INFO - 'PWD': '/builds/slave/test', 12:03:44 INFO - 'SHELL': '/bin/bash', 12:03:44 INFO - 'SHLVL': '1', 12:03:44 INFO - 'TERM': 'linux', 12:03:44 INFO - 'TMOUT': '86400', 12:03:44 INFO - 'USER': 'cltbld', 12:03:44 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:44 INFO - '_': '/tools/buildbot/bin/python'} 12:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:44 INFO - Running setup.py (path:/tmp/pip-ix7WCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:44 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)) 12:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:44 INFO - Running setup.py (path:/tmp/pip-IuZSOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:44 INFO - Running setup.py (path:/tmp/pip-T9Pn0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:44 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)) 12:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:44 INFO - Running setup.py (path:/tmp/pip-rlbI2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:45 INFO - Running setup.py (path:/tmp/pip-sirsaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:45 INFO - Running setup.py (path:/tmp/pip-A3UGx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:45 INFO - Running setup.py (path:/tmp/pip-5KAPNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:45 INFO - Running setup.py (path:/tmp/pip-AqZSo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:45 INFO - Running setup.py (path:/tmp/pip-EPMQT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:45 INFO - Running setup.py (path:/tmp/pip-qJ3SXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:45 INFO - Running setup.py (path:/tmp/pip-TX6v5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:45 INFO - Running setup.py (path:/tmp/pip-zA7GkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:45 INFO - Running setup.py (path:/tmp/pip-QJ1wFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:45 INFO - Running setup.py (path:/tmp/pip-p2JY4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:45 INFO - Running setup.py (path:/tmp/pip-1KJwgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:45 INFO - Running setup.py (path:/tmp/pip-j7iZji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:45 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)) 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:45 INFO - Running setup.py (path:/tmp/pip-H8zM1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:45 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)) 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:45 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)) 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:45 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 12:03:45 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 12:03:45 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 12:03:45 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 12:03:45 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 12:03:45 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 12:03:47 INFO - Downloading blessings-1.6.tar.gz 12:03:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:03:47 INFO - Installing collected packages: blessings 12:03:47 INFO - Running setup.py install for blessings 12:03:48 INFO - Successfully installed blessings 12:03:48 INFO - Cleaning up... 12:03:48 INFO - Return code: 0 12:03:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:48 INFO - Reading from file tmpfile_stdout 12:03:48 INFO - Current package versions: 12:03:48 INFO - argparse == 1.2.1 12:03:48 INFO - blessings == 1.6 12:03:48 INFO - blobuploader == 1.2.4 12:03:48 INFO - docopt == 0.6.1 12:03:48 INFO - functools32 == 3.2.3-2 12:03:48 INFO - jsonschema == 2.5.1 12:03:48 INFO - manifestparser == 1.1 12:03:48 INFO - mozInstall == 1.12 12:03:48 INFO - mozcrash == 0.17 12:03:48 INFO - mozdebug == 0.1 12:03:48 INFO - mozdevice == 0.48 12:03:48 INFO - mozfile == 1.2 12:03:48 INFO - mozhttpd == 0.7 12:03:48 INFO - mozinfo == 0.9 12:03:48 INFO - mozleak == 0.1 12:03:48 INFO - mozlog == 3.4 12:03:48 INFO - moznetwork == 0.27 12:03:48 INFO - mozprocess == 0.23 12:03:48 INFO - mozprofile == 0.28 12:03:48 INFO - mozrunner == 6.12 12:03:48 INFO - mozscreenshot == 0.1 12:03:48 INFO - mozsystemmonitor == 0.3 12:03:48 INFO - moztest == 0.7 12:03:48 INFO - mozversion == 1.4 12:03:48 INFO - psutil == 3.1.1 12:03:48 INFO - requests == 1.2.3 12:03:48 INFO - wsgiref == 0.1.2 12:03:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:48 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')]} 12:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:48 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')]} 12:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:48 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:48 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 12:03:48 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 12:03:48 INFO - Using env: {'DISPLAY': ':0', 12:03:48 INFO - 'HOME': '/home/cltbld', 12:03:48 INFO - 'LANG': 'en_US.UTF-8', 12:03:48 INFO - 'LANGUAGE': 'en_US:en', 12:03:48 INFO - 'LOGNAME': 'cltbld', 12:03:48 INFO - 'MAIL': '/var/mail/cltbld', 12:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:48 INFO - 'MOZ_NO_REMOTE': '1', 12:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:48 INFO - 'NO_EM_RESTART': '1', 12:03:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:48 INFO - 'PWD': '/builds/slave/test', 12:03:48 INFO - 'SHELL': '/bin/bash', 12:03:48 INFO - 'SHLVL': '1', 12:03:48 INFO - 'TERM': 'linux', 12:03:48 INFO - 'TMOUT': '86400', 12:03:48 INFO - 'USER': 'cltbld', 12:03:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:48 INFO - '_': '/tools/buildbot/bin/python'} 12:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 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)) 12:03:48 INFO - Cleaning up... 12:03:48 INFO - Return code: 0 12:03:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:03:48 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')]} 12:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:48 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')]} 12:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:48 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 0x7fba38cc1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d57ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, '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-1478807748.725791-1076030357', '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 12:03:48 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 12:03:48 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 12:03:48 INFO - Using env: {'DISPLAY': ':0', 12:03:48 INFO - 'HOME': '/home/cltbld', 12:03:48 INFO - 'LANG': 'en_US.UTF-8', 12:03:48 INFO - 'LANGUAGE': 'en_US:en', 12:03:48 INFO - 'LOGNAME': 'cltbld', 12:03:48 INFO - 'MAIL': '/var/mail/cltbld', 12:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:48 INFO - 'MOZ_NO_REMOTE': '1', 12:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:48 INFO - 'NO_EM_RESTART': '1', 12:03:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:48 INFO - 'PWD': '/builds/slave/test', 12:03:48 INFO - 'SHELL': '/bin/bash', 12:03:48 INFO - 'SHLVL': '1', 12:03:48 INFO - 'TERM': 'linux', 12:03:48 INFO - 'TMOUT': '86400', 12:03:48 INFO - 'USER': 'cltbld', 12:03:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:48 INFO - '_': '/tools/buildbot/bin/python'} 12:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:03:48 INFO - Cleaning up... 12:03:48 INFO - Return code: 0 12:03:48 INFO - Running post-action listener: _resource_record_post_action 12:03:48 INFO - Running post-action listener: _start_resource_monitoring 12:03:48 INFO - Starting resource monitoring. 12:03:48 INFO - [mozharness: 2016-11-10 20:03:48.848222Z] Finished create-virtualenv step (success) 12:03:48 INFO - [mozharness: 2016-11-10 20:03:48.848724Z] Running install step. 12:03:48 INFO - Running pre-action listener: _resource_record_pre_action 12:03:48 INFO - Running main action method: install 12:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:49 INFO - Reading from file tmpfile_stdout 12:03:49 INFO - Detecting whether we're running mozinstall >=1.0... 12:03:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:03:49 INFO - Reading from file tmpfile_stdout 12:03:49 INFO - Output received: 12:03:49 INFO - Usage: mozinstall [options] installer 12:03:49 INFO - Options: 12:03:49 INFO - -h, --help show this help message and exit 12:03:49 INFO - -d DEST, --destination=DEST 12:03:49 INFO - Directory to install application into. [default: 12:03:49 INFO - "/builds/slave/test"] 12:03:49 INFO - --app=APP Application being installed. [default: firefox] 12:03:49 INFO - mkdir: /builds/slave/test/build/application 12:03:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:04:05 INFO - Reading from file tmpfile_stdout 12:04:05 INFO - Output received: 12:04:05 INFO - /builds/slave/test/build/application/firefox/firefox 12:04:05 INFO - Running post-action listener: _resource_record_post_action 12:04:05 INFO - [mozharness: 2016-11-10 20:04:05.137377Z] Finished install step (success) 12:04:05 INFO - [mozharness: 2016-11-10 20:04:05.137620Z] Running run-tests step. 12:04:05 INFO - Running pre-action listener: _resource_record_pre_action 12:04:05 INFO - Running main action method: run_tests 12:04:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:04:05 INFO - Python 2.7.3 12:04:05 INFO - Return code: 0 12:04:05 INFO - grabbing minidump binary from tooltool 12:04:05 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')]} 12:04:05 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 0x1d54f60>, '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 0x1d55d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d561d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:04:05 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 12:04:05 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 12:04:05 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 12:04:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:04:05 INFO - Return code: 0 12:04:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:04:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:04:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:04:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:04:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:04:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:04:05 INFO - Using env: {'DISPLAY': ':0', 12:04:05 INFO - 'HOME': '/home/cltbld', 12:04:05 INFO - 'LANG': 'en_US.UTF-8', 12:04:05 INFO - 'LANGUAGE': 'en_US:en', 12:04:05 INFO - 'LOGNAME': 'cltbld', 12:04:05 INFO - 'MAIL': '/var/mail/cltbld', 12:04:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:04:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:05 INFO - 'MOZ_NO_REMOTE': '1', 12:04:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:04:05 INFO - 'NO_EM_RESTART': '1', 12:04:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:05 INFO - 'PWD': '/builds/slave/test', 12:04:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:04:05 INFO - 'SHELL': '/bin/bash', 12:04:05 INFO - 'SHLVL': '1', 12:04:05 INFO - 'TERM': 'linux', 12:04:05 INFO - 'TMOUT': '86400', 12:04:05 INFO - 'USER': 'cltbld', 12:04:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1478807748.725791-1076030357', 12:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:05 INFO - '_': '/tools/buildbot/bin/python'} 12:04:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:04:05 INFO - mozversion application_buildid: 20161110073622 12:04:05 INFO - mozversion application_changeset: fa5bb25eb05ff73bb23c06cde372db389d8eb030 12:04:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:04:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:04:05 INFO - mozversion application_name: Firefox 12:04:05 INFO - mozversion application_remotingname: firefox-dev 12:04:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:04:05 INFO - mozversion application_vendor: Mozilla 12:04:05 INFO - mozversion application_version: 51.0a2 12:04:05 INFO - mozversion platform_buildid: 20161110073622 12:04:05 INFO - mozversion platform_changeset: fa5bb25eb05ff73bb23c06cde372db389d8eb030 12:04:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:04:05 INFO - mozversion platform_version: 51.0a2 12:04:05 INFO - using testdate: 1478808245 12:04:05 INFO - actual date: 1478808245 12:04:05 INFO - starting webserver on 'localhost:41295' 12:04:05 INFO - SUITE-START | Running 2 tests 12:04:05 INFO - TEST-START | dromaeo_css 12:04:05 INFO - operating with platform_type : linux_ 12:04:05 INFO - Initialising browser for dromaeo_css test... 12:04:05 INFO - TEST-INFO | started process 32522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIphAJ5/profile http://localhost:41295/getInfo.html) 12:04:09 INFO - PROCESS | 32522 | __metrics Screen width/height:1600/1200 12:04:09 INFO - PROCESS | 32522 | colorDepth:24 12:04:09 INFO - PROCESS | 32522 | Browser inner width/height: 1024/697 12:04:09 INFO - PROCESS | 32522 | __metrics 12:04:10 INFO - PROCESS | 32522 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:04:10 INFO - PROCESS | 32522 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:04:10 INFO - PROCESS | 32522 | console.error: PushService: 12:04:10 INFO - PROCESS | 32522 | stateChangeProcessEnqueue: Error transitioning state 12:04:10 INFO - PROCESS | 32522 | UnknownError 12:04:11 INFO - TEST-INFO | 32522: exit 0 12:04:11 INFO - Browser initialized. 12:04:11 INFO - Running cycle 1/1 for dromaeo_css test... 12:04:11 INFO - TEST-INFO | started process 32612 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIphAJ5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:23 INFO - PROCESS | 32612 | RSS: Main: 145448960 12:04:23 INFO - PROCESS | 32612 | 12:05:14 INFO - PROCESS | 32612 | 1478808314234 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] 12:07:23 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41295/tests/dromaeo/cssquery-ext.html) 12:07:24 INFO - PROCESS | 32612 | RSS: Main: 250155008 12:07:24 INFO - PROCESS | 32612 | 12:10:27 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-ext.html (next: http://localhost:41295/tests/dromaeo/cssquery-jquery.html) 12:10:27 INFO - PROCESS | 32612 | RSS: Main: 238522368 12:10:27 INFO - PROCESS | 32612 | 12:13:29 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41295/tests/dromaeo/cssquery-mootools.html) 12:13:30 INFO - PROCESS | 32612 | RSS: Main: 215265280 12:13:30 INFO - PROCESS | 32612 | 12:16:32 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41295/tests/dromaeo/cssquery-prototype.html) 12:16:32 INFO - PROCESS | 32612 | RSS: Main: 220479488 12:16:32 INFO - PROCESS | 32612 | 12:19:35 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41295/tests/dromaeo/cssquery-yui.html) 12:19:35 INFO - PROCESS | 32612 | RSS: Main: 178565120 12:19:35 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | Cycle 1(1): loaded http://localhost:41295/tests/dromaeo/cssquery-yui.html (next: http://localhost:41295/tests/dromaeo/cssquery-dojo.html) 12:22:38 INFO - PROCESS | 32612 | RSS: Main: 260190208 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | __start_tp_report 12:22:38 INFO - PROCESS | 32612 | _x_x_mozilla_page_load 12:22:38 INFO - PROCESS | 32612 | _x_x_mozilla_page_load_details 12:22:38 INFO - PROCESS | 32612 | |i|pagename|runs| 12:22:38 INFO - PROCESS | 32612 | |0;dojo.html;3126.0621813455964;3152.5113607390854;3169.556262123303;3186.5379519969592;3205.599300087489;5415.9458405415935;5441.537469315103;5442.673439593627;5464.808731694391;5508;5559;5581.330240371151;5596.300462442195;5601.915971260434;5607.551395888329;5037.143685573452;5063.417706963691;5084;5108.642395032331;5160.380754309469;4858.222684370501;5100.107481190796;5159.58723302135;5166;5167.741612919358;4820.493848145947;4998.925016124761;5065.949340506585;5067.315912351823;5086.262491938666;5099.388073431193;5121.436641969381;5128.153854613985;5190.091733946582;5210.088234558937;5027.723475208862;5099.980003999178;5150.227465880111;5159.845204643867;5189.740512974374;5357.517823395913;5412.350517937873;5454.563634909237;5457.481539253765;5542.196381524696;5829.067349224104;5925.377835327315;5947.256592925884;5949.315828679714;5956.285245770492;5865.794697185578;5885.823425297248;5898.64608123514;5899.882002359972;5908.438698323653;5292.364916210079;5324.227986941911;5445.863853403657;5520.585956053232;5545.861353466195;5995.550333724988;6032.969835150884;6036.607620504653;6037.45662890342;6040.728167232485;5764.308283005982;5768.826935191951;5769.740361683735;5772.595918285764;5773.04744717117;5690.0895856663565;5750.6262092963825;5830.7084645767545;5937.881242375129;5945.732442040032;5973.701314934236;5974.193483879708;5975.073863551156;5979.880402391927;6001.639901605918;5920.8815823684145;5976.491998180117;5976.5218782497295;5982.790602328898;6007.189029480965;4452.866414007585;4498.1003799239625;4498.707584007029;4573.954260457419;4625.930611040837;4072.714909956275;4089.5092588889524;4105.815238314283;4107.322291821875;4129.401236820644;5649.067903795828;5690.857728556736;5695.45893140151;5714.685692286881;5720.8283751487525;5728.427157284322;5779.942200578024;5794.797182098607;5833.270841144857;5967.16459695643;5634.915476267859;5634.971825140848;5646.774129034875;5655.915161272585;5677.914831277534;5146.768395422215;5158.509941555546;5164.483551644806;5170.457102004236;5202.401723801811;5830.562707796932;5884.352721200579;5886.793962211302;6123.663198524035;6125.234345706786;5812.476877081083;5859.150423188528;5863.355030946678;5877.23595932526;5922.437368449991;6007.3992600739575;6029.939700602938;6046.27444706638;6053.243344581927;6058.394160583906;5671.6597004179885;5710.143478478095;5768.798092066758;5785.8553536161935;5790.044642633919;5806.042003069615;5933.673647949404;5933.940660593339;5990.580659353977;6022.427869352405;5845.035569131046;5851.795187168599;5852.034414321589;5852.7658893645485;5852.970735146297;5606.831795046155;5656.547476201812;5805.9839528081575;5818.534517238695;5822.272215973004;5603.635763675348;5658.660480371191;5723.942760572341;5772.19189313488;5802.94197058024;5793.449622285876;5803.071508558611;5841.065429531254;5844.678542680023;5849.3858144894175;5503.3395992481155;5515.641483303572;5570.498655120897;5660.462256085665;5703 12:22:38 INFO - PROCESS | 32612 | |1;ext.html;585.9296884373875;618.9752409903604;646.0857886091181;666.9433098186654;670.1823774994505;36230.63769362306;38776.418353724715;47223.29165062522;48480.757596211966;48571.02857942848;49057.75471122639;49555.25667114996;49581;49589.75205123978;49663.25505117417;9295.535223238829;9453.763655908622;9463.101005404476;9463.38487998278;9463.621455141785;8572.828543429128;8769.123087691243;8777.42946708465;9275.489848058354;11258.943705281503;11431.199816012882;11496.137789665767;11496.425178741072;11502.482388292505;11512.539498420054;11169.44152792357;11212.10303175744;11216.66350009501;11231.269967452172;11232.269902456314;11061.889381106166;11109.83335249972;11114.833277500844;11121.388323642197;11127;45036.549634503564;45397;45406.772966135286;45411.54588454106;45509.08981820378;3676.5955744868043;3857.864974725899;3933.0560665440375;4068.4507591475067;4482.663800214997;33926.83036584826;34484.82757586221;34709.30581388383;35304.470432943526;35882;4644.8606541803465;4672.065586882603;4677.64917631179;4707.976460117712;4767.642426817968;363599;365898;366120;367529;369182;65803.34196657973;65952;66158.33841661522;66197.66901165464;66246;40657.59342406597;41594;41966;44230.77884610621;44274.114517709466;19954.50113747139;20122.39632811018;20130.496737581383;20145.597088058155;20165.39503814888;15349.9232503839;15459.845401546065;15464.304106315243;15473.690526189414;15474.922625387026;18845.246190152513;19329.4201173965;20446.591068178554;20863;21231.36305910825;7414.962925185339;7421.6269990052015;7476.514026588255;7518.699252029857;7532.39740820733;973.0707174648336;1022.9846552301722;1104.884226481315;1132.6715252576826;1161.9941900290614;33763.32473350519;33886.66113338835;34350;34392;34498.310033799185;1060.989390106089;1106.397013627575;1144.673767976123;1146.1633007904277;1227.7421741434398;16974.915125424293;18156.45530634083;18217.27130914775;18262.086895655164;18333;548480;552660;552970;554998;555202;43973.78013109914;44380;44455.55544444514;65069.349306507276;65129.348706512326;1344.8318960129984;1396.8952328575397;1422.5092343141807;1434.956951291463;1448.3482432905234;33167.83416082904;35350.116247093;35625.109372264866;36032.81983590065;36057.27885442329;55557.72221138868;55821;56230.71884640551;56298.43701562932;56365.43634563766;429297;432631;433194;433619;433760;30907.69092309138;32142.19644508906;32192.195195119373;33602.49596256009;33603.83198083994;52651;53045.4695453056;53078.93842123213;53182.2022669668;53401.4659853412;43284.35073473964;44615.10769784651;44863.775681121384;45134;45991;43682.78158609186;43876;44412.333814992155;44799.10401792011;45363.77318113388;43711.56288437202;44223.77888110668;45210.54789452195;45368.31947520856;45577;36302.455463168604;39256.607433925295;39680.801595991834;39803;40287.597124029555;29278.61685214643;29380.706192937796;29429.11712648624;29636.85181574078;29936.25159371033 12:22:38 INFO - PROCESS | 32612 | |2;jquery.html;395.7704531371805;428.8239214209139;431.40466156703746;433.6985794872564;443.2199329180641;16700.749488757665;21147;21223.151073957044;21363.35909922703;21631.242906498275;20830.89584552075;20915;20998.05508752106;21028.369148925558;21039.05324260408;5258.290130832346;5711.543076553866;6129.785457508988;6158.876822463548;6163.260408750956;6005.849853753669;6024.638521688691;6048.123022161784;6052.303985041733;6065;6230.813075607739;6366.649834259114;6373.139626150457;6376.52176086793;6379.744810207586;6380.7128679209545;6382.297947225801;6666.500012499057;6844.5208835381545;6895.069165662621;6132.264128304588;6178.289496707846;6190.659513726789;6196.101565273055;6197.87604247917;15301.005434646824;15607.141607211599;23732.64401033986;23848.880755596285;24075.879620601783;2360.917367892133;2466.8149888758417;2512.5351809915223;2620.685517737884;2853.2296280004284;10470.790584188273;11107.500162492708;11108.055815255713;13667.931660341636;14232.217228052454;2007.8895660738654;2025.9898700506403;2045.8750829172827;2079.521710006707;2082.416923261489;79624.20375796284;83799.5810020946;83949;85688;87125.56437217837;11296.49165787525;11752.177347585586;11760;11810.40947952599;11813.88186118145;16853;16860.156992150343;16869.15654217284;16930.153492325335;16972.06653634037;8813.383063185644;8865.911340886509;8993.640254389751;9069.138431848964;9090.54547272647;8657.740267791976;8722.476651400937;8724.91275087241;8840.292776577988;8849.20357167858;5115.2071428928575;5148.665336753099;5183.3261675981885;5186.740662966837;5232.084385232569;2077.6883467479997;2083.3229200509895;2101.4956410461687;2105.978940210609;2153.3109404990564;746.3432179681845;901.9413738107002;972.0959507657805;979.5151400056875;1010.7372083258406;19694.507637308896;20405.897970510054;20749.688754668692;20862.478438038866;20903.47741306449;1031.0771859186136;1043.7599352149048;1061.7186445591924;1072.3940973350032;1117.6647005898199;10762.56949722018;11530.308181509135;11578.71053223676;11594.942025289989;11805.350705711096;549524;562937;564258;564750;565284.8267085843;96582.51708741552;97777;98490;101212;102710.48644756728;1017.59296281488;1037.7561273100966;1098.0611577101622;1116.4640972333164;1118.4016551145098;17724.46826595204;17958.5510362242;18079.096045197424;18380.17289222015;18611.441656750318;42563.1487370257;43553.78223108864;44566.55433445624;44812.551874480836;45608;59279.70360148171;59499;61316;62449;64000.67999660158;8327.916720832714;8591.527465989431;8772.649094036167;8802.119788021148;8828.690995815115;8461.280791132576;13558.661033474242;13883.583492495241;13898.583042508337;13934.372953216927;11703.76592468129;11725.648230553097;12069.396530173632;12141.332226727614;12376.75246495047;11414.828777568177;11473.483693233658;11545.48045337945;11782.764344712887;12065;11674.423952766607;11687.18189726711;11924.70188245266;12140.271583705005;12428.197911302992;8494.023187333536;8511.787205319917;8543.316534677087;8757.649694012423;8806.075362087146;8536.829263414822;11493.942530287295;11825.172237943261;11986.460609272777;11997.820032699341 12:22:38 INFO - PROCESS | 32612 | |3;mootools.html;1131.2194585735842;1143.1483544759153;1325.9061274448582;1328.8139660447532;1346.0375831280633;8426.705065322714;9023.72928812135;9024.72925812225;9028.593713282893;9029;7774.8445031099345;7968.3625309975205;8391.790205244872;8657.783555411117;8877.201051905327;2468.6050231962936;2496.8127390445698;2499.3376755159898;2501.1746123779108;2510.3724068982756;2402.0992127952018;2452.7792498675117;2468.6173643085262;2471.9864855409287;2473.122041675206;2026.1490174126902;2156.1159924430985;2278.4417817634703;2348.790372957928;2355.9764402356013;2376.370261880603;2380.262118743195;2390.175389490623;2392.222527678503;2392.83250172488;2354.976450235493;2397.676313697646;2399.616061430163;2400.987995060031;2402.3513651314224;8557.743267701979;8567.828643427096;8599.914000859973;8609.139086091403;8613.569321533962;5193.766280517386;5326.440723723992;5328.600354973354;5341.759620817073;5352.384475785296;3323.7341012718925;3378.324335132959;3496.842642081087;3499.422595271777;3500.2299494111253;1164.5109054197258;1187.9109066820024;1210.1891732539132;1218.5430463576158;1236.6846454154222;11848.88151118495;11990.400479975966;12002.279863208236;12006.999999999913;12007.219530730474;4546.340780586796;4676.228422310281;4786.2581299898575;4830.275458681155;4838.371011768448;10114.241431892637;10127.64553240633;10131.13885319756;10201.744956376002;10204.081632653097;5408.945910540923;5411.729413529308;5415.810446634349;5416.295881535375;5420.376656684532;4315.352697095398;4479.417675702203;4919.680220785637;4992.975035124801;5031.069252188357;4285.271503844354;4290.29210180323;4396.318570621559;4459.6655250855665;4524.796384162721;1361.46902707944;1396.7346204221164;1399.237415608496;1407.8873690104767;1420.2756594136858;958.1185309515263;994.2642444591095;1009.7677534167036;1010.2473657125488;1011.5144730529387;5988.221531200916;6071.787487437919;6103.572749907553;6239.563230573816;6273.0904018917;942.123824842889;992.245893121233;1025.5743866295404;1061;1077.4020418667715;4057.39139129133;4105.938410923838;4166.145940082276;4257.6168144867115;4306.267934451151;10496.947515262375;10861.565537378574;10864.293820901616;10868.619598314179;10870.510827012804;10779.137668986463;10785.676429707122;10799.298045627167;10801.567937282578;10802.729931751612;7860.3318717909615;8080.71717489885;8200.671973121009;8239.546824924566;8257.133001034925;5752.568557358299;5778.855528611818;6014.879702406015;6205.5345849060595;6209.813705588839;9674.419534827934;9759.512024398893;9892.703218903445;9928.652497162564;9983.900160998588;9991.150752186146;10184.287099902936;10188.796224075626;10194.235432342455;10271.075603195748;6752.831179220559;7232.421406287379;7506.399488041053;7567.697292108474;7693.615319234128;8251.876221856557;8333.916660833314;8344.749657510285;8355.080941096596;8410.369222308229;7163.391111755559;7364.815879603053;7426;7484.850302994018;7758.495697779626;7312.744053958086;7344.596047217455;7415.692187858773;7557.622118894144;7673.424493162924;7090.64546772649;7104.076470059066;7124.430045596237;7125.287471252833;7172.964135179291;5362.892742145214;5448.56411487088;5515.172724091419;5574.08027675429;5593.888122237451;5449.591280654046;5466.753996070266;5506.394296627288;5544.7227638618715;5624.634398764068 12:22:38 INFO - PROCESS | 32612 | |4;prototype.html;343.3510664843445;349.6171691997264;350.498786734969;363.06329669452816;365.0563293885306;5586.715055537229;5596.4403559644015;5620.775168993236;5646.378898321182;5650.576206784493;5197.948020519793;5332.413434522209;5336.17289320155;5370.570354371651;5519.923614895087;4170.97914510427;4274.829006839723;4298.140371925611;4300.9247688077985;4307.590778876001;4282.892927676818;4283.586416482553;4299.161663475624;4303.54812744662;4306.806193721274;4055.371417430303;4228.365745138223;4245.7240279381895;4246.150769846027;4305.504866940295;4204.1801848639525;4231.555686652919;4236.597523235288;4240.618344349024;4272.145570885819;3773.9998900291444;3774.621988147477;3785.62143785622;3791.620837916214;3810.6951443884423;5261.552768014723;5283.260343551906;5321.600879933982;5414.7834086636485;5459.945400545984;4807.759612019385;4811.278308253755;4818.253170758538;4827.9034419311765;4851.757412129415;5696.401877802812;5721.56705976415;5736.311642602873;5747.798827041033;5795.1886735857015;4015.2371049500503;4072.511298644182;4078.5309689385804;4130.793460326971;4138.151678905817;5950.8809823803285;5990.214550234162;5998.40015998401;6022.156898034279;6037.792441511715;4163.937540936889;4187.664986801049;4199.391088292181;4200.264953633163;4202.915941681211;4059.7361171523758;4181.393697913785;4198.1393814268;4219.641330486882;4230.788460576959;4759.428868535798;4770.236762118045;4774.328984855273;4794.2808578712775;4798.688085274446;5722.399148089474;5730.541556675539;5744.736158045223;5751.338596061423;5770.971145144247;1408.0988167572762;1480.8889333300046;1491.1649476293094;1501.1743541052379;1510.2826157575064;935.1630290889616;946.0870260198941;962.9277804164716;964.5628014258704;971.8882328532168;4992;4993.950060499421;5006.574441172468;5008.549230569266;5019.648624596243;5189.209722645681;5225.503577160163;5226.386806596702;5231.319928409359;5256.26412302278;4692.671512994126;4924.9507504925205;4987.800487980513;4998.90002199954;5007.474215207423;2747.7114902935305;2771.750542451189;2774.3631257557736;2811.971880281171;2812.915612531627;5351.545118664957;5377.650452720561;5396.734975339941;5406.6213115655255;5432.266644003088;4977.377827771528;5275.3142091528625;5313.698143954756;5341.946580534222;5350.946490535122;5280.65675731084;5297.231901374279;5346.812861549827;5362.3565172178805;5376.354837419534;5178.990096931062;5181.92227116586;5207.3230480037355;5251.737413129405;5271.367435907716;5123.283699960555;5509.917351239815;5849.53203743708;5879.941610510149;5885.47030767233;6132.202813634199;6150.600210986271;6150.938490615036;6154.784582539769;6175.085723425753;4137.420761093509;4286.435451060403;4319.6544276458435;4337.1976184405985;4339.783010849382;5893.911591326219;5896.8820623586425;5906.586538942404;5910.911336329872;5916.201312822714;5737.397573254748;5758.62568933018;5772.124059680705;5778.104393818965;5797.768089276382;5587.804426845041;5734.655920644775;5741.483266506035;5779.190913272061;5783.277090363705;5712.514436272963;5713.285839270091;5721.685307308138;5734;5738.598298119092;5524.861878453071;5790;5820.76716931335;5889.440503152194;5902.671898822902;5619.662820230799;5624.465675760796;5631.211630371833;5641.097424412238;5652 12:22:38 INFO - PROCESS | 32612 | |5;yui.html;680.7787469072553;707.9185893622233;742.5284944060523;763.5342441110918;782.2451334462245;14983;15825.366985320574;15906.52280431586;15932.12373319467;15945.68108637828;13225.603231903058;13244.668883277924;13264.867351326484;13265;13290.202587844737;714.203662915849;733.7248531800575;847.6694089305176;868.5700578213762;871.0984131423946;872.4808738800435;876.1895246896627;876.2902049340023;876.8027193881358;879.0198928195081;929.4144688846052;933.0948979489884;936.1387523478382;939.7791518993031;940.0223767282018;756.7464899258728;770.3990887108065;889.226373055446;907.2197909797569;930.8231436027168;667.7062092679254;668.7024274197984;671.5198632977396;672.3612568060313;672.7174586673609;14661.486847960377;14666.70666586673;14719.190444525548;14728.968972171951;14795.630109247248;13644.522441714493;14225.502107426191;14365.64085897861;14496.710065798625;14551.3451894665;12903.87096129036;12976.416061277265;13008.284544350057;13016;13276.137051091744;4415.183191109655;4439.711418757771;4442.822287108512;4460.197164510387;4518.774061296922;81497;82009.58995205046;85834.57082714609;87094.56452717696;88879.55560222222;10958.28771129874;11235.66293011211;11260;11345.205835591594;11558.190926635054;21140.894295528426;21147.682784758243;21165.576688466146;21174.682379764316;21179.576408471745;8716.128387161232;8783.077776833468;8894.822103557892;8954.059823718546;9025.368224224372;4807.6153907688;4847.612191024709;4899.412070551557;4932.28481870134;5130.897382052338;3397.303552771676;3525.1892064825233;3528.0297918072224;3529.8764543240864;3530.2409981854025;585.1690599348934;590.7932223721749;596.6194882419203;604.3684349854412;610.6763415389851;598.9520838332922;629.2951893878793;659.1957811469999;664.9460604941125;679.0934102972587;9319.254459643213;9518.428894266228;9697.903020969841;9714.90285097154;9848.261380396356;590.539379284159;638.7381173718753;644.2591020326662;667.9432248258952;668.9899651505232;4598.448186217608;4705.082508910729;5106.285120083191;5216.33623240297;5248.816291429782;123834;124146.3792681049;124261;126985.3650731759;126988;111861.44069279601;114515;115845.4207728956;117111.41444292894;119525;95584;96164.51085897537;98872.50563747279;101573.98426015793;101686;9893;10140.188784897338;10457.372557646566;10468.895311047097;10472.476376181314;55141.44858551363;56172.719136404055;56602.7169864148;57126.714366429565;58899.41100589111;77578.2242177571;78283.60858195672;85128.57435712781;86424;86465.56767216124;12480;12593.685157871127;12606.93696531548;12717.87282127204;12842.422091005741;14763;14785.630359240675;15035.54893353201;15067.321970511133;15136.243187840784;13339.733205336033;13578.592642220749;14033.087849289763;14045.438182472586;14496.782548261574;13420.39608217613;13622.250776207404;13879.791803123164;13914.09558378702;13986.16083035021;13465.39405726725;13601.523946661819;13606.795898061338;13667.589972300846;14122.152670839783;11241.381724005258;11423.200375973602;11461.312321260752;11757.470913808727;11894;11501.252418592765;11715.472803724024;11875.406229688653;12041.879581204075;12062.396880155782 12:22:38 INFO - PROCESS | 32612 | __end_tp_report 12:22:38 INFO - PROCESS | 32612 | __start_cc_report 12:22:38 INFO - PROCESS | 32612 | _x_x_mozilla_cycle_collect,887 12:22:38 INFO - PROCESS | 32612 | __end_cc_report 12:22:38 INFO - PROCESS | 32612 | __startTimestamp1478809358413__endTimestamp 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | ------- Summary: start ------- 12:22:38 INFO - PROCESS | 32612 | Number of tests: 6 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#0] dojo.html Cycles:165 Average:5490.55 Median:5695.46 stddev:606.64 (10.7%) stddev-sans-first:579.44 12:22:38 INFO - PROCESS | 32612 | Values: 3126.1 3152.5 3169.6 3186.5 3205.6 5415.9 5441.5 5442.7 5464.8 5508.0 5559.0 5581.3 5596.3 5601.9 5607.6 5037.1 5063.4 5084.0 5108.6 5160.4 4858.2 5100.1 5159.6 5166.0 5167.7 4820.5 4998.9 5065.9 5067.3 5086.3 5099.4 5121.4 5128.2 5190.1 5210.1 5027.7 5100.0 5150.2 5159.8 5189.7 5357.5 5412.4 5454.6 5457.5 5542.2 5829.1 5925.4 5947.3 5949.3 5956.3 5865.8 5885.8 5898.6 5899.9 5908.4 5292.4 5324.2 5445.9 5520.6 5545.9 5995.6 6033.0 6036.6 6037.5 6040.7 5764.3 5768.8 5769.7 5772.6 5773.0 5690.1 5750.6 5830.7 5937.9 5945.7 5973.7 5974.2 5975.1 5979.9 6001.6 5920.9 5976.5 5976.5 5982.8 6007.2 4452.9 4498.1 4498.7 4574.0 4625.9 4072.7 4089.5 4105.8 4107.3 4129.4 5649.1 5690.9 5695.5 5714.7 5720.8 5728.4 5779.9 5794.8 5833.3 5967.2 5634.9 5635.0 5646.8 5655.9 5677.9 5146.8 5158.5 5164.5 5170.5 5202.4 5830.6 5884.4 5886.8 6123.7 6125.2 5812.5 5859.2 5863.4 5877.2 5922.4 6007.4 6029.9 6046.3 6053.2 6058.4 5671.7 5710.1 5768.8 5785.9 5790.0 5806.0 5933.7 5933.9 5990.6 6022.4 5845.0 5851.8 5852.0 5852.8 5853.0 5606.8 5656.5 5806.0 5818.5 5822.3 5603.6 5658.7 5723.9 5772.2 5802.9 5793.4 5803.1 5841.1 5844.7 5849.4 5503.3 5515.6 5570.5 5660.5 5703.0 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#1] ext.html Cycles:180 Average:62415.19 Median:22363.02 stddev:120907.50 (540.7%) stddev-sans-first:121157.56 12:22:38 INFO - PROCESS | 32612 | Values: 585.9 619.0 646.1 666.9 670.2 36230.6 38776.4 47223.3 48480.8 48571.0 49057.8 49555.3 49581.0 49589.8 49663.3 9295.5 9453.8 9463.1 9463.4 9463.6 8572.8 8769.1 8777.4 9275.5 11258.9 11431.2 11496.1 11496.4 11502.5 11512.5 11169.4 11212.1 11216.7 11231.3 11232.3 11061.9 11109.8 11114.8 11121.4 11127.0 45036.5 45397.0 45406.8 45411.5 45509.1 3676.6 3857.9 3933.1 4068.5 4482.7 33926.8 34484.8 34709.3 35304.5 35882.0 4644.9 4672.1 4677.6 4708.0 4767.6 363599.0 365898.0 366120.0 367529.0 369182.0 65803.3 65952.0 66158.3 66197.7 66246.0 40657.6 41594.0 41966.0 44230.8 44274.1 19954.5 20122.4 20130.5 20145.6 20165.4 15349.9 15459.8 15464.3 15473.7 15474.9 18845.2 19329.4 20446.6 20863.0 21231.4 7415.0 7421.6 7476.5 7518.7 7532.4 973.1 1023.0 1104.9 1132.7 1162.0 33763.3 33886.7 34350.0 34392.0 34498.3 1061.0 1106.4 1144.7 1146.2 1227.7 16974.9 18156.5 18217.3 18262.1 18333.0 548480.0 552660.0 552970.0 554998.0 555202.0 43973.8 44380.0 44455.6 65069.3 65129.3 1344.8 1396.9 1422.5 1435.0 1448.3 33167.8 35350.1 35625.1 36032.8 36057.3 55557.7 55821.0 56230.7 56298.4 56365.4 429297.0 432631.0 433194.0 433619.0 433760.0 30907.7 32142.2 32192.2 33602.5 33603.8 52651.0 53045.5 53078.9 53182.2 53401.5 43284.4 44615.1 44863.8 45134.0 45991.0 43682.8 43876.0 44412.3 44799.1 45363.8 43711.6 44223.8 45210.5 45368.3 45577.0 36302.5 39256.6 39680.8 39803.0 40287.6 29278.6 29380.7 29429.1 29636.9 29936.3 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#2] jquery.html Cycles:180 Average:31778.07 Median:23962.38 stddev:92372.99 (385.5%) stddev-sans-first:92602.07 12:22:38 INFO - PROCESS | 32612 | Values: 395.8 428.8 431.4 433.7 443.2 16700.7 21147.0 21223.2 21363.4 21631.2 20830.9 20915.0 20998.1 21028.4 21039.1 5258.3 5711.5 6129.8 6158.9 6163.3 6005.8 6024.6 6048.1 6052.3 6065.0 6230.8 6366.6 6373.1 6376.5 6379.7 6380.7 6382.3 6666.5 6844.5 6895.1 6132.3 6178.3 6190.7 6196.1 6197.9 15301.0 15607.1 23732.6 23848.9 24075.9 2360.9 2466.8 2512.5 2620.7 2853.2 10470.8 11107.5 11108.1 13667.9 14232.2 2007.9 2026.0 2045.9 2079.5 2082.4 79624.2 83799.6 83949.0 85688.0 87125.6 11296.5 11752.2 11760.0 11810.4 11813.9 16853.0 16860.2 16869.2 16930.2 16972.1 8813.4 8865.9 8993.6 9069.1 9090.5 8657.7 8722.5 8724.9 8840.3 8849.2 5115.2 5148.7 5183.3 5186.7 5232.1 2077.7 2083.3 2101.5 2106.0 2153.3 746.3 901.9 972.1 979.5 1010.7 19694.5 20405.9 20749.7 20862.5 20903.5 1031.1 1043.8 1061.7 1072.4 1117.7 10762.6 11530.3 11578.7 11594.9 11805.4 549524.0 562937.0 564258.0 564750.0 565284.8 96582.5 97777.0 98490.0 101212.0 102710.5 1017.6 1037.8 1098.1 1116.5 1118.4 17724.5 17958.6 18079.1 18380.2 18611.4 42563.1 43553.8 44566.6 44812.6 45608.0 59279.7 59499.0 61316.0 62449.0 64000.7 8327.9 8591.5 8772.6 8802.1 8828.7 8461.3 13558.7 13883.6 13898.6 13934.4 11703.8 11725.6 12069.4 12141.3 12376.8 11414.8 11473.5 11545.5 11782.8 12065.0 11674.4 11687.2 11924.7 12140.3 12428.2 8494.0 8511.8 8543.3 8757.6 8806.1 8536.8 11493.9 11825.2 11986.5 11997.8 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#3] mootools.html Cycles:180 Average:5794.19 Median:4834.32 stddev:3200.51 (66.2%) stddev-sans-first:3190.30 12:22:38 INFO - PROCESS | 32612 | Values: 1131.2 1143.1 1325.9 1328.8 1346.0 8426.7 9023.7 9024.7 9028.6 9029.0 7774.8 7968.4 8391.8 8657.8 8877.2 2468.6 2496.8 2499.3 2501.2 2510.4 2402.1 2452.8 2468.6 2472.0 2473.1 2026.1 2156.1 2278.4 2348.8 2356.0 2376.4 2380.3 2390.2 2392.2 2392.8 2355.0 2397.7 2399.6 2401.0 2402.4 8557.7 8567.8 8599.9 8609.1 8613.6 5193.8 5326.4 5328.6 5341.8 5352.4 3323.7 3378.3 3496.8 3499.4 3500.2 1164.5 1187.9 1210.2 1218.5 1236.7 11848.9 11990.4 12002.3 12007.0 12007.2 4546.3 4676.2 4786.3 4830.3 4838.4 10114.2 10127.6 10131.1 10201.7 10204.1 5408.9 5411.7 5415.8 5416.3 5420.4 4315.4 4479.4 4919.7 4993.0 5031.1 4285.3 4290.3 4396.3 4459.7 4524.8 1361.5 1396.7 1399.2 1407.9 1420.3 958.1 994.3 1009.8 1010.2 1011.5 5988.2 6071.8 6103.6 6239.6 6273.1 942.1 992.2 1025.6 1061.0 1077.4 4057.4 4105.9 4166.1 4257.6 4306.3 10496.9 10861.6 10864.3 10868.6 10870.5 10779.1 10785.7 10799.3 10801.6 10802.7 7860.3 8080.7 8200.7 8239.5 8257.1 5752.6 5778.9 6014.9 6205.5 6209.8 9674.4 9759.5 9892.7 9928.7 9983.9 9991.2 10184.3 10188.8 10194.2 10271.1 6752.8 7232.4 7506.4 7567.7 7693.6 8251.9 8333.9 8344.7 8355.1 8410.4 7163.4 7364.8 7426.0 7484.9 7758.5 7312.7 7344.6 7415.7 7557.6 7673.4 7090.6 7104.1 7124.4 7125.3 7173.0 5362.9 5448.6 5515.2 5574.1 5593.9 5449.6 5466.8 5506.4 5544.7 5624.6 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#4] prototype.html Cycles:180 Average:4700.48 Median:5254.00 stddev:1368.64 (26.0%) stddev-sans-first:1332.84 12:22:38 INFO - PROCESS | 32612 | Values: 343.4 349.6 350.5 363.1 365.1 5586.7 5596.4 5620.8 5646.4 5650.6 5197.9 5332.4 5336.2 5370.6 5519.9 4171.0 4274.8 4298.1 4300.9 4307.6 4282.9 4283.6 4299.2 4303.5 4306.8 4055.4 4228.4 4245.7 4246.2 4305.5 4204.2 4231.6 4236.6 4240.6 4272.1 3774.0 3774.6 3785.6 3791.6 3810.7 5261.6 5283.3 5321.6 5414.8 5459.9 4807.8 4811.3 4818.3 4827.9 4851.8 5696.4 5721.6 5736.3 5747.8 5795.2 4015.2 4072.5 4078.5 4130.8 4138.2 5950.9 5990.2 5998.4 6022.2 6037.8 4163.9 4187.7 4199.4 4200.3 4202.9 4059.7 4181.4 4198.1 4219.6 4230.8 4759.4 4770.2 4774.3 4794.3 4798.7 5722.4 5730.5 5744.7 5751.3 5771.0 1408.1 1480.9 1491.2 1501.2 1510.3 935.2 946.1 962.9 964.6 971.9 4992.0 4994.0 5006.6 5008.5 5019.6 5189.2 5225.5 5226.4 5231.3 5256.3 4692.7 4925.0 4987.8 4998.9 5007.5 2747.7 2771.8 2774.4 2812.0 2812.9 5351.5 5377.7 5396.7 5406.6 5432.3 4977.4 5275.3 5313.7 5341.9 5350.9 5280.7 5297.2 5346.8 5362.4 5376.4 5179.0 5181.9 5207.3 5251.7 5271.4 5123.3 5509.9 5849.5 5879.9 5885.5 6132.2 6150.6 6150.9 6154.8 6175.1 4137.4 4286.4 4319.7 4337.2 4339.8 5893.9 5896.9 5906.6 5910.9 5916.2 5737.4 5758.6 5772.1 5778.1 5797.8 5587.8 5734.7 5741.5 5779.2 5783.3 5712.5 5713.3 5721.7 5734.0 5738.6 5524.9 5790.0 5820.8 5889.4 5902.7 5619.7 5624.5 5631.2 5641.1 5652.0 12:22:38 INFO - PROCESS | 32612 | 12:22:38 INFO - PROCESS | 32612 | [#5] yui.html Cycles:180 Average:22599.74 Median:3972.71 stddev:33838.04 (851.8%) stddev-sans-first:33892.94 12:22:38 INFO - PROCESS | 32612 | Values: 680.8 707.9 742.5 763.5 782.2 14983.0 15825.4 15906.5 15932.1 15945.7 13225.6 13244.7 13264.9 13265.0 13290.2 714.2 733.7 847.7 868.6 871.1 872.5 876.2 876.3 876.8 879.0 929.4 933.1 936.1 939.8 940.0 756.7 770.4 889.2 907.2 930.8 667.7 668.7 671.5 672.4 672.7 14661.5 14666.7 14719.2 14729.0 14795.6 13644.5 14225.5 14365.6 14496.7 14551.3 12903.9 12976.4 13008.3 13016.0 13276.1 4415.2 4439.7 4442.8 4460.2 4518.8 81497.0 82009.6 85834.6 87094.6 88879.6 10958.3 11235.7 11260.0 11345.2 11558.2 21140.9 21147.7 21165.6 21174.7 21179.6 8716.1 8783.1 8894.8 8954.1 9025.4 4807.6 4847.6 4899.4 4932.3 5130.9 3397.3 3525.2 3528.0 3529.9 3530.2 585.2 590.8 596.6 604.4 610.7 599.0 629.3 659.2 664.9 679.1 9319.3 9518.4 9697.9 9714.9 9848.3 590.5 638.7 644.3 667.9 669.0 4598.4 4705.1 5106.3 5216.3 5248.8 123834.0 124146.4 124261.0 126985.4 126988.0 111861.4 114515.0 115845.4 117111.4 119525.0 95584.0 96164.5 98872.5 101574.0 101686.0 9893.0 10140.2 10457.4 10468.9 10472.5 55141.4 56172.7 56602.7 57126.7 58899.4 77578.2 78283.6 85128.6 86424.0 86465.6 12480.0 12593.7 12606.9 12717.9 12842.4 14763.0 14785.6 15035.5 15067.3 15136.2 13339.7 13578.6 14033.1 14045.4 14496.8 13420.4 13622.3 13879.8 13914.1 13986.2 13465.4 13601.5 13606.8 13667.6 14122.2 11241.4 11423.2 11461.3 11757.5 11894.0 11501.3 11715.5 11875.4 12041.9 12062.4 12:22:38 INFO - PROCESS | 32612 | -------- Summary: end -------- 12:22:38 INFO - PROCESS | 32612 | 12:22:39 INFO - TEST-INFO | 32612: exit 0 12:22:39 INFO - TEST-OK | dromaeo_css | took 1113786ms 12:22:39 INFO - TEST-START | kraken 12:22:39 INFO - operating with platform_type : linux_ 12:22:39 INFO - Initialising browser for kraken test... 12:22:39 INFO - TEST-INFO | started process 469 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxdh2QS/profile http://localhost:41295/getInfo.html) 12:22:43 INFO - PROCESS | 469 | __metrics Screen width/height:1600/1200 12:22:43 INFO - PROCESS | 469 | colorDepth:24 12:22:43 INFO - PROCESS | 469 | Browser inner width/height: 1024/697 12:22:43 INFO - PROCESS | 469 | __metrics 12:22:43 INFO - PROCESS | 469 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:43 INFO - PROCESS | 469 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:44 INFO - PROCESS | 469 | console.error: PushService: 12:22:44 INFO - PROCESS | 469 | stateChangeProcessEnqueue: Error transitioning state 12:22:44 INFO - PROCESS | 469 | UnknownError 12:22:45 INFO - TEST-INFO | 469: exit 0 12:22:45 INFO - Browser initialized. 12:22:45 INFO - Running cycle 1/1 for kraken test... 12:22:45 INFO - TEST-INFO | started process 557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxdh2QS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:56 INFO - PROCESS | 557 | RSS: Main: 145240064 12:22:56 INFO - PROCESS | 557 | 12:22:59 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41295/tests/kraken/driver.html?testName=audio-beat-detection) 12:23:00 INFO - PROCESS | 557 | RSS: Main: 186580992 12:23:00 INFO - PROCESS | 557 | 12:23:02 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41295/tests/kraken/driver.html?testName=audio-dft) 12:23:03 INFO - PROCESS | 557 | RSS: Main: 176230400 12:23:03 INFO - PROCESS | 557 | 12:23:06 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41295/tests/kraken/driver.html?testName=audio-fft) 12:23:06 INFO - PROCESS | 557 | RSS: Main: 267055104 12:23:06 INFO - PROCESS | 557 | 12:23:08 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41295/tests/kraken/driver.html?testName=audio-oscillator) 12:23:09 INFO - PROCESS | 557 | RSS: Main: 174489600 12:23:09 INFO - PROCESS | 557 | 12:23:11 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:23:12 INFO - PROCESS | 557 | RSS: Main: 176644096 12:23:12 INFO - PROCESS | 557 | 12:23:15 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41295/tests/kraken/driver.html?testName=imaging-darkroom) 12:23:15 INFO - PROCESS | 557 | RSS: Main: 211771392 12:23:15 INFO - PROCESS | 557 | 12:23:19 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41295/tests/kraken/driver.html?testName=imaging-desaturate) 12:23:19 INFO - PROCESS | 557 | RSS: Main: 209940480 12:23:19 INFO - PROCESS | 557 | 12:23:22 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41295/tests/kraken/driver.html?testName=json-parse-financial) 12:23:23 INFO - PROCESS | 557 | RSS: Main: 210731008 12:23:23 INFO - PROCESS | 557 | 12:23:24 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:23:25 INFO - PROCESS | 557 | RSS: Main: 172150784 12:23:25 INFO - PROCESS | 557 | 12:23:26 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:23:27 INFO - PROCESS | 557 | RSS: Main: 178847744 12:23:27 INFO - PROCESS | 557 | 12:23:29 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:23:30 INFO - PROCESS | 557 | RSS: Main: 188751872 12:23:30 INFO - PROCESS | 557 | 12:23:32 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:23:33 INFO - PROCESS | 557 | RSS: Main: 189997056 12:23:33 INFO - PROCESS | 557 | 12:23:35 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:23:36 INFO - PROCESS | 557 | RSS: Main: 193146880 12:23:36 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | Cycle 1(1): loaded http://localhost:41295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41295/tests/kraken/driver.html?testName=ai-astar) 12:23:38 INFO - PROCESS | 557 | RSS: Main: 242601984 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | __start_tp_report 12:23:38 INFO - PROCESS | 557 | _x_x_mozilla_page_load 12:23:38 INFO - PROCESS | 557 | _x_x_mozilla_page_load_details 12:23:38 INFO - PROCESS | 557 | |i|pagename|runs| 12:23:38 INFO - PROCESS | 557 | |0;ai-astar;110;112;110;111;111;110;112;112;111;111 12:23:38 INFO - PROCESS | 557 | |1;audio-beat-detection;139;116;121;159;119;118;123;124;150;120 12:23:38 INFO - PROCESS | 557 | |2;audio-dft;164;160;160;161;160;161;165;159;162;162 12:23:38 INFO - PROCESS | 557 | |3;audio-fft;76;78;77;112;76;77;77;77;102;74 12:23:38 INFO - PROCESS | 557 | |4;audio-oscillator;82;81;122;82;83;81;102;81;82;115 12:23:38 INFO - PROCESS | 557 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;118;117 12:23:38 INFO - PROCESS | 557 | |6;imaging-darkroom;147;146;147;146;146;146;146;147;145;145 12:23:38 INFO - PROCESS | 557 | |7;imaging-desaturate;94;95;95;95;94;94;95;95;94;94 12:23:38 INFO - PROCESS | 557 | |8;json-parse-financial;52;51;51;50;51;51;50;51;51;50 12:23:38 INFO - PROCESS | 557 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;60;61;60 12:23:38 INFO - PROCESS | 557 | |10;stanford-crypto-aes;73;76;74;73;72;72;72;78;78;73 12:23:38 INFO - PROCESS | 557 | |11;stanford-crypto-ccm;130;121;126;135;132;121;129;129;152;122 12:23:38 INFO - PROCESS | 557 | |12;stanford-crypto-pbkdf2;156;159;153;156;160;155;159;156;151;153 12:23:38 INFO - PROCESS | 557 | |13;stanford-crypto-sha256-iterative;68;63;67;62;62;63;64;63;63;63 12:23:38 INFO - PROCESS | 557 | __end_tp_report 12:23:38 INFO - PROCESS | 557 | __start_cc_report 12:23:38 INFO - PROCESS | 557 | _x_x_mozilla_cycle_collect,1614 12:23:38 INFO - PROCESS | 557 | __end_cc_report 12:23:38 INFO - PROCESS | 557 | __startTimestamp1478809418432__endTimestamp 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | ------- Summary: start ------- 12:23:38 INFO - PROCESS | 557 | Number of tests: 14 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 12:23:38 INFO - PROCESS | 557 | Values: 110.0 112.0 110.0 111.0 111.0 110.0 112.0 112.0 111.0 111.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:123.50 stddev:15.04 (12.2%) stddev-sans-first:15.51 12:23:38 INFO - PROCESS | 557 | Values: 139.0 116.0 121.0 159.0 119.0 118.0 123.0 124.0 150.0 120.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.90 (1.2%) stddev-sans-first:1.76 12:23:38 INFO - PROCESS | 557 | Values: 164.0 160.0 160.0 161.0 160.0 161.0 165.0 159.0 162.0 162.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#3] audio-fft Cycles:10 Average:82.60 Median:77.00 stddev:13.12 (17.0%) stddev-sans-first:13.69 12:23:38 INFO - PROCESS | 557 | Values: 76.0 78.0 77.0 112.0 76.0 77.0 77.0 77.0 102.0 74.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:81.50 stddev:15.86 (19.5%) stddev-sans-first:16.48 12:23:38 INFO - PROCESS | 557 | Values: 82.0 81.0 122.0 82.0 83.0 81.0 102.0 81.0 82.0 115.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:23:38 INFO - PROCESS | 557 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 12:23:38 INFO - PROCESS | 557 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 146.0 147.0 145.0 145.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 12:23:38 INFO - PROCESS | 557 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 12:23:38 INFO - PROCESS | 557 | Values: 52.0 51.0 51.0 50.0 51.0 51.0 50.0 51.0 51.0 50.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 12:23:38 INFO - PROCESS | 557 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:73.50 stddev:2.38 (3.2%) stddev-sans-first:2.49 12:23:38 INFO - PROCESS | 557 | Values: 73.0 76.0 74.0 73.0 72.0 72.0 72.0 78.0 78.0 73.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:129.50 stddev:9.17 (7.1%) stddev-sans-first:9.72 12:23:38 INFO - PROCESS | 557 | Values: 130.0 121.0 126.0 135.0 132.0 121.0 129.0 129.0 152.0 122.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:156.00 stddev:2.94 (1.9%) stddev-sans-first:3.11 12:23:38 INFO - PROCESS | 557 | Values: 156.0 159.0 153.0 156.0 160.0 155.0 159.0 156.0 151.0 153.0 12:23:38 INFO - PROCESS | 557 | 12:23:38 INFO - PROCESS | 557 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.00 stddev:2.04 (3.2%) stddev-sans-first:1.50 12:23:38 INFO - PROCESS | 557 | Values: 68.0 63.0 67.0 62.0 62.0 63.0 64.0 63.0 63.0 63.0 12:23:38 INFO - PROCESS | 557 | -------- Summary: end -------- 12:23:38 INFO - PROCESS | 557 | 12:23:39 INFO - TEST-INFO | 557: exit 0 12:23:39 INFO - TEST-OK | kraken | took 60314ms 12:23:39 INFO - SUITE-END | took 1174s 12:23:39 INFO - Completed test suite (00:19:34) 12:23:39 INFO - javascript benchmark 12:23:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.0621813455964, 3152.5113607390854, 3169.556262123303, 3186.5379519969592, 3205.599300087489, 5415.9458405415935, 5441.537469315103, 5442.673439593627, 5464.808731694391, 5508.0, 5559.0, 5581.330240371151, 5596.300462442195, 5601.915971260434, 5607.551395888329, 5037.143685573452, 5063.417706963691, 5084.0, 5108.642395032331, 5160.380754309469, 4858.222684370501, 5100.107481190796, 5159.58723302135, 5166.0, 5167.741612919358, 4820.493848145947, 4998.925016124761, 5065.949340506585, 5067.315912351823, 5086.262491938666, 5099.388073431193, 5121.436641969381, 5128.153854613985, 5190.091733946582, 5210.088234558937, 5027.723475208862, 5099.980003999178, 5150.227465880111, 5159.845204643867, 5189.740512974374, 5357.517823395913, 5412.350517937873, 5454.563634909237, 5457.481539253765, 5542.196381524696, 5829.067349224104, 5925.377835327315, 5947.256592925884, 5949.315828679714, 5956.285245770492, 5865.794697185578, 5885.823425297248, 5898.64608123514, 5899.882002359972, 5908.438698323653, 5292.364916210079, 5324.227986941911, 5445.863853403657, 5520.585956053232, 5545.861353466195, 5995.550333724988, 6032.969835150884, 6036.607620504653, 6037.45662890342, 6040.728167232485, 5764.308283005982, 5768.826935191951, 5769.740361683735, 5772.595918285764, 5773.04744717117, 5690.0895856663565, 5750.6262092963825, 5830.7084645767545, 5937.881242375129, 5945.732442040032, 5973.701314934236, 5974.193483879708, 5975.073863551156, 5979.880402391927, 6001.639901605918, 5920.8815823684145, 5976.491998180117, 5976.5218782497295, 5982.790602328898, 6007.189029480965, 4452.866414007585, 4498.1003799239625, 4498.707584007029, 4573.954260457419, 4625.930611040837, 4072.714909956275, 4089.5092588889524, 4105.815238314283, 4107.322291821875, 4129.401236820644, 5649.067903795828, 5690.857728556736, 5695.45893140151, 5714.685692286881, 5720.8283751487525, 5728.427157284322, 5779.942200578024, 5794.797182098607, 5833.270841144857, 5967.16459695643, 5634.915476267859, 5634.971825140848, 5646.774129034875, 5655.915161272585, 5677.914831277534, 5146.768395422215, 5158.509941555546, 5164.483551644806, 5170.457102004236, 5202.401723801811, 5830.562707796932, 5884.352721200579, 5886.793962211302, 6123.663198524035, 6125.234345706786, 5812.476877081083, 5859.150423188528, 5863.355030946678, 5877.23595932526, 5922.437368449991, 6007.3992600739575, 6029.939700602938, 6046.27444706638, 6053.243344581927, 6058.394160583906, 5671.6597004179885, 5710.143478478095, 5768.798092066758, 5785.8553536161935, 5790.044642633919, 5806.042003069615, 5933.673647949404, 5933.940660593339, 5990.580659353977, 6022.427869352405, 5845.035569131046, 5851.795187168599, 5852.034414321589, 5852.7658893645485, 5852.970735146297, 5606.831795046155, 5656.547476201812, 5805.9839528081575, 5818.534517238695, 5822.272215973004, 5603.635763675348, 5658.660480371191, 5723.942760572341, 5772.19189313488, 5802.94197058024, 5793.449622285876, 5803.071508558611, 5841.065429531254, 5844.678542680023, 5849.3858144894175, 5503.3395992481155, 5515.641483303572, 5570.498655120897, 5660.462256085665, 5703.0], "value": 5450.28416167012, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.9296884373875, 618.9752409903604, 646.0857886091181, 666.9433098186654, 670.1823774994505, 36230.63769362306, 38776.418353724715, 47223.29165062522, 48480.757596211966, 48571.02857942848, 49057.75471122639, 49555.25667114996, 49581.0, 49589.75205123978, 49663.25505117417, 9295.535223238829, 9453.763655908622, 9463.101005404476, 9463.38487998278, 9463.621455141785, 8572.828543429128, 8769.123087691243, 8777.42946708465, 9275.489848058354, 11258.943705281503, 11431.199816012882, 11496.137789665767, 11496.425178741072, 11502.482388292505, 11512.539498420054, 11169.44152792357, 11212.10303175744, 11216.66350009501, 11231.269967452172, 11232.269902456314, 11061.889381106166, 11109.83335249972, 11114.833277500844, 11121.388323642197, 11127.0, 45036.549634503564, 45397.0, 45406.772966135286, 45411.54588454106, 45509.08981820378, 3676.5955744868043, 3857.864974725899, 3933.0560665440375, 4068.4507591475067, 4482.663800214997, 33926.83036584826, 34484.82757586221, 34709.30581388383, 35304.470432943526, 35882.0, 4644.8606541803465, 4672.065586882603, 4677.64917631179, 4707.976460117712, 4767.642426817968, 363599.0, 365898.0, 366120.0, 367529.0, 369182.0, 65803.34196657973, 65952.0, 66158.33841661522, 66197.66901165464, 66246.0, 40657.59342406597, 41594.0, 41966.0, 44230.77884610621, 44274.114517709466, 19954.50113747139, 20122.39632811018, 20130.496737581383, 20145.597088058155, 20165.39503814888, 15349.9232503839, 15459.845401546065, 15464.304106315243, 15473.690526189414, 15474.922625387026, 18845.246190152513, 19329.4201173965, 20446.591068178554, 20863.0, 21231.36305910825, 7414.962925185339, 7421.6269990052015, 7476.514026588255, 7518.699252029857, 7532.39740820733, 973.0707174648336, 1022.9846552301722, 1104.884226481315, 1132.6715252576826, 1161.9941900290614, 33763.32473350519, 33886.66113338835, 34350.0, 34392.0, 34498.310033799185, 1060.989390106089, 1106.397013627575, 1144.673767976123, 1146.1633007904277, 1227.7421741434398, 16974.915125424293, 18156.45530634083, 18217.27130914775, 18262.086895655164, 18333.0, 548480.0, 552660.0, 552970.0, 554998.0, 555202.0, 43973.78013109914, 44380.0, 44455.55544444514, 65069.349306507276, 65129.348706512326, 1344.8318960129984, 1396.8952328575397, 1422.5092343141807, 1434.956951291463, 1448.3482432905234, 33167.83416082904, 35350.116247093, 35625.109372264866, 36032.81983590065, 36057.27885442329, 55557.72221138868, 55821.0, 56230.71884640551, 56298.43701562932, 56365.43634563766, 429297.0, 432631.0, 433194.0, 433619.0, 433760.0, 30907.69092309138, 32142.19644508906, 32192.195195119373, 33602.49596256009, 33603.83198083994, 52651.0, 53045.4695453056, 53078.93842123213, 53182.2022669668, 53401.4659853412, 43284.35073473964, 44615.10769784651, 44863.775681121384, 45134.0, 45991.0, 43682.78158609186, 43876.0, 44412.333814992155, 44799.10401792011, 45363.77318113388, 43711.56288437202, 44223.77888110668, 45210.54789452195, 45368.31947520856, 45577.0, 36302.455463168604, 39256.607433925295, 39680.801595991834, 39803.0, 40287.597124029555, 29278.61685214643, 29380.706192937796, 29429.11712648624, 29636.85181574078, 29936.25159371033], "value": 21639.899126427663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.7704531371805, 428.8239214209139, 431.40466156703746, 433.6985794872564, 443.2199329180641, 16700.749488757665, 21147.0, 21223.151073957044, 21363.35909922703, 21631.242906498275, 20830.89584552075, 20915.0, 20998.05508752106, 21028.369148925558, 21039.05324260408, 5258.290130832346, 5711.543076553866, 6129.785457508988, 6158.876822463548, 6163.260408750956, 6005.849853753669, 6024.638521688691, 6048.123022161784, 6052.303985041733, 6065.0, 6230.813075607739, 6366.649834259114, 6373.139626150457, 6376.52176086793, 6379.744810207586, 6380.7128679209545, 6382.297947225801, 6666.500012499057, 6844.5208835381545, 6895.069165662621, 6132.264128304588, 6178.289496707846, 6190.659513726789, 6196.101565273055, 6197.87604247917, 15301.005434646824, 15607.141607211599, 23732.64401033986, 23848.880755596285, 24075.879620601783, 2360.917367892133, 2466.8149888758417, 2512.5351809915223, 2620.685517737884, 2853.2296280004284, 10470.790584188273, 11107.500162492708, 11108.055815255713, 13667.931660341636, 14232.217228052454, 2007.8895660738654, 2025.9898700506403, 2045.8750829172827, 2079.521710006707, 2082.416923261489, 79624.20375796284, 83799.5810020946, 83949.0, 85688.0, 87125.56437217837, 11296.49165787525, 11752.177347585586, 11760.0, 11810.40947952599, 11813.88186118145, 16853.0, 16860.156992150343, 16869.15654217284, 16930.153492325335, 16972.06653634037, 8813.383063185644, 8865.911340886509, 8993.640254389751, 9069.138431848964, 9090.54547272647, 8657.740267791976, 8722.476651400937, 8724.91275087241, 8840.292776577988, 8849.20357167858, 5115.2071428928575, 5148.665336753099, 5183.3261675981885, 5186.740662966837, 5232.084385232569, 2077.6883467479997, 2083.3229200509895, 2101.4956410461687, 2105.978940210609, 2153.3109404990564, 746.3432179681845, 901.9413738107002, 972.0959507657805, 979.5151400056875, 1010.7372083258406, 19694.507637308896, 20405.897970510054, 20749.688754668692, 20862.478438038866, 20903.47741306449, 1031.0771859186136, 1043.7599352149048, 1061.7186445591924, 1072.3940973350032, 1117.6647005898199, 10762.56949722018, 11530.308181509135, 11578.71053223676, 11594.942025289989, 11805.350705711096, 549524.0, 562937.0, 564258.0, 564750.0, 565284.8267085843, 96582.51708741552, 97777.0, 98490.0, 101212.0, 102710.48644756728, 1017.59296281488, 1037.7561273100966, 1098.0611577101622, 1116.4640972333164, 1118.4016551145098, 17724.46826595204, 17958.5510362242, 18079.096045197424, 18380.17289222015, 18611.441656750318, 42563.1487370257, 43553.78223108864, 44566.55433445624, 44812.551874480836, 45608.0, 59279.70360148171, 59499.0, 61316.0, 62449.0, 64000.67999660158, 8327.916720832714, 8591.527465989431, 8772.649094036167, 8802.119788021148, 8828.690995815115, 8461.280791132576, 13558.661033474242, 13883.583492495241, 13898.583042508337, 13934.372953216927, 11703.76592468129, 11725.648230553097, 12069.396530173632, 12141.332226727614, 12376.75246495047, 11414.828777568177, 11473.483693233658, 11545.48045337945, 11782.764344712887, 12065.0, 11674.423952766607, 11687.18189726711, 11924.70188245266, 12140.271583705005, 12428.197911302992, 8494.023187333536, 8511.787205319917, 8543.316534677087, 8757.649694012423, 8806.075362087146, 8536.829263414822, 11493.942530287295, 11825.172237943261, 11986.460609272777, 11997.820032699341], "value": 9736.327225409323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1131.2194585735842, 1143.1483544759153, 1325.9061274448582, 1328.8139660447532, 1346.0375831280633, 8426.705065322714, 9023.72928812135, 9024.72925812225, 9028.593713282893, 9029.0, 7774.8445031099345, 7968.3625309975205, 8391.790205244872, 8657.783555411117, 8877.201051905327, 2468.6050231962936, 2496.8127390445698, 2499.3376755159898, 2501.1746123779108, 2510.3724068982756, 2402.0992127952018, 2452.7792498675117, 2468.6173643085262, 2471.9864855409287, 2473.122041675206, 2026.1490174126902, 2156.1159924430985, 2278.4417817634703, 2348.790372957928, 2355.9764402356013, 2376.370261880603, 2380.262118743195, 2390.175389490623, 2392.222527678503, 2392.83250172488, 2354.976450235493, 2397.676313697646, 2399.616061430163, 2400.987995060031, 2402.3513651314224, 8557.743267701979, 8567.828643427096, 8599.914000859973, 8609.139086091403, 8613.569321533962, 5193.766280517386, 5326.440723723992, 5328.600354973354, 5341.759620817073, 5352.384475785296, 3323.7341012718925, 3378.324335132959, 3496.842642081087, 3499.422595271777, 3500.2299494111253, 1164.5109054197258, 1187.9109066820024, 1210.1891732539132, 1218.5430463576158, 1236.6846454154222, 11848.88151118495, 11990.400479975966, 12002.279863208236, 12006.999999999913, 12007.219530730474, 4546.340780586796, 4676.228422310281, 4786.2581299898575, 4830.275458681155, 4838.371011768448, 10114.241431892637, 10127.64553240633, 10131.13885319756, 10201.744956376002, 10204.081632653097, 5408.945910540923, 5411.729413529308, 5415.810446634349, 5416.295881535375, 5420.376656684532, 4315.352697095398, 4479.417675702203, 4919.680220785637, 4992.975035124801, 5031.069252188357, 4285.271503844354, 4290.29210180323, 4396.318570621559, 4459.6655250855665, 4524.796384162721, 1361.46902707944, 1396.7346204221164, 1399.237415608496, 1407.8873690104767, 1420.2756594136858, 958.1185309515263, 994.2642444591095, 1009.7677534167036, 1010.2473657125488, 1011.5144730529387, 5988.221531200916, 6071.787487437919, 6103.572749907553, 6239.563230573816, 6273.0904018917, 942.123824842889, 992.245893121233, 1025.5743866295404, 1061.0, 1077.4020418667715, 4057.39139129133, 4105.938410923838, 4166.145940082276, 4257.6168144867115, 4306.267934451151, 10496.947515262375, 10861.565537378574, 10864.293820901616, 10868.619598314179, 10870.510827012804, 10779.137668986463, 10785.676429707122, 10799.298045627167, 10801.567937282578, 10802.729931751612, 7860.3318717909615, 8080.71717489885, 8200.671973121009, 8239.546824924566, 8257.133001034925, 5752.568557358299, 5778.855528611818, 6014.879702406015, 6205.5345849060595, 6209.813705588839, 9674.419534827934, 9759.512024398893, 9892.703218903445, 9928.652497162564, 9983.900160998588, 9991.150752186146, 10184.287099902936, 10188.796224075626, 10194.235432342455, 10271.075603195748, 6752.831179220559, 7232.421406287379, 7506.399488041053, 7567.697292108474, 7693.615319234128, 8251.876221856557, 8333.916660833314, 8344.749657510285, 8355.080941096596, 8410.369222308229, 7163.391111755559, 7364.815879603053, 7426.0, 7484.850302994018, 7758.495697779626, 7312.744053958086, 7344.596047217455, 7415.692187858773, 7557.622118894144, 7673.424493162924, 7090.64546772649, 7104.076470059066, 7124.430045596237, 7125.287471252833, 7172.964135179291, 5362.892742145214, 5448.56411487088, 5515.172724091419, 5574.08027675429, 5593.888122237451, 5449.591280654046, 5466.753996070266, 5506.394296627288, 5544.7227638618715, 5624.634398764068], "value": 4681.371495603288, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.3510664843445, 349.6171691997264, 350.498786734969, 363.06329669452816, 365.0563293885306, 5586.715055537229, 5596.4403559644015, 5620.775168993236, 5646.378898321182, 5650.576206784493, 5197.948020519793, 5332.413434522209, 5336.17289320155, 5370.570354371651, 5519.923614895087, 4170.97914510427, 4274.829006839723, 4298.140371925611, 4300.9247688077985, 4307.590778876001, 4282.892927676818, 4283.586416482553, 4299.161663475624, 4303.54812744662, 4306.806193721274, 4055.371417430303, 4228.365745138223, 4245.7240279381895, 4246.150769846027, 4305.504866940295, 4204.1801848639525, 4231.555686652919, 4236.597523235288, 4240.618344349024, 4272.145570885819, 3773.9998900291444, 3774.621988147477, 3785.62143785622, 3791.620837916214, 3810.6951443884423, 5261.552768014723, 5283.260343551906, 5321.600879933982, 5414.7834086636485, 5459.945400545984, 4807.759612019385, 4811.278308253755, 4818.253170758538, 4827.9034419311765, 4851.757412129415, 5696.401877802812, 5721.56705976415, 5736.311642602873, 5747.798827041033, 5795.1886735857015, 4015.2371049500503, 4072.511298644182, 4078.5309689385804, 4130.793460326971, 4138.151678905817, 5950.8809823803285, 5990.214550234162, 5998.40015998401, 6022.156898034279, 6037.792441511715, 4163.937540936889, 4187.664986801049, 4199.391088292181, 4200.264953633163, 4202.915941681211, 4059.7361171523758, 4181.393697913785, 4198.1393814268, 4219.641330486882, 4230.788460576959, 4759.428868535798, 4770.236762118045, 4774.328984855273, 4794.2808578712775, 4798.688085274446, 5722.399148089474, 5730.541556675539, 5744.736158045223, 5751.338596061423, 5770.971145144247, 1408.0988167572762, 1480.8889333300046, 1491.1649476293094, 1501.1743541052379, 1510.2826157575064, 935.1630290889616, 946.0870260198941, 962.9277804164716, 964.5628014258704, 971.8882328532168, 4992.0, 4993.950060499421, 5006.574441172468, 5008.549230569266, 5019.648624596243, 5189.209722645681, 5225.503577160163, 5226.386806596702, 5231.319928409359, 5256.26412302278, 4692.671512994126, 4924.9507504925205, 4987.800487980513, 4998.90002199954, 5007.474215207423, 2747.7114902935305, 2771.750542451189, 2774.3631257557736, 2811.971880281171, 2812.915612531627, 5351.545118664957, 5377.650452720561, 5396.734975339941, 5406.6213115655255, 5432.266644003088, 4977.377827771528, 5275.3142091528625, 5313.698143954756, 5341.946580534222, 5350.946490535122, 5280.65675731084, 5297.231901374279, 5346.812861549827, 5362.3565172178805, 5376.354837419534, 5178.990096931062, 5181.92227116586, 5207.3230480037355, 5251.737413129405, 5271.367435907716, 5123.283699960555, 5509.917351239815, 5849.53203743708, 5879.941610510149, 5885.47030767233, 6132.202813634199, 6150.600210986271, 6150.938490615036, 6154.784582539769, 6175.085723425753, 4137.420761093509, 4286.435451060403, 4319.6544276458435, 4337.1976184405985, 4339.783010849382, 5893.911591326219, 5896.8820623586425, 5906.586538942404, 5910.911336329872, 5916.201312822714, 5737.397573254748, 5758.62568933018, 5772.124059680705, 5778.104393818965, 5797.768089276382, 5587.804426845041, 5734.655920644775, 5741.483266506035, 5779.190913272061, 5783.277090363705, 5712.514436272963, 5713.285839270091, 5721.685307308138, 5734.0, 5738.598298119092, 5524.861878453071, 5790.0, 5820.76716931335, 5889.440503152194, 5902.671898822902, 5619.662820230799, 5624.465675760796, 5631.211630371833, 5641.097424412238, 5652.0], "value": 4271.122779314598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.7787469072553, 707.9185893622233, 742.5284944060523, 763.5342441110918, 782.2451334462245, 14983.0, 15825.366985320574, 15906.52280431586, 15932.12373319467, 15945.68108637828, 13225.603231903058, 13244.668883277924, 13264.867351326484, 13265.0, 13290.202587844737, 714.203662915849, 733.7248531800575, 847.6694089305176, 868.5700578213762, 871.0984131423946, 872.4808738800435, 876.1895246896627, 876.2902049340023, 876.8027193881358, 879.0198928195081, 929.4144688846052, 933.0948979489884, 936.1387523478382, 939.7791518993031, 940.0223767282018, 756.7464899258728, 770.3990887108065, 889.226373055446, 907.2197909797569, 930.8231436027168, 667.7062092679254, 668.7024274197984, 671.5198632977396, 672.3612568060313, 672.7174586673609, 14661.486847960377, 14666.70666586673, 14719.190444525548, 14728.968972171951, 14795.630109247248, 13644.522441714493, 14225.502107426191, 14365.64085897861, 14496.710065798625, 14551.3451894665, 12903.87096129036, 12976.416061277265, 13008.284544350057, 13016.0, 13276.137051091744, 4415.183191109655, 4439.711418757771, 4442.822287108512, 4460.197164510387, 4518.774061296922, 81497.0, 82009.58995205046, 85834.57082714609, 87094.56452717696, 88879.55560222222, 10958.28771129874, 11235.66293011211, 11260.0, 11345.205835591594, 11558.190926635054, 21140.894295528426, 21147.682784758243, 21165.576688466146, 21174.682379764316, 21179.576408471745, 8716.128387161232, 8783.077776833468, 8894.822103557892, 8954.059823718546, 9025.368224224372, 4807.6153907688, 4847.612191024709, 4899.412070551557, 4932.28481870134, 5130.897382052338, 3397.303552771676, 3525.1892064825233, 3528.0297918072224, 3529.8764543240864, 3530.2409981854025, 585.1690599348934, 590.7932223721749, 596.6194882419203, 604.3684349854412, 610.6763415389851, 598.9520838332922, 629.2951893878793, 659.1957811469999, 664.9460604941125, 679.0934102972587, 9319.254459643213, 9518.428894266228, 9697.903020969841, 9714.90285097154, 9848.261380396356, 590.539379284159, 638.7381173718753, 644.2591020326662, 667.9432248258952, 668.9899651505232, 4598.448186217608, 4705.082508910729, 5106.285120083191, 5216.33623240297, 5248.816291429782, 123834.0, 124146.3792681049, 124261.0, 126985.3650731759, 126988.0, 111861.44069279601, 114515.0, 115845.4207728956, 117111.41444292894, 119525.0, 95584.0, 96164.51085897537, 98872.50563747279, 101573.98426015793, 101686.0, 9893.0, 10140.188784897338, 10457.372557646566, 10468.895311047097, 10472.476376181314, 55141.44858551363, 56172.719136404055, 56602.7169864148, 57126.714366429565, 58899.41100589111, 77578.2242177571, 78283.60858195672, 85128.57435712781, 86424.0, 86465.56767216124, 12480.0, 12593.685157871127, 12606.93696531548, 12717.87282127204, 12842.422091005741, 14763.0, 14785.630359240675, 15035.54893353201, 15067.321970511133, 15136.243187840784, 13339.733205336033, 13578.592642220749, 14033.087849289763, 14045.438182472586, 14496.782548261574, 13420.39608217613, 13622.250776207404, 13879.791803123164, 13914.09558378702, 13986.16083035021, 13465.39405726725, 13601.523946661819, 13606.795898061338, 13667.589972300846, 14122.152670839783, 11241.381724005258, 11423.200375973602, 11461.312321260752, 11757.470913808727, 11894.0, 11501.252418592765, 11715.472803724024, 11875.406229688653, 12041.879581204075, 12062.396880155782], "value": 7787.594916049919, "unit": "score"}], "name": "dromaeo_css", "value": 7505.95419758542, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 110.0, 111.0, 111.0, 110.0, 112.0, 112.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 121.0, 159.0, 119.0, 118.0, 123.0, 124.0, 150.0, 120.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 160.0, 161.0, 160.0, 161.0, 165.0, 159.0, 162.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 112.0, 76.0, 77.0, 77.0, 77.0, 102.0, 74.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 122.0, 82.0, 83.0, 81.0, 102.0, 81.0, 82.0, 115.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 50.0, 51.0, 51.0, 50.0, 51.0, 51.0, 50.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 74.0, 73.0, 72.0, 72.0, 72.0, 78.0, 78.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 121.0, 126.0, 135.0, 132.0, 121.0, 129.0, 129.0, 152.0, 122.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 159.0, 153.0, 156.0, 160.0, 155.0, 159.0, 156.0, 151.0, 153.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 67.0, 62.0, 62.0, 63.0, 64.0, 63.0, 63.0, 63.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1466.8999999999999, "alertThreshold": 2.0}]} 12:23:39 INFO - Return code: 0 12:23:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:23:39 INFO - # TBPL SUCCESS # 12:23:39 INFO - Running post-action listener: _resource_record_post_action 12:23:39 INFO - [mozharness: 2016-11-10 20:23:39.743221Z] Finished run-tests step (success) 12:23:39 INFO - Running post-run listener: _resource_record_post_run 12:23:39 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 372273152; Read time: 20; Write time: 288652 12:23:39 INFO - TinderboxPrint: CPU usage
13.0% 12:23:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 12:23:39 INFO - TinderboxPrint: I/O write bytes / time
372,273,152 / 288,652 12:23:39 INFO - TinderboxPrint: CPU idle
8,263.8 (87.0%) 12:23:39 INFO - TinderboxPrint: CPU user
1,187.7 (12.5%) 12:23:39 INFO - TinderboxPrint: Swap in / out
0 / 0 12:23:39 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124121088; Read time: 0; Write time: 87472 12:23:39 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 248152064; Read time: 0; Write time: 201180 12:23:40 INFO - Running post-run listener: _upload_blobber_files 12:23:40 INFO - Blob upload gear active. 12:23:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:23:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:23:40 INFO - (blobuploader) - INFO - Open directory for files ... 12:23:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:23:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:23:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:41 INFO - (blobuploader) - INFO - Done attempting. 12:23:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:23:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:23:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:45 INFO - (blobuploader) - INFO - Done attempting. 12:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:23:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:46 INFO - (blobuploader) - INFO - Done attempting. 12:23:46 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:46 INFO - Return code: 0 12:23:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4812a26069294afeaf4467ab9e8c1b087a48db5207a5b521a7d360046c01afc8bd5400a1b2cd2c35133a6c34efdb784df89cc4ec68393a9e6d45c6cfe9bfb38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/932f067546ea8a5f27868b03330e5fdfde6530d283ef1efae02f5984610db26488e40b7e2770be50af7c524e82d80688166858d21752b68f8a016710b5e79939", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8aaa516e3ffde90ac23db3cb5c206b91a3aeb33978cad6952ea596ce4e35eb8140ede09c3e7f8506fe9547dc23e630825d56687a5bf5a2a9dd6c9a355f9b091c"} 12:23:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:46 INFO - Contents: 12:23:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4812a26069294afeaf4467ab9e8c1b087a48db5207a5b521a7d360046c01afc8bd5400a1b2cd2c35133a6c34efdb784df89cc4ec68393a9e6d45c6cfe9bfb38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/932f067546ea8a5f27868b03330e5fdfde6530d283ef1efae02f5984610db26488e40b7e2770be50af7c524e82d80688166858d21752b68f8a016710b5e79939", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8aaa516e3ffde90ac23db3cb5c206b91a3aeb33978cad6952ea596ce4e35eb8140ede09c3e7f8506fe9547dc23e630825d56687a5bf5a2a9dd6c9a355f9b091c"} 12:23:46 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:46 INFO - Copying logs to upload dir... 12:23:46 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.016260 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-10 12:23:45.607400) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 12:23:45.610674) ========= 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-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4812a26069294afeaf4467ab9e8c1b087a48db5207a5b521a7d360046c01afc8bd5400a1b2cd2c35133a6c34efdb784df89cc4ec68393a9e6d45c6cfe9bfb38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/932f067546ea8a5f27868b03330e5fdfde6530d283ef1efae02f5984610db26488e40b7e2770be50af7c524e82d80688166858d21752b68f8a016710b5e79939", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8aaa516e3ffde90ac23db3cb5c206b91a3aeb33978cad6952ea596ce4e35eb8140ede09c3e7f8506fe9547dc23e630825d56687a5bf5a2a9dd6c9a355f9b091c"} build_url:https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/APCPh8uZQ_qD3PxtVRPeEA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4812a26069294afeaf4467ab9e8c1b087a48db5207a5b521a7d360046c01afc8bd5400a1b2cd2c35133a6c34efdb784df89cc4ec68393a9e6d45c6cfe9bfb38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/932f067546ea8a5f27868b03330e5fdfde6530d283ef1efae02f5984610db26488e40b7e2770be50af7c524e82d80688166858d21752b68f8a016710b5e79939", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8aaa516e3ffde90ac23db3cb5c206b91a3aeb33978cad6952ea596ce4e35eb8140ede09c3e7f8506fe9547dc23e630825d56687a5bf5a2a9dd6c9a355f9b091c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 12:23:45.636203) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:23:45.636556) ========= 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-1478807748.725791-1076030357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 12:23:45.722101) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 12:23:45.722432) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 12:23:45.722756) ========= ========= Total master_lag: 0.16 =========