builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1481907604.1 results: success (0) buildid: 20161216081149 builduid: 7906d5c4784e4608bb1f9367646312af revision: e4c249612e0e3b7570942073060315fee5dff07b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:04.097093) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:04.097555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:04.097836) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:04.112958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 09:00:04.113355) ========= 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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-16 09:00:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-12-16 09:00:05 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.299358 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 09:00:05.426366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:05.426720) ========= 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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017109 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:05.479074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:05.479435) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev e4c249612e0e3b7570942073060315fee5dff07b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev e4c249612e0e3b7570942073060315fee5dff07b --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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-16 09:00:05,717 truncating revision to first 12 chars 2016-12-16 09:00:05,717 Setting DEBUG logging. 2016-12-16 09:00:05,717 attempt 1/10 2016-12-16 09:00:05,717 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/e4c249612e0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 09:00:06,013 unpacking tar archive at: graphics-e4c249612e0e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.591778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:06.093027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:06.093648) ========= script_repo_revision: e4c249612e0e3b7570942073060315fee5dff07b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:06.097542) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:06.097948) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 09:00:06.109108) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-16 09:00:06.109523) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:00:06 INFO - MultiFileLogger online at 20161216 09:00:06 in /builds/slave/test 09:00:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:00:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:06 INFO - {'append_to_log': False, 09:00:06 INFO - 'base_work_dir': '/builds/slave/test', 09:00:06 INFO - 'blob_upload_branch': 'Graphics', 09:00:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:06 INFO - 'branch': 'Graphics', 09:00:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:06 INFO - 'config_files': ('talos/linux_config.py',), 09:00:06 INFO - 'default_actions': ('clobber', 09:00:06 INFO - 'read-buildbot-config', 09:00:06 INFO - 'download-and-extract', 09:00:06 INFO - 'populate-webroot', 09:00:06 INFO - 'create-virtualenv', 09:00:06 INFO - 'install', 09:00:06 INFO - 'run-tests'), 09:00:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:06 INFO - 'download_minidump_stackwalk': True, 09:00:06 INFO - 'download_symbols': 'ondemand', 09:00:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:06 INFO - 'installer_path': 'installer.exe', 09:00:06 INFO - 'log_level': 'info', 09:00:06 INFO - 'log_name': 'talos', 09:00:06 INFO - 'log_to_console': True, 09:00:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:00:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:00:06 INFO - 'opt_config_files': (), 09:00:06 INFO - 'pip_index': False, 09:00:06 INFO - 'sps_profile': False, 09:00:06 INFO - 'sps_profile_interval': 0, 09:00:06 INFO - 'suite': 'dromaeojs', 09:00:06 INFO - 'system_bits': '32', 09:00:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:06 INFO - 'title': 'talos-linux64-ix-029', 09:00:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:06 INFO - 'use_talos_json': True, 09:00:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:06 INFO - 'work_dir': 'build'} 09:00:06 INFO - [mozharness: 2016-12-16 17:00:06.443041Z] Running clobber step. 09:00:06 INFO - Running pre-action listener: _resource_record_pre_action 09:00:06 INFO - Running main action method: clobber 09:00:06 INFO - rmtree: /builds/slave/test/build 09:00:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:06 INFO - Running post-action listener: _resource_record_post_action 09:00:06 INFO - [mozharness: 2016-12-16 17:00:06.899551Z] Finished clobber step (success) 09:00:06 INFO - [mozharness: 2016-12-16 17:00:06.899650Z] Running read-buildbot-config step. 09:00:06 INFO - Running pre-action listener: _resource_record_pre_action 09:00:06 INFO - Running main action method: read_buildbot_config 09:00:06 INFO - Using buildbot properties: 09:00:06 INFO - { 09:00:06 INFO - "project": "", 09:00:06 INFO - "product": "firefox", 09:00:06 INFO - "script_repo_revision": "default", 09:00:06 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 09:00:06 INFO - "repository": "", 09:00:06 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 09:00:06 INFO - "buildid": "20161216081149", 09:00:06 INFO - "pgo_build": "False", 09:00:06 INFO - "basedir": "/builds/slave/test", 09:00:06 INFO - "buildnumber": 23, 09:00:06 INFO - "slavename": "talos-linux64-ix-029", 09:00:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:00:06 INFO - "platform": "ubuntu64_hw", 09:00:06 INFO - "scheduler": "tests-graphics-linux64-talos", 09:00:06 INFO - "branch": "graphics", 09:00:06 INFO - "repo_path": "projects/graphics", 09:00:06 INFO - "revision": "e4c249612e0e3b7570942073060315fee5dff07b", 09:00:06 INFO - "stage_platform": "linux64", 09:00:06 INFO - "builduid": "7906d5c4784e4608bb1f9367646312af", 09:00:06 INFO - "slavebuilddir": "test" 09:00:06 INFO - } 09:00:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:00:06 INFO - Running post-action listener: _resource_record_post_action 09:00:06 INFO - [mozharness: 2016-12-16 17:00:06.902070Z] Finished read-buildbot-config step (success) 09:00:06 INFO - [mozharness: 2016-12-16 17:00:06.902157Z] Running download-and-extract step. 09:00:06 INFO - Running pre-action listener: _resource_record_pre_action 09:00:06 INFO - Running main action method: download_and_extract 09:00:06 INFO - mkdir: /builds/slave/test/build/tests 09:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:06 INFO - trying https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:00:06 INFO - Downloading https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:00:07 INFO - Downloaded 1361 bytes. 09:00:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:00:07 INFO - Using the following test package requirements: 09:00:07 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:00:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:00:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:00:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'jsshell-linux-x86_64.zip'], 09:00:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:00:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:00:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:00:07 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:00:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:00:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:00:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:00:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:00:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:07 INFO - Fetch https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:00:08 INFO - Expected file size: 20213302 09:00:08 INFO - Obtained file size: 20213302 09:00:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:00:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:00:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:12 INFO - Fetch https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:00:13 INFO - Expected file size: 20213302 09:00:13 INFO - Obtained file size: 20213302 09:00:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:17 INFO - Fetch https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:00:18 INFO - Expected file size: 14409734 09:00:18 INFO - Obtained file size: 14409734 09:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:18 INFO - trying https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:00:18 INFO - Downloading https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:19 INFO - Downloaded 55609308 bytes. 09:00:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:00:19 INFO - mkdir: /builds/slave/test/properties 09:00:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:19 INFO - Contents: 09:00:19 INFO - build_url:https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:00:20 INFO - Running post-action listener: _resource_record_post_action 09:00:20 INFO - Running post-action listener: set_extra_try_arguments 09:00:20 INFO - [mozharness: 2016-12-16 17:00:20.318945Z] Finished download-and-extract step (success) 09:00:20 INFO - [mozharness: 2016-12-16 17:00:20.319170Z] Running populate-webroot step. 09:00:20 INFO - Running pre-action listener: _resource_record_pre_action 09:00:20 INFO - Running main action method: populate_webroot 09:00:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:20 INFO - '--tppagecycles', 09:00:20 INFO - '7'], 09:00:20 INFO - 'tests': ['tsvgm']}}, 09:00:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['damp', 'tps']}, 09:00:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'tests': ['damp', 'tps']}, 09:00:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['dromaeo_dom']}, 09:00:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['basic_compositor_video']}, 09:00:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['a11yr', 09:00:20 INFO - 'ts_paint', 09:00:20 INFO - 'tpaint', 09:00:20 INFO - 'sessionrestore', 09:00:20 INFO - 'sessionrestore_no_auto_restore', 09:00:20 INFO - 'tabpaint']}, 09:00:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['tsvgx', 09:00:20 INFO - 'tsvgr_opacity', 09:00:20 INFO - 'tart', 09:00:20 INFO - 'tscrollx', 09:00:20 INFO - 'cart', 09:00:20 INFO - 'tsvg_static']}, 09:00:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:20 INFO - 'tsvgr_opacity', 09:00:20 INFO - 'tart', 09:00:20 INFO - 'tscrollx', 09:00:20 INFO - 'cart', 09:00:20 INFO - 'tsvg_static']}, 09:00:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'talos_options': ['--disable-e10s'], 09:00:20 INFO - 'tests': ['tp5o']}, 09:00:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:00:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'talos_options': ['--disable-e10s', 09:00:20 INFO - '--xperf_path', 09:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:20 INFO - 'tests': ['tp5n']}, 09:00:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:00:20 INFO - 'talos_options': ['--xperf_path', 09:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:20 INFO - 'tests': ['tp5n']}}, 09:00:20 INFO - 'talos.zip': {'path': '', 09:00:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:20 INFO - Running post-action listener: _resource_record_post_action 09:00:20 INFO - [mozharness: 2016-12-16 17:00:20.335270Z] Finished populate-webroot step (success) 09:00:20 INFO - [mozharness: 2016-12-16 17:00:20.335358Z] Running create-virtualenv step. 09:00:20 INFO - Running pre-action listener: _resource_record_pre_action 09:00:20 INFO - Running main action method: create_virtualenv 09:00:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:20 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 09:00:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:20 INFO - Using real prefix '/usr' 09:00:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:22 INFO - Installing pip.................done. 09:00:22 INFO - Return code: 0 09:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:23 INFO - Reading from file tmpfile_stdout 09:00:23 INFO - Output received: 09:00:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:00:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:23 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')]} 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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')]} 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:23 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 09:00:23 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 09:00:23 INFO - Using env: {'DISPLAY': ':0', 09:00:23 INFO - 'HOME': '/home/cltbld', 09:00:23 INFO - 'LANG': 'en_US.UTF-8', 09:00:23 INFO - 'LANGUAGE': 'en_US:en', 09:00:23 INFO - 'LOGNAME': 'cltbld', 09:00:23 INFO - 'MAIL': '/var/mail/cltbld', 09:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:23 INFO - 'MOZ_NO_REMOTE': '1', 09:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:23 INFO - 'NO_EM_RESTART': '1', 09:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:23 INFO - 'PWD': '/builds/slave/test', 09:00:23 INFO - 'SHELL': '/bin/bash', 09:00:23 INFO - 'SHLVL': '1', 09:00:23 INFO - 'TERM': 'linux', 09:00:23 INFO - 'TMOUT': '86400', 09:00:23 INFO - 'USER': 'cltbld', 09:00:23 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:23 INFO - '_': '/tools/buildbot/bin/python'} 09:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:23 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 09:00:23 INFO - Cleaning up... 09:00:23 INFO - Return code: 0 09:00:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:23 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')]} 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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')]} 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:23 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 09:00:23 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 09:00:23 INFO - Using env: {'DISPLAY': ':0', 09:00:23 INFO - 'HOME': '/home/cltbld', 09:00:23 INFO - 'LANG': 'en_US.UTF-8', 09:00:23 INFO - 'LANGUAGE': 'en_US:en', 09:00:23 INFO - 'LOGNAME': 'cltbld', 09:00:23 INFO - 'MAIL': '/var/mail/cltbld', 09:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:23 INFO - 'MOZ_NO_REMOTE': '1', 09:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:23 INFO - 'NO_EM_RESTART': '1', 09:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:23 INFO - 'PWD': '/builds/slave/test', 09:00:23 INFO - 'SHELL': '/bin/bash', 09:00:23 INFO - 'SHLVL': '1', 09:00:23 INFO - 'TERM': 'linux', 09:00:23 INFO - 'TMOUT': '86400', 09:00:23 INFO - 'USER': 'cltbld', 09:00:23 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:23 INFO - '_': '/tools/buildbot/bin/python'} 09:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:23 INFO - Downloading/unpacking psutil>=3.1.1 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:25 INFO - Installing collected packages: psutil 09:00:25 INFO - Running setup.py install for psutil 09:00:25 INFO - building 'psutil._psutil_linux' extension 09:00:25 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 09:00:25 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 09:00:25 INFO - building 'psutil._psutil_posix' extension 09:00:25 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 09:00:26 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 09:00:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:26 INFO - Successfully installed psutil 09:00:26 INFO - Cleaning up... 09:00:26 INFO - Return code: 0 09:00:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:26 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')]} 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 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')]} 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:26 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 09:00:26 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 09:00:26 INFO - Using env: {'DISPLAY': ':0', 09:00:26 INFO - 'HOME': '/home/cltbld', 09:00:26 INFO - 'LANG': 'en_US.UTF-8', 09:00:26 INFO - 'LANGUAGE': 'en_US:en', 09:00:26 INFO - 'LOGNAME': 'cltbld', 09:00:26 INFO - 'MAIL': '/var/mail/cltbld', 09:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:26 INFO - 'MOZ_NO_REMOTE': '1', 09:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:26 INFO - 'NO_EM_RESTART': '1', 09:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:26 INFO - 'PWD': '/builds/slave/test', 09:00:26 INFO - 'SHELL': '/bin/bash', 09:00:26 INFO - 'SHLVL': '1', 09:00:26 INFO - 'TERM': 'linux', 09:00:26 INFO - 'TMOUT': '86400', 09:00:26 INFO - 'USER': 'cltbld', 09:00:26 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:26 INFO - '_': '/tools/buildbot/bin/python'} 09:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:28 INFO - Installing collected packages: mozsystemmonitor 09:00:28 INFO - Running setup.py install for mozsystemmonitor 09:00:28 INFO - Successfully installed mozsystemmonitor 09:00:28 INFO - Cleaning up... 09:00:28 INFO - Return code: 0 09:00:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:00:28 INFO - Using env: {'DISPLAY': ':0', 09:00:28 INFO - 'HOME': '/home/cltbld', 09:00:28 INFO - 'LANG': 'en_US.UTF-8', 09:00:28 INFO - 'LANGUAGE': 'en_US:en', 09:00:28 INFO - 'LOGNAME': 'cltbld', 09:00:28 INFO - 'MAIL': '/var/mail/cltbld', 09:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:28 INFO - 'MOZ_NO_REMOTE': '1', 09:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:28 INFO - 'NO_EM_RESTART': '1', 09:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:28 INFO - 'PWD': '/builds/slave/test', 09:00:28 INFO - 'SHELL': '/bin/bash', 09:00:28 INFO - 'SHLVL': '1', 09:00:28 INFO - 'TERM': 'linux', 09:00:28 INFO - 'TMOUT': '86400', 09:00:28 INFO - 'USER': 'cltbld', 09:00:28 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:28 INFO - '_': '/tools/buildbot/bin/python'} 09:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:31 INFO - warning: no files found matching '*.txt' 09:00:31 INFO - no previously-included directories found matching 'build' 09:00:31 INFO - no previously-included directories found matching 'dist' 09:00:31 INFO - no previously-included directories found matching '.git*' 09:00:31 INFO - Installing collected packages: jsonschema, functools32 09:00:31 INFO - Running setup.py install for functools32 09:00:31 INFO - warning: no files found matching '*.txt' 09:00:31 INFO - no previously-included directories found matching 'build' 09:00:31 INFO - no previously-included directories found matching 'dist' 09:00:31 INFO - no previously-included directories found matching '.git*' 09:00:31 INFO - Successfully installed jsonschema functools32 09:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - Downloading docopt-0.6.1.tar.gz 09:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:34 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:34 INFO - Running setup.py install for blobuploader 09:00:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:34 INFO - Running setup.py install for requests 09:00:34 INFO - Running setup.py install for docopt 09:00:35 INFO - Successfully installed blobuploader requests docopt 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:35 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 09:00:35 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 09:00:35 INFO - Using env: {'DISPLAY': ':0', 09:00:35 INFO - 'HOME': '/home/cltbld', 09:00:35 INFO - 'LANG': 'en_US.UTF-8', 09:00:35 INFO - 'LANGUAGE': 'en_US:en', 09:00:35 INFO - 'LOGNAME': 'cltbld', 09:00:35 INFO - 'MAIL': '/var/mail/cltbld', 09:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:35 INFO - 'MOZ_NO_REMOTE': '1', 09:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:35 INFO - 'NO_EM_RESTART': '1', 09:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:35 INFO - 'PWD': '/builds/slave/test', 09:00:35 INFO - 'SHELL': '/bin/bash', 09:00:35 INFO - 'SHLVL': '1', 09:00:35 INFO - 'TERM': 'linux', 09:00:35 INFO - 'TMOUT': '86400', 09:00:35 INFO - 'USER': 'cltbld', 09:00:35 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:35 INFO - '_': '/tools/buildbot/bin/python'} 09:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:35 INFO - Running setup.py (path:/tmp/pip-6YqlUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:35 INFO - Running setup.py (path:/tmp/pip-plj1hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:35 INFO - Running setup.py (path:/tmp/pip-zQZc63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:35 INFO - Running setup.py (path:/tmp/pip-aAvPrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:35 INFO - Running setup.py (path:/tmp/pip-gcsF37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:35 INFO - Running setup.py (path:/tmp/pip-o5SWul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:35 INFO - Running setup.py (path:/tmp/pip-pI9yci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:35 INFO - Running setup.py (path:/tmp/pip-1s0Et0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:35 INFO - Running setup.py (path:/tmp/pip-rVMwEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:36 INFO - Running setup.py (path:/tmp/pip-FH3IUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:36 INFO - Running setup.py (path:/tmp/pip-3mCTAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:36 INFO - Running setup.py (path:/tmp/pip-Nn99IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:36 INFO - Running setup.py (path:/tmp/pip-sLCk5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:36 INFO - Running setup.py (path:/tmp/pip-k9b4jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:36 INFO - Running setup.py (path:/tmp/pip-ca_Yzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:36 INFO - Running setup.py (path:/tmp/pip-YyA8sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:36 INFO - Running setup.py (path:/tmp/pip-X5jyre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:36 INFO - Running setup.py install for manifestparser 09:00:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:36 INFO - Running setup.py install for mozcrash 09:00:36 INFO - Running setup.py install for mozdebug 09:00:36 INFO - Running setup.py install for mozdevice 09:00:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozfile 09:00:37 INFO - Running setup.py install for mozhttpd 09:00:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozinfo 09:00:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozInstall 09:00:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozleak 09:00:37 INFO - Running setup.py install for mozlog 09:00:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for moznetwork 09:00:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozprocess 09:00:37 INFO - Running setup.py install for mozprofile 09:00:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:37 INFO - Running setup.py install for mozrunner 09:00:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:38 INFO - Running setup.py install for mozscreenshot 09:00:38 INFO - Running setup.py install for moztest 09:00:38 INFO - Running setup.py install for mozversion 09:00:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:38 INFO - Cleaning up... 09:00:38 INFO - Return code: 0 09:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:38 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:38 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 09:00:38 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 09:00:38 INFO - Using env: {'DISPLAY': ':0', 09:00:38 INFO - 'HOME': '/home/cltbld', 09:00:38 INFO - 'LANG': 'en_US.UTF-8', 09:00:38 INFO - 'LANGUAGE': 'en_US:en', 09:00:38 INFO - 'LOGNAME': 'cltbld', 09:00:38 INFO - 'MAIL': '/var/mail/cltbld', 09:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:38 INFO - 'MOZ_NO_REMOTE': '1', 09:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:38 INFO - 'NO_EM_RESTART': '1', 09:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:38 INFO - 'PWD': '/builds/slave/test', 09:00:38 INFO - 'SHELL': '/bin/bash', 09:00:38 INFO - 'SHLVL': '1', 09:00:38 INFO - 'TERM': 'linux', 09:00:38 INFO - 'TMOUT': '86400', 09:00:38 INFO - 'USER': 'cltbld', 09:00:38 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:38 INFO - '_': '/tools/buildbot/bin/python'} 09:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:38 INFO - Running setup.py (path:/tmp/pip-r6KgFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:38 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)) 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:38 INFO - Running setup.py (path:/tmp/pip-zdQH6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:38 INFO - Running setup.py (path:/tmp/pip-C5ymNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:38 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)) 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:38 INFO - Running setup.py (path:/tmp/pip-Ppf_c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:38 INFO - Running setup.py (path:/tmp/pip-s2TD95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:38 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)) 09:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:38 INFO - Running setup.py (path:/tmp/pip-lbSa6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:39 INFO - Running setup.py (path:/tmp/pip-HftAgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:39 INFO - Running setup.py (path:/tmp/pip-A75RXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:39 INFO - Running setup.py (path:/tmp/pip-zUaZwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:39 INFO - Running setup.py (path:/tmp/pip-epZ7gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:39 INFO - Running setup.py (path:/tmp/pip-yBO4km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:39 INFO - Running setup.py (path:/tmp/pip-gE8t1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:39 INFO - Running setup.py (path:/tmp/pip-lNJYPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:39 INFO - Running setup.py (path:/tmp/pip-GXKT76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:39 INFO - Running setup.py (path:/tmp/pip-I4lozk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:39 INFO - Running setup.py (path:/tmp/pip-1K8Kfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:39 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)) 09:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:39 INFO - Running setup.py (path:/tmp/pip-j_328G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:39 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)) 09:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:39 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)) 09:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:41 INFO - Downloading blessings-1.6.tar.gz 09:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:41 INFO - Installing collected packages: blessings 09:00:41 INFO - Running setup.py install for blessings 09:00:42 INFO - Successfully installed blessings 09:00:42 INFO - Cleaning up... 09:00:42 INFO - Return code: 0 09:00:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:42 INFO - Reading from file tmpfile_stdout 09:00:42 INFO - Current package versions: 09:00:42 INFO - argparse == 1.2.1 09:00:42 INFO - blessings == 1.6 09:00:42 INFO - blobuploader == 1.2.4 09:00:42 INFO - docopt == 0.6.1 09:00:42 INFO - functools32 == 3.2.3-2 09:00:42 INFO - jsonschema == 2.5.1 09:00:42 INFO - manifestparser == 1.1 09:00:42 INFO - mozInstall == 1.12 09:00:42 INFO - mozcrash == 1.0 09:00:42 INFO - mozdebug == 0.1 09:00:42 INFO - mozdevice == 0.49 09:00:42 INFO - mozfile == 1.2 09:00:42 INFO - mozhttpd == 0.7 09:00:42 INFO - mozinfo == 0.9 09:00:42 INFO - mozleak == 0.1 09:00:42 INFO - mozlog == 3.4 09:00:42 INFO - moznetwork == 0.27 09:00:42 INFO - mozprocess == 0.23 09:00:42 INFO - mozprofile == 0.28 09:00:42 INFO - mozrunner == 6.13 09:00:42 INFO - mozscreenshot == 0.1 09:00:42 INFO - mozsystemmonitor == 0.3 09:00:42 INFO - moztest == 0.7 09:00:42 INFO - mozversion == 1.4 09:00:42 INFO - psutil == 3.1.1 09:00:42 INFO - requests == 1.2.3 09:00:42 INFO - wsgiref == 0.1.2 09:00:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:42 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')]} 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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')]} 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:42 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 09:00:42 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 09:00:42 INFO - Using env: {'DISPLAY': ':0', 09:00:42 INFO - 'HOME': '/home/cltbld', 09:00:42 INFO - 'LANG': 'en_US.UTF-8', 09:00:42 INFO - 'LANGUAGE': 'en_US:en', 09:00:42 INFO - 'LOGNAME': 'cltbld', 09:00:42 INFO - 'MAIL': '/var/mail/cltbld', 09:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:42 INFO - 'MOZ_NO_REMOTE': '1', 09:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:42 INFO - 'NO_EM_RESTART': '1', 09:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:42 INFO - 'PWD': '/builds/slave/test', 09:00:42 INFO - 'SHELL': '/bin/bash', 09:00:42 INFO - 'SHLVL': '1', 09:00:42 INFO - 'TERM': 'linux', 09:00:42 INFO - 'TMOUT': '86400', 09:00:42 INFO - 'USER': 'cltbld', 09:00:42 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:42 INFO - '_': '/tools/buildbot/bin/python'} 09:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 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)) 09:00:42 INFO - Cleaning up... 09:00:42 INFO - Return code: 0 09:00:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:42 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')]} 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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')]} 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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 0x7fb80939ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', '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 09:00:42 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 09:00:42 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 09:00:42 INFO - Using env: {'DISPLAY': ':0', 09:00:42 INFO - 'HOME': '/home/cltbld', 09:00:42 INFO - 'LANG': 'en_US.UTF-8', 09:00:42 INFO - 'LANGUAGE': 'en_US:en', 09:00:42 INFO - 'LOGNAME': 'cltbld', 09:00:42 INFO - 'MAIL': '/var/mail/cltbld', 09:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:42 INFO - 'MOZ_NO_REMOTE': '1', 09:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:42 INFO - 'NO_EM_RESTART': '1', 09:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:42 INFO - 'PWD': '/builds/slave/test', 09:00:42 INFO - 'SHELL': '/bin/bash', 09:00:42 INFO - 'SHLVL': '1', 09:00:42 INFO - 'TERM': 'linux', 09:00:42 INFO - 'TMOUT': '86400', 09:00:42 INFO - 'USER': 'cltbld', 09:00:42 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:42 INFO - '_': '/tools/buildbot/bin/python'} 09:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:42 INFO - Cleaning up... 09:00:42 INFO - Return code: 0 09:00:42 INFO - Running post-action listener: _resource_record_post_action 09:00:42 INFO - Running post-action listener: _start_resource_monitoring 09:00:42 INFO - Starting resource monitoring. 09:00:42 INFO - [mozharness: 2016-12-16 17:00:42.778379Z] Finished create-virtualenv step (success) 09:00:42 INFO - [mozharness: 2016-12-16 17:00:42.778896Z] Running install step. 09:00:42 INFO - Running pre-action listener: _resource_record_pre_action 09:00:42 INFO - Running main action method: install 09:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:42 INFO - Reading from file tmpfile_stdout 09:00:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:43 INFO - Reading from file tmpfile_stdout 09:00:43 INFO - Output received: 09:00:43 INFO - Usage: mozinstall [options] installer 09:00:43 INFO - Options: 09:00:43 INFO - -h, --help show this help message and exit 09:00:43 INFO - -d DEST, --destination=DEST 09:00:43 INFO - Directory to install application into. [default: 09:00:43 INFO - "/builds/slave/test"] 09:00:43 INFO - --app=APP Application being installed. [default: firefox] 09:00:43 INFO - mkdir: /builds/slave/test/build/application 09:00:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:00:59 INFO - Reading from file tmpfile_stdout 09:00:59 INFO - Output received: 09:00:59 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:59 INFO - Running post-action listener: _resource_record_post_action 09:00:59 INFO - [mozharness: 2016-12-16 17:00:59.110409Z] Finished install step (success) 09:00:59 INFO - [mozharness: 2016-12-16 17:00:59.110651Z] Running run-tests step. 09:00:59 INFO - Running pre-action listener: _resource_record_pre_action 09:00:59 INFO - Running main action method: run_tests 09:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:59 INFO - Python 2.7.3 09:00:59 INFO - Return code: 0 09:00:59 INFO - grabbing minidump binary from tooltool 09:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:59 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 0x2776f20>, '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 0x26e3c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e3f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:59 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 09:00:59 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 09:00:59 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 09:00:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:59 INFO - Return code: 0 09:00:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 09:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 09:00:59 INFO - Using env: {'DISPLAY': ':0', 09:00:59 INFO - 'HOME': '/home/cltbld', 09:00:59 INFO - 'LANG': 'en_US.UTF-8', 09:00:59 INFO - 'LANGUAGE': 'en_US:en', 09:00:59 INFO - 'LOGNAME': 'cltbld', 09:00:59 INFO - 'MAIL': '/var/mail/cltbld', 09:00:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:59 INFO - 'MOZ_NO_REMOTE': '1', 09:00:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:59 INFO - 'NO_EM_RESTART': '1', 09:00:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:59 INFO - 'PWD': '/builds/slave/test', 09:00:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:59 INFO - 'SHELL': '/bin/bash', 09:00:59 INFO - 'SHLVL': '1', 09:00:59 INFO - 'TERM': 'linux', 09:00:59 INFO - 'TMOUT': '86400', 09:00:59 INFO - 'USER': 'cltbld', 09:00:59 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757', 09:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:59 INFO - '_': '/tools/buildbot/bin/python'} 09:00:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 09:00:59 INFO - mozversion application_buildid: 20161216081149 09:00:59 INFO - mozversion application_changeset: e4c249612e0e3b7570942073060315fee5dff07b 09:00:59 INFO - mozversion application_display_name: Nightly 09:00:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:59 INFO - mozversion application_name: Firefox 09:00:59 INFO - mozversion application_remotingname: firefox 09:00:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:00:59 INFO - mozversion application_vendor: Mozilla 09:00:59 INFO - mozversion application_version: 53.0a1 09:00:59 INFO - mozversion platform_buildid: 20161216081149 09:00:59 INFO - mozversion platform_changeset: e4c249612e0e3b7570942073060315fee5dff07b 09:00:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:00:59 INFO - mozversion platform_version: 53.0a1 09:00:59 INFO - using testdate: 1481907659 09:00:59 INFO - actual date: 1481907659 09:00:59 INFO - starting webserver on 'localhost:42500' 09:00:59 INFO - SUITE-START | Running 2 tests 09:00:59 INFO - TEST-START | dromaeo_css 09:00:59 INFO - operating with platform_type : linux_ 09:00:59 INFO - Initialising browser for dromaeo_css test... 09:00:59 INFO - TEST-INFO | started process 17794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLr9quh/profile http://localhost:42500/getInfo.html) 09:01:03 INFO - PROCESS | 17794 | __metrics Screen width/height:1600/1200 09:01:03 INFO - PROCESS | 17794 | colorDepth:24 09:01:03 INFO - PROCESS | 17794 | Browser inner width/height: 1024/697 09:01:03 INFO - PROCESS | 17794 | __metrics 09:01:04 INFO - PROCESS | 17794 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:01:04 INFO - PROCESS | 17794 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:04 INFO - PROCESS | 17794 | console.error: 09:01:04 INFO - PROCESS | 17794 | Message: Error: Connection closed before committing the transaction. 09:01:04 INFO - PROCESS | 17794 | Stack: 09:01:04 INFO - PROCESS | 17794 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 09:01:04 INFO - PROCESS | 17794 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 09:01:04 INFO - PROCESS | 17794 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 09:01:04 INFO - PROCESS | 17794 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 09:01:04 INFO - PROCESS | 17794 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 09:01:04 INFO - PROCESS | 17794 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 09:01:04 INFO - PROCESS | 17794 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 09:01:04 INFO - PROCESS | 17794 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 09:01:04 INFO - PROCESS | 17794 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 09:01:04 INFO - PROCESS | 17794 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 09:01:04 INFO - PROCESS | 17794 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 09:01:04 INFO - PROCESS | 17794 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 09:01:04 INFO - PROCESS | 17794 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 09:01:04 INFO - PROCESS | 17794 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 09:01:04 INFO - PROCESS | 17794 | 09:01:05 INFO - PROCESS | 17794 | console.error: PushService: 09:01:05 INFO - PROCESS | 17794 | stateChangeProcessEnqueue: Error transitioning state 09:01:05 INFO - PROCESS | 17794 | UnknownError 09:01:05 INFO - TEST-INFO | 17794: exit 0 09:01:05 INFO - Browser initialized. 09:01:05 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:05 INFO - TEST-INFO | started process 17877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLr9quh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:17 INFO - PROCESS | 17877 | RSS: Main: 160464896 09:01:17 INFO - PROCESS | 17877 | 09:02:08 INFO - PROCESS | 17877 | 1481907728472 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] 09:04:21 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42500/tests/dromaeo/cssquery-ext.html) 09:04:24 INFO - PROCESS | 17877 | RSS: Main: 338710528 09:04:24 INFO - PROCESS | 17877 | 09:07:27 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-ext.html (next: http://localhost:42500/tests/dromaeo/cssquery-jquery.html) 09:07:27 INFO - PROCESS | 17877 | RSS: Main: 256798720 09:07:27 INFO - PROCESS | 17877 | 09:10:30 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42500/tests/dromaeo/cssquery-mootools.html) 09:10:30 INFO - PROCESS | 17877 | RSS: Main: 192552960 09:10:30 INFO - PROCESS | 17877 | 09:13:33 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42500/tests/dromaeo/cssquery-prototype.html) 09:13:33 INFO - PROCESS | 17877 | RSS: Main: 190803968 09:13:33 INFO - PROCESS | 17877 | 09:16:37 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42500/tests/dromaeo/cssquery-yui.html) 09:16:37 INFO - PROCESS | 17877 | RSS: Main: 192217088 09:16:37 INFO - PROCESS | 17877 | 09:19:40 INFO - PROCESS | 17877 | Cycle 1(1): loaded http://localhost:42500/tests/dromaeo/cssquery-yui.html (next: http://localhost:42500/tests/dromaeo/cssquery-dojo.html) 09:19:41 INFO - PROCESS | 17877 | RSS: Main: 283344896 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | __start_tp_report 09:19:41 INFO - PROCESS | 17877 | _x_x_mozilla_page_load 09:19:41 INFO - PROCESS | 17877 | _x_x_mozilla_page_load_details 09:19:41 INFO - PROCESS | 17877 | |i|pagename|runs| 09:19:41 INFO - PROCESS | 17877 | |0;dojo.html;2582.48350329934;2594.0791019188177;2608.791296696264;2621.1743300860226;2632.328756167178;4678.111158879819;4708.69393489423;4715.811367545299;4722.197226471504;4724.24412094065;4739.194336962716;4762.952370476294;4794.808207671689;4826.420829500456;4864.975675121628;4333.371661109138;4349.69552131351;4385.42989411376;4405.172242035426;4408.228560002003;4028.617281358266;4465.486469056068;4466.3300504924355;4495.617872480827;4549.931751023737;4258.339957306623;4422.845200418002;4435.3125265583885;4452.3321501774835;4529.932051019237;4285.078708077778;4324.351347297899;4378.343248512717;4396.824127034914;4427.535108813592;4381.145676593065;4394.62645675118;4395.692301538894;4428.601425871687;4459.777011149429;4758.78585463655;4765.904681906342;4783.1868582341085;4786.114568804782;4786.976065119687;4935.1363511385725;4958;4969.478204788517;5012.674176178537;5119.872003199913;5079.187330027215;5084.745762711849;5098;5121.180611102206;5137.02396544659;4704.929426058646;4705.717656940595;4706.976465117721;4736.384270044872;4743.810247590058;4976.477469865685;5023.698578085254;5187.455317191716;5187.714675692798;5231.9476805232225;4874.098291815954;4880.755962201875;4972.875678108004;5003.874903127451;5010.448850626428;5100.209467532539;5113.9744301279;5138.100832354323;5144.845654630367;5145.305383773218;5012.473690262543;5029.723365214948;5045.495450454925;5066.316047333563;5124.5131712487255;4903;5050.72221027847;5054.696718196847;5060.165072763027;5094.668846525038;4200.85297014603;4240.5123410808155;4243.257429949746;4243.405923170759;4243.6605071594195;3707.4253490708984;3730.0301921500604;3766.7928263945746;3767.962320376761;3781.4516895050065;4350.782460876943;4387.451568553931;4399.5820397062225;4399.890002749893;4407.603315701603;5080.7205603692155;5116.616253780981;5123.410807757081;5126.641135120506;5171.896562068738;4273.1240095780295;4352.586504282088;4359.346098085312;4362.389265502832;4398.098389830077;4505.098980203972;4520.16376970255;4546.090781843644;4547.15877562652;4566;4789.042191561702;4792.544708252711;4801;4801.855944321676;4841.782119804618;5138.743062846768;5140.203268493389;5141.614378921521;5141.897162056813;5199;4642.071585682945;4740.928886066643;4785.210440277316;4785.353977213032;4836.903261934812;5017.874553136201;5017.949820501748;5053.924191137063;5096.362954630671;5101.311322971352;5132.050570644443;5134;5136.614753893398;5138.743062846917;5187.066328060985;4760.714357138583;4790.688605240648;4991.675541089818;5000.899982000266;5010.148274793221;4807.086653535817;4831.661783675249;4836.709797412166;4841.612670986242;4861.246506791453;4750.12122757291;4757.691634800458;4763.2140696784645;4764.237721964469;4769.594584460359;4881.609471242363;4899.16714158601;4909.95090049095;4932.975335123301;5009.148444764326;4797.808087676453;4831.130396528659;4832.178529650038;4844.878878028077;4886.438059623189 09:19:41 INFO - PROCESS | 17877 | |1;ext.html;496.28534909729984;534.6257619666235;539.9379071406789;552.925355077065;558.1460365640569;35350.64649353506;37834.43248351277;37879.24241515168;40697.38953915694;40865.182696346055;36886.44670329941;37171.25657486849;37220.81389593055;37222.627773722255;37373.252534949286;7382.261773822628;7574.621268936545;7740.574268415235;7747.496412733182;7751.302382785549;7387.37207337377;9622.711318660451;9885.703428897144;9893.109620134186;10216.48917554123;9901.653442129527;9975.650852220173;9981.251406144533;9985.75035624108;9987.90012099877;9875.358101723401;9945.104940555313;9959.203263738884;9959.800803983951;9965;9760.072793084646;9785.26610504208;9818.410895346231;9831.410115393099;9832.262580306435;35885.102872428135;36007.63992360095;36210.456843147374;36229;36246.275074498626;3010.247438140465;3130.514770210621;3580.1765593913547;3622.9456558151387;3623.4564815277654;26627.467450650878;27292.590611140848;27340.58989115165;27373.178804635892;27387.86306068477;3362.596488421405;3390.3558323918624;3485.268093700326;3597.94603080954;3605.963940360615;231264;232252;232289;232358.9999999983;232705;49709.25436118461;49950.750246248535;49996;50112.498875011515;50119.49880501221;51367.48632513627;51428;51543.48456515387;51581.48418515842;51606.74196628993;13847.238401887791;13861.306934653428;13964.301784910715;13970.580882573538;13990.650233744238;9380.624775008922;9718.465484398284;10322.741931451623;10331.070203681706;11137.777244455228;17404.912975435294;17704.468865934043;18101.45695629133;18112.909435453003;18160;6894.207166175954;6939.618320992394;7003.509754317251;7059.5726053564385;7243;753.9962300188464;806.641044735099;847.1020718038899;868.1015149320549;920.9447433154032;27044;27296.45407091847;27770.722292777216;27966.16101516958;27966.580501292498;880.366136381804;886.8137691084816;893.2898345815092;898.501331760879;1042.9478526073665;14114.22371769542;14506.1296322221;14750.55748327552;14879.851201488063;14897.404103835943;112220.43889780663;112663;112776.43611781888;112910;113089;47936.28095578569;48563.51436485661;48604.513954860704;48645.270320945216;48661.75669121632;1028.377831412;1077.0145317034983;1080.6866008857344;1121.6354684727496;1150.5685367987005;20392.490187745425;26307.344901355067;27904.143784323514;28003.319800812016;28290.292742680773;40763.184736304516;41519.792401037805;41559;41562;41563.58436415597;331982;333517;333957;334491;334568;23118.64779606981;23215.53568928646;23347.766522335238;23766.405839854142;24656.137035203683;35747;36452.81773591115;36541.45187822131;36713.81643091767;37072;30943;31160.53259201159;31511.684883150876;32048.5192722114;32165.826308135827;31064.689353107085;31079;31520.842395787873;32429.51355729713;32964.50553241751;29949.85025074861;30078.548821767254;30184.24539386533;30266;32373.838130809196;25638.743612563634;27030.729692702822;29923.55114673325;29925.700742993162;30086.24784380408;21937.34187974363;22901.541969160855;23310.184143554896;23380.415489612897;23380.64929026032 09:19:41 INFO - PROCESS | 17877 | |2;jquery.html;341.510389262278;361.5209846952787;372.61992767377274;375.6994404476419;396.14432825097776;12223;12249.938750306248;13058.085933984625;13902.791458128137;16506;16060.11669358185;16370.83629163708;16580.75128873068;16612;16620.916895415536;4878.243872199815;4897.142999975008;5080.771365288552;5088.18589025756;5090.745462726867;3617.2584620152807;3811.7522361046445;4981.103401387749;5066.316047333599;5070.949290507103;5629.437056294359;5856.2094117294255;5871.588988770788;5936.43603857635;5942.346341902387;5696.601237913348;5824.534037277008;5842.503387212092;5926.644401335912;5949.286085669704;5165.535101840815;5168.974155129238;5170.767315470813;5171.276021357013;5176.870578235536;18740.812591874044;19708.40874773759;19773;19863.20547178111;19865;1881.7741870975433;2030.6953956906434;2191.660292654641;2334.7781960713705;2495.538325409805;12095.153339266255;12921.483140674361;13025.283609401478;13190.11665068704;13424.865751342557;1583.8178609459944;1678.152532970842;1708.4105983435695;1729.8097209306966;1748.6764948484572;63234.36765632331;64638.676806615666;64697;65510.3448965509;66789.66605166992;9164.404313719588;9235.1688348049;9534.713958581253;9563.808723825623;9734.561944712505;13120.343982801012;13121.934390327988;13130.868691313155;13149.934250328688;13178.60464186076;6993.6503174841055;6997.93002069983;7163.606001669854;7169.7849064528145;7363.263673632594;6207.86502816075;6582.868342633121;6686.13080299558;6696.2968888267005;6728.932710672929;5976.61152025117;5995.52035837132;6111.144439778434;6123.357047510036;6229.96885015572;1713.254734190606;1782.9197686104158;1790.9552261193573;1804.3865085870866;1811.25738447236;786.6263524825662;844.9619767110372;871.2072014466805;888.4225253585221;904.3579058868277;16112.436064737678;16699.165041747863;16736.497905062868;16847.83152168487;17258.913705431645;838.9454685445427;850.9191626795445;851.4848516647463;865.312076898867;872.6901949807853;8963.731088067369;9751.463669498246;9809;9903.900960990442;10033.849492257623;258668;265253;345531;345925;348728;54777.45222547724;65219;76862.61568692121;77874.2212577867;78218.6089069551;887.1394747095308;917.8852643419706;942.4251206763869;948.0993056596261;950.6007476859736;15135.697286053997;15273.159976201416;15851.920740396225;16015;16040.759388608947;35285.823570881985;35733.28533429369;36080.63919360773;36374.27251454903;37026.444603330434;48176.27735584039;48375;48385.75807120942;48482.75758621185;48931.75534122306;7348.154962179417;7389.889151662622;7502.924970750222;7526.397888169043;7611.238876112345;11545.942270288595;11954.940225298818;12114.697132571757;12297.385130743249;12300.938495307466;9685.128338449573;10238.488075596042;10739.301945373525;10857.34855908648;10955.83566246491;10016.849747253942;10023.198144148304;10086.142677872464;10154.136898363722;10310.690679279634;5805.738741756715;10257.487125643538;10405.791884162425;10562.630307939186;10570.10154136907;7389.556626602421;7410.073740782403;7461.888071678821;7521.172671006076;7556.962215189109;9405.20055795265;10299.536520856429;10303.278770486293;10486.318389304672;10775.73060673458 09:19:41 INFO - PROCESS | 17877 | |3;mootools.html;1231.384307846077;1320.3860205004673;1359.6872719274565;1452.709458108378;1687.4937518744373;6368.649724265164;7040.507164498481;7328.120625524931;7328.596927169004;7487.962560187205;7292.817679558014;7356.301151390609;7360.116785985676;7366.042414486109;7373.5207211531215;1830.9176087076046;1893.9337123200692;2101.947451313718;2140.9892950535227;2143.8284937204985;2076.5743022680313;2161.059938926564;2167.111484291441;2185.836062295334;2195.4182141732454;2078.812906838384;2108.9367318980453;2116.1852686715647;2121.7029615853794;2125.6598944168936;2099.748030236374;2110.082114280293;2117.6400011997844;2128.1168315149193;2137.7862213778653;2036.8166864982222;2067.7415323084615;2123.819475344598;2132.744070711509;2136.209602447089;7092.326229008236;7199.712011519533;7223.638818059076;7240.963795181043;7250.129984401853;4319.870403887889;4433.512313645497;4436.356728274414;4446.844360447369;4449;2869.1249169003445;2903.956440653392;2961.526155815081;3132.6554079051284;3139.230888432349;879.6041781198486;892.5180402582598;942.5146049784366;972.5769290358717;973.4694591447608;9486.810263794754;9519.333646644669;9535.713928582154;9572.377795443273;9604.471754053524;3834.6357096075826;4027.657649099801;4071.3892916062828;4140.275451795984;4176.60322269384;8207.630656620348;8216.301614362726;8228.917710822816;8232.300254478436;8233;4368.5194628590825;4420.778961051935;4430.13612345596;4549.408576884984;4554.157480865984;3616.475611036385;3782.6406491383273;4025.7987100645437;4046.3323551614244;4051.0480037191237;3383.983080084633;3454.3764430717524;3466.0295117367177;3468.583769947572;3579.856805727794;1148.1446322489799;1183.1836033137108;1189.0547015122997;1189.0784641902421;1206.4872429217548;805.7985503624094;810.0846043970275;814.4624547798543;815.5473712089847;816.7177531275959;4730.810767569327;4731.929021064687;4731.935314554198;4811.230203167476;4962.826301079445;408.16122867506965;412.4988139410631;413.39437723734875;414.601982097184;418.1553262409915;3234.741220702338;3268.509723541488;3295.2256219788333;3335.9992002399185;3398.8810391636175;8031.518108913484;8318.085010648823;8450.450720703135;8639.524826134497;8669.523176225368;8477.194666506795;8557.229849313468;8588.957055214683;8597.441166324168;8602.408554417327;6552.903071017324;6765.154355705537;6766.018927255521;6766.357196066366;6775;4988.077205716954;5002.574781143643;5094.337736094432;5107.514786095315;5203.401608814884;7865.292123708894;7897.289243968071;8065.153158918464;8144.877826832484;8232.053313868742;8133.088724149939;8155.347572194091;8167.469114507538;8220.506769593843;8304.418690691593;5505.091659876076;5707.1724599933605;6273.309935906956;6352.428281454691;6387.073874288109;6648.800535983928;6862.553933994274;6911.75808846688;7014.333638304353;7070.575765454089;6219.160413344322;6367.586106903036;6383.892885691521;6503.967480162569;6581.07864898905;6079.908801368071;6093.146959425595;6133.785317513865;6193.969030154821;6214.25428948529;5860.531157507304;5985.281766188085;5998.4601385875485;6003.549733770072;6026.487748541423;4299.97850010748;4388.605025547716;4468.64250859924;4487.589432031614;4514.774261286857;4204.789760512023;4324.6107850292365;4348.173846969065;4368.082702632356;4416.580424859633 09:19:41 INFO - PROCESS | 17877 | |4;prototype.html;347.2534051788654;357.6709427326859;358.2423174984906;362.2465272233754;365.37520839364686;4790.425148982118;4814.7351895645725;4825.589824864886;4829.913269514357;4837.379477874907;4749.857504274869;4750.90498190036;4767.6185905127595;4787.377640906686;4806.591439727627;3480.712136509495;3557.430811070229;3642.763220390673;3668.5433636799294;3744.7191460640424;3773.7546160034735;3828.5214348206473;3843.5195600549932;3856.826442810066;3872.5671501544466;3811.980940095296;3812.961870381302;3817.10298079951;3817.8164768921583;3836.251930873481;3686.4838922550866;3826.330392181357;3829.1767270036935;3830.367989281765;3841.1741475582603;3364.411228035084;3388.3223355329033;3428.400029994766;3443.3974054540604;3446.7079392204405;4614.007988282536;4634.050019745944;4644.373009643722;4647.60495357895;4676.415448068991;4340.544242854518;4358.673099517518;4370.191514569815;4380.737155770664;4396.560343965578;4785.521447855222;4932.457429682732;4956.950430495685;4982.800687972477;4998.775055122492;3742.3450896093077;3747.8126093695205;3750.2124553843723;3758.0229140423685;3771.283456143324;4945.950540494585;5040.47075057121;5106;5198.376194856642;5236.842894713127;3850.2492014057552;3854.5567259765485;3857.379900441821;3862.9227415451533;3877.360235561101;3905.082305658168;3915.980420097881;3918.5885482024546;3920.941185882214;3939.803009849496;4246.171996460722;4361.520232774392;4365.301551751768;4366.345048242725;4369.650427965755;4932.199747092285;4934.975325123423;4966.503349665005;4969.776360063805;4972.8508144755715;1319.8548159702425;1332.4337156708555;1335.8998075144402;1349.2174538767492;1353.8510763815973;926.2354246295348;975.1759763000254;981.1954197558076;983.4001259231862;988.110700369673;4226.2392769301205;4279.957200428018;4284.914301713949;4293.892652683708;4307.440032795716;4933.679310844784;4954.578860796801;4959.90080198394;4974.353334066621;4981.9252711209365;4143.97928010358;4267.935980960288;4277.144571085795;4283.8580709645175;4297.355396690521;2598.3634009667753;2600.4019075612714;2619.148776647597;2619.5546757051347;2653.7080921098664;4652.418447694038;4672.906541869143;4680;4680.67235293533;4692.694974826625;4790.461055640516;5025.3718285214345;5072.82245121419;5087.26234695967;5101.158308879068;4646.326282688991;4647.814087436533;4660.790264438041;4678.228092364721;4698.882527936829;4959.231319145538;4961.776720047678;4964.900701985868;4981.925271121008;4986.301917731593;4812.951870481251;4870.415550133865;4900.240462728283;4910.386201724785;4916.631252656137;4613.5847773700525;4705.55297246761;4706;4809.182438985311;4824.782884770264;3605.170810713498;3608.80151591665;3628.822706376259;3635.3819850625987;3635.6727894489622;5117.053345131163;5126.87260770922;5128.205128205134;5138.922916156335;5159.17453207485;4872.049431742444;4911.435184953776;4969.776360063734;4981.178105612534;5001.724905130107;4974.776135074003;4977.4773648767805;4978.975105124451;4989.925151122663;5001.8499445016705;4856.915858429852;4864.61083113343;4870.025994800983;4875.536824001714;4879;4873.805047798049;4954.752262386938;4992.500749924979;5002.824901128443;5016.899662006666;4868.591985610158;4869.220924652039;4876.341693871282;4881.780319885543;4899.583535399531 09:19:41 INFO - PROCESS | 17877 | |5;yui.html;630.1870586942844;642.9099926010358;694.9895751563724;724.4856152131986;738.4904415952996;12783.3608319584;13085.084044116904;13636.386362613668;14725;15653.45212917548;15107.093574385544;15330.69338613227;15332.846671533282;15367.077975321488;15395.69208615827;620.6710443464971;639.9680015999205;773.8877862709903;797.5932274539997;800.3597122302164;762.5158024654329;790.2374208888422;793.218679600593;798.6725442568548;802.3380710913491;836.5984327522792;838.7567605394458;840.4369072721261;840.4831028917203;840.7645859159445;637.3212528656966;821.3716506872247;821.8068753842842;822;822.4489591973393;490.252365143156;591.1015256809668;607.6384551192034;610.6214147228746;614.3457218062794;13548.254845983467;13549.729005419837;13583.796243056362;13592.592222233348;13593.048486605941;12320.876791232153;12403.13178077535;12484.313362765044;12771;12779.169353992054;11541.76916461666;11548.556430446208;11653;11681.357525336103;11755.17713760037;4033.757974521538;4061.4923134608175;4062.1469491406842;4066.2883995301;4088.366303223005;62929.37070629281;63301.36698633001;64139;64206.35793642051;66116;9281.489518076436;9408.905910940939;9748.31761776683;9763.121319081316;9837.11465968052;19081.713774293396;19519.902400487907;19547.218111275673;19566.902165489082;19585.706214406797;6445.45213656835;6665.966670166618;6763;6897.172339319314;7031.92968070323;3616.258666973264;3733.8984999425234;3742.438634204891;3772.7359084864347;3812.8856134316015;2936.309967157716;3003.684613115591;3016.668166501683;3022.9546556801665;3023.909282721522;509.80334039746936;517.1905967161381;526.402533124906;530.1702835063114;533.6371267538111;508.3010860067407;521.6009752539311;531.2138035707175;638.4477427025589;654.2639530528156;8314.8337033259;8777.473351598925;8883.200511953826;8954.955225223832;9027.864582031274;506.6883866422124;587.9323877754027;631.8546734251056;639.8912184928573;648.7307767276621;4044.8179831907637;4065.5121385433936;4109.321961876257;4121.4848143982;4219.261629214875;49859.75070124626;59515;62250.68874655688;64784.67607661932;65987;54789.726051370286;56275;56436;57299.71350143223;58402.12396814051;51110.23334649909;51819.740901296005;52035.47964520382;52226.47773522292;52349;8179.427440079376;8213.219744124299;8333.874991875247;8382.874256886274;8574.957125214334;37393.626063739015;38550.80724596359;38581.42127868028;38904;38991.6100838988;45753.54246457626;45779.31331030104;49210.75394623004;50813;51812.74093629508;10004.649837255662;10109.39343639384;10140.898591013995;10362.792744145225;10499.107575855833;10853.728656783644;11214.439278035903;11274.436278185894;11321.830172547583;11716;10743.623973160902;10808.945955270174;10930.070943969851;11418.486168122288;12104.394780261127;11826.76346473083;12269.69325766863;12589.24464532131;12985.220886746825;13007.739845203232;12230.694232644255;12622.621321360373;12702.301373424181;12952.28762418076;12954;10211.438370889673;10270.435126068138;10472.633457828939;10518.631847885288;10603.840942386025;9754;10349.586016559555;10512.26414151002;10680.359178449318;10995.285306455055 09:19:41 INFO - PROCESS | 17877 | __end_tp_report 09:19:41 INFO - PROCESS | 17877 | __start_cc_report 09:19:41 INFO - PROCESS | 17877 | _x_x_mozilla_cycle_collect,2218 09:19:41 INFO - PROCESS | 17877 | __end_cc_report 09:19:41 INFO - PROCESS | 17877 | __startTimestamp1481908781206__endTimestamp 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | ------- Summary: start ------- 09:19:41 INFO - PROCESS | 17877 | Number of tests: 6 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#0] dojo.html Cycles:165 Average:4683.27 Median:4789.04 stddev:496.80 (10.4%) stddev-sans-first:470.20 09:19:41 INFO - PROCESS | 17877 | Values: 2582.5 2594.1 2608.8 2621.2 2632.3 4678.1 4708.7 4715.8 4722.2 4724.2 4739.2 4763.0 4794.8 4826.4 4865.0 4333.4 4349.7 4385.4 4405.2 4408.2 4028.6 4465.5 4466.3 4495.6 4549.9 4258.3 4422.8 4435.3 4452.3 4529.9 4285.1 4324.4 4378.3 4396.8 4427.5 4381.1 4394.6 4395.7 4428.6 4459.8 4758.8 4765.9 4783.2 4786.1 4787.0 4935.1 4958.0 4969.5 5012.7 5119.9 5079.2 5084.7 5098.0 5121.2 5137.0 4704.9 4705.7 4707.0 4736.4 4743.8 4976.5 5023.7 5187.5 5187.7 5231.9 4874.1 4880.8 4972.9 5003.9 5010.4 5100.2 5114.0 5138.1 5144.8 5145.3 5012.5 5029.7 5045.5 5066.3 5124.5 4903.0 5050.7 5054.7 5060.2 5094.7 4200.9 4240.5 4243.3 4243.4 4243.7 3707.4 3730.0 3766.8 3768.0 3781.5 4350.8 4387.5 4399.6 4399.9 4407.6 5080.7 5116.6 5123.4 5126.6 5171.9 4273.1 4352.6 4359.3 4362.4 4398.1 4505.1 4520.2 4546.1 4547.2 4566.0 4789.0 4792.5 4801.0 4801.9 4841.8 5138.7 5140.2 5141.6 5141.9 5199.0 4642.1 4740.9 4785.2 4785.4 4836.9 5017.9 5017.9 5053.9 5096.4 5101.3 5132.1 5134.0 5136.6 5138.7 5187.1 4760.7 4790.7 4991.7 5000.9 5010.1 4807.1 4831.7 4836.7 4841.6 4861.2 4750.1 4757.7 4763.2 4764.2 4769.6 4881.6 4899.2 4910.0 4933.0 5009.1 4797.8 4831.1 4832.2 4844.9 4886.4 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#1] ext.html Cycles:180 Average:38584.32 Median:35548.82 stddev:64187.02 (180.6%) stddev-sans-first:64303.37 09:19:41 INFO - PROCESS | 17877 | Values: 496.3 534.6 539.9 552.9 558.1 35350.6 37834.4 37879.2 40697.4 40865.2 36886.4 37171.3 37220.8 37222.6 37373.3 7382.3 7574.6 7740.6 7747.5 7751.3 7387.4 9622.7 9885.7 9893.1 10216.5 9901.7 9975.7 9981.3 9985.8 9987.9 9875.4 9945.1 9959.2 9959.8 9965.0 9760.1 9785.3 9818.4 9831.4 9832.3 35885.1 36007.6 36210.5 36229.0 36246.3 3010.2 3130.5 3580.2 3622.9 3623.5 26627.5 27292.6 27340.6 27373.2 27387.9 3362.6 3390.4 3485.3 3597.9 3606.0 231264.0 232252.0 232289.0 232359.0 232705.0 49709.3 49950.8 49996.0 50112.5 50119.5 51367.5 51428.0 51543.5 51581.5 51606.7 13847.2 13861.3 13964.3 13970.6 13990.7 9380.6 9718.5 10322.7 10331.1 11137.8 17404.9 17704.5 18101.5 18112.9 18160.0 6894.2 6939.6 7003.5 7059.6 7243.0 754.0 806.6 847.1 868.1 920.9 27044.0 27296.5 27770.7 27966.2 27966.6 880.4 886.8 893.3 898.5 1042.9 14114.2 14506.1 14750.6 14879.9 14897.4 112220.4 112663.0 112776.4 112910.0 113089.0 47936.3 48563.5 48604.5 48645.3 48661.8 1028.4 1077.0 1080.7 1121.6 1150.6 20392.5 26307.3 27904.1 28003.3 28290.3 40763.2 41519.8 41559.0 41562.0 41563.6 331982.0 333517.0 333957.0 334491.0 334568.0 23118.6 23215.5 23347.8 23766.4 24656.1 35747.0 36452.8 36541.5 36713.8 37072.0 30943.0 31160.5 31511.7 32048.5 32165.8 31064.7 31079.0 31520.8 32429.5 32964.5 29949.9 30078.5 30184.2 30266.0 32373.8 25638.7 27030.7 29923.6 29925.7 30086.2 21937.3 22901.5 23310.2 23380.4 23380.6 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#2] jquery.html Cycles:180 Average:21733.90 Median:48275.64 stddev:52419.74 (108.6%) stddev-sans-first:52542.18 09:19:41 INFO - PROCESS | 17877 | Values: 341.5 361.5 372.6 375.7 396.1 12223.0 12249.9 13058.1 13902.8 16506.0 16060.1 16370.8 16580.8 16612.0 16620.9 4878.2 4897.1 5080.8 5088.2 5090.7 3617.3 3811.8 4981.1 5066.3 5070.9 5629.4 5856.2 5871.6 5936.4 5942.3 5696.6 5824.5 5842.5 5926.6 5949.3 5165.5 5169.0 5170.8 5171.3 5176.9 18740.8 19708.4 19773.0 19863.2 19865.0 1881.8 2030.7 2191.7 2334.8 2495.5 12095.2 12921.5 13025.3 13190.1 13424.9 1583.8 1678.2 1708.4 1729.8 1748.7 63234.4 64638.7 64697.0 65510.3 66789.7 9164.4 9235.2 9534.7 9563.8 9734.6 13120.3 13121.9 13130.9 13149.9 13178.6 6993.7 6997.9 7163.6 7169.8 7363.3 6207.9 6582.9 6686.1 6696.3 6728.9 5976.6 5995.5 6111.1 6123.4 6230.0 1713.3 1782.9 1791.0 1804.4 1811.3 786.6 845.0 871.2 888.4 904.4 16112.4 16699.2 16736.5 16847.8 17258.9 838.9 850.9 851.5 865.3 872.7 8963.7 9751.5 9809.0 9903.9 10033.8 258668.0 265253.0 345531.0 345925.0 348728.0 54777.5 65219.0 76862.6 77874.2 78218.6 887.1 917.9 942.4 948.1 950.6 15135.7 15273.2 15851.9 16015.0 16040.8 35285.8 35733.3 36080.6 36374.3 37026.4 48176.3 48375.0 48385.8 48482.8 48931.8 7348.2 7389.9 7502.9 7526.4 7611.2 11545.9 11954.9 12114.7 12297.4 12300.9 9685.1 10238.5 10739.3 10857.3 10955.8 10016.8 10023.2 10086.1 10154.1 10310.7 5805.7 10257.5 10405.8 10562.6 10570.1 7389.6 7410.1 7461.9 7521.2 7557.0 9405.2 10299.5 10303.3 10486.3 10775.7 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#3] mootools.html Cycles:180 Average:4760.14 Median:4995.33 stddev:2575.72 (51.6%) stddev-sans-first:2569.29 09:19:41 INFO - PROCESS | 17877 | Values: 1231.4 1320.4 1359.7 1452.7 1687.5 6368.6 7040.5 7328.1 7328.6 7488.0 7292.8 7356.3 7360.1 7366.0 7373.5 1830.9 1893.9 2101.9 2141.0 2143.8 2076.6 2161.1 2167.1 2185.8 2195.4 2078.8 2108.9 2116.2 2121.7 2125.7 2099.7 2110.1 2117.6 2128.1 2137.8 2036.8 2067.7 2123.8 2132.7 2136.2 7092.3 7199.7 7223.6 7241.0 7250.1 4319.9 4433.5 4436.4 4446.8 4449.0 2869.1 2904.0 2961.5 3132.7 3139.2 879.6 892.5 942.5 972.6 973.5 9486.8 9519.3 9535.7 9572.4 9604.5 3834.6 4027.7 4071.4 4140.3 4176.6 8207.6 8216.3 8228.9 8232.3 8233.0 4368.5 4420.8 4430.1 4549.4 4554.2 3616.5 3782.6 4025.8 4046.3 4051.0 3384.0 3454.4 3466.0 3468.6 3579.9 1148.1 1183.2 1189.1 1189.1 1206.5 805.8 810.1 814.5 815.5 816.7 4730.8 4731.9 4731.9 4811.2 4962.8 408.2 412.5 413.4 414.6 418.2 3234.7 3268.5 3295.2 3336.0 3398.9 8031.5 8318.1 8450.5 8639.5 8669.5 8477.2 8557.2 8589.0 8597.4 8602.4 6552.9 6765.2 6766.0 6766.4 6775.0 4988.1 5002.6 5094.3 5107.5 5203.4 7865.3 7897.3 8065.2 8144.9 8232.1 8133.1 8155.3 8167.5 8220.5 8304.4 5505.1 5707.2 6273.3 6352.4 6387.1 6648.8 6862.6 6911.8 7014.3 7070.6 6219.2 6367.6 6383.9 6504.0 6581.1 6079.9 6093.1 6133.8 6194.0 6214.3 5860.5 5985.3 5998.5 6003.5 6026.5 4300.0 4388.6 4468.6 4487.6 4514.8 4204.8 4324.6 4348.2 4368.1 4416.6 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#4] prototype.html Cycles:180 Average:4141.38 Median:4677.32 stddev:1150.74 (24.6%) stddev-sans-first:1118.18 09:19:41 INFO - PROCESS | 17877 | Values: 347.3 357.7 358.2 362.2 365.4 4790.4 4814.7 4825.6 4829.9 4837.4 4749.9 4750.9 4767.6 4787.4 4806.6 3480.7 3557.4 3642.8 3668.5 3744.7 3773.8 3828.5 3843.5 3856.8 3872.6 3812.0 3813.0 3817.1 3817.8 3836.3 3686.5 3826.3 3829.2 3830.4 3841.2 3364.4 3388.3 3428.4 3443.4 3446.7 4614.0 4634.1 4644.4 4647.6 4676.4 4340.5 4358.7 4370.2 4380.7 4396.6 4785.5 4932.5 4957.0 4982.8 4998.8 3742.3 3747.8 3750.2 3758.0 3771.3 4946.0 5040.5 5106.0 5198.4 5236.8 3850.2 3854.6 3857.4 3862.9 3877.4 3905.1 3916.0 3918.6 3920.9 3939.8 4246.2 4361.5 4365.3 4366.3 4369.7 4932.2 4935.0 4966.5 4969.8 4972.9 1319.9 1332.4 1335.9 1349.2 1353.9 926.2 975.2 981.2 983.4 988.1 4226.2 4280.0 4284.9 4293.9 4307.4 4933.7 4954.6 4959.9 4974.4 4981.9 4144.0 4267.9 4277.1 4283.9 4297.4 2598.4 2600.4 2619.1 2619.6 2653.7 4652.4 4672.9 4680.0 4680.7 4692.7 4790.5 5025.4 5072.8 5087.3 5101.2 4646.3 4647.8 4660.8 4678.2 4698.9 4959.2 4961.8 4964.9 4981.9 4986.3 4813.0 4870.4 4900.2 4910.4 4916.6 4613.6 4705.6 4706.0 4809.2 4824.8 3605.2 3608.8 3628.8 3635.4 3635.7 5117.1 5126.9 5128.2 5138.9 5159.2 4872.0 4911.4 4969.8 4981.2 5001.7 4974.8 4977.5 4979.0 4989.9 5001.8 4856.9 4864.6 4870.0 4875.5 4879.0 4873.8 4954.8 4992.5 5002.8 5016.9 4868.6 4869.2 4876.3 4881.8 4899.6 09:19:41 INFO - PROCESS | 17877 | 09:19:41 INFO - PROCESS | 17877 | [#5] yui.html Cycles:180 Average:15004.06 Median:23134.78 stddev:18299.94 (79.1%) stddev-sans-first:18319.44 09:19:41 INFO - PROCESS | 17877 | Values: 630.2 642.9 695.0 724.5 738.5 12783.4 13085.1 13636.4 14725.0 15653.5 15107.1 15330.7 15332.8 15367.1 15395.7 620.7 640.0 773.9 797.6 800.4 762.5 790.2 793.2 798.7 802.3 836.6 838.8 840.4 840.5 840.8 637.3 821.4 821.8 822.0 822.4 490.3 591.1 607.6 610.6 614.3 13548.3 13549.7 13583.8 13592.6 13593.0 12320.9 12403.1 12484.3 12771.0 12779.2 11541.8 11548.6 11653.0 11681.4 11755.2 4033.8 4061.5 4062.1 4066.3 4088.4 62929.4 63301.4 64139.0 64206.4 66116.0 9281.5 9408.9 9748.3 9763.1 9837.1 19081.7 19519.9 19547.2 19566.9 19585.7 6445.5 6666.0 6763.0 6897.2 7031.9 3616.3 3733.9 3742.4 3772.7 3812.9 2936.3 3003.7 3016.7 3023.0 3023.9 509.8 517.2 526.4 530.2 533.6 508.3 521.6 531.2 638.4 654.3 8314.8 8777.5 8883.2 8955.0 9027.9 506.7 587.9 631.9 639.9 648.7 4044.8 4065.5 4109.3 4121.5 4219.3 49859.8 59515.0 62250.7 64784.7 65987.0 54789.7 56275.0 56436.0 57299.7 58402.1 51110.2 51819.7 52035.5 52226.5 52349.0 8179.4 8213.2 8333.9 8382.9 8575.0 37393.6 38550.8 38581.4 38904.0 38991.6 45753.5 45779.3 49210.8 50813.0 51812.7 10004.6 10109.4 10140.9 10362.8 10499.1 10853.7 11214.4 11274.4 11321.8 11716.0 10743.6 10808.9 10930.1 11418.5 12104.4 11826.8 12269.7 12589.2 12985.2 13007.7 12230.7 12622.6 12702.3 12952.3 12954.0 10211.4 10270.4 10472.6 10518.6 10603.8 9754.0 10349.6 10512.3 10680.4 10995.3 09:19:41 INFO - PROCESS | 17877 | -------- Summary: end -------- 09:19:41 INFO - PROCESS | 17877 | 09:19:42 INFO - TEST-INFO | 17877: exit 0 09:19:42 INFO - TEST-OK | dromaeo_css | took 1122761ms 09:19:42 INFO - TEST-START | kraken 09:19:42 INFO - operating with platform_type : linux_ 09:19:42 INFO - Initialising browser for kraken test... 09:19:42 INFO - TEST-INFO | started process 18180 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRsiok/profile http://localhost:42500/getInfo.html) 09:19:46 INFO - PROCESS | 18180 | __metrics Screen width/height:1600/1200 09:19:46 INFO - PROCESS | 18180 | colorDepth:24 09:19:46 INFO - PROCESS | 18180 | Browser inner width/height: 1024/697 09:19:46 INFO - PROCESS | 18180 | __metrics 09:19:47 INFO - PROCESS | 18180 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:19:47 INFO - PROCESS | 18180 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:47 INFO - PROCESS | 18180 | console.error: PushService: 09:19:47 INFO - PROCESS | 18180 | stateChangeProcessEnqueue: Error transitioning state 09:19:47 INFO - PROCESS | 18180 | UnknownError 09:19:48 INFO - TEST-INFO | 18180: exit 0 09:19:48 INFO - Browser initialized. 09:19:48 INFO - Running cycle 1/1 for kraken test... 09:19:48 INFO - TEST-INFO | started process 18265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRsiok/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:00 INFO - PROCESS | 18265 | RSS: Main: 155860992 09:20:00 INFO - PROCESS | 18265 | 09:20:03 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42500/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:04 INFO - PROCESS | 18265 | RSS: Main: 209530880 09:20:04 INFO - PROCESS | 18265 | 09:20:06 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42500/tests/kraken/driver.html?testName=audio-dft) 09:20:07 INFO - PROCESS | 18265 | RSS: Main: 187719680 09:20:07 INFO - PROCESS | 18265 | 09:20:10 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42500/tests/kraken/driver.html?testName=audio-fft) 09:20:11 INFO - PROCESS | 18265 | RSS: Main: 270077952 09:20:11 INFO - PROCESS | 18265 | 09:20:13 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42500/tests/kraken/driver.html?testName=audio-oscillator) 09:20:13 INFO - PROCESS | 18265 | RSS: Main: 181276672 09:20:13 INFO - PROCESS | 18265 | 09:20:16 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42500/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:16 INFO - PROCESS | 18265 | RSS: Main: 184266752 09:20:16 INFO - PROCESS | 18265 | 09:20:19 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42500/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:20 INFO - PROCESS | 18265 | RSS: Main: 223682560 09:20:20 INFO - PROCESS | 18265 | 09:20:24 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42500/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:24 INFO - PROCESS | 18265 | RSS: Main: 217702400 09:20:24 INFO - PROCESS | 18265 | 09:20:27 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42500/tests/kraken/driver.html?testName=json-parse-financial) 09:20:28 INFO - PROCESS | 18265 | RSS: Main: 219590656 09:20:28 INFO - PROCESS | 18265 | 09:20:29 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42500/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:30 INFO - PROCESS | 18265 | RSS: Main: 183406592 09:20:30 INFO - PROCESS | 18265 | 09:20:32 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:33 INFO - PROCESS | 18265 | RSS: Main: 190111744 09:20:33 INFO - PROCESS | 18265 | 09:20:35 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:35 INFO - PROCESS | 18265 | RSS: Main: 198733824 09:20:35 INFO - PROCESS | 18265 | 09:20:38 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:39 INFO - PROCESS | 18265 | RSS: Main: 207519744 09:20:39 INFO - PROCESS | 18265 | 09:20:42 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:43 INFO - PROCESS | 18265 | RSS: Main: 204414976 09:20:43 INFO - PROCESS | 18265 | 09:20:44 INFO - PROCESS | 18265 | Cycle 1(1): loaded http://localhost:42500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42500/tests/kraken/driver.html?testName=ai-astar) 09:20:45 INFO - PROCESS | 18265 | RSS: Main: 256012288 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | __start_tp_report 09:20:45 INFO - PROCESS | 18265 | _x_x_mozilla_page_load 09:20:45 INFO - PROCESS | 18265 | _x_x_mozilla_page_load_details 09:20:45 INFO - PROCESS | 18265 | |i|pagename|runs| 09:20:45 INFO - PROCESS | 18265 | |0;ai-astar;110;116;117;117;114;115;115;114;115;118 09:20:45 INFO - PROCESS | 18265 | |1;audio-beat-detection;158;125;131;175;126;125;124;124;169;130 09:20:45 INFO - PROCESS | 18265 | |2;audio-dft;160;160;161;161;162;163;161;161;161;163 09:20:45 INFO - PROCESS | 18265 | |3;audio-fft;76;77;75;109;76;76;76;76;105;77 09:20:45 INFO - PROCESS | 18265 | |4;audio-oscillator;85;85;120;85;84;86;106;85;85;114 09:20:45 INFO - PROCESS | 18265 | |5;imaging-gaussian-blur;129;127;127;127;128;126;127;127;127;128 09:20:45 INFO - PROCESS | 18265 | |6;imaging-darkroom;147;147;147;147;146;146;147;147;147;147 09:20:45 INFO - PROCESS | 18265 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;95;95 09:20:45 INFO - PROCESS | 18265 | |8;json-parse-financial;65;63;64;63;64;66;72;64;64;64 09:20:45 INFO - PROCESS | 18265 | |9;json-stringify-tinderbox;66;66;65;65;66;65;67;65;65;66 09:20:45 INFO - PROCESS | 18265 | |10;stanford-crypto-aes;83;99;87;94;86;85;84;83;83;85 09:20:45 INFO - PROCESS | 18265 | |11;stanford-crypto-ccm;137;146;141;133;134;141;139;134;179;137 09:20:45 INFO - PROCESS | 18265 | |12;stanford-crypto-pbkdf2;178;179;176;175;176;179;174;176;187;181 09:20:45 INFO - PROCESS | 18265 | |13;stanford-crypto-sha256-iterative;68;67;68;68;69;68;68;67;66;65 09:20:45 INFO - PROCESS | 18265 | __end_tp_report 09:20:45 INFO - PROCESS | 18265 | __start_cc_report 09:20:45 INFO - PROCESS | 18265 | _x_x_mozilla_cycle_collect,1947 09:20:45 INFO - PROCESS | 18265 | __end_cc_report 09:20:45 INFO - PROCESS | 18265 | __startTimestamp1481908845120__endTimestamp 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | ------- Summary: start ------- 09:20:45 INFO - PROCESS | 18265 | Number of tests: 14 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:2.23 (1.9%) stddev-sans-first:1.41 09:20:45 INFO - PROCESS | 18265 | Values: 110.0 116.0 117.0 117.0 114.0 115.0 115.0 114.0 115.0 118.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:130.50 stddev:20.31 (15.6%) stddev-sans-first:20.30 09:20:45 INFO - PROCESS | 18265 | Values: 158.0 125.0 131.0 175.0 126.0 125.0 124.0 124.0 169.0 130.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 09:20:45 INFO - PROCESS | 18265 | Values: 160.0 160.0 161.0 161.0 162.0 163.0 161.0 161.0 161.0 163.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:13.06 (17.2%) stddev-sans-first:13.66 09:20:45 INFO - PROCESS | 18265 | Values: 76.0 77.0 75.0 109.0 76.0 76.0 76.0 76.0 105.0 77.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:85.00 stddev:14.09 (16.6%) stddev-sans-first:14.60 09:20:45 INFO - PROCESS | 18265 | Values: 85.0 85.0 120.0 85.0 84.0 86.0 106.0 85.0 85.0 114.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#5] imaging-gaussian-blur Cycles:10 Average:127.30 Median:127.00 stddev:0.82 (0.6%) stddev-sans-first:0.60 09:20:45 INFO - PROCESS | 18265 | Values: 129.0 127.0 127.0 127.0 128.0 126.0 127.0 127.0 127.0 128.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:20:45 INFO - PROCESS | 18265 | Values: 147.0 147.0 147.0 147.0 146.0 146.0 147.0 147.0 147.0 147.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:20:45 INFO - PROCESS | 18265 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:2.64 (4.1%) stddev-sans-first:2.80 09:20:45 INFO - PROCESS | 18265 | Values: 65.0 63.0 64.0 63.0 64.0 66.0 72.0 64.0 64.0 64.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:20:45 INFO - PROCESS | 18265 | Values: 66.0 66.0 65.0 65.0 66.0 65.0 67.0 65.0 65.0 66.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:85.50 stddev:5.36 (6.3%) stddev-sans-first:5.50 09:20:45 INFO - PROCESS | 18265 | Values: 83.0 99.0 87.0 94.0 86.0 85.0 84.0 83.0 83.0 85.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:140.00 stddev:13.56 (9.7%) stddev-sans-first:14.26 09:20:45 INFO - PROCESS | 18265 | Values: 137.0 146.0 141.0 133.0 134.0 141.0 139.0 134.0 179.0 137.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:178.50 stddev:3.78 (2.1%) stddev-sans-first:4.01 09:20:45 INFO - PROCESS | 18265 | Values: 178.0 179.0 176.0 175.0 176.0 179.0 174.0 176.0 187.0 181.0 09:20:45 INFO - PROCESS | 18265 | 09:20:45 INFO - PROCESS | 18265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 09:20:45 INFO - PROCESS | 18265 | Values: 68.0 67.0 68.0 68.0 69.0 68.0 68.0 67.0 66.0 65.0 09:20:45 INFO - PROCESS | 18265 | -------- Summary: end -------- 09:20:45 INFO - PROCESS | 18265 | 09:20:46 INFO - TEST-INFO | 18265: exit 0 09:20:46 INFO - TEST-OK | kraken | took 64141ms 09:20:46 INFO - SUITE-END | took 1186s 09:20:46 INFO - Completed test suite (00:19:47) 09:20:46 INFO - javascript benchmark 09:20:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2582.48350329934, 2594.0791019188177, 2608.791296696264, 2621.1743300860226, 2632.328756167178, 4678.111158879819, 4708.69393489423, 4715.811367545299, 4722.197226471504, 4724.24412094065, 4739.194336962716, 4762.952370476294, 4794.808207671689, 4826.420829500456, 4864.975675121628, 4333.371661109138, 4349.69552131351, 4385.42989411376, 4405.172242035426, 4408.228560002003, 4028.617281358266, 4465.486469056068, 4466.3300504924355, 4495.617872480827, 4549.931751023737, 4258.339957306623, 4422.845200418002, 4435.3125265583885, 4452.3321501774835, 4529.932051019237, 4285.078708077778, 4324.351347297899, 4378.343248512717, 4396.824127034914, 4427.535108813592, 4381.145676593065, 4394.62645675118, 4395.692301538894, 4428.601425871687, 4459.777011149429, 4758.78585463655, 4765.904681906342, 4783.1868582341085, 4786.114568804782, 4786.976065119687, 4935.1363511385725, 4958.0, 4969.478204788517, 5012.674176178537, 5119.872003199913, 5079.187330027215, 5084.745762711849, 5098.0, 5121.180611102206, 5137.02396544659, 4704.929426058646, 4705.717656940595, 4706.976465117721, 4736.384270044872, 4743.810247590058, 4976.477469865685, 5023.698578085254, 5187.455317191716, 5187.714675692798, 5231.9476805232225, 4874.098291815954, 4880.755962201875, 4972.875678108004, 5003.874903127451, 5010.448850626428, 5100.209467532539, 5113.9744301279, 5138.100832354323, 5144.845654630367, 5145.305383773218, 5012.473690262543, 5029.723365214948, 5045.495450454925, 5066.316047333563, 5124.5131712487255, 4903.0, 5050.72221027847, 5054.696718196847, 5060.165072763027, 5094.668846525038, 4200.85297014603, 4240.5123410808155, 4243.257429949746, 4243.405923170759, 4243.6605071594195, 3707.4253490708984, 3730.0301921500604, 3766.7928263945746, 3767.962320376761, 3781.4516895050065, 4350.782460876943, 4387.451568553931, 4399.5820397062225, 4399.890002749893, 4407.603315701603, 5080.7205603692155, 5116.616253780981, 5123.410807757081, 5126.641135120506, 5171.896562068738, 4273.1240095780295, 4352.586504282088, 4359.346098085312, 4362.389265502832, 4398.098389830077, 4505.098980203972, 4520.16376970255, 4546.090781843644, 4547.15877562652, 4566.0, 4789.042191561702, 4792.544708252711, 4801.0, 4801.855944321676, 4841.782119804618, 5138.743062846768, 5140.203268493389, 5141.614378921521, 5141.897162056813, 5199.0, 4642.071585682945, 4740.928886066643, 4785.210440277316, 4785.353977213032, 4836.903261934812, 5017.874553136201, 5017.949820501748, 5053.924191137063, 5096.362954630671, 5101.311322971352, 5132.050570644443, 5134.0, 5136.614753893398, 5138.743062846917, 5187.066328060985, 4760.714357138583, 4790.688605240648, 4991.675541089818, 5000.899982000266, 5010.148274793221, 4807.086653535817, 4831.661783675249, 4836.709797412166, 4841.612670986242, 4861.246506791453, 4750.12122757291, 4757.691634800458, 4763.2140696784645, 4764.237721964469, 4769.594584460359, 4881.609471242363, 4899.16714158601, 4909.95090049095, 4932.975335123301, 5009.148444764326, 4797.808087676453, 4831.130396528659, 4832.178529650038, 4844.878878028077, 4886.438059623189], "value": 4651.023987376074, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.28534909729984, 534.6257619666235, 539.9379071406789, 552.925355077065, 558.1460365640569, 35350.64649353506, 37834.43248351277, 37879.24241515168, 40697.38953915694, 40865.182696346055, 36886.44670329941, 37171.25657486849, 37220.81389593055, 37222.627773722255, 37373.252534949286, 7382.261773822628, 7574.621268936545, 7740.574268415235, 7747.496412733182, 7751.302382785549, 7387.37207337377, 9622.711318660451, 9885.703428897144, 9893.109620134186, 10216.48917554123, 9901.653442129527, 9975.650852220173, 9981.251406144533, 9985.75035624108, 9987.90012099877, 9875.358101723401, 9945.104940555313, 9959.203263738884, 9959.800803983951, 9965.0, 9760.072793084646, 9785.26610504208, 9818.410895346231, 9831.410115393099, 9832.262580306435, 35885.102872428135, 36007.63992360095, 36210.456843147374, 36229.0, 36246.275074498626, 3010.247438140465, 3130.514770210621, 3580.1765593913547, 3622.9456558151387, 3623.4564815277654, 26627.467450650878, 27292.590611140848, 27340.58989115165, 27373.178804635892, 27387.86306068477, 3362.596488421405, 3390.3558323918624, 3485.268093700326, 3597.94603080954, 3605.963940360615, 231264.0, 232252.0, 232289.0, 232358.9999999983, 232705.0, 49709.25436118461, 49950.750246248535, 49996.0, 50112.498875011515, 50119.49880501221, 51367.48632513627, 51428.0, 51543.48456515387, 51581.48418515842, 51606.74196628993, 13847.238401887791, 13861.306934653428, 13964.301784910715, 13970.580882573538, 13990.650233744238, 9380.624775008922, 9718.465484398284, 10322.741931451623, 10331.070203681706, 11137.777244455228, 17404.912975435294, 17704.468865934043, 18101.45695629133, 18112.909435453003, 18160.0, 6894.207166175954, 6939.618320992394, 7003.509754317251, 7059.5726053564385, 7243.0, 753.9962300188464, 806.641044735099, 847.1020718038899, 868.1015149320549, 920.9447433154032, 27044.0, 27296.45407091847, 27770.722292777216, 27966.16101516958, 27966.580501292498, 880.366136381804, 886.8137691084816, 893.2898345815092, 898.501331760879, 1042.9478526073665, 14114.22371769542, 14506.1296322221, 14750.55748327552, 14879.851201488063, 14897.404103835943, 112220.43889780663, 112663.0, 112776.43611781888, 112910.0, 113089.0, 47936.28095578569, 48563.51436485661, 48604.513954860704, 48645.270320945216, 48661.75669121632, 1028.377831412, 1077.0145317034983, 1080.6866008857344, 1121.6354684727496, 1150.5685367987005, 20392.490187745425, 26307.344901355067, 27904.143784323514, 28003.319800812016, 28290.292742680773, 40763.184736304516, 41519.792401037805, 41559.0, 41562.0, 41563.58436415597, 331982.0, 333517.0, 333957.0, 334491.0, 334568.0, 23118.64779606981, 23215.53568928646, 23347.766522335238, 23766.405839854142, 24656.137035203683, 35747.0, 36452.81773591115, 36541.45187822131, 36713.81643091767, 37072.0, 30943.0, 31160.53259201159, 31511.684883150876, 32048.5192722114, 32165.826308135827, 31064.689353107085, 31079.0, 31520.842395787873, 32429.51355729713, 32964.50553241751, 29949.85025074861, 30078.548821767254, 30184.24539386533, 30266.0, 32373.838130809196, 25638.743612563634, 27030.729692702822, 29923.55114673325, 29925.700742993162, 30086.24784380408, 21937.34187974363, 22901.541969160855, 23310.184143554896, 23380.415489612897, 23380.64929026032], "value": 16566.121977995896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.510389262278, 361.5209846952787, 372.61992767377274, 375.6994404476419, 396.14432825097776, 12223.0, 12249.938750306248, 13058.085933984625, 13902.791458128137, 16506.0, 16060.11669358185, 16370.83629163708, 16580.75128873068, 16612.0, 16620.916895415536, 4878.243872199815, 4897.142999975008, 5080.771365288552, 5088.18589025756, 5090.745462726867, 3617.2584620152807, 3811.7522361046445, 4981.103401387749, 5066.316047333599, 5070.949290507103, 5629.437056294359, 5856.2094117294255, 5871.588988770788, 5936.43603857635, 5942.346341902387, 5696.601237913348, 5824.534037277008, 5842.503387212092, 5926.644401335912, 5949.286085669704, 5165.535101840815, 5168.974155129238, 5170.767315470813, 5171.276021357013, 5176.870578235536, 18740.812591874044, 19708.40874773759, 19773.0, 19863.20547178111, 19865.0, 1881.7741870975433, 2030.6953956906434, 2191.660292654641, 2334.7781960713705, 2495.538325409805, 12095.153339266255, 12921.483140674361, 13025.283609401478, 13190.11665068704, 13424.865751342557, 1583.8178609459944, 1678.152532970842, 1708.4105983435695, 1729.8097209306966, 1748.6764948484572, 63234.36765632331, 64638.676806615666, 64697.0, 65510.3448965509, 66789.66605166992, 9164.404313719588, 9235.1688348049, 9534.713958581253, 9563.808723825623, 9734.561944712505, 13120.343982801012, 13121.934390327988, 13130.868691313155, 13149.934250328688, 13178.60464186076, 6993.6503174841055, 6997.93002069983, 7163.606001669854, 7169.7849064528145, 7363.263673632594, 6207.86502816075, 6582.868342633121, 6686.13080299558, 6696.2968888267005, 6728.932710672929, 5976.61152025117, 5995.52035837132, 6111.144439778434, 6123.357047510036, 6229.96885015572, 1713.254734190606, 1782.9197686104158, 1790.9552261193573, 1804.3865085870866, 1811.25738447236, 786.6263524825662, 844.9619767110372, 871.2072014466805, 888.4225253585221, 904.3579058868277, 16112.436064737678, 16699.165041747863, 16736.497905062868, 16847.83152168487, 17258.913705431645, 838.9454685445427, 850.9191626795445, 851.4848516647463, 865.312076898867, 872.6901949807853, 8963.731088067369, 9751.463669498246, 9809.0, 9903.900960990442, 10033.849492257623, 258668.0, 265253.0, 345531.0, 345925.0, 348728.0, 54777.45222547724, 65219.0, 76862.61568692121, 77874.2212577867, 78218.6089069551, 887.1394747095308, 917.8852643419706, 942.4251206763869, 948.0993056596261, 950.6007476859736, 15135.697286053997, 15273.159976201416, 15851.920740396225, 16015.0, 16040.759388608947, 35285.823570881985, 35733.28533429369, 36080.63919360773, 36374.27251454903, 37026.444603330434, 48176.27735584039, 48375.0, 48385.75807120942, 48482.75758621185, 48931.75534122306, 7348.154962179417, 7389.889151662622, 7502.924970750222, 7526.397888169043, 7611.238876112345, 11545.942270288595, 11954.940225298818, 12114.697132571757, 12297.385130743249, 12300.938495307466, 9685.128338449573, 10238.488075596042, 10739.301945373525, 10857.34855908648, 10955.83566246491, 10016.849747253942, 10023.198144148304, 10086.142677872464, 10154.136898363722, 10310.690679279634, 5805.738741756715, 10257.487125643538, 10405.791884162425, 10562.630307939186, 10570.10154136907, 7389.556626602421, 7410.073740782403, 7461.888071678821, 7521.172671006076, 7556.962215189109, 9405.20055795265, 10299.536520856429, 10303.278770486293, 10486.318389304672, 10775.73060673458], "value": 8136.2188402513575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1231.384307846077, 1320.3860205004673, 1359.6872719274565, 1452.709458108378, 1687.4937518744373, 6368.649724265164, 7040.507164498481, 7328.120625524931, 7328.596927169004, 7487.962560187205, 7292.817679558014, 7356.301151390609, 7360.116785985676, 7366.042414486109, 7373.5207211531215, 1830.9176087076046, 1893.9337123200692, 2101.947451313718, 2140.9892950535227, 2143.8284937204985, 2076.5743022680313, 2161.059938926564, 2167.111484291441, 2185.836062295334, 2195.4182141732454, 2078.812906838384, 2108.9367318980453, 2116.1852686715647, 2121.7029615853794, 2125.6598944168936, 2099.748030236374, 2110.082114280293, 2117.6400011997844, 2128.1168315149193, 2137.7862213778653, 2036.8166864982222, 2067.7415323084615, 2123.819475344598, 2132.744070711509, 2136.209602447089, 7092.326229008236, 7199.712011519533, 7223.638818059076, 7240.963795181043, 7250.129984401853, 4319.870403887889, 4433.512313645497, 4436.356728274414, 4446.844360447369, 4449.0, 2869.1249169003445, 2903.956440653392, 2961.526155815081, 3132.6554079051284, 3139.230888432349, 879.6041781198486, 892.5180402582598, 942.5146049784366, 972.5769290358717, 973.4694591447608, 9486.810263794754, 9519.333646644669, 9535.713928582154, 9572.377795443273, 9604.471754053524, 3834.6357096075826, 4027.657649099801, 4071.3892916062828, 4140.275451795984, 4176.60322269384, 8207.630656620348, 8216.301614362726, 8228.917710822816, 8232.300254478436, 8233.0, 4368.5194628590825, 4420.778961051935, 4430.13612345596, 4549.408576884984, 4554.157480865984, 3616.475611036385, 3782.6406491383273, 4025.7987100645437, 4046.3323551614244, 4051.0480037191237, 3383.983080084633, 3454.3764430717524, 3466.0295117367177, 3468.583769947572, 3579.856805727794, 1148.1446322489799, 1183.1836033137108, 1189.0547015122997, 1189.0784641902421, 1206.4872429217548, 805.7985503624094, 810.0846043970275, 814.4624547798543, 815.5473712089847, 816.7177531275959, 4730.810767569327, 4731.929021064687, 4731.935314554198, 4811.230203167476, 4962.826301079445, 408.16122867506965, 412.4988139410631, 413.39437723734875, 414.601982097184, 418.1553262409915, 3234.741220702338, 3268.509723541488, 3295.2256219788333, 3335.9992002399185, 3398.8810391636175, 8031.518108913484, 8318.085010648823, 8450.450720703135, 8639.524826134497, 8669.523176225368, 8477.194666506795, 8557.229849313468, 8588.957055214683, 8597.441166324168, 8602.408554417327, 6552.903071017324, 6765.154355705537, 6766.018927255521, 6766.357196066366, 6775.0, 4988.077205716954, 5002.574781143643, 5094.337736094432, 5107.514786095315, 5203.401608814884, 7865.292123708894, 7897.289243968071, 8065.153158918464, 8144.877826832484, 8232.053313868742, 8133.088724149939, 8155.347572194091, 8167.469114507538, 8220.506769593843, 8304.418690691593, 5505.091659876076, 5707.1724599933605, 6273.309935906956, 6352.428281454691, 6387.073874288109, 6648.800535983928, 6862.553933994274, 6911.75808846688, 7014.333638304353, 7070.575765454089, 6219.160413344322, 6367.586106903036, 6383.892885691521, 6503.967480162569, 6581.07864898905, 6079.908801368071, 6093.146959425595, 6133.785317513865, 6193.969030154821, 6214.25428948529, 5860.531157507304, 5985.281766188085, 5998.4601385875485, 6003.549733770072, 6026.487748541423, 4299.97850010748, 4388.605025547716, 4468.64250859924, 4487.589432031614, 4514.774261286857, 4204.789760512023, 4324.6107850292365, 4348.173846969065, 4368.082702632356, 4416.580424859633], "value": 3831.184048751585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.2534051788654, 357.6709427326859, 358.2423174984906, 362.2465272233754, 365.37520839364686, 4790.425148982118, 4814.7351895645725, 4825.589824864886, 4829.913269514357, 4837.379477874907, 4749.857504274869, 4750.90498190036, 4767.6185905127595, 4787.377640906686, 4806.591439727627, 3480.712136509495, 3557.430811070229, 3642.763220390673, 3668.5433636799294, 3744.7191460640424, 3773.7546160034735, 3828.5214348206473, 3843.5195600549932, 3856.826442810066, 3872.5671501544466, 3811.980940095296, 3812.961870381302, 3817.10298079951, 3817.8164768921583, 3836.251930873481, 3686.4838922550866, 3826.330392181357, 3829.1767270036935, 3830.367989281765, 3841.1741475582603, 3364.411228035084, 3388.3223355329033, 3428.400029994766, 3443.3974054540604, 3446.7079392204405, 4614.007988282536, 4634.050019745944, 4644.373009643722, 4647.60495357895, 4676.415448068991, 4340.544242854518, 4358.673099517518, 4370.191514569815, 4380.737155770664, 4396.560343965578, 4785.521447855222, 4932.457429682732, 4956.950430495685, 4982.800687972477, 4998.775055122492, 3742.3450896093077, 3747.8126093695205, 3750.2124553843723, 3758.0229140423685, 3771.283456143324, 4945.950540494585, 5040.47075057121, 5106.0, 5198.376194856642, 5236.842894713127, 3850.2492014057552, 3854.5567259765485, 3857.379900441821, 3862.9227415451533, 3877.360235561101, 3905.082305658168, 3915.980420097881, 3918.5885482024546, 3920.941185882214, 3939.803009849496, 4246.171996460722, 4361.520232774392, 4365.301551751768, 4366.345048242725, 4369.650427965755, 4932.199747092285, 4934.975325123423, 4966.503349665005, 4969.776360063805, 4972.8508144755715, 1319.8548159702425, 1332.4337156708555, 1335.8998075144402, 1349.2174538767492, 1353.8510763815973, 926.2354246295348, 975.1759763000254, 981.1954197558076, 983.4001259231862, 988.110700369673, 4226.2392769301205, 4279.957200428018, 4284.914301713949, 4293.892652683708, 4307.440032795716, 4933.679310844784, 4954.578860796801, 4959.90080198394, 4974.353334066621, 4981.9252711209365, 4143.97928010358, 4267.935980960288, 4277.144571085795, 4283.8580709645175, 4297.355396690521, 2598.3634009667753, 2600.4019075612714, 2619.148776647597, 2619.5546757051347, 2653.7080921098664, 4652.418447694038, 4672.906541869143, 4680.0, 4680.67235293533, 4692.694974826625, 4790.461055640516, 5025.3718285214345, 5072.82245121419, 5087.26234695967, 5101.158308879068, 4646.326282688991, 4647.814087436533, 4660.790264438041, 4678.228092364721, 4698.882527936829, 4959.231319145538, 4961.776720047678, 4964.900701985868, 4981.925271121008, 4986.301917731593, 4812.951870481251, 4870.415550133865, 4900.240462728283, 4910.386201724785, 4916.631252656137, 4613.5847773700525, 4705.55297246761, 4706.0, 4809.182438985311, 4824.782884770264, 3605.170810713498, 3608.80151591665, 3628.822706376259, 3635.3819850625987, 3635.6727894489622, 5117.053345131163, 5126.87260770922, 5128.205128205134, 5138.922916156335, 5159.17453207485, 4872.049431742444, 4911.435184953776, 4969.776360063734, 4981.178105612534, 5001.724905130107, 4974.776135074003, 4977.4773648767805, 4978.975105124451, 4989.925151122663, 5001.8499445016705, 4856.915858429852, 4864.61083113343, 4870.025994800983, 4875.536824001714, 4879.0, 4873.805047798049, 4954.752262386938, 4992.500749924979, 5002.824901128443, 5016.899662006666, 4868.591985610158, 4869.220924652039, 4876.341693871282, 4881.780319885543, 4899.583535399531], "value": 3799.497143647742, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.1870586942844, 642.9099926010358, 694.9895751563724, 724.4856152131986, 738.4904415952996, 12783.3608319584, 13085.084044116904, 13636.386362613668, 14725.0, 15653.45212917548, 15107.093574385544, 15330.69338613227, 15332.846671533282, 15367.077975321488, 15395.69208615827, 620.6710443464971, 639.9680015999205, 773.8877862709903, 797.5932274539997, 800.3597122302164, 762.5158024654329, 790.2374208888422, 793.218679600593, 798.6725442568548, 802.3380710913491, 836.5984327522792, 838.7567605394458, 840.4369072721261, 840.4831028917203, 840.7645859159445, 637.3212528656966, 821.3716506872247, 821.8068753842842, 822.0, 822.4489591973393, 490.252365143156, 591.1015256809668, 607.6384551192034, 610.6214147228746, 614.3457218062794, 13548.254845983467, 13549.729005419837, 13583.796243056362, 13592.592222233348, 13593.048486605941, 12320.876791232153, 12403.13178077535, 12484.313362765044, 12771.0, 12779.169353992054, 11541.76916461666, 11548.556430446208, 11653.0, 11681.357525336103, 11755.17713760037, 4033.757974521538, 4061.4923134608175, 4062.1469491406842, 4066.2883995301, 4088.366303223005, 62929.37070629281, 63301.36698633001, 64139.0, 64206.35793642051, 66116.0, 9281.489518076436, 9408.905910940939, 9748.31761776683, 9763.121319081316, 9837.11465968052, 19081.713774293396, 19519.902400487907, 19547.218111275673, 19566.902165489082, 19585.706214406797, 6445.45213656835, 6665.966670166618, 6763.0, 6897.172339319314, 7031.92968070323, 3616.258666973264, 3733.8984999425234, 3742.438634204891, 3772.7359084864347, 3812.8856134316015, 2936.309967157716, 3003.684613115591, 3016.668166501683, 3022.9546556801665, 3023.909282721522, 509.80334039746936, 517.1905967161381, 526.402533124906, 530.1702835063114, 533.6371267538111, 508.3010860067407, 521.6009752539311, 531.2138035707175, 638.4477427025589, 654.2639530528156, 8314.8337033259, 8777.473351598925, 8883.200511953826, 8954.955225223832, 9027.864582031274, 506.6883866422124, 587.9323877754027, 631.8546734251056, 639.8912184928573, 648.7307767276621, 4044.8179831907637, 4065.5121385433936, 4109.321961876257, 4121.4848143982, 4219.261629214875, 49859.75070124626, 59515.0, 62250.68874655688, 64784.67607661932, 65987.0, 54789.726051370286, 56275.0, 56436.0, 57299.71350143223, 58402.12396814051, 51110.23334649909, 51819.740901296005, 52035.47964520382, 52226.47773522292, 52349.0, 8179.427440079376, 8213.219744124299, 8333.874991875247, 8382.874256886274, 8574.957125214334, 37393.626063739015, 38550.80724596359, 38581.42127868028, 38904.0, 38991.6100838988, 45753.54246457626, 45779.31331030104, 49210.75394623004, 50813.0, 51812.74093629508, 10004.649837255662, 10109.39343639384, 10140.898591013995, 10362.792744145225, 10499.107575855833, 10853.728656783644, 11214.439278035903, 11274.436278185894, 11321.830172547583, 11716.0, 10743.623973160902, 10808.945955270174, 10930.070943969851, 11418.486168122288, 12104.394780261127, 11826.76346473083, 12269.69325766863, 12589.24464532131, 12985.220886746825, 13007.739845203232, 12230.694232644255, 12622.621321360373, 12702.301373424181, 12952.28762418076, 12954.0, 10211.438370889673, 10270.435126068138, 10472.633457828939, 10518.631847885288, 10603.840942386025, 9754.0, 10349.586016559555, 10512.26414151002, 10680.359178449318, 10995.285306455055], "value": 6385.046296105919, "unit": "score"}], "name": "dromaeo_css", "value": 6226.503407742438, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 117.0, 117.0, 114.0, 115.0, 115.0, 114.0, 115.0, 118.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 131.0, 175.0, 126.0, 125.0, 124.0, 124.0, 169.0, 130.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 162.0, 163.0, 161.0, 161.0, 161.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 109.0, 76.0, 76.0, 76.0, 76.0, 105.0, 77.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 120.0, 85.0, 84.0, 86.0, 106.0, 85.0, 85.0, 114.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 127.0, 127.0, 127.0, 128.0, 126.0, 127.0, 127.0, 127.0, 128.0], "value": 127.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 64.0, 66.0, 72.0, 64.0, 64.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 67.0, 65.0, 65.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 99.0, 87.0, 94.0, 86.0, 85.0, 84.0, 83.0, 83.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 146.0, 141.0, 133.0, 134.0, 141.0, 139.0, 134.0, 179.0, 137.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 179.0, 176.0, 175.0, 176.0, 179.0, 174.0, 176.0, 187.0, 181.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 66.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1565.1, "alertThreshold": 2.0}]} 09:20:46 INFO - Return code: 0 09:20:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:46 INFO - # TBPL SUCCESS # 09:20:46 INFO - Running post-action listener: _resource_record_post_action 09:20:46 INFO - [mozharness: 2016-12-16 17:20:46.942007Z] Finished run-tests step (success) 09:20:46 INFO - Running post-run listener: _resource_record_post_run 09:20:47 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 403939328; Read time: 28; Write time: 389240 09:20:47 INFO - TinderboxPrint: CPU usage
13.1% 09:20:47 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 09:20:47 INFO - TinderboxPrint: I/O write bytes / time
403,939,328 / 389,240 09:20:47 INFO - TinderboxPrint: CPU idle
8,346.4 (86.8%) 09:20:47 INFO - TinderboxPrint: CPU user
1,216.3 (12.7%) 09:20:47 INFO - TinderboxPrint: Swap in / out
0 / 0 09:20:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110247936; Read time: 0; Write time: 103652 09:20:47 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292687872; Read time: 28; Write time: 285560 09:20:47 INFO - Running post-run listener: _upload_blobber_files 09:20:47 INFO - Blob upload gear active. 09:20:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:47 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 09:20:47 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:47 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:49 INFO - (blobuploader) - INFO - Done attempting. 09:20:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:54 INFO - (blobuploader) - INFO - Done attempting. 09:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:54 INFO - (blobuploader) - INFO - Done attempting. 09:20:54 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:54 INFO - Return code: 0 09:20:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd318050e7df5d301e20f145cbb4f1f44ff9bd5f53e1d2ab7cdd76cdb431030413a6ddcdcd5d7d2b27e726e62fd47a5385e1d8b065790ddd18fd8d4c7d0868c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2cedeba67fdbe0bd2b841b5304184f8738c923ee2273f25df7ef70a575b733f65c6a50cb249c6e38e76a6931ab1969dc9a2c4586ffbe2aa8c1c909d41576935", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d0234a66bea9817b67280d8c68a17cca66c158267e1ee4b19f4c4639b91c005ce38d70e445058a8b511ea88da6980f08b337d1be08f967b6cc25ae3727f8347"} 09:20:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:54 INFO - Contents: 09:20:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd318050e7df5d301e20f145cbb4f1f44ff9bd5f53e1d2ab7cdd76cdb431030413a6ddcdcd5d7d2b27e726e62fd47a5385e1d8b065790ddd18fd8d4c7d0868c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2cedeba67fdbe0bd2b841b5304184f8738c923ee2273f25df7ef70a575b733f65c6a50cb249c6e38e76a6931ab1969dc9a2c4586ffbe2aa8c1c909d41576935", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d0234a66bea9817b67280d8c68a17cca66c158267e1ee4b19f4c4639b91c005ce38d70e445058a8b511ea88da6980f08b337d1be08f967b6cc25ae3727f8347"} 09:20:54 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:54 INFO - Copying logs to upload dir... 09:20:54 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.643236 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-16 09:20:54.767405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 09:20:54.771655) ========= 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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd318050e7df5d301e20f145cbb4f1f44ff9bd5f53e1d2ab7cdd76cdb431030413a6ddcdcd5d7d2b27e726e62fd47a5385e1d8b065790ddd18fd8d4c7d0868c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2cedeba67fdbe0bd2b841b5304184f8738c923ee2273f25df7ef70a575b733f65c6a50cb249c6e38e76a6931ab1969dc9a2c4586ffbe2aa8c1c909d41576935", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d0234a66bea9817b67280d8c68a17cca66c158267e1ee4b19f4c4639b91c005ce38d70e445058a8b511ea88da6980f08b337d1be08f967b6cc25ae3727f8347"} build_url:https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011870 build_url: 'https://queue.taskcluster.net/v1/task/dhetwO47SMOxEwVOo2RIFQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd318050e7df5d301e20f145cbb4f1f44ff9bd5f53e1d2ab7cdd76cdb431030413a6ddcdcd5d7d2b27e726e62fd47a5385e1d8b065790ddd18fd8d4c7d0868c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2cedeba67fdbe0bd2b841b5304184f8738c923ee2273f25df7ef70a575b733f65c6a50cb249c6e38e76a6931ab1969dc9a2c4586ffbe2aa8c1c909d41576935", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d0234a66bea9817b67280d8c68a17cca66c158267e1ee4b19f4c4639b91c005ce38d70e445058a8b511ea88da6980f08b337d1be08f967b6cc25ae3727f8347"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 09:20:54.798021) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:20:54.798667) ========= 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=ba164b0a7b0ec7655860e956000001c1-1481907496.161398-643423757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 09:20:54.882307) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 09:20:54.882673) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 09:20:54.883056) ========= ========= Total master_lag: 0.18 =========