builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1482403298.17 results: success (0) buildid: 20161222002253 builduid: 7dcd9c61e9cf4c0683d7f4745a2bfdc4 revision: c5bb426cc2fca82869822e16cdb02bd1d11e6319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.170800) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.171260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.171571) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.199370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.200089) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-22 02:41:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to 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% 187M=0s 2016-12-22 02:41:38 (187 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.195408 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.424038) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.424574) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.029299 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:38.478182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 02:41:38.478670) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev c5bb426cc2fca82869822e16cdb02bd1d11e6319 --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 c5bb426cc2fca82869822e16cdb02bd1d11e6319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-22 02:41:38,573 truncating revision to first 12 chars 2016-12-22 02:41:38,573 Setting DEBUG logging. 2016-12-22 02:41:38,573 attempt 1/10 2016-12-22 02:41:38,573 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/c5bb426cc2fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 02:41:39,678 unpacking tar archive at: graphics-c5bb426cc2fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376590 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 02:41:39.882355) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:39.882778) ========= script_repo_revision: c5bb426cc2fca82869822e16cdb02bd1d11e6319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:39.883662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:39.884175) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 02:41:39.904922) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-22 02:41:39.905242) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_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', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:41:40 INFO - MultiFileLogger online at 20161222 02:41:40 in /builds/slave/test 02:41:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 02:41:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:41:40 INFO - {'append_to_log': False, 02:41:40 INFO - 'base_work_dir': '/builds/slave/test', 02:41:40 INFO - 'blob_upload_branch': 'Graphics', 02:41:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:41:40 INFO - 'branch': 'Graphics', 02:41:40 INFO - 'buildbot_json_path': 'buildprops.json', 02:41:40 INFO - 'config_files': ('talos/mac_config.py',), 02:41:40 INFO - 'default_actions': ('clobber', 02:41:40 INFO - 'read-buildbot-config', 02:41:40 INFO - 'download-and-extract', 02:41:40 INFO - 'populate-webroot', 02:41:40 INFO - 'create-virtualenv', 02:41:40 INFO - 'install', 02:41:40 INFO - 'run-tests'), 02:41:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:41:40 INFO - 'download_minidump_stackwalk': True, 02:41:40 INFO - 'download_symbols': 'ondemand', 02:41:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:41:40 INFO - 'tooltool.py': '/tools/tooltool.py', 02:41:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:41:40 INFO - '/tools/misc-python/virtualenv.py')}, 02:41:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:41:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:41:40 INFO - 'installer_path': 'installer.exe', 02:41:40 INFO - 'log_level': 'info', 02:41:40 INFO - 'log_name': 'talos', 02:41:40 INFO - 'log_to_console': True, 02:41:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:41:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:41:40 INFO - 'opt_config_files': (), 02:41:40 INFO - 'pip_index': False, 02:41:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:40 INFO - 'cmd': ('bash', 02:41:40 INFO - '-c', 02:41:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:40 INFO - 'enabled': True, 02:41:40 INFO - 'halt_on_failure': False, 02:41:40 INFO - 'name': 'check_screen_resolution'},), 02:41:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:40 INFO - 'cmd': ('bash', 02:41:40 INFO - '-c', 02:41:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:40 INFO - 'enabled': True, 02:41:40 INFO - 'halt_on_failure': False, 02:41:40 INFO - 'name': 'check_screen_resolution'},), 02:41:40 INFO - 'run_cmd_checks_enabled': True, 02:41:40 INFO - 'sps_profile': False, 02:41:40 INFO - 'sps_profile_interval': 0, 02:41:40 INFO - 'suite': 'dromaeojs-e10s', 02:41:40 INFO - 'system_bits': '32', 02:41:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:41:40 INFO - 'title': 't-yosemite-r7-0123', 02:41:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:41:40 INFO - 'use_talos_json': True, 02:41:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:41:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:41:40 INFO - 'work_dir': 'build'} 02:41:40 INFO - [mozharness: 2016-12-22 10:41:40.109884Z] Running clobber step. 02:41:40 INFO - Running pre-action listener: _resource_record_pre_action 02:41:40 INFO - Running main action method: clobber 02:41:40 INFO - rmtree: /builds/slave/test/build 02:41:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:41:43 INFO - Running post-action listener: _resource_record_post_action 02:41:43 INFO - [mozharness: 2016-12-22 10:41:43.101443Z] Finished clobber step (success) 02:41:43 INFO - [mozharness: 2016-12-22 10:41:43.101639Z] Running read-buildbot-config step. 02:41:43 INFO - Running pre-action listener: _resource_record_pre_action 02:41:43 INFO - Running main action method: read_buildbot_config 02:41:43 INFO - Using buildbot properties: 02:41:43 INFO - { 02:41:43 INFO - "project": "", 02:41:43 INFO - "product": "firefox", 02:41:43 INFO - "script_repo_revision": "default", 02:41:43 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 02:41:43 INFO - "repository": "", 02:41:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 02:41:43 INFO - "buildid": "20161222002253", 02:41:43 INFO - "pgo_build": "False", 02:41:43 INFO - "basedir": "/builds/slave/test", 02:41:43 INFO - "buildnumber": 16, 02:41:43 INFO - "slavename": "t-yosemite-r7-0123", 02:41:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:41:43 INFO - "platform": "yosemite_r7", 02:41:43 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 02:41:43 INFO - "branch": "graphics", 02:41:43 INFO - "repo_path": "projects/graphics", 02:41:43 INFO - "revision": "c5bb426cc2fca82869822e16cdb02bd1d11e6319", 02:41:43 INFO - "stage_platform": "macosx64", 02:41:43 INFO - "builduid": "7dcd9c61e9cf4c0683d7f4745a2bfdc4", 02:41:43 INFO - "slavebuilddir": "test" 02:41:43 INFO - } 02:41:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 02:41:43 INFO - Running post-action listener: _resource_record_post_action 02:41:43 INFO - [mozharness: 2016-12-22 10:41:43.105026Z] Finished read-buildbot-config step (success) 02:41:43 INFO - [mozharness: 2016-12-22 10:41:43.105160Z] Running download-and-extract step. 02:41:43 INFO - Running pre-action listener: _resource_record_pre_action 02:41:43 INFO - Running main action method: download_and_extract 02:41:43 INFO - mkdir: /builds/slave/test/build/tests 02:41:43 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')]} 02:41:43 INFO - trying https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 02:41:43 INFO - Downloading https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:41:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 02:41:44 INFO - Downloaded 1199 bytes. 02:41:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:41:44 INFO - Using the following test package requirements: 02:41:44 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:41:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 02:41:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 02:41:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'jsshell-mac.zip'], 02:41:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 02:41:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:41:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 02:41:44 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 02:41:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 02:41:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:41:44 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 02:41:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:41:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:41:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:41:44 INFO - Fetch https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:41:44 INFO - Expected file size: 16277744 02:41:44 INFO - Obtained file size: 16277744 02:41:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:41:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:41:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:41:51 INFO - Fetch https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:41:52 INFO - Expected file size: 16277744 02:41:52 INFO - Obtained file size: 16277744 02:41:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 02:41:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:41:59 INFO - Fetch https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 02:42:00 INFO - Expected file size: 14409734 02:42:00 INFO - Obtained file size: 14409734 02:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:00 INFO - trying https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:42:00 INFO - Downloading https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 02:42:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 02:42:02 INFO - Downloaded 69590054 bytes. 02:42:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:42:02 INFO - mkdir: /builds/slave/test/properties 02:42:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:42:02 INFO - Writing to file /builds/slave/test/properties/build_url 02:42:02 INFO - Contents: 02:42:02 INFO - build_url:https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:42:03 INFO - Running post-action listener: _resource_record_post_action 02:42:03 INFO - Running post-action listener: set_extra_try_arguments 02:42:03 INFO - [mozharness: 2016-12-22 10:42:03.209272Z] Finished download-and-extract step (success) 02:42:03 INFO - [mozharness: 2016-12-22 10:42:03.209399Z] Running populate-webroot step. 02:42:03 INFO - Running pre-action listener: _resource_record_pre_action 02:42:03 INFO - Running main action method: populate_webroot 02:42:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:42:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:42:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:42:03 INFO - '--tppagecycles', 02:42:03 INFO - '7'], 02:42:03 INFO - 'tests': ['tsvgm']}}, 02:42:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:42:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:42:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:42:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:42:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['damp', 'tps']}, 02:42:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'tests': ['damp', 'tps']}, 02:42:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['dromaeo_dom']}, 02:42:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:42:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['basic_compositor_video']}, 02:42:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:42:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'other-e10s': {'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['a11yr', 02:42:03 INFO - 'ts_paint', 02:42:03 INFO - 'tpaint', 02:42:03 INFO - 'sessionrestore', 02:42:03 INFO - 'sessionrestore_no_auto_restore', 02:42:03 INFO - 'tabpaint']}, 02:42:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['tsvgx', 02:42:03 INFO - 'tsvgr_opacity', 02:42:03 INFO - 'tart', 02:42:03 INFO - 'tscrollx', 02:42:03 INFO - 'cart', 02:42:03 INFO - 'tsvg_static']}, 02:42:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:42:03 INFO - 'tsvgr_opacity', 02:42:03 INFO - 'tart', 02:42:03 INFO - 'tscrollx', 02:42:03 INFO - 'cart', 02:42:03 INFO - 'tsvg_static']}, 02:42:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'talos_options': ['--disable-e10s'], 02:42:03 INFO - 'tests': ['tp5o']}, 02:42:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:42:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'talos_options': ['--disable-e10s', 02:42:03 INFO - '--xperf_path', 02:42:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:03 INFO - 'tests': ['tp5n']}, 02:42:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:42:03 INFO - 'talos_options': ['--xperf_path', 02:42:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:03 INFO - 'tests': ['tp5n']}}, 02:42:03 INFO - 'talos.zip': {'path': '', 02:42:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:42:03 INFO - Running post-action listener: _resource_record_post_action 02:42:03 INFO - [mozharness: 2016-12-22 10:42:03.227751Z] Finished populate-webroot step (success) 02:42:03 INFO - [mozharness: 2016-12-22 10:42:03.227870Z] Running create-virtualenv step. 02:42:03 INFO - Running pre-action listener: _resource_record_pre_action 02:42:03 INFO - Running main action method: create_virtualenv 02:42:03 INFO - Creating virtualenv /builds/slave/test/build/venv 02:42:03 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 02:42:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:42:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:42:03 INFO - Using real prefix '/tools/python27' 02:42:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:42:04 INFO - Installing distribute.............................................................................................................................................................................................done. 02:42:06 INFO - Installing pip.................done. 02:42:06 INFO - Return code: 0 02:42:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:42:07 INFO - Reading from file tmpfile_stdout 02:42:07 INFO - Output received: 02:42:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:42:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:42:07 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')]} 02:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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')]} 02:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:07 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 02:42:07 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 02:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:07 INFO - 'HOME': '/Users/cltbld', 02:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:07 INFO - 'LOGNAME': 'cltbld', 02:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:07 INFO - 'MOZ_NO_REMOTE': '1', 02:42:07 INFO - 'NO_EM_RESTART': '1', 02:42:07 INFO - 'PAGER': '/bin/cat', 02:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:07 INFO - 'PWD': '/builds/slave/test', 02:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:07 INFO - 'SHELL': '/bin/bash', 02:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:07 INFO - 'USER': 'cltbld', 02:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:07 INFO - 'XPC_FLAGS': '0x0', 02:42:07 INFO - 'XPC_SERVICE_NAME': '0', 02:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:07 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 02:42:07 INFO - Cleaning up... 02:42:07 INFO - Return code: 0 02:42:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:42:07 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')]} 02:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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')]} 02:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:07 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 02:42:07 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 02:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:07 INFO - 'HOME': '/Users/cltbld', 02:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:07 INFO - 'LOGNAME': 'cltbld', 02:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:07 INFO - 'MOZ_NO_REMOTE': '1', 02:42:07 INFO - 'NO_EM_RESTART': '1', 02:42:07 INFO - 'PAGER': '/bin/cat', 02:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:07 INFO - 'PWD': '/builds/slave/test', 02:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:07 INFO - 'SHELL': '/bin/bash', 02:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:07 INFO - 'USER': 'cltbld', 02:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:07 INFO - 'XPC_FLAGS': '0x0', 02:42:07 INFO - 'XPC_SERVICE_NAME': '0', 02:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:07 INFO - Downloading/unpacking psutil>=3.1.1 02:42:07 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 02:42:07 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 02:42:07 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 02:42:07 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 02:42:07 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 02:42:07 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 02:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:42:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:11 INFO - Installing collected packages: psutil 02:42:11 INFO - Running setup.py install for psutil 02:42:11 INFO - building 'psutil._psutil_osx' extension 02:42:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:42:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:42:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:42:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:42:12 INFO - building 'psutil._psutil_posix' extension 02:42:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:42:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:42:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:42:12 INFO - ^ 02:42:12 INFO - 1 warning generated. 02:42:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:42:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:12 INFO - Successfully installed psutil 02:42:12 INFO - Cleaning up... 02:42:12 INFO - Return code: 0 02:42:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:12 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:12 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 02:42:12 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 02:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:12 INFO - 'HOME': '/Users/cltbld', 02:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:12 INFO - 'LOGNAME': 'cltbld', 02:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:12 INFO - 'MOZ_NO_REMOTE': '1', 02:42:12 INFO - 'NO_EM_RESTART': '1', 02:42:12 INFO - 'PAGER': '/bin/cat', 02:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:12 INFO - 'PWD': '/builds/slave/test', 02:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:12 INFO - 'SHELL': '/bin/bash', 02:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:12 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:12 INFO - 'USER': 'cltbld', 02:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:12 INFO - 'XPC_FLAGS': '0x0', 02:42:12 INFO - 'XPC_SERVICE_NAME': '0', 02:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:42:12 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 02:42:12 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 02:42:12 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 02:42:12 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 02:42:12 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 02:42:12 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 02:42:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:42:15 INFO - Installing collected packages: mozsystemmonitor 02:42:15 INFO - Running setup.py install for mozsystemmonitor 02:42:15 INFO - Successfully installed mozsystemmonitor 02:42:15 INFO - Cleaning up... 02:42:15 INFO - Return code: 0 02:42:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:15 INFO - 'HOME': '/Users/cltbld', 02:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:15 INFO - 'LOGNAME': 'cltbld', 02:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:15 INFO - 'MOZ_NO_REMOTE': '1', 02:42:15 INFO - 'NO_EM_RESTART': '1', 02:42:15 INFO - 'PAGER': '/bin/cat', 02:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:15 INFO - 'PWD': '/builds/slave/test', 02:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:15 INFO - 'SHELL': '/bin/bash', 02:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:15 INFO - 'USER': 'cltbld', 02:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:15 INFO - 'XPC_FLAGS': '0x0', 02:42:15 INFO - 'XPC_SERVICE_NAME': '0', 02:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:16 INFO - Downloading/unpacking jsonschema==2.5.1 02:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:42:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:42:18 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 02:42:18 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 02:42:18 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 02:42:18 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 02:42:18 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 02:42:18 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 02:42:19 INFO - Downloading functools32-3.2.3-2.tar.gz 02:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:42:19 INFO - warning: no files found matching '*.txt' 02:42:19 INFO - no previously-included directories found matching 'build' 02:42:19 INFO - no previously-included directories found matching 'dist' 02:42:19 INFO - no previously-included directories found matching '.git*' 02:42:19 INFO - Installing collected packages: jsonschema, functools32 02:42:19 INFO - Running setup.py install for functools32 02:42:19 INFO - warning: no files found matching '*.txt' 02:42:19 INFO - no previously-included directories found matching 'build' 02:42:19 INFO - no previously-included directories found matching 'dist' 02:42:19 INFO - no previously-included directories found matching '.git*' 02:42:19 INFO - Successfully installed jsonschema functools32 02:42:19 INFO - Cleaning up... 02:42:19 INFO - Return code: 0 02:42:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:42:19 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')]} 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:19 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')]} 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:19 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:19 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 02:42:19 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 02:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:19 INFO - 'HOME': '/Users/cltbld', 02:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:19 INFO - 'LOGNAME': 'cltbld', 02:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:19 INFO - 'MOZ_NO_REMOTE': '1', 02:42:19 INFO - 'NO_EM_RESTART': '1', 02:42:19 INFO - 'PAGER': '/bin/cat', 02:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:19 INFO - 'PWD': '/builds/slave/test', 02:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:19 INFO - 'SHELL': '/bin/bash', 02:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:19 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:19 INFO - 'USER': 'cltbld', 02:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:19 INFO - 'XPC_FLAGS': '0x0', 02:42:19 INFO - 'XPC_SERVICE_NAME': '0', 02:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:42:19 INFO - Cleaning up... 02:42:19 INFO - Return code: 0 02:42:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:42:19 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')]} 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:19 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')]} 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:19 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:19 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 02:42:19 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 02:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:19 INFO - 'HOME': '/Users/cltbld', 02:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:19 INFO - 'LOGNAME': 'cltbld', 02:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:19 INFO - 'MOZ_NO_REMOTE': '1', 02:42:19 INFO - 'NO_EM_RESTART': '1', 02:42:19 INFO - 'PAGER': '/bin/cat', 02:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:19 INFO - 'PWD': '/builds/slave/test', 02:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:19 INFO - 'SHELL': '/bin/bash', 02:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:19 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:19 INFO - 'USER': 'cltbld', 02:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:19 INFO - 'XPC_FLAGS': '0x0', 02:42:19 INFO - 'XPC_SERVICE_NAME': '0', 02:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:19 INFO - Downloading/unpacking blobuploader==1.2.4 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:22 INFO - Downloading blobuploader-1.2.4.tar.gz 02:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:42:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:42:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:23 INFO - Downloading docopt-0.6.1.tar.gz 02:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:42:23 INFO - Installing collected packages: blobuploader, requests, docopt 02:42:23 INFO - Running setup.py install for blobuploader 02:42:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:42:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:42:24 INFO - Running setup.py install for requests 02:42:24 INFO - Running setup.py install for docopt 02:42:24 INFO - Successfully installed blobuploader requests docopt 02:42:24 INFO - Cleaning up... 02:42:24 INFO - Return code: 0 02:42:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:24 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:24 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 02:42:24 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 02:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:24 INFO - 'HOME': '/Users/cltbld', 02:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:24 INFO - 'LOGNAME': 'cltbld', 02:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:24 INFO - 'MOZ_NO_REMOTE': '1', 02:42:24 INFO - 'NO_EM_RESTART': '1', 02:42:24 INFO - 'PAGER': '/bin/cat', 02:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:24 INFO - 'PWD': '/builds/slave/test', 02:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:24 INFO - 'SHELL': '/bin/bash', 02:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:24 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:24 INFO - 'USER': 'cltbld', 02:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:24 INFO - 'XPC_FLAGS': '0x0', 02:42:24 INFO - 'XPC_SERVICE_NAME': '0', 02:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-SMxDCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1KUKTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VU8eLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1vLOk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-G9vpCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3TTCNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UO9mGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qte7_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Y3Hqpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-W_Gld7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-iyNMAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_rnXnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-R68jGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-HVqTT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RiDnos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7PDS6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RN6EOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:42:26 INFO - Running setup.py install for manifestparser 02:42:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:42:26 INFO - Running setup.py install for mozcrash 02:42:27 INFO - Running setup.py install for mozdebug 02:42:27 INFO - Running setup.py install for mozdevice 02:42:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Running setup.py install for mozfile 02:42:27 INFO - Running setup.py install for mozhttpd 02:42:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Running setup.py install for mozinfo 02:42:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Running setup.py install for mozInstall 02:42:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:42:27 INFO - Running setup.py install for mozleak 02:42:28 INFO - Running setup.py install for mozlog 02:42:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Running setup.py install for moznetwork 02:42:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Running setup.py install for mozprocess 02:42:28 INFO - Running setup.py install for mozprofile 02:42:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Running setup.py install for mozrunner 02:42:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:42:28 INFO - Running setup.py install for mozscreenshot 02:42:29 INFO - Running setup.py install for moztest 02:42:29 INFO - Running setup.py install for mozversion 02:42:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:42:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:42:29 INFO - Cleaning up... 02:42:29 INFO - Return code: 0 02:42:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:29 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 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:29 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 02:42:29 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 02:42:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:29 INFO - 'HOME': '/Users/cltbld', 02:42:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:29 INFO - 'LOGNAME': 'cltbld', 02:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:29 INFO - 'MOZ_NO_REMOTE': '1', 02:42:29 INFO - 'NO_EM_RESTART': '1', 02:42:29 INFO - 'PAGER': '/bin/cat', 02:42:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:29 INFO - 'PWD': '/builds/slave/test', 02:42:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:29 INFO - 'SHELL': '/bin/bash', 02:42:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:29 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:29 INFO - 'USER': 'cltbld', 02:42:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:29 INFO - 'XPC_FLAGS': '0x0', 02:42:29 INFO - 'XPC_SERVICE_NAME': '0', 02:42:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:29 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1h5Cif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:29 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)) 02:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:29 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-5Q_oQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:29 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)) 02:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:29 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RE0GuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:29 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)) 02:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:29 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YzYClb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-nuAIdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-NgQxE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-uqzhAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QmyWgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-4TKdUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-37DRQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fl3UTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KKOGuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:30 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)) 02:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:30 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2Vy4Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:31 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)) 02:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2T1UGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:31 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)) 02:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-nFW7ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:31 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)) 02:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RrRv0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:31 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)) 02:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-s2cJF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:31 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)) 02:42:31 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)) 02:42:31 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)) 02:42:31 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)) 02:42:31 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)) 02:42:31 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)) 02:42:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:35 INFO - Downloading blessings-1.6.tar.gz 02:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:42:35 INFO - Installing collected packages: blessings 02:42:35 INFO - Running setup.py install for blessings 02:42:35 INFO - Successfully installed blessings 02:42:35 INFO - Cleaning up... 02:42:35 INFO - Return code: 0 02:42:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:42:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:35 INFO - Reading from file tmpfile_stdout 02:42:35 INFO - Current package versions: 02:42:35 INFO - blessings == 1.6 02:42:35 INFO - blobuploader == 1.2.4 02:42:35 INFO - docopt == 0.6.1 02:42:35 INFO - functools32 == 3.2.3-2 02:42:35 INFO - jsonschema == 2.5.1 02:42:35 INFO - manifestparser == 1.1 02:42:35 INFO - mozInstall == 1.12 02:42:35 INFO - mozcrash == 1.0 02:42:35 INFO - mozdebug == 0.1 02:42:35 INFO - mozdevice == 0.49 02:42:35 INFO - mozfile == 1.2 02:42:35 INFO - mozhttpd == 0.7 02:42:35 INFO - mozinfo == 0.9 02:42:35 INFO - mozleak == 0.1 02:42:35 INFO - mozlog == 3.4 02:42:35 INFO - moznetwork == 0.27 02:42:35 INFO - mozprocess == 0.23 02:42:35 INFO - mozprofile == 0.28 02:42:35 INFO - mozrunner == 6.13 02:42:35 INFO - mozscreenshot == 0.1 02:42:35 INFO - mozsystemmonitor == 0.3 02:42:35 INFO - moztest == 0.7 02:42:35 INFO - mozversion == 1.4 02:42:35 INFO - psutil == 3.1.1 02:42:35 INFO - requests == 1.2.3 02:42:35 INFO - wsgiref == 0.1.2 02:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42: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')]} 02:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:36 INFO - 'HOME': '/Users/cltbld', 02:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:36 INFO - 'LOGNAME': 'cltbld', 02:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:36 INFO - 'MOZ_NO_REMOTE': '1', 02:42:36 INFO - 'NO_EM_RESTART': '1', 02:42:36 INFO - 'PAGER': '/bin/cat', 02:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:36 INFO - 'PWD': '/builds/slave/test', 02:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:36 INFO - 'SHELL': '/bin/bash', 02:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:36 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:36 INFO - 'USER': 'cltbld', 02:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:36 INFO - 'XPC_FLAGS': '0x0', 02:42:36 INFO - 'XPC_SERVICE_NAME': '0', 02:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:42:36 INFO - Cleaning up... 02:42:36 INFO - Return code: 0 02:42:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e343b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e346768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea9cb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:37 INFO - 'HOME': '/Users/cltbld', 02:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:37 INFO - 'LOGNAME': 'cltbld', 02:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:37 INFO - 'MOZ_NO_REMOTE': '1', 02:42:37 INFO - 'NO_EM_RESTART': '1', 02:42:37 INFO - 'PAGER': '/bin/cat', 02:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:37 INFO - 'PWD': '/builds/slave/test', 02:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:37 INFO - 'SHELL': '/bin/bash', 02:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:37 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:37 INFO - 'USER': 'cltbld', 02:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:37 INFO - 'XPC_FLAGS': '0x0', 02:42:37 INFO - 'XPC_SERVICE_NAME': '0', 02:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:42:37 INFO - Cleaning up... 02:42:37 INFO - Return code: 0 02:42:37 INFO - Running post-action listener: _resource_record_post_action 02:42:37 INFO - Running post-action listener: _start_resource_monitoring 02:42:37 INFO - Starting resource monitoring. 02:42:37 INFO - [mozharness: 2016-12-22 10:42:37.446279Z] Finished create-virtualenv step (success) 02:42:37 INFO - [mozharness: 2016-12-22 10:42:37.447266Z] Running install step. 02:42:37 INFO - Running pre-action listener: _resource_record_pre_action 02:42:37 INFO - Running main action method: install 02:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:37 INFO - Reading from file tmpfile_stdout 02:42:37 INFO - Detecting whether we're running mozinstall >=1.0... 02:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:42:37 INFO - Reading from file tmpfile_stdout 02:42:37 INFO - Output received: 02:42:37 INFO - Usage: mozinstall [options] installer 02:42:37 INFO - Options: 02:42:37 INFO - -h, --help show this help message and exit 02:42:37 INFO - -d DEST, --destination=DEST 02:42:37 INFO - Directory to install application into. [default: 02:42:37 INFO - "/builds/slave/test"] 02:42:37 INFO - --app=APP Application being installed. [default: firefox] 02:42:37 INFO - mkdir: /builds/slave/test/build/application 02:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:42:59 INFO - Reading from file tmpfile_stdout 02:42:59 INFO - Output received: 02:42:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:42:59 INFO - Running post-action listener: _resource_record_post_action 02:42:59 INFO - [mozharness: 2016-12-22 10:42:59.218625Z] Finished install step (success) 02:42:59 INFO - [mozharness: 2016-12-22 10:42:59.218775Z] Running run-tests step. 02:42:59 INFO - Running pre-action listener: _resource_record_pre_action 02:42:59 INFO - Running main action method: run_tests 02:42:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:59 INFO - 2016-12-22 02:42:59.233 screenresolution[1627:7256] starting screenresolution argv=screenresolution get 02:42:59 INFO - 2016-12-22 02:42:59.235 screenresolution[1627:7256] Display 0: 1600x1200x32@60 02:42:59 INFO - 2016-12-22 02:42:59.241 screenresolution[1628:7259] starting screenresolution argv=screenresolution list 02:42:59 INFO - Available Modes on Display 0 02:42:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:59 INFO - Intel Iris: 02:42:59 INFO - Chipset Model: Intel Iris 02:42:59 INFO - Type: GPU 02:42:59 INFO - Bus: Built-In 02:42:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:59 INFO - Vendor: Intel (0x8086) 02:42:59 INFO - Device ID: 0x0a2e 02:42:59 INFO - Revision ID: 0x0009 02:42:59 INFO - Displays: 02:42:59 INFO - Display: 02:42:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:59 INFO - Display Serial Number: 1600x1200 60 02:42:59 INFO - Main Display: Yes 02:42:59 INFO - Mirror: Off 02:42:59 INFO - Online: Yes 02:42:59 INFO - Rotation: Supported 02:42:59 INFO - Return code: 0 02:42:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:42:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:42:59 INFO - Python 2.7.3 02:42:59 INFO - Return code: 0 02:42:59 INFO - grabbing minidump binary from tooltool 02:42: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')]} 02:42: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/macosx64/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 0x10e121d30>, '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 0x10e048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e116be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:42: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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:42: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/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:42: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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:42:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:42:59 INFO - Return code: 0 02:42:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:42:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:42:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:42:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:42:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:42: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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:42: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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:42:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y25ldezveh/Render', 02:42:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:59 INFO - 'HOME': '/Users/cltbld', 02:42:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:59 INFO - 'LOGNAME': 'cltbld', 02:42:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:42:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:59 INFO - 'MOZ_NO_REMOTE': '1', 02:42:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:42:59 INFO - 'NO_EM_RESTART': '1', 02:42:59 INFO - 'PAGER': '/bin/cat', 02:42:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:59 INFO - 'PWD': '/builds/slave/test', 02:42:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:42:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:59 INFO - 'SHELL': '/bin/bash', 02:42:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uy11zb207j/Listeners', 02:42:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:42:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:59 INFO - 'USER': 'cltbld', 02:42:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:59 INFO - 'XPC_FLAGS': '0x0', 02:42:59 INFO - 'XPC_SERVICE_NAME': '0', 02:42:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:42:59 INFO - mozversion application_buildid: 20161222002253 02:42:59 INFO - mozversion application_changeset: c5bb426cc2fca82869822e16cdb02bd1d11e6319 02:42:59 INFO - mozversion application_display_name: Nightly 02:42:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:42:59 INFO - mozversion application_name: Firefox 02:42:59 INFO - mozversion application_remotingname: firefox 02:42:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 02:42:59 INFO - mozversion application_vendor: Mozilla 02:42:59 INFO - mozversion application_version: 53.0a1 02:42:59 INFO - mozversion platform_buildid: 20161222002253 02:42:59 INFO - mozversion platform_changeset: c5bb426cc2fca82869822e16cdb02bd1d11e6319 02:42:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 02:42:59 INFO - mozversion platform_version: 53.0a1 02:42:59 INFO - using testdate: 1482403379 02:42:59 INFO - actual date: 1482403379 02:42:59 INFO - starting webserver on 'localhost:49246' 02:42:59 INFO - SUITE-START | Running 2 tests 02:42:59 INFO - TEST-START | dromaeo_css 02:42:59 INFO - operating with platform_type : mac_ 02:42:59 INFO - Initialising browser for dromaeo_css test... 02:42:59 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpSiKSPr/profile http://localhost:49246/getInfo.html) 02:43:02 INFO - PROCESS | 1638 | 2016-12-22 02:43:02.717 plugin-container[1640:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 02:43:02 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:43:02 INFO - PROCESS | 1638 | 2016-12-22 02:43:02.720 plugin-container[1640:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 02:43:02 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:43:02 INFO - PROCESS | 1638 | 2016-12-22 02:43:02.720 plugin-container[1640:7422] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:43:02 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 02:43:02 INFO - PROCESS | 1638 | colorDepth:24 02:43:02 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 02:43:02 INFO - PROCESS | 1638 | __metrics 02:43:02 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 02:43:03 INFO - TEST-INFO | 1638: exit 0 02:43:03 INFO - Browser initialized. 02:43:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:43:03 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpSiKSPr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:43:05 INFO - PROCESS | 1641 | 2016-12-22 02:43:05.903 plugin-container[1642:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:43:05 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:43:05 INFO - PROCESS | 1641 | 2016-12-22 02:43:05.904 plugin-container[1642:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:43:05 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:43:05 INFO - PROCESS | 1641 | 2016-12-22 02:43:05.904 plugin-container[1642:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:43:15 INFO - PROCESS | 1641 | RSS: Main: 234655744 02:43:15 INFO - PROCESS | 1641 | 02:44:05 INFO - PROCESS | 1641 | 1482403445255 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] 02:46:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 02:46:20 INFO - PROCESS | 1641 | RSS: Main: 248582144 02:46:20 INFO - PROCESS | 1641 | 02:49:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 02:49:23 INFO - PROCESS | 1641 | RSS: Main: 262389760 02:49:23 INFO - PROCESS | 1641 | 02:52:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 02:52:26 INFO - PROCESS | 1641 | RSS: Main: 266559488 02:52:26 INFO - PROCESS | 1641 | 02:55:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 02:55:29 INFO - PROCESS | 1641 | RSS: Main: 266330112 02:55:29 INFO - PROCESS | 1641 | 02:58:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 02:58:32 INFO - PROCESS | 1641 | RSS: Main: 272465920 02:58:32 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 03:01:35 INFO - PROCESS | 1641 | RSS: Main: 275124224 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | __start_tp_report 03:01:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 03:01:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 03:01:35 INFO - PROCESS | 1641 | |i|pagename|runs| 03:01:35 INFO - PROCESS | 1641 | |0;dojo.html;3408.130926613713;3415.334009868075;3433.4506478963353;3435.914102147446;3442.5868895732515;4354.911272181955;4475.418195634571;4894.755262236892;5783.334916484606;5829.533637309016;4399.49405818331;5650.660960342372;5721.570882183832;5760.337561180466;5818.3308919474275;4782.545658162469;5167.509086636783;5169.948300517003;5305.310309659739;5309.575233981282;5084.3644544431945;5118.283440318359;5136.871578210537;5152.74236288186;5152.92270615941;4526.434195725535;5308.150695888658;5323.1749078892835;5337.786488540473;5367.275417818583;5177.948220517785;5204.375474943031;5250.947490525123;5273.419923808378;5274.367075950872;5172.793088276464;5207.401148867891;5209.348831396075;5233.398159211663;5238.947610523884;4998.475160108209;5134.1528647773075;5615.101583746581;5747.339056008571;5769.221155144044;6299.181106456132;6317.74729010839;6319.778807741753;6320.4311611955145;6323.588966717149;6289.591176573508;6296.496280297611;6299.338569450233;6302.369763023707;6331.461825744817;4924.926126108111;5735.1684005818925;5780.132980053026;5913.674747888907;5946.16753654493;6172.537059720539;6201.627902325875;6257.624542527463;6262.2172228471445;6274.2784579773;6197.101420294122;6207.379262073757;6239;6239.469645080219;6239.750409983641;6297.338779428185;6305.054241863757;6305.716242769086;6333.556651034475;6338.397852204033;6105.338445949952;6112.321110680185;6136.846578835565;6231.189945307081;6271.749130034747;6283.8114856554375;6296.874062518724;6299.307076221612;6310.968445157745;6319.115323854664;4888.853334399973;4935.950640493549;4958.6776859504735;4969.701817890938;4986.551210391082;4168.24971505132;4380.299152135643;4411.977940110343;4421.38100665907;4510.120526497301;4800.303955926441;5869.002269614176;5913.172155898178;5958.255218097738;5993.101034844808;6260.092286618415;6266.342034086447;6277.780277690259;6294.370562943669;6299.464545513591;5706.115552089433;5759.481646651821;5767.826965191051;5923.733431995571;5937.910931336033;5348.1175606025345;5426.158945363475;5443.918341224885;5465.180222966507;5548.195511650869;6311.960345379053;6339.366063393716;6374.521910856797;6378.712957916905;6395.840103997437;6196.504279657706;6205.28639206479;6292.716827742671;6296.181496405438;6304.432601065742;4655.2784318430695;4698.671093023456;5646.435356464321;5750.741216645178;6221.4089661482085;4583.679142459996;6132.5707200495535;6134.222293196984;6195.380461953769;6203.503719702323;5133.153029750049;5370.597205209547;6216.875662486816;6280.842978925563;6318.399752023551;6099.512039036777;6146.078088286614;6154.415330543591;6159.414855588712;6163.445289923929;6035.849103772265;6038.275406951194;6045.304789949212;6140.969295153495;6152.200213972155;4234.449521562177;4521.819127234874;5027.321311622884;5970.373110823301;5983.760649573968;6182.010878259457;6190.690465476799;6212.192414986023;6227.034809604518;6231.345708700521;6092.299385570716;6098.634081955097;6100.786472473442;6126.050462178369;6133.509319254538 03:01:35 INFO - PROCESS | 1641 | |1;ext.html;478.7941185290325;488.6066716293382;499.612800079938;505.4162442379051;544.6704743630102;37099.62900370995;41945.370819437696;42073.948151296194;42280.57719422805;44009.55990440094;42937;43183.35224971628;43408.782956085175;43429.34855977163;43453.565464345345;7529.698812047512;8123.75628731139;9265.351425400224;9454.90545094547;9544.904550954505;7378.37283830875;11202.103831693486;11424.71438214043;11478.827817582744;11568.537258509692;11372.601958931438;11384;11428.885711142906;11433.542658293658;11477.196596238267;11415.48630311638;11426.257293275952;11466.598669046585;11518.539258429737;11544.711382215428;11252.831207531894;11267.04230140439;11359.886401135966;11360.261582997078;11397.715057123556;40159.79920100381;40501.594984050076;40581.39127913084;40593.91091336294;40595;3415.846286917095;3449.301219149564;3519.680119955017;3919.314120029012;4063.1061166543313;30217.697823021706;30554.930577429684;31332.903348382926;31366.686333136604;31373.37253254922;3169.7147256747003;3248.9278538082376;3255.3913073703143;3320.750943679234;3330.550375699274;439737;447387;448484.99999999674;451307;451879;60912.69543652253;61286.69356653188;61450.692746535984;61526.770224531414;61749;52855.73572132115;62395.816146434656;63481.41296467625;65511.34488655148;65575.01637475441;12283;12841.871581284255;13290.601281961362;16204.83795162057;16229;11196.664100076849;11399.316041037564;12239.32683702405;12465.376731163406;12514.687132821753;17622.118894055504;18085.638287234182;18115.81884181141;18169.36407225741;18254.178561964476;6307.747690092437;6464.385883341075;6468.8059358219325;6482.643454610042;6505.381988711065;828.9709860154986;841.5750046226616;984.1978787288338;1013.763208885159;1027.1833892055834;23969.28092157238;29704.40591188164;30017.549736753965;30686.92595759138;30730.846345768576;839.61377766227;893.5308962794585;1009.2228983682523;1100.7688385438987;1156.1848896527927;14307.85692143086;14744.631384215267;15209.087454752751;15434.073955562702;15996.201614314103;636501;636778;638601;640377;646909;52773.94452110936;53134.20298695523;53182.73408632932;53277.93444131096;53382;1354.106289848695;1365.1877133105786;1377.2012232904892;1378.5106287268077;1392.1647011793045;27891.581626275216;28215.71784282131;29111.708882911746;29556.852215738785;29793;47408.577742667774;47415.52584474111;47539.76230118966;47576.286355705386;47958.76020619875;564755;570072;570583;571529;571718;24756.504869902863;24925.87537062303;25065;25116.62325065089;25225.86483608205;41995.56342955813;42436.78781606072;42548.57451425446;42633.147337053706;43632.78183609062;35573.822130890214;35597;36141;36182.829762800626;36655.45016824696;35313.64686353104;35428.11429714174;35999;36347.81826090958;36364.90905272846;35056.47415288722;35268.823655881555;35385.82307088448;35818.462723058656;35954.10114747048;30974;32184;32205;32209.194770130933;32281.67718322787;19359.12883920199;22069;22389.104435822384;23802.64296035526;24139.637905431082 03:01:35 INFO - PROCESS | 1641 | |2;jquery.html;326.40431213036214;328.5548082348418;334.3682827800334;378.0624052350171;378.5576951813589;14332.928335358334;17006.574835629115;17042.233099510522;17179.71152163588;20513.282035128774;17918.462446126603;20119.29582464614;20169.394918152444;20408.59182816343;20523;5399;5638.492535671779;5939.821805345825;5973.701314934257;5976;5572.164175373686;5644.294463192101;5829.475347218749;5867.090600956859;5966.67183304918;5815;5818.854528636797;5819.563532735041;5836.795712150076;5851.678157701324;5652.265205523277;5697.914531282034;5735.0250457422335;5771.769129234825;5777.797777077803;5028.32117664118;5036;5057.645964782467;5063.569596584295;5070.949290507085;23967.640485392734;24116.276511704677;24374.878125609437;24416.511669766503;24540.509189816104;2162.524244666187;2345.542619189259;2455.1897873701637;2962.9333439961597;3036.878524859003;14092.506762263272;14741.631459213499;15105.244737763178;15185.240737963168;15565.45520906774;1761.5067781021335;1783.6343549572307;1834.238790901774;1849.6208277303122;1881.9059047047592;95810;105581.47209263904;105940.41089383588;106725.93274067238;107020;9882.555285012191;10459.529321180566;10579.682609521727;10773.78452430947;10858.185636077167;13060.934695326463;13164.868351316556;13179.60461186166;13198.07613467048;13207;7543.053292995121;8254.59671855786;8255.917440825635;8324.375671824517;8378.12029736881;6199.473044791243;8218.835623287501;8299.925018745314;8474.491530508189;8489.363297752694;5275.551578115903;6204.883121038166;6262.185915830913;6455.160829092282;6458.967705161444;1631.7062928672715;1839.2064995265023;1865.0767869904485;1865.7481240032694;1867;845.4842546046906;920.7053742802373;922.1792604581927;934.7990182110879;1027.2141811514198;20779.480512987295;21417.57164856694;21436.892815536135;21445.46386340354;21538;857.0829212742306;873.611242996873;945.0596656326999;975.9268054895912;1000.6940942070668;7791.766247012599;11456.083513318914;11591.420428978518;11602.6519204424;11615.070794336432;531651.3669726584;533672;552725.9454810881;562929.370706296;566460;75453.62273188708;95581.52209238909;96590.0340996595;107184.4640776791;131327;895.3374502867831;970.5341436110706;1039.9220058495491;1042.4058286776465;1055.5250137438184;18384.540386490444;18471.168797404418;18594.163262652943;18807.40137088363;18979.43061708151;29491.410171796873;37026.07434814151;43491.56508435002;51484;52043.47956520387;73344.89982650371;74167.25832741603;76832.61583692046;76835.23164768281;77220;5835.509463055501;7839.804004899923;7904.498145352365;8013.278804907353;8231.34149268069;8960.148785865333;11400.144989125683;12255.019598432282;12282.75434491323;12702.87297127017;10515.15878729715;10516.316439431412;10943.069839063768;11024.779504410028;11058.834117488404;10621.097206737513;10776.67669969902;10901.127909767358;11038.061764750086;11246.7750644985;8635.6977505787;10968.561257549927;10975.341479511255;11129.332240065622;11265.943670281596;7940.682372705258;7946.7615971520945;8021.919780802351;8135.959320203361;8246.175382461706;10643.574257029632;10828.945855270673;10921.726956826144;11179.273347232403;11285.37930413835 03:01:35 INFO - PROCESS | 1641 | |3;mootools.html;1496.7006598680266;1506.1565523306945;1744.720844664854;1846.4922146409745;1872.1855992643193;6031;6594.274629790719;7104.289571042899;7539.2460753924715;8493.999999999993;7923.12845586985;8203.630836612367;8223.876641850376;8342.95828520858;8345.207205315486;2245.8877056147167;2272.4773302140516;2273.7044184256024;2279.1111466527987;2291.072115793098;1913.5598812273186;2322.7328857181387;2323.6514522821626;2332.4518738096535;2337.602607556711;2304.7925686688195;2305.9192928247517;2326.7673232676766;2333.9649905251435;2348.6477028445784;1945.2608008956506;2268.172117177235;2270.7842754938256;2290.518991011881;2305.1816605105196;2335.801456876168;2362.9291121266388;2363.940901477477;2370.6799582056547;2372.0511795281855;8340.416170868042;8387.62255698495;8399.034111077242;8413.200745929127;8420.915790842075;5026.648134630541;5116.309298244763;5180.818671346485;5182.896342073137;5188.066148093341;3330.916727081818;3421.1789170598877;3619.8914032579064;3642.344378011957;3644.7266455015715;1040.9583616655325;1048.958041678332;1061.930974486656;1072.5280876414354;1090.334895713614;9623.663171788954;10568.207384446197;10922.235443518957;10968.012878840862;10993.945030274877;4473.597376236155;4634.165850146938;4704.5765881070865;4749.596284315803;4754.548317909793;9367.718968430958;9440.763980900532;9451.480168590793;9465.52672366379;9466.384684995453;5192.7403629818355;5193.1171700810955;5202.739863006835;5203.765830537635;5227.111391063529;4153.605407486284;4652.116097941381;4703.17694403478;4761.976190119027;4771.928421073687;3435.1927297085167;3820.0449887528116;3851.287511810268;3863.343231650626;3935.2916475034194;1102.6361300770727;1179.7345597240724;1190.8924700028883;1285.0875878126635;1294.1199984010775;801.0987638906231;841.5876220651836;882.8587426011808;940.9623615055322;957.674390707163;5184.377874655065;5526.226328314039;5811.912821307684;5821.94178058214;5963.970180149072;938.1416004355938;943.9528023598792;950.2920324358397;954.5227386306847;1000.4097582426258;3761.078535758702;3773.7735735855385;3899.532056153223;3990.521137463523;4008.7995600219874;9728.881178664402;10170.440625765654;10196.082352588302;10203.846942295722;10219.182465402899;10096.545655445523;10139.797204055878;10156.33983791066;10166.949165254126;10188.388696678076;7424.368928641018;7803;7804.882926756103;7830.765077047698;7890.329322007694;5489.862753431196;5815.82552523425;5855.824325270249;5973.1338955852025;6007.849803754766;9019.323550733852;9216.064626428524;9314.860277095713;9351.251899847859;9406.81186376255;9369.484678342757;9452.385594936582;9500.619975200914;9503.904960950302;9533.142017218484;5509.724513774376;6715.764948226789;7205.243449437734;7245.855082898207;7354.154272258758;6606.867862642816;7819.218078192135;7912.762617121495;8009.038915330198;8011.599420028859;5232;5719.799807006735;6518.152640156749;7195.13658361;7342;7024;7124.216336203117;7138.143422789299;7145.285471452813;7147.82130446743;6603.603783772989;6644.4020038196795;6645.95501429104;6661.034150048313;6669.333066693292;4786.712797232177;4851.296561998561;4856.708597484162;4869.853904382874;4968.155413579628;4835.637327200544;4905.484924083063;4935.506449355035;4947.802087916443;4961.801527938842 03:01:35 INFO - PROCESS | 1641 | |4;prototype.html;323.45659292388785;355.5200479352874;358.1243858765317;369.56022333423226;373.9943900841486;4989.575886049685;5270.420253772082;5454.754536045877;5481.972590137054;5596.600849787553;4575.9313610295785;5271.9472805271935;5431.34823821142;5504.64219825712;5549.861253468665;3629.1570571380057;4251.723637963531;4275.935860962088;4281.550437204095;4292.892677683051;3809.4285857121376;3970.3051965905997;4277.914441711164;4292.270314046619;4326.74039557626;3262.096733906813;4120.814563344657;4209.536950935395;4214.431051808013;4238.427812245338;4251.659867210647;4257.659387249013;4260.637845783112;4278.358246263055;4285.485741710983;3152.25050097196;3744.2698673758628;3765.021094515418;3782.6406491383273;3790.2419516096893;4871.4397844248015;5141.203113517411;5192.169252919514;5313.628046036779;5374.355077390738;4221.366794980747;4357.673174511892;4414.168120230101;4787.305840653121;4897.534734200245;5666.134960815581;5792.640268644176;5825.621334613236;5838.445347692005;5856.443637854397;3427.365937301607;3631.2569966416004;3654.397024490956;3724.1806802503675;4168.228877657643;5099.974500127476;5783.421657834225;5830.60065584261;6098.57309988301;6131.632102073845;3523.4672917281064;4200.327947528442;4211.431456753299;4230.344296634026;4260.4248426462655;3327.8336083195745;3708.8887333380044;3721.2557488502407;4028.1269209817574;4309.418228539107;4194.433751443577;4495.370648109268;4653.302004699322;4721.386219791474;4784.521547845187;4779.681854300661;5238.973805131026;5613.775448981995;5824.11706205431;5872.354041055481;1129.8192289233848;1191.7724743514195;1261.6404324767398;1290.4773566705499;1298.68831480444;746.4401698725956;861.3238607692931;875.5709702245981;883.6642076011075;889.5285498685578;4583;5150.690958542499;5178.145605975047;5203.479652034842;5224.9738751306;5075.771590278447;5463.016657001778;5703.144528320785;5727.742251598688;5732.280315905212;4708.246680531099;4927.384076990376;5115.18157094863;5224.399194092728;5225.634205605647;2724.3733941193636;2763.2677340504783;2978.27032377069;3028.4700177468853;3093.582366380555;5145.578190503274;5396.4603539646505;5763.654180749085;6006.624071456023;6071.726772295257;4921.680090794087;5020.899582008339;5811.738471768781;5860.780238228469;5927.496162826208;5171.965606878564;5785.82642520725;6052.182955301154;6055.666938318347;6074;5011.674241174312;5267.552258058108;5362.436944120967;5568.466347811655;5614.354349249888;5055.823046193365;5152.4847515248175;5457;5611.625151837826;5614.943850561442;5160.948390516047;5202.739863006759;5951.137085122719;6023.5783495156675;6048.60684055535;3263.6083669958807;3628.818559071983;3753.1367785410057;3758.3986562149926;3799.259144466913;5013.348264725562;5426.620136590401;5782.629122059935;5842.853928651818;5874;4698.201305777958;5051.636058264322;5705.942940570541;5878.794242201502;5893.613086699894;4953.628477864103;5203.453637368173;5311.7609707564025;5819.912701309399;5837.803250333756;4807.855764327076;5058.64589478733;5233.755071855474;5715.028055501387;5721.599488035964;5007.624428167829;5052.873678158076;5357.732113394392;5569.078667859582;5799.2750906136735;4624.843789052737;5039.748012599282;5470.276862788209;5638.01334766406;5647.955128301277 03:01:35 INFO - PROCESS | 1641 | |5;yui.html;641.7368878759706;659.6899457255091;666.8399584099816;705.9505834591581;728.1917072050023;14796;15138.016028958124;15557.066576005447;15723.92138039311;15858.444954426597;14313.141211527316;14465.493707720232;14505.854941450609;15089.773653395208;15635.609109772262;876.38215058384;898.3397203055749;898.9280857531414;899.4962820820328;900.8603666431713;693.535331328009;713.661011019764;865.2991077227434;891.6611687558718;913.2739472119681;928.4661319741176;931.352709866643;933.286036182321;933.9579718912631;934.8130373925209;757.4319260554529;922.1239822168933;923.4413180026122;924.8890133184027;925.7963248085445;685.6503183376464;686.6806934775303;686.842026333946;688.102026854954;689.3244620272111;13310.534131305358;15264.236788160548;15391.153486558234;15431.151286679227;15716.685666286725;13937.233452160128;14207.431702731878;14243.643908902257;14290.071145375621;14308.28458577067;11699.883001169965;11804.704882377924;11853.881461185363;11933.34366609836;12112.697182570419;3645.2891686121216;3656.3053019926397;3709.8516059357594;3714.9814250928844;3816.122291872885;112775;115742;116042.99999999916;116295;116562.41718791352;10617;10660.307080039773;10831.458427078614;10901.400422976812;10910.992157350927;21906.78093219079;22329.55340893173;22341.329760107223;22361.888190558944;22597.548049038927;7657.93245743254;7694.653740581687;7804.9609751950875;7897.644605992744;8006.359491240687;4250.234957707642;4317.503487099024;4347.739135651871;4421.46942366918;4485.618722408632;3494.633063528344;3545.822708864598;3598.4602309653237;3608.242269123461;3611.8735844245853;578.2251782611321;639.9792331231678;655.9180102487189;661.1240106858431;675.5237557521936;525.6819624127425;539.4335947255366;557.6486813307671;681.9931800681929;685.873113474009;9071.319651026068;9197.81604367909;9328.34701570897;9369.672061477952;9525.476098814632;585.5608293779666;638.7732355013997;648.5168549430705;670.9228438729511;680.9547344825768;3534.8511733686655;4439.045605194899;4674.672772905864;4729.4797572267025;4783.545563171493;99015;99855;108046.3793043105;108924;110980.4450977756;74195.62902185455;76108.85836712454;81693;81768;84048.57975710205;77464;78232.43535129266;80189.3962120766;81118;81704.59147704342;9621.807563848544;9884.654037108667;9887.85168222463;9936;9974.60101595928;47875;50193.74903125461;50338.49661503338;51958.48041519688;52643.73678131585;49250.01499970052;63096;66426.33573664394;66869;67620;10255.846162307422;10256.794864102825;12470;12993.48026078984;13767.586972390844;14590.927045364706;14879.181645009628;14894.77657835155;14949.701005980038;14952.850471495582;11387.747347791901;12883.48466061347;14011.929940350234;14847.554573362817;14871.553853384416;11631.302121872715;11997.700057498632;14216.857831421554;14280.714385712019;14429.567112986628;13272.668183295495;13522.86477135216;13701.45194192221;13909.165450072624;14261.073030253001;11285.379304138023;11287.43562821872;11332.376719280519;11440.084793216689;11547.307161570332;9862.161716254199;10884.238103332691;11624.941875290908;11656.18406711522;11697.23967942081 03:01:35 INFO - PROCESS | 1641 | __end_tp_report 03:01:35 INFO - PROCESS | 1641 | __start_cc_report 03:01:35 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,373 03:01:35 INFO - PROCESS | 1641 | __end_cc_report 03:01:35 INFO - PROCESS | 1641 | __startTimestamp1482404495772__endTimestamp 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | ------- Summary: start ------- 03:01:35 INFO - PROCESS | 1641 | Number of tests: 6 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5661.87 Median:5946.17 stddev:716.65 (12.1%) stddev-sans-first:696.70 03:01:35 INFO - PROCESS | 1641 | Values: 3408.1 3415.3 3433.5 3435.9 3442.6 4354.9 4475.4 4894.8 5783.3 5829.5 4399.5 5650.7 5721.6 5760.3 5818.3 4782.5 5167.5 5169.9 5305.3 5309.6 5084.4 5118.3 5136.9 5152.7 5152.9 4526.4 5308.2 5323.2 5337.8 5367.3 5177.9 5204.4 5250.9 5273.4 5274.4 5172.8 5207.4 5209.3 5233.4 5238.9 4998.5 5134.2 5615.1 5747.3 5769.2 6299.2 6317.7 6319.8 6320.4 6323.6 6289.6 6296.5 6299.3 6302.4 6331.5 4924.9 5735.2 5780.1 5913.7 5946.2 6172.5 6201.6 6257.6 6262.2 6274.3 6197.1 6207.4 6239.0 6239.5 6239.8 6297.3 6305.1 6305.7 6333.6 6338.4 6105.3 6112.3 6136.8 6231.2 6271.7 6283.8 6296.9 6299.3 6311.0 6319.1 4888.9 4936.0 4958.7 4969.7 4986.6 4168.2 4380.3 4412.0 4421.4 4510.1 4800.3 5869.0 5913.2 5958.3 5993.1 6260.1 6266.3 6277.8 6294.4 6299.5 5706.1 5759.5 5767.8 5923.7 5937.9 5348.1 5426.2 5443.9 5465.2 5548.2 6312.0 6339.4 6374.5 6378.7 6395.8 6196.5 6205.3 6292.7 6296.2 6304.4 4655.3 4698.7 5646.4 5750.7 6221.4 4583.7 6132.6 6134.2 6195.4 6203.5 5133.2 5370.6 6216.9 6280.8 6318.4 6099.5 6146.1 6154.4 6159.4 6163.4 6035.8 6038.3 6045.3 6141.0 6152.2 4234.4 4521.8 5027.3 5970.4 5983.8 6182.0 6190.7 6212.2 6227.0 6231.3 6092.3 6098.6 6100.8 6126.1 6133.5 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:68197.27 Median:35406.97 stddev:149211.33 (421.4%) stddev-sans-first:149543.28 03:01:35 INFO - PROCESS | 1641 | Values: 478.8 488.6 499.6 505.4 544.7 37099.6 41945.4 42073.9 42280.6 44009.6 42937.0 43183.4 43408.8 43429.3 43453.6 7529.7 8123.8 9265.4 9454.9 9544.9 7378.4 11202.1 11424.7 11478.8 11568.5 11372.6 11384.0 11428.9 11433.5 11477.2 11415.5 11426.3 11466.6 11518.5 11544.7 11252.8 11267.0 11359.9 11360.3 11397.7 40159.8 40501.6 40581.4 40593.9 40595.0 3415.8 3449.3 3519.7 3919.3 4063.1 30217.7 30554.9 31332.9 31366.7 31373.4 3169.7 3248.9 3255.4 3320.8 3330.6 439737.0 447387.0 448485.0 451307.0 451879.0 60912.7 61286.7 61450.7 61526.8 61749.0 52855.7 62395.8 63481.4 65511.3 65575.0 12283.0 12841.9 13290.6 16204.8 16229.0 11196.7 11399.3 12239.3 12465.4 12514.7 17622.1 18085.6 18115.8 18169.4 18254.2 6307.7 6464.4 6468.8 6482.6 6505.4 829.0 841.6 984.2 1013.8 1027.2 23969.3 29704.4 30017.5 30686.9 30730.8 839.6 893.5 1009.2 1100.8 1156.2 14307.9 14744.6 15209.1 15434.1 15996.2 636501.0 636778.0 638601.0 640377.0 646909.0 52773.9 53134.2 53182.7 53277.9 53382.0 1354.1 1365.2 1377.2 1378.5 1392.2 27891.6 28215.7 29111.7 29556.9 29793.0 47408.6 47415.5 47539.8 47576.3 47958.8 564755.0 570072.0 570583.0 571529.0 571718.0 24756.5 24925.9 25065.0 25116.6 25225.9 41995.6 42436.8 42548.6 42633.1 43632.8 35573.8 35597.0 36141.0 36182.8 36655.5 35313.6 35428.1 35999.0 36347.8 36364.9 35056.5 35268.8 35385.8 35818.5 35954.1 30974.0 32184.0 32205.0 32209.2 32281.7 19359.1 22069.0 22389.1 23802.6 24139.6 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:32063.93 Median:16227.17 stddev:91236.78 (562.2%) stddev-sans-first:91461.60 03:01:35 INFO - PROCESS | 1641 | Values: 326.4 328.6 334.4 378.1 378.6 14332.9 17006.6 17042.2 17179.7 20513.3 17918.5 20119.3 20169.4 20408.6 20523.0 5399.0 5638.5 5939.8 5973.7 5976.0 5572.2 5644.3 5829.5 5867.1 5966.7 5815.0 5818.9 5819.6 5836.8 5851.7 5652.3 5697.9 5735.0 5771.8 5777.8 5028.3 5036.0 5057.6 5063.6 5070.9 23967.6 24116.3 24374.9 24416.5 24540.5 2162.5 2345.5 2455.2 2962.9 3036.9 14092.5 14741.6 15105.2 15185.2 15565.5 1761.5 1783.6 1834.2 1849.6 1881.9 95810.0 105581.5 105940.4 106725.9 107020.0 9882.6 10459.5 10579.7 10773.8 10858.2 13060.9 13164.9 13179.6 13198.1 13207.0 7543.1 8254.6 8255.9 8324.4 8378.1 6199.5 8218.8 8299.9 8474.5 8489.4 5275.6 6204.9 6262.2 6455.2 6459.0 1631.7 1839.2 1865.1 1865.7 1867.0 845.5 920.7 922.2 934.8 1027.2 20779.5 21417.6 21436.9 21445.5 21538.0 857.1 873.6 945.1 975.9 1000.7 7791.8 11456.1 11591.4 11602.7 11615.1 531651.4 533672.0 552725.9 562929.4 566460.0 75453.6 95581.5 96590.0 107184.5 131327.0 895.3 970.5 1039.9 1042.4 1055.5 18384.5 18471.2 18594.2 18807.4 18979.4 29491.4 37026.1 43491.6 51484.0 52043.5 73344.9 74167.3 76832.6 76835.2 77220.0 5835.5 7839.8 7904.5 8013.3 8231.3 8960.1 11400.1 12255.0 12282.8 12702.9 10515.2 10516.3 10943.1 11024.8 11058.8 10621.1 10776.7 10901.1 11038.1 11246.8 8635.7 10968.6 10975.3 11129.3 11265.9 7940.7 7946.8 8021.9 8136.0 8246.2 10643.6 10828.9 10921.7 11179.3 11285.4 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5402.80 Median:5148.56 stddev:2941.86 (57.1%) stddev-sans-first:2935.46 03:01:35 INFO - PROCESS | 1641 | Values: 1496.7 1506.2 1744.7 1846.5 1872.2 6031.0 6594.3 7104.3 7539.2 8494.0 7923.1 8203.6 8223.9 8343.0 8345.2 2245.9 2272.5 2273.7 2279.1 2291.1 1913.6 2322.7 2323.7 2332.5 2337.6 2304.8 2305.9 2326.8 2334.0 2348.6 1945.3 2268.2 2270.8 2290.5 2305.2 2335.8 2362.9 2363.9 2370.7 2372.1 8340.4 8387.6 8399.0 8413.2 8420.9 5026.6 5116.3 5180.8 5182.9 5188.1 3330.9 3421.2 3619.9 3642.3 3644.7 1041.0 1049.0 1061.9 1072.5 1090.3 9623.7 10568.2 10922.2 10968.0 10993.9 4473.6 4634.2 4704.6 4749.6 4754.5 9367.7 9440.8 9451.5 9465.5 9466.4 5192.7 5193.1 5202.7 5203.8 5227.1 4153.6 4652.1 4703.2 4762.0 4771.9 3435.2 3820.0 3851.3 3863.3 3935.3 1102.6 1179.7 1190.9 1285.1 1294.1 801.1 841.6 882.9 941.0 957.7 5184.4 5526.2 5811.9 5821.9 5964.0 938.1 944.0 950.3 954.5 1000.4 3761.1 3773.8 3899.5 3990.5 4008.8 9728.9 10170.4 10196.1 10203.8 10219.2 10096.5 10139.8 10156.3 10166.9 10188.4 7424.4 7803.0 7804.9 7830.8 7890.3 5489.9 5815.8 5855.8 5973.1 6007.8 9019.3 9216.1 9314.9 9351.3 9406.8 9369.5 9452.4 9500.6 9503.9 9533.1 5509.7 6715.8 7205.2 7245.9 7354.2 6606.9 7819.2 7912.8 8009.0 8011.6 5232.0 5719.8 6518.2 7195.1 7342.0 7024.0 7124.2 7138.1 7145.3 7147.8 6603.6 6644.4 6646.0 6661.0 6669.3 4786.7 4851.3 4856.7 4869.9 4968.2 4835.6 4905.5 4935.5 4947.8 4961.8 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4563.33 Median:5067.21 stddev:1393.40 (27.5%) stddev-sans-first:1360.48 03:01:35 INFO - PROCESS | 1641 | Values: 323.5 355.5 358.1 369.6 374.0 4989.6 5270.4 5454.8 5482.0 5596.6 4575.9 5271.9 5431.3 5504.6 5549.9 3629.2 4251.7 4275.9 4281.6 4292.9 3809.4 3970.3 4277.9 4292.3 4326.7 3262.1 4120.8 4209.5 4214.4 4238.4 4251.7 4257.7 4260.6 4278.4 4285.5 3152.3 3744.3 3765.0 3782.6 3790.2 4871.4 5141.2 5192.2 5313.6 5374.4 4221.4 4357.7 4414.2 4787.3 4897.5 5666.1 5792.6 5825.6 5838.4 5856.4 3427.4 3631.3 3654.4 3724.2 4168.2 5100.0 5783.4 5830.6 6098.6 6131.6 3523.5 4200.3 4211.4 4230.3 4260.4 3327.8 3708.9 3721.3 4028.1 4309.4 4194.4 4495.4 4653.3 4721.4 4784.5 4779.7 5239.0 5613.8 5824.1 5872.4 1129.8 1191.8 1261.6 1290.5 1298.7 746.4 861.3 875.6 883.7 889.5 4583.0 5150.7 5178.1 5203.5 5225.0 5075.8 5463.0 5703.1 5727.7 5732.3 4708.2 4927.4 5115.2 5224.4 5225.6 2724.4 2763.3 2978.3 3028.5 3093.6 5145.6 5396.5 5763.7 6006.6 6071.7 4921.7 5020.9 5811.7 5860.8 5927.5 5172.0 5785.8 6052.2 6055.7 6074.0 5011.7 5267.6 5362.4 5568.5 5614.4 5055.8 5152.5 5457.0 5611.6 5614.9 5160.9 5202.7 5951.1 6023.6 6048.6 3263.6 3628.8 3753.1 3758.4 3799.3 5013.3 5426.6 5782.6 5842.9 5874.0 4698.2 5051.6 5705.9 5878.8 5893.6 4953.6 5203.5 5311.8 5819.9 5837.8 4807.9 5058.6 5233.8 5715.0 5721.6 5007.6 5052.9 5357.7 5569.1 5799.3 4624.8 5039.7 5470.3 5638.0 5648.0 03:01:35 INFO - PROCESS | 1641 | 03:01:35 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20489.50 Median:4384.60 stddev:29767.30 (678.9%) stddev-sans-first:29813.50 03:01:35 INFO - PROCESS | 1641 | Values: 641.7 659.7 666.8 706.0 728.2 14796.0 15138.0 15557.1 15723.9 15858.4 14313.1 14465.5 14505.9 15089.8 15635.6 876.4 898.3 898.9 899.5 900.9 693.5 713.7 865.3 891.7 913.3 928.5 931.4 933.3 934.0 934.8 757.4 922.1 923.4 924.9 925.8 685.7 686.7 686.8 688.1 689.3 13310.5 15264.2 15391.2 15431.2 15716.7 13937.2 14207.4 14243.6 14290.1 14308.3 11699.9 11804.7 11853.9 11933.3 12112.7 3645.3 3656.3 3709.9 3715.0 3816.1 112775.0 115742.0 116043.0 116295.0 116562.4 10617.0 10660.3 10831.5 10901.4 10911.0 21906.8 22329.6 22341.3 22361.9 22597.5 7657.9 7694.7 7805.0 7897.6 8006.4 4250.2 4317.5 4347.7 4421.5 4485.6 3494.6 3545.8 3598.5 3608.2 3611.9 578.2 640.0 655.9 661.1 675.5 525.7 539.4 557.6 682.0 685.9 9071.3 9197.8 9328.3 9369.7 9525.5 585.6 638.8 648.5 670.9 681.0 3534.9 4439.0 4674.7 4729.5 4783.5 99015.0 99855.0 108046.4 108924.0 110980.4 74195.6 76108.9 81693.0 81768.0 84048.6 77464.0 78232.4 80189.4 81118.0 81704.6 9621.8 9884.7 9887.9 9936.0 9974.6 47875.0 50193.7 50338.5 51958.5 52643.7 49250.0 63096.0 66426.3 66869.0 67620.0 10255.8 10256.8 12470.0 12993.5 13767.6 14590.9 14879.2 14894.8 14949.7 14952.9 11387.7 12883.5 14011.9 14847.6 14871.6 11631.3 11997.7 14216.9 14280.7 14429.6 13272.7 13522.9 13701.5 13909.2 14261.1 11285.4 11287.4 11332.4 11440.1 11547.3 9862.2 10884.2 11624.9 11656.2 11697.2 03:01:35 INFO - PROCESS | 1641 | -------- Summary: end -------- 03:01:35 INFO - PROCESS | 1641 | 03:01:36 INFO - TEST-INFO | 1641: exit 0 03:01:36 INFO - TEST-OK | dromaeo_css | took 1116580ms 03:01:36 INFO - TEST-START | kraken 03:01:36 INFO - operating with platform_type : mac_ 03:01:36 INFO - Initialising browser for kraken test... 03:01:36 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp2nmm6f/profile http://localhost:49246/getInfo.html) 03:01:39 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 03:01:39 INFO - PROCESS | 1654 | colorDepth:24 03:01:39 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 03:01:39 INFO - PROCESS | 1654 | __metrics 03:01:39 INFO - PROCESS | 1654 | 2016-12-22 03:01:39.191 plugin-container[1655:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 03:01:39 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:39 INFO - PROCESS | 1654 | 2016-12-22 03:01:39.193 plugin-container[1655:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 03:01:39 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:39 INFO - PROCESS | 1654 | 2016-12-22 03:01:39.193 plugin-container[1655:10038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:01:39 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 03:01:40 INFO - TEST-INFO | 1654: exit 0 03:01:40 INFO - Browser initialized. 03:01:40 INFO - Running cycle 1/1 for kraken test... 03:01:40 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp2nmm6f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:42 INFO - PROCESS | 1656 | 2016-12-22 03:01:42.479 plugin-container[1657:10153] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 03:01:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:42 INFO - PROCESS | 1656 | 2016-12-22 03:01:42.479 plugin-container[1657:10153] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 03:01:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:42 INFO - PROCESS | 1656 | 2016-12-22 03:01:42.479 plugin-container[1657:10153] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:01:52 INFO - PROCESS | 1656 | RSS: Main: 234917888 03:01:52 INFO - PROCESS | 1656 | 03:01:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:56 INFO - PROCESS | 1656 | RSS: Main: 247726080 03:01:56 INFO - PROCESS | 1656 | 03:01:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 03:01:59 INFO - PROCESS | 1656 | RSS: Main: 255287296 03:01:59 INFO - PROCESS | 1656 | 03:02:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 03:02:03 INFO - PROCESS | 1656 | RSS: Main: 257892352 03:02:03 INFO - PROCESS | 1656 | 03:02:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 03:02:06 INFO - PROCESS | 1656 | RSS: Main: 260071424 03:02:06 INFO - PROCESS | 1656 | 03:02:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:02:08 INFO - PROCESS | 1656 | RSS: Main: 260694016 03:02:08 INFO - PROCESS | 1656 | 03:02:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 03:02:11 INFO - PROCESS | 1656 | RSS: Main: 267141120 03:02:11 INFO - PROCESS | 1656 | 03:02:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 03:02:15 INFO - PROCESS | 1656 | RSS: Main: 266944512 03:02:15 INFO - PROCESS | 1656 | 03:02:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 03:02:19 INFO - PROCESS | 1656 | RSS: Main: 266235904 03:02:19 INFO - PROCESS | 1656 | 03:02:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:02:21 INFO - PROCESS | 1656 | RSS: Main: 265097216 03:02:21 INFO - PROCESS | 1656 | 03:02:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:02:24 INFO - PROCESS | 1656 | RSS: Main: 265191424 03:02:24 INFO - PROCESS | 1656 | 03:02:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:02:27 INFO - PROCESS | 1656 | RSS: Main: 265203712 03:02:27 INFO - PROCESS | 1656 | 03:02:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:02:30 INFO - PROCESS | 1656 | RSS: Main: 265449472 03:02:30 INFO - PROCESS | 1656 | 03:02:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:02:34 INFO - PROCESS | 1656 | RSS: Main: 265482240 03:02:34 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 03:02:36 INFO - PROCESS | 1656 | RSS: Main: 266080256 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | __start_tp_report 03:02:36 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 03:02:36 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 03:02:36 INFO - PROCESS | 1656 | |i|pagename|runs| 03:02:36 INFO - PROCESS | 1656 | |0;ai-astar;107;116;117;116;116;117;100;172;173;126 03:02:36 INFO - PROCESS | 1656 | |1;audio-beat-detection;145;134;115;133;142;144;145;144;131;124 03:02:36 INFO - PROCESS | 1656 | |2;audio-dft;214;203;199;197;197;197;198;200;194;192 03:02:36 INFO - PROCESS | 1656 | |3;audio-fft;81;58;60;85;59;60;57;58;71;58 03:02:36 INFO - PROCESS | 1656 | |4;audio-oscillator;91;70;84;70;69;71;81;72;71;82 03:02:36 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;91;134;86;90;102;89;86;88;87 03:02:36 INFO - PROCESS | 1656 | |6;imaging-darkroom;106;96;95;95;96;103;95;94;95;97 03:02:36 INFO - PROCESS | 1656 | |7;imaging-desaturate;92;95;121;85;144;149;136;82;147;150 03:02:36 INFO - PROCESS | 1656 | |8;json-parse-financial;60;61;60;61;61;61;62;62;60;62 03:02:36 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;70;59;91;116;112;88;71;75;74;73 03:02:36 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;89;89;87;82;88;110;107;86;90;81 03:02:36 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;139;181;154;159;144;138;140;142;217;140 03:02:36 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;178;175;174;173;181;171;178;173;170;174 03:02:36 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;71;67;68;67;68;69;70;66;70;74 03:02:36 INFO - PROCESS | 1656 | __end_tp_report 03:02:36 INFO - PROCESS | 1656 | __start_cc_report 03:02:36 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,929 03:02:36 INFO - PROCESS | 1656 | __end_cc_report 03:02:36 INFO - PROCESS | 1656 | __startTimestamp1482404556361__endTimestamp 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | ------- Summary: start ------- 03:02:36 INFO - PROCESS | 1656 | Number of tests: 14 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:126.00 Median:117.00 stddev:25.44 (21.7%) stddev-sans-first:26.04 03:02:36 INFO - PROCESS | 1656 | Values: 107.0 116.0 117.0 116.0 116.0 117.0 100.0 172.0 173.0 126.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:143.00 stddev:10.26 (7.2%) stddev-sans-first:10.32 03:02:36 INFO - PROCESS | 1656 | Values: 145.0 134.0 115.0 133.0 142.0 144.0 145.0 144.0 131.0 124.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:199.10 Median:198.50 stddev:6.05 (3.0%) stddev-sans-first:3.21 03:02:36 INFO - PROCESS | 1656 | Values: 214.0 203.0 199.0 197.0 197.0 197.0 198.0 200.0 194.0 192.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:64.70 Median:60.00 stddev:10.48 (17.5%) stddev-sans-first:9.31 03:02:36 INFO - PROCESS | 1656 | Values: 81.0 58.0 60.0 85.0 59.0 60.0 57.0 58.0 71.0 58.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.50 stddev:7.72 (10.1%) stddev-sans-first:6.02 03:02:36 INFO - PROCESS | 1656 | Values: 91.0 70.0 84.0 70.0 69.0 71.0 81.0 72.0 71.0 82.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:88.00 stddev:14.77 (16.8%) stddev-sans-first:15.50 03:02:36 INFO - PROCESS | 1656 | Values: 88.0 91.0 134.0 86.0 90.0 102.0 89.0 86.0 88.0 87.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:95.00 stddev:3.99 (4.2%) stddev-sans-first:2.68 03:02:36 INFO - PROCESS | 1656 | Values: 106.0 96.0 95.0 95.0 96.0 103.0 95.0 94.0 95.0 97.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:120.10 Median:116.00 stddev:28.64 (24.7%) stddev-sans-first:28.51 03:02:36 INFO - PROCESS | 1656 | Values: 92.0 95.0 121.0 85.0 144.0 149.0 136.0 82.0 147.0 150.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:61.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 03:02:36 INFO - PROCESS | 1656 | Values: 60.0 61.0 60.0 61.0 61.0 61.0 62.0 62.0 60.0 62.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:82.90 Median:73.50 stddev:18.72 (25.5%) stddev-sans-first:19.26 03:02:36 INFO - PROCESS | 1656 | Values: 70.0 59.0 91.0 116.0 112.0 88.0 71.0 75.0 74.0 73.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.50 stddev:9.76 (11.2%) stddev-sans-first:10.33 03:02:36 INFO - PROCESS | 1656 | Values: 89.0 89.0 87.0 82.0 88.0 110.0 107.0 86.0 90.0 81.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:149.00 stddev:25.43 (17.1%) stddev-sans-first:26.27 03:02:36 INFO - PROCESS | 1656 | Values: 139.0 181.0 154.0 159.0 144.0 138.0 140.0 142.0 217.0 140.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:174.50 stddev:3.40 (1.9%) stddev-sans-first:3.39 03:02:36 INFO - PROCESS | 1656 | Values: 178.0 175.0 174.0 173.0 181.0 171.0 178.0 173.0 170.0 174.0 03:02:36 INFO - PROCESS | 1656 | 03:02:36 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:2.36 (3.4%) stddev-sans-first:2.39 03:02:36 INFO - PROCESS | 1656 | Values: 71.0 67.0 68.0 67.0 68.0 69.0 70.0 66.0 70.0 74.0 03:02:36 INFO - PROCESS | 1656 | -------- Summary: end -------- 03:02:36 INFO - PROCESS | 1656 | 03:02:37 INFO - TEST-INFO | 1656: exit 0 03:02:37 INFO - TEST-OK | kraken | took 60833ms 03:02:37 INFO - SUITE-END | took 1177s 03:02:37 INFO - Completed test suite (00:19:37) 03:02:37 INFO - javascript benchmark 03:02:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7195.545123507598, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.130926613713, 3415.334009868075, 3433.4506478963353, 3435.914102147446, 3442.5868895732515, 4354.911272181955, 4475.418195634571, 4894.755262236892, 5783.334916484606, 5829.533637309016, 4399.49405818331, 5650.660960342372, 5721.570882183832, 5760.337561180466, 5818.3308919474275, 4782.545658162469, 5167.509086636783, 5169.948300517003, 5305.310309659739, 5309.575233981282, 5084.3644544431945, 5118.283440318359, 5136.871578210537, 5152.74236288186, 5152.92270615941, 4526.434195725535, 5308.150695888658, 5323.1749078892835, 5337.786488540473, 5367.275417818583, 5177.948220517785, 5204.375474943031, 5250.947490525123, 5273.419923808378, 5274.367075950872, 5172.793088276464, 5207.401148867891, 5209.348831396075, 5233.398159211663, 5238.947610523884, 4998.475160108209, 5134.1528647773075, 5615.101583746581, 5747.339056008571, 5769.221155144044, 6299.181106456132, 6317.74729010839, 6319.778807741753, 6320.4311611955145, 6323.588966717149, 6289.591176573508, 6296.496280297611, 6299.338569450233, 6302.369763023707, 6331.461825744817, 4924.926126108111, 5735.1684005818925, 5780.132980053026, 5913.674747888907, 5946.16753654493, 6172.537059720539, 6201.627902325875, 6257.624542527463, 6262.2172228471445, 6274.2784579773, 6197.101420294122, 6207.379262073757, 6239.0, 6239.469645080219, 6239.750409983641, 6297.338779428185, 6305.054241863757, 6305.716242769086, 6333.556651034475, 6338.397852204033, 6105.338445949952, 6112.321110680185, 6136.846578835565, 6231.189945307081, 6271.749130034747, 6283.8114856554375, 6296.874062518724, 6299.307076221612, 6310.968445157745, 6319.115323854664, 4888.853334399973, 4935.950640493549, 4958.6776859504735, 4969.701817890938, 4986.551210391082, 4168.24971505132, 4380.299152135643, 4411.977940110343, 4421.38100665907, 4510.120526497301, 4800.303955926441, 5869.002269614176, 5913.172155898178, 5958.255218097738, 5993.101034844808, 6260.092286618415, 6266.342034086447, 6277.780277690259, 6294.370562943669, 6299.464545513591, 5706.115552089433, 5759.481646651821, 5767.826965191051, 5923.733431995571, 5937.910931336033, 5348.1175606025345, 5426.158945363475, 5443.918341224885, 5465.180222966507, 5548.195511650869, 6311.960345379053, 6339.366063393716, 6374.521910856797, 6378.712957916905, 6395.840103997437, 6196.504279657706, 6205.28639206479, 6292.716827742671, 6296.181496405438, 6304.432601065742, 4655.2784318430695, 4698.671093023456, 5646.435356464321, 5750.741216645178, 6221.4089661482085, 4583.679142459996, 6132.5707200495535, 6134.222293196984, 6195.380461953769, 6203.503719702323, 5133.153029750049, 5370.597205209547, 6216.875662486816, 6280.842978925563, 6318.399752023551, 6099.512039036777, 6146.078088286614, 6154.415330543591, 6159.414855588712, 6163.445289923929, 6035.849103772265, 6038.275406951194, 6045.304789949212, 6140.969295153495, 6152.200213972155, 4234.449521562177, 4521.819127234874, 5027.321311622884, 5970.373110823301, 5983.760649573968, 6182.010878259457, 6190.690465476799, 6212.192414986023, 6227.034809604518, 6231.345708700521, 6092.299385570716, 6098.634081955097, 6100.786472473442, 6126.050462178369, 6133.509319254538], "value": 5619.200492988371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.7941185290325, 488.6066716293382, 499.612800079938, 505.4162442379051, 544.6704743630102, 37099.62900370995, 41945.370819437696, 42073.948151296194, 42280.57719422805, 44009.55990440094, 42937.0, 43183.35224971628, 43408.782956085175, 43429.34855977163, 43453.565464345345, 7529.698812047512, 8123.75628731139, 9265.351425400224, 9454.90545094547, 9544.904550954505, 7378.37283830875, 11202.103831693486, 11424.71438214043, 11478.827817582744, 11568.537258509692, 11372.601958931438, 11384.0, 11428.885711142906, 11433.542658293658, 11477.196596238267, 11415.48630311638, 11426.257293275952, 11466.598669046585, 11518.539258429737, 11544.711382215428, 11252.831207531894, 11267.04230140439, 11359.886401135966, 11360.261582997078, 11397.715057123556, 40159.79920100381, 40501.594984050076, 40581.39127913084, 40593.91091336294, 40595.0, 3415.846286917095, 3449.301219149564, 3519.680119955017, 3919.314120029012, 4063.1061166543313, 30217.697823021706, 30554.930577429684, 31332.903348382926, 31366.686333136604, 31373.37253254922, 3169.7147256747003, 3248.9278538082376, 3255.3913073703143, 3320.750943679234, 3330.550375699274, 439737.0, 447387.0, 448484.99999999674, 451307.0, 451879.0, 60912.69543652253, 61286.69356653188, 61450.692746535984, 61526.770224531414, 61749.0, 52855.73572132115, 62395.816146434656, 63481.41296467625, 65511.34488655148, 65575.01637475441, 12283.0, 12841.871581284255, 13290.601281961362, 16204.83795162057, 16229.0, 11196.664100076849, 11399.316041037564, 12239.32683702405, 12465.376731163406, 12514.687132821753, 17622.118894055504, 18085.638287234182, 18115.81884181141, 18169.36407225741, 18254.178561964476, 6307.747690092437, 6464.385883341075, 6468.8059358219325, 6482.643454610042, 6505.381988711065, 828.9709860154986, 841.5750046226616, 984.1978787288338, 1013.763208885159, 1027.1833892055834, 23969.28092157238, 29704.40591188164, 30017.549736753965, 30686.92595759138, 30730.846345768576, 839.61377766227, 893.5308962794585, 1009.2228983682523, 1100.7688385438987, 1156.1848896527927, 14307.85692143086, 14744.631384215267, 15209.087454752751, 15434.073955562702, 15996.201614314103, 636501.0, 636778.0, 638601.0, 640377.0, 646909.0, 52773.94452110936, 53134.20298695523, 53182.73408632932, 53277.93444131096, 53382.0, 1354.106289848695, 1365.1877133105786, 1377.2012232904892, 1378.5106287268077, 1392.1647011793045, 27891.581626275216, 28215.71784282131, 29111.708882911746, 29556.852215738785, 29793.0, 47408.577742667774, 47415.52584474111, 47539.76230118966, 47576.286355705386, 47958.76020619875, 564755.0, 570072.0, 570583.0, 571529.0, 571718.0, 24756.504869902863, 24925.87537062303, 25065.0, 25116.62325065089, 25225.86483608205, 41995.56342955813, 42436.78781606072, 42548.57451425446, 42633.147337053706, 43632.78183609062, 35573.822130890214, 35597.0, 36141.0, 36182.829762800626, 36655.45016824696, 35313.64686353104, 35428.11429714174, 35999.0, 36347.81826090958, 36364.90905272846, 35056.47415288722, 35268.823655881555, 35385.82307088448, 35818.462723058656, 35954.10114747048, 30974.0, 32184.0, 32205.0, 32209.194770130933, 32281.67718322787, 19359.12883920199, 22069.0, 22389.104435822384, 23802.64296035526, 24139.637905431082], "value": 19605.564039959852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.40431213036214, 328.5548082348418, 334.3682827800334, 378.0624052350171, 378.5576951813589, 14332.928335358334, 17006.574835629115, 17042.233099510522, 17179.71152163588, 20513.282035128774, 17918.462446126603, 20119.29582464614, 20169.394918152444, 20408.59182816343, 20523.0, 5399.0, 5638.492535671779, 5939.821805345825, 5973.701314934257, 5976.0, 5572.164175373686, 5644.294463192101, 5829.475347218749, 5867.090600956859, 5966.67183304918, 5815.0, 5818.854528636797, 5819.563532735041, 5836.795712150076, 5851.678157701324, 5652.265205523277, 5697.914531282034, 5735.0250457422335, 5771.769129234825, 5777.797777077803, 5028.32117664118, 5036.0, 5057.645964782467, 5063.569596584295, 5070.949290507085, 23967.640485392734, 24116.276511704677, 24374.878125609437, 24416.511669766503, 24540.509189816104, 2162.524244666187, 2345.542619189259, 2455.1897873701637, 2962.9333439961597, 3036.878524859003, 14092.506762263272, 14741.631459213499, 15105.244737763178, 15185.240737963168, 15565.45520906774, 1761.5067781021335, 1783.6343549572307, 1834.238790901774, 1849.6208277303122, 1881.9059047047592, 95810.0, 105581.47209263904, 105940.41089383588, 106725.93274067238, 107020.0, 9882.555285012191, 10459.529321180566, 10579.682609521727, 10773.78452430947, 10858.185636077167, 13060.934695326463, 13164.868351316556, 13179.60461186166, 13198.07613467048, 13207.0, 7543.053292995121, 8254.59671855786, 8255.917440825635, 8324.375671824517, 8378.12029736881, 6199.473044791243, 8218.835623287501, 8299.925018745314, 8474.491530508189, 8489.363297752694, 5275.551578115903, 6204.883121038166, 6262.185915830913, 6455.160829092282, 6458.967705161444, 1631.7062928672715, 1839.2064995265023, 1865.0767869904485, 1865.7481240032694, 1867.0, 845.4842546046906, 920.7053742802373, 922.1792604581927, 934.7990182110879, 1027.2141811514198, 20779.480512987295, 21417.57164856694, 21436.892815536135, 21445.46386340354, 21538.0, 857.0829212742306, 873.611242996873, 945.0596656326999, 975.9268054895912, 1000.6940942070668, 7791.766247012599, 11456.083513318914, 11591.420428978518, 11602.6519204424, 11615.070794336432, 531651.3669726584, 533672.0, 552725.9454810881, 562929.370706296, 566460.0, 75453.62273188708, 95581.52209238909, 96590.0340996595, 107184.4640776791, 131327.0, 895.3374502867831, 970.5341436110706, 1039.9220058495491, 1042.4058286776465, 1055.5250137438184, 18384.540386490444, 18471.168797404418, 18594.163262652943, 18807.40137088363, 18979.43061708151, 29491.410171796873, 37026.07434814151, 43491.56508435002, 51484.0, 52043.47956520387, 73344.89982650371, 74167.25832741603, 76832.61583692046, 76835.23164768281, 77220.0, 5835.509463055501, 7839.804004899923, 7904.498145352365, 8013.278804907353, 8231.34149268069, 8960.148785865333, 11400.144989125683, 12255.019598432282, 12282.75434491323, 12702.87297127017, 10515.15878729715, 10516.316439431412, 10943.069839063768, 11024.779504410028, 11058.834117488404, 10621.097206737513, 10776.67669969902, 10901.127909767358, 11038.061764750086, 11246.7750644985, 8635.6977505787, 10968.561257549927, 10975.341479511255, 11129.332240065622, 11265.943670281596, 7940.682372705258, 7946.7615971520945, 8021.919780802351, 8135.959320203361, 8246.175382461706, 10643.574257029632, 10828.945855270673, 10921.726956826144, 11179.273347232403, 11285.37930413835], "value": 9314.120576530717, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.7006598680266, 1506.1565523306945, 1744.720844664854, 1846.4922146409745, 1872.1855992643193, 6031.0, 6594.274629790719, 7104.289571042899, 7539.2460753924715, 8493.999999999993, 7923.12845586985, 8203.630836612367, 8223.876641850376, 8342.95828520858, 8345.207205315486, 2245.8877056147167, 2272.4773302140516, 2273.7044184256024, 2279.1111466527987, 2291.072115793098, 1913.5598812273186, 2322.7328857181387, 2323.6514522821626, 2332.4518738096535, 2337.602607556711, 2304.7925686688195, 2305.9192928247517, 2326.7673232676766, 2333.9649905251435, 2348.6477028445784, 1945.2608008956506, 2268.172117177235, 2270.7842754938256, 2290.518991011881, 2305.1816605105196, 2335.801456876168, 2362.9291121266388, 2363.940901477477, 2370.6799582056547, 2372.0511795281855, 8340.416170868042, 8387.62255698495, 8399.034111077242, 8413.200745929127, 8420.915790842075, 5026.648134630541, 5116.309298244763, 5180.818671346485, 5182.896342073137, 5188.066148093341, 3330.916727081818, 3421.1789170598877, 3619.8914032579064, 3642.344378011957, 3644.7266455015715, 1040.9583616655325, 1048.958041678332, 1061.930974486656, 1072.5280876414354, 1090.334895713614, 9623.663171788954, 10568.207384446197, 10922.235443518957, 10968.012878840862, 10993.945030274877, 4473.597376236155, 4634.165850146938, 4704.5765881070865, 4749.596284315803, 4754.548317909793, 9367.718968430958, 9440.763980900532, 9451.480168590793, 9465.52672366379, 9466.384684995453, 5192.7403629818355, 5193.1171700810955, 5202.739863006835, 5203.765830537635, 5227.111391063529, 4153.605407486284, 4652.116097941381, 4703.17694403478, 4761.976190119027, 4771.928421073687, 3435.1927297085167, 3820.0449887528116, 3851.287511810268, 3863.343231650626, 3935.2916475034194, 1102.6361300770727, 1179.7345597240724, 1190.8924700028883, 1285.0875878126635, 1294.1199984010775, 801.0987638906231, 841.5876220651836, 882.8587426011808, 940.9623615055322, 957.674390707163, 5184.377874655065, 5526.226328314039, 5811.912821307684, 5821.94178058214, 5963.970180149072, 938.1416004355938, 943.9528023598792, 950.2920324358397, 954.5227386306847, 1000.4097582426258, 3761.078535758702, 3773.7735735855385, 3899.532056153223, 3990.521137463523, 4008.7995600219874, 9728.881178664402, 10170.440625765654, 10196.082352588302, 10203.846942295722, 10219.182465402899, 10096.545655445523, 10139.797204055878, 10156.33983791066, 10166.949165254126, 10188.388696678076, 7424.368928641018, 7803.0, 7804.882926756103, 7830.765077047698, 7890.329322007694, 5489.862753431196, 5815.82552523425, 5855.824325270249, 5973.1338955852025, 6007.849803754766, 9019.323550733852, 9216.064626428524, 9314.860277095713, 9351.251899847859, 9406.81186376255, 9369.484678342757, 9452.385594936582, 9500.619975200914, 9503.904960950302, 9533.142017218484, 5509.724513774376, 6715.764948226789, 7205.243449437734, 7245.855082898207, 7354.154272258758, 6606.867862642816, 7819.218078192135, 7912.762617121495, 8009.038915330198, 8011.599420028859, 5232.0, 5719.799807006735, 6518.152640156749, 7195.13658361, 7342.0, 7024.0, 7124.216336203117, 7138.143422789299, 7145.285471452813, 7147.82130446743, 6603.603783772989, 6644.4020038196795, 6645.95501429104, 6661.034150048313, 6669.333066693292, 4786.712797232177, 4851.296561998561, 4856.708597484162, 4869.853904382874, 4968.155413579628, 4835.637327200544, 4905.484924083063, 4935.506449355035, 4947.802087916443, 4961.801527938842], "value": 4401.0091478010745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.45659292388785, 355.5200479352874, 358.1243858765317, 369.56022333423226, 373.9943900841486, 4989.575886049685, 5270.420253772082, 5454.754536045877, 5481.972590137054, 5596.600849787553, 4575.9313610295785, 5271.9472805271935, 5431.34823821142, 5504.64219825712, 5549.861253468665, 3629.1570571380057, 4251.723637963531, 4275.935860962088, 4281.550437204095, 4292.892677683051, 3809.4285857121376, 3970.3051965905997, 4277.914441711164, 4292.270314046619, 4326.74039557626, 3262.096733906813, 4120.814563344657, 4209.536950935395, 4214.431051808013, 4238.427812245338, 4251.659867210647, 4257.659387249013, 4260.637845783112, 4278.358246263055, 4285.485741710983, 3152.25050097196, 3744.2698673758628, 3765.021094515418, 3782.6406491383273, 3790.2419516096893, 4871.4397844248015, 5141.203113517411, 5192.169252919514, 5313.628046036779, 5374.355077390738, 4221.366794980747, 4357.673174511892, 4414.168120230101, 4787.305840653121, 4897.534734200245, 5666.134960815581, 5792.640268644176, 5825.621334613236, 5838.445347692005, 5856.443637854397, 3427.365937301607, 3631.2569966416004, 3654.397024490956, 3724.1806802503675, 4168.228877657643, 5099.974500127476, 5783.421657834225, 5830.60065584261, 6098.57309988301, 6131.632102073845, 3523.4672917281064, 4200.327947528442, 4211.431456753299, 4230.344296634026, 4260.4248426462655, 3327.8336083195745, 3708.8887333380044, 3721.2557488502407, 4028.1269209817574, 4309.418228539107, 4194.433751443577, 4495.370648109268, 4653.302004699322, 4721.386219791474, 4784.521547845187, 4779.681854300661, 5238.973805131026, 5613.775448981995, 5824.11706205431, 5872.354041055481, 1129.8192289233848, 1191.7724743514195, 1261.6404324767398, 1290.4773566705499, 1298.68831480444, 746.4401698725956, 861.3238607692931, 875.5709702245981, 883.6642076011075, 889.5285498685578, 4583.0, 5150.690958542499, 5178.145605975047, 5203.479652034842, 5224.9738751306, 5075.771590278447, 5463.016657001778, 5703.144528320785, 5727.742251598688, 5732.280315905212, 4708.246680531099, 4927.384076990376, 5115.18157094863, 5224.399194092728, 5225.634205605647, 2724.3733941193636, 2763.2677340504783, 2978.27032377069, 3028.4700177468853, 3093.582366380555, 5145.578190503274, 5396.4603539646505, 5763.654180749085, 6006.624071456023, 6071.726772295257, 4921.680090794087, 5020.899582008339, 5811.738471768781, 5860.780238228469, 5927.496162826208, 5171.965606878564, 5785.82642520725, 6052.182955301154, 6055.666938318347, 6074.0, 5011.674241174312, 5267.552258058108, 5362.436944120967, 5568.466347811655, 5614.354349249888, 5055.823046193365, 5152.4847515248175, 5457.0, 5611.625151837826, 5614.943850561442, 5160.948390516047, 5202.739863006759, 5951.137085122719, 6023.5783495156675, 6048.60684055535, 3263.6083669958807, 3628.818559071983, 3753.1367785410057, 3758.3986562149926, 3799.259144466913, 5013.348264725562, 5426.620136590401, 5782.629122059935, 5842.853928651818, 5874.0, 4698.201305777958, 5051.636058264322, 5705.942940570541, 5878.794242201502, 5893.613086699894, 4953.628477864103, 5203.453637368173, 5311.7609707564025, 5819.912701309399, 5837.803250333756, 4807.855764327076, 5058.64589478733, 5233.755071855474, 5715.028055501387, 5721.599488035964, 5007.624428167829, 5052.873678158076, 5357.732113394392, 5569.078667859582, 5799.2750906136735, 4624.843789052737, 5039.748012599282, 5470.276862788209, 5638.01334766406, 5647.955128301277], "value": 4125.94095632128, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.7368878759706, 659.6899457255091, 666.8399584099816, 705.9505834591581, 728.1917072050023, 14796.0, 15138.016028958124, 15557.066576005447, 15723.92138039311, 15858.444954426597, 14313.141211527316, 14465.493707720232, 14505.854941450609, 15089.773653395208, 15635.609109772262, 876.38215058384, 898.3397203055749, 898.9280857531414, 899.4962820820328, 900.8603666431713, 693.535331328009, 713.661011019764, 865.2991077227434, 891.6611687558718, 913.2739472119681, 928.4661319741176, 931.352709866643, 933.286036182321, 933.9579718912631, 934.8130373925209, 757.4319260554529, 922.1239822168933, 923.4413180026122, 924.8890133184027, 925.7963248085445, 685.6503183376464, 686.6806934775303, 686.842026333946, 688.102026854954, 689.3244620272111, 13310.534131305358, 15264.236788160548, 15391.153486558234, 15431.151286679227, 15716.685666286725, 13937.233452160128, 14207.431702731878, 14243.643908902257, 14290.071145375621, 14308.28458577067, 11699.883001169965, 11804.704882377924, 11853.881461185363, 11933.34366609836, 12112.697182570419, 3645.2891686121216, 3656.3053019926397, 3709.8516059357594, 3714.9814250928844, 3816.122291872885, 112775.0, 115742.0, 116042.99999999916, 116295.0, 116562.41718791352, 10617.0, 10660.307080039773, 10831.458427078614, 10901.400422976812, 10910.992157350927, 21906.78093219079, 22329.55340893173, 22341.329760107223, 22361.888190558944, 22597.548049038927, 7657.93245743254, 7694.653740581687, 7804.9609751950875, 7897.644605992744, 8006.359491240687, 4250.234957707642, 4317.503487099024, 4347.739135651871, 4421.46942366918, 4485.618722408632, 3494.633063528344, 3545.822708864598, 3598.4602309653237, 3608.242269123461, 3611.8735844245853, 578.2251782611321, 639.9792331231678, 655.9180102487189, 661.1240106858431, 675.5237557521936, 525.6819624127425, 539.4335947255366, 557.6486813307671, 681.9931800681929, 685.873113474009, 9071.319651026068, 9197.81604367909, 9328.34701570897, 9369.672061477952, 9525.476098814632, 585.5608293779666, 638.7732355013997, 648.5168549430705, 670.9228438729511, 680.9547344825768, 3534.8511733686655, 4439.045605194899, 4674.672772905864, 4729.4797572267025, 4783.545563171493, 99015.0, 99855.0, 108046.3793043105, 108924.0, 110980.4450977756, 74195.62902185455, 76108.85836712454, 81693.0, 81768.0, 84048.57975710205, 77464.0, 78232.43535129266, 80189.3962120766, 81118.0, 81704.59147704342, 9621.807563848544, 9884.654037108667, 9887.85168222463, 9936.0, 9974.60101595928, 47875.0, 50193.74903125461, 50338.49661503338, 51958.48041519688, 52643.73678131585, 49250.01499970052, 63096.0, 66426.33573664394, 66869.0, 67620.0, 10255.846162307422, 10256.794864102825, 12470.0, 12993.48026078984, 13767.586972390844, 14590.927045364706, 14879.181645009628, 14894.77657835155, 14949.701005980038, 14952.850471495582, 11387.747347791901, 12883.48466061347, 14011.929940350234, 14847.554573362817, 14871.553853384416, 11631.302121872715, 11997.700057498632, 14216.857831421554, 14280.714385712019, 14429.567112986628, 13272.668183295495, 13522.86477135216, 13701.45194192221, 13909.165450072624, 14261.073030253001, 11285.379304138023, 11287.43562821872, 11332.376719280519, 11440.084793216689, 11547.307161570332, 9862.161716254199, 10884.238103332691, 11624.941875290908, 11656.18406711522, 11697.23967942081], "value": 7448.435066912002, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 117.0, 116.0, 116.0, 117.0, 100.0, 172.0, 173.0, 126.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 115.0, 133.0, 142.0, 144.0, 145.0, 144.0, 131.0, 124.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 203.0, 199.0, 197.0, 197.0, 197.0, 198.0, 200.0, 194.0, 192.0], "value": 199.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 85.0, 59.0, 60.0, 57.0, 58.0, 71.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 84.0, 70.0, 69.0, 71.0, 81.0, 72.0, 71.0, 82.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 134.0, 86.0, 90.0, 102.0, 89.0, 86.0, 88.0, 87.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 96.0, 95.0, 95.0, 96.0, 103.0, 95.0, 94.0, 95.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 121.0, 85.0, 144.0, 149.0, 136.0, 82.0, 147.0, 150.0], "value": 120.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 61.0, 62.0, 62.0, 60.0, 62.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 59.0, 91.0, 116.0, 112.0, 88.0, 71.0, 75.0, 74.0, 73.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 87.0, 82.0, 88.0, 110.0, 107.0, 86.0, 90.0, 81.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 181.0, 154.0, 159.0, 144.0, 138.0, 140.0, 142.0, 217.0, 140.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 175.0, 174.0, 173.0, 181.0, 171.0, 178.0, 173.0, 170.0, 174.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 68.0, 67.0, 68.0, 69.0, 70.0, 66.0, 70.0, 74.0], "value": 69.0, "unit": "score"}]}]} 03:02:37 INFO - Return code: 0 03:02:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:02:37 ERROR - # TBPL SUCCESS # 03:02:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:37 INFO - 2016-12-22 03:02:37.692 screenresolution[1661:10547] starting screenresolution argv=screenresolution get 03:02:37 INFO - 2016-12-22 03:02:37.695 screenresolution[1661:10547] Display 0: 1600x1200x32@60 03:02:37 INFO - 2016-12-22 03:02:37.701 screenresolution[1662:10550] starting screenresolution argv=screenresolution list 03:02:37 INFO - Available Modes on Display 0 03:02:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:37 INFO - Intel Iris: 03:02:37 INFO - Chipset Model: Intel Iris 03:02:37 INFO - Type: GPU 03:02:37 INFO - Bus: Built-In 03:02:37 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:37 INFO - Vendor: Intel (0x8086) 03:02:37 INFO - Device ID: 0x0a2e 03:02:37 INFO - Revision ID: 0x0009 03:02:37 INFO - Displays: 03:02:37 INFO - Display: 03:02:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:37 INFO - Display Serial Number: 1600x1200 60 03:02:37 INFO - Main Display: Yes 03:02:37 INFO - Mirror: Off 03:02:37 INFO - Online: Yes 03:02:37 INFO - Rotation: Supported 03:02:37 INFO - Return code: 0 03:02:37 INFO - Running post-action listener: _resource_record_post_action 03:02:37 INFO - [mozharness: 2016-12-22 11:02:37.937229Z] Finished run-tests step (success) 03:02:37 INFO - Running post-run listener: _resource_record_post_run 03:02:37 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 39766528; Write bytes: 552113664; Read time: 387; Write time: 1896 03:02:37 INFO - TinderboxPrint: CPU usage
54.8% 03:02:37 INFO - TinderboxPrint: I/O read bytes / time
39,766,528 / 387 03:02:37 INFO - TinderboxPrint: I/O write bytes / time
552,113,664 / 1,896 03:02:38 INFO - TinderboxPrint: CPU idle
2,169.9 (45.2%) 03:02:38 INFO - TinderboxPrint: CPU system
969.7 (20.2%) 03:02:38 INFO - TinderboxPrint: CPU user
1,658.1 (34.6%) 03:02:38 INFO - TinderboxPrint: Swap in / out
289,845,248 / 0 03:02:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233171456; Write bytes: 225476608; Read time: 16526; Write time: 391 03:02:38 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 38844928; Write bytes: 301585920; Read time: 370; Write time: 1452 03:02:38 INFO - Running post-run listener: _upload_blobber_files 03:02:38 INFO - Blob upload gear active. 03:02:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:38 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 03:02:38 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'] 03:02:38 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 03:02:38 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:02:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:40 INFO - (blobuploader) - INFO - Done attempting. 03:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:40 INFO - (blobuploader) - INFO - Done attempting. 03:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:41 INFO - (blobuploader) - INFO - Done attempting. 03:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:44 INFO - (blobuploader) - INFO - Done attempting. 03:02:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:44 INFO - Return code: 0 03:02:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/88eade7e85c091dbb7edd089aa447c30f6b109331c38e21e6faedcdbd142d8aad21dfc4dd3064309356f0220d0c9d355bd0c5c49da0c8cc0aea4e589643bc1ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fd10243cfdb695389ecc7f7c6beda37b0a0543139dbde9a0e45eedbd92315d9fb96da7b8e9a570c223aeabe028a438eab84732545ac424c3c06d6d9c8fb7502a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5efc57f1b0f8f5aee73bf14302e6781793ad34c5a7af8bd5c831d8873c8b793926de3e34a34ac7b448c74227f349758fdd1aeee7b5af8fcb2427237a38dde386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9f8cffb661db58112024d085810b7f7872f27937c509a0aed157f9dadc36486c24908a07c73e6f3f17352c5f139ea675b8ad2008c44eb1ad58b7c4bcef381278"} 03:02:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:44 INFO - Contents: 03:02:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/88eade7e85c091dbb7edd089aa447c30f6b109331c38e21e6faedcdbd142d8aad21dfc4dd3064309356f0220d0c9d355bd0c5c49da0c8cc0aea4e589643bc1ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fd10243cfdb695389ecc7f7c6beda37b0a0543139dbde9a0e45eedbd92315d9fb96da7b8e9a570c223aeabe028a438eab84732545ac424c3c06d6d9c8fb7502a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5efc57f1b0f8f5aee73bf14302e6781793ad34c5a7af8bd5c831d8873c8b793926de3e34a34ac7b448c74227f349758fdd1aeee7b5af8fcb2427237a38dde386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9f8cffb661db58112024d085810b7f7872f27937c509a0aed157f9dadc36486c24908a07c73e6f3f17352c5f139ea675b8ad2008c44eb1ad58b7c4bcef381278"} 03:02:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:44 INFO - Copying logs to upload dir... 03:02:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.357143 ========= master_lag: 1.90 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-22 03:02:46.164160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-22 03:02:46.168238) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/88eade7e85c091dbb7edd089aa447c30f6b109331c38e21e6faedcdbd142d8aad21dfc4dd3064309356f0220d0c9d355bd0c5c49da0c8cc0aea4e589643bc1ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fd10243cfdb695389ecc7f7c6beda37b0a0543139dbde9a0e45eedbd92315d9fb96da7b8e9a570c223aeabe028a438eab84732545ac424c3c06d6d9c8fb7502a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5efc57f1b0f8f5aee73bf14302e6781793ad34c5a7af8bd5c831d8873c8b793926de3e34a34ac7b448c74227f349758fdd1aeee7b5af8fcb2427237a38dde386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9f8cffb661db58112024d085810b7f7872f27937c509a0aed157f9dadc36486c24908a07c73e6f3f17352c5f139ea675b8ad2008c44eb1ad58b7c4bcef381278"} build_url:https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010989 build_url: 'https://queue.taskcluster.net/v1/task/KkguTPHMRLuGSo85md1nmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/88eade7e85c091dbb7edd089aa447c30f6b109331c38e21e6faedcdbd142d8aad21dfc4dd3064309356f0220d0c9d355bd0c5c49da0c8cc0aea4e589643bc1ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fd10243cfdb695389ecc7f7c6beda37b0a0543139dbde9a0e45eedbd92315d9fb96da7b8e9a570c223aeabe028a438eab84732545ac424c3c06d6d9c8fb7502a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5efc57f1b0f8f5aee73bf14302e6781793ad34c5a7af8bd5c831d8873c8b793926de3e34a34ac7b448c74227f349758fdd1aeee7b5af8fcb2427237a38dde386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9f8cffb661db58112024d085810b7f7872f27937c509a0aed157f9dadc36486c24908a07c73e6f3f17352c5f139ea675b8ad2008c44eb1ad58b7c4bcef381278"}' ========= master_lag: 1.27 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-22 03:02:47.445626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:02:47.446049) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y25ldezveh/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.uy11zb207j/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005173 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 03:02:47.477518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 03:02:47.478272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 03:02:47.478994) ========= ========= Total master_lag: 3.27 =========