builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0312 starttime: 1476865009.24 results: success (0) buildid: 20161018221023 builduid: 6943907c256f4ff5ae4427933b8e9313 revision: e2511b7cd8d98cb9b4df844d439b9550bb357afc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.236054) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.236492) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.236765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.260648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.260941) ========= 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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-10-19 01:16:49-- 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% 157M=0s 2016-10-19 01:16:49 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133184 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.415985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.416294) ========= 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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.025026 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:49.464230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 01:16:49.464584) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e2511b7cd8d98cb9b4df844d439b9550bb357afc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e2511b7cd8d98cb9b4df844d439b9550bb357afc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-10-19 01:16:49,545 truncating revision to first 12 chars 2016-10-19 01:16:49,545 Setting DEBUG logging. 2016-10-19 01:16:49,545 attempt 1/10 2016-10-19 01:16:49,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e2511b7cd8d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 01:16:50,803 unpacking tar archive at: autoland-e2511b7cd8d9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 01:16:51.006374) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:51.006704) ========= script_repo_revision: e2511b7cd8d98cb9b4df844d439b9550bb357afc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:51.007077) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:51.007337) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 01:16:51.025412) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-19 01:16:51.025693) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:16:51 INFO - MultiFileLogger online at 20161019 01:16:51 in /builds/slave/test 01:16:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:16:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:16:51 INFO - {'append_to_log': False, 01:16:51 INFO - 'base_work_dir': '/builds/slave/test', 01:16:51 INFO - 'blob_upload_branch': 'Autoland', 01:16:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:16:51 INFO - 'branch': 'Autoland', 01:16:51 INFO - 'buildbot_json_path': 'buildprops.json', 01:16:51 INFO - 'config_files': ('talos/mac_config.py',), 01:16:51 INFO - 'default_actions': ('clobber', 01:16:51 INFO - 'read-buildbot-config', 01:16:51 INFO - 'download-and-extract', 01:16:51 INFO - 'populate-webroot', 01:16:51 INFO - 'create-virtualenv', 01:16:51 INFO - 'install', 01:16:51 INFO - 'run-tests'), 01:16:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:16:51 INFO - 'download_minidump_stackwalk': True, 01:16:51 INFO - 'download_symbols': 'ondemand', 01:16:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:16:51 INFO - 'tooltool.py': '/tools/tooltool.py', 01:16:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:16:51 INFO - '/tools/misc-python/virtualenv.py')}, 01:16:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:16:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:16:51 INFO - 'installer_path': 'installer.exe', 01:16:51 INFO - 'log_level': 'info', 01:16:51 INFO - 'log_name': 'talos', 01:16:51 INFO - 'log_to_console': True, 01:16:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:16:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:16:51 INFO - 'opt_config_files': (), 01:16:51 INFO - 'pip_index': False, 01:16:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:16:51 INFO - 'cmd': ('bash', 01:16:51 INFO - '-c', 01:16:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:16:51 INFO - 'enabled': True, 01:16:51 INFO - 'halt_on_failure': False, 01:16:51 INFO - 'name': 'check_screen_resolution'},), 01:16:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:16:51 INFO - 'cmd': ('bash', 01:16:51 INFO - '-c', 01:16:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:16:51 INFO - 'enabled': True, 01:16:51 INFO - 'halt_on_failure': False, 01:16:51 INFO - 'name': 'check_screen_resolution'},), 01:16:51 INFO - 'run_cmd_checks_enabled': True, 01:16:51 INFO - 'sps_profile': False, 01:16:51 INFO - 'sps_profile_interval': 0, 01:16:51 INFO - 'suite': 'dromaeojs-e10s', 01:16:51 INFO - 'system_bits': '32', 01:16:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:16:51 INFO - 'title': 't-yosemite-r7-0312', 01:16:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:16:51 INFO - 'use_talos_json': True, 01:16:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:16:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:16:51 INFO - 'work_dir': 'build'} 01:16:51 INFO - [mozharness: 2016-10-19 08:16:51.229560Z] Running clobber step. 01:16:51 INFO - Running pre-action listener: _resource_record_pre_action 01:16:51 INFO - Running main action method: clobber 01:16:51 INFO - rmtree: /builds/slave/test/build 01:16:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:16:53 INFO - Running post-action listener: _resource_record_post_action 01:16:53 INFO - [mozharness: 2016-10-19 08:16:53.756800Z] Finished clobber step (success) 01:16:53 INFO - [mozharness: 2016-10-19 08:16:53.757048Z] Running read-buildbot-config step. 01:16:53 INFO - Running pre-action listener: _resource_record_pre_action 01:16:53 INFO - Running main action method: read_buildbot_config 01:16:53 INFO - Using buildbot properties: 01:16:53 INFO - { 01:16:53 INFO - "project": "", 01:16:53 INFO - "product": "firefox", 01:16:53 INFO - "script_repo_revision": "production", 01:16:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 01:16:53 INFO - "repository": "", 01:16:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 01:16:53 INFO - "buildid": "20161018221023", 01:16:53 INFO - "pgo_build": "False", 01:16:53 INFO - "basedir": "/builds/slave/test", 01:16:53 INFO - "buildnumber": 219, 01:16:53 INFO - "slavename": "t-yosemite-r7-0312", 01:16:53 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:16:53 INFO - "platform": "yosemite_r7", 01:16:53 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 01:16:53 INFO - "branch": "autoland", 01:16:53 INFO - "repo_path": "integration/autoland", 01:16:53 INFO - "revision": "e2511b7cd8d98cb9b4df844d439b9550bb357afc", 01:16:53 INFO - "stage_platform": "macosx64", 01:16:53 INFO - "builduid": "6943907c256f4ff5ae4427933b8e9313", 01:16:53 INFO - "slavebuilddir": "test" 01:16:53 INFO - } 01:16:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:16:53 INFO - Running post-action listener: _resource_record_post_action 01:16:53 INFO - [mozharness: 2016-10-19 08:16:53.762922Z] Finished read-buildbot-config step (success) 01:16:53 INFO - [mozharness: 2016-10-19 08:16:53.763104Z] Running download-and-extract step. 01:16:53 INFO - Running pre-action listener: _resource_record_pre_action 01:16:53 INFO - Running main action method: download_and_extract 01:16:53 INFO - mkdir: /builds/slave/test/build/tests 01:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:53 INFO - trying https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 01:16:54 INFO - Downloaded 1199 bytes. 01:16:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:16:54 INFO - Using the following test package requirements: 01:16:54 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:16:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:16:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:16:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'jsshell-mac.zip'], 01:16:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:16:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:16:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:16:54 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:16:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:16:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:16:54 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:16:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:16:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:16:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:16:54 INFO - Fetch https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:16:55 INFO - Http code: 200 01:16:55 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 01:16:55 INFO - x-amz-cf-id: 0AZxLLGz1EK5IIZzdrzMGnLuEAsD0r1YOVdQrt6Jz7m87iVK-EfmyQ== 01:16:55 INFO - x-amz-version-id: 6z6QXWyHgnLBLJxfdGgRzjl0Le9WUk4J 01:16:55 INFO - Expected file size: 16499845 01:16:55 INFO - Obtained file size: 16499845 01:17:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:17:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:17:02 INFO - Fetch https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:17:02 INFO - Http code: 200 01:17:02 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 01:17:02 INFO - x-amz-cf-id: FHdYiKXBvVLTA8_rhLIsWrfX9m3wq-aoPzer7XaUNF_DkdVL6qRzEw== 01:17:02 INFO - x-amz-version-id: 6z6QXWyHgnLBLJxfdGgRzjl0Le9WUk4J 01:17:03 INFO - Expected file size: 16499845 01:17:03 INFO - Obtained file size: 16499845 01:17:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:17:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:17:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:17:10 INFO - Http code: 200 01:17:10 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 01:17:10 INFO - x-amz-cf-id: Y_HYYtQwtTPztHy7yotcxYyhaqQD9lZ6Galf-UqzSApRfdg4b5J11g== 01:17:10 INFO - x-amz-version-id: YDj9qLRPYhWSUgmXDOPO7xCns.SOXvfu 01:17:10 INFO - Expected file size: 14409028 01:17:10 INFO - Obtained file size: 14409028 01:17:10 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')]} 01:17:10 INFO - trying https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:17:10 INFO - Downloading https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 01:17:13 INFO - Downloaded 111655948 bytes. 01:17:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:17:13 INFO - mkdir: /builds/slave/test/properties 01:17:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:17:13 INFO - Writing to file /builds/slave/test/properties/build_url 01:17:13 INFO - Contents: 01:17:13 INFO - build_url:https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:17:14 INFO - Running post-action listener: _resource_record_post_action 01:17:14 INFO - Running post-action listener: set_extra_try_arguments 01:17:14 INFO - [mozharness: 2016-10-19 08:17:14.693421Z] Finished download-and-extract step (success) 01:17:14 INFO - [mozharness: 2016-10-19 08:17:14.693511Z] Running populate-webroot step. 01:17:14 INFO - Running pre-action listener: _resource_record_pre_action 01:17:14 INFO - Running main action method: populate_webroot 01:17:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:17:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:17:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:17:14 INFO - '--tppagecycles', 01:17:14 INFO - '7'], 01:17:14 INFO - 'tests': ['tsvgm']}}, 01:17:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:17:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:17:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:17:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:17:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['damp', 'tps']}, 01:17:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'tests': ['damp', 'tps']}, 01:17:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['dromaeo_dom']}, 01:17:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:17:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['basic_compositor_video']}, 01:17:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:17:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'other-e10s': {'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['a11yr', 01:17:14 INFO - 'ts_paint', 01:17:14 INFO - 'tpaint', 01:17:14 INFO - 'sessionrestore', 01:17:14 INFO - 'sessionrestore_no_auto_restore', 01:17:14 INFO - 'tabpaint']}, 01:17:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['tsvgx', 01:17:14 INFO - 'tsvgr_opacity', 01:17:14 INFO - 'tart', 01:17:14 INFO - 'tscrollx', 01:17:14 INFO - 'cart']}, 01:17:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:17:14 INFO - 'tsvgr_opacity', 01:17:14 INFO - 'tart', 01:17:14 INFO - 'tscrollx', 01:17:14 INFO - 'cart']}, 01:17:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'talos_options': ['--disable-e10s'], 01:17:14 INFO - 'tests': ['tp5o']}, 01:17:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'tests': ['tp5o']}, 01:17:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'talos_options': ['--disable-e10s', 01:17:14 INFO - '--xperf_path', 01:17:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:14 INFO - 'tests': ['tp5n']}, 01:17:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:14 INFO - 'talos_options': ['--xperf_path', 01:17:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:14 INFO - 'tests': ['tp5n']}}, 01:17:14 INFO - 'talos.zip': {'path': '', 01:17:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:17:14 INFO - Running post-action listener: _resource_record_post_action 01:17:14 INFO - [mozharness: 2016-10-19 08:17:14.712938Z] Finished populate-webroot step (success) 01:17:14 INFO - [mozharness: 2016-10-19 08:17:14.713066Z] Running create-virtualenv step. 01:17:14 INFO - Running pre-action listener: _resource_record_pre_action 01:17:14 INFO - Running main action method: create_virtualenv 01:17:14 INFO - Creating virtualenv /builds/slave/test/build/venv 01:17:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:17:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:17:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:17:14 INFO - Using real prefix '/tools/python27' 01:17:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:17:15 INFO - Installing distribute.............................................................................................................................................................................................done. 01:17:18 INFO - Installing pip.................done. 01:17:18 INFO - Return code: 0 01:17:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:17:18 INFO - Reading from file tmpfile_stdout 01:17:18 INFO - Output received: 01:17:18 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) 01:17:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:18 INFO - 'HOME': '/Users/cltbld', 01:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:18 INFO - 'LOGNAME': 'cltbld', 01:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:18 INFO - 'MOZ_NO_REMOTE': '1', 01:17:18 INFO - 'NO_EM_RESTART': '1', 01:17:18 INFO - 'PAGER': '/bin/cat', 01:17:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:18 INFO - 'PWD': '/builds/slave/test', 01:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:18 INFO - 'SHELL': '/bin/bash', 01:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:18 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:18 INFO - 'USER': 'cltbld', 01:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:18 INFO - 'XPC_FLAGS': '0x0', 01:17:18 INFO - 'XPC_SERVICE_NAME': '0', 01:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:18 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 01:17:18 INFO - Cleaning up... 01:17:18 INFO - Return code: 0 01:17:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:18 INFO - 'HOME': '/Users/cltbld', 01:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:18 INFO - 'LOGNAME': 'cltbld', 01:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:18 INFO - 'MOZ_NO_REMOTE': '1', 01:17:18 INFO - 'NO_EM_RESTART': '1', 01:17:18 INFO - 'PAGER': '/bin/cat', 01:17:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:18 INFO - 'PWD': '/builds/slave/test', 01:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:18 INFO - 'SHELL': '/bin/bash', 01:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:18 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:18 INFO - 'USER': 'cltbld', 01:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:18 INFO - 'XPC_FLAGS': '0x0', 01:17:18 INFO - 'XPC_SERVICE_NAME': '0', 01:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:19 INFO - Downloading/unpacking psutil>=3.1.1 01:17: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 01:17: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 01:17: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 01:17: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 01:17: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 01:17: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 01:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:17:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:22 INFO - Installing collected packages: psutil 01:17:22 INFO - Running setup.py install for psutil 01:17:22 INFO - building 'psutil._psutil_osx' extension 01:17:22 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 01:17:23 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 01:17:23 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 01:17:23 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 01:17:23 INFO - building 'psutil._psutil_posix' extension 01:17:23 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 01:17:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:17:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:17:23 INFO - ^ 01:17:23 INFO - 1 warning generated. 01:17:23 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 01:17:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:23 INFO - Successfully installed psutil 01:17:23 INFO - Cleaning up... 01:17:23 INFO - Return code: 0 01:17:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:23 INFO - 'HOME': '/Users/cltbld', 01:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:23 INFO - 'LOGNAME': 'cltbld', 01:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:23 INFO - 'MOZ_NO_REMOTE': '1', 01:17:23 INFO - 'NO_EM_RESTART': '1', 01:17:23 INFO - 'PAGER': '/bin/cat', 01:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:23 INFO - 'PWD': '/builds/slave/test', 01:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:23 INFO - 'SHELL': '/bin/bash', 01:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:23 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:23 INFO - 'USER': 'cltbld', 01:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:23 INFO - 'XPC_FLAGS': '0x0', 01:17:23 INFO - 'XPC_SERVICE_NAME': '0', 01:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:17:26 INFO - Installing collected packages: mozsystemmonitor 01:17:26 INFO - Running setup.py install for mozsystemmonitor 01:17:26 INFO - Successfully installed mozsystemmonitor 01:17:26 INFO - Cleaning up... 01:17:26 INFO - Return code: 0 01:17:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:26 INFO - 'HOME': '/Users/cltbld', 01:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:26 INFO - 'LOGNAME': 'cltbld', 01:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:26 INFO - 'MOZ_NO_REMOTE': '1', 01:17:26 INFO - 'NO_EM_RESTART': '1', 01:17:26 INFO - 'PAGER': '/bin/cat', 01:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:26 INFO - 'PWD': '/builds/slave/test', 01:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:26 INFO - 'SHELL': '/bin/bash', 01:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:26 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:26 INFO - 'USER': 'cltbld', 01:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:26 INFO - 'XPC_FLAGS': '0x0', 01:17:26 INFO - 'XPC_SERVICE_NAME': '0', 01:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:27 INFO - Downloading/unpacking jsonschema==2.5.1 01:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:17:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:17:29 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 01:17:29 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 01:17:29 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 01:17:29 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 01:17:29 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 01:17:29 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 01:17:30 INFO - Downloading functools32-3.2.3-2.tar.gz 01:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:17:30 INFO - warning: no files found matching '*.txt' 01:17:30 INFO - no previously-included directories found matching 'build' 01:17:30 INFO - no previously-included directories found matching 'dist' 01:17:30 INFO - no previously-included directories found matching '.git*' 01:17:30 INFO - Installing collected packages: jsonschema, functools32 01:17:30 INFO - Running setup.py install for functools32 01:17:30 INFO - warning: no files found matching '*.txt' 01:17:30 INFO - no previously-included directories found matching 'build' 01:17:30 INFO - no previously-included directories found matching 'dist' 01:17:30 INFO - no previously-included directories found matching '.git*' 01:17:30 INFO - Successfully installed jsonschema functools32 01:17:30 INFO - Cleaning up... 01:17:30 INFO - Return code: 0 01:17:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:17:30 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')]} 01:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:30 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')]} 01:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:30 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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:30 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 01:17:30 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 01:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:30 INFO - 'HOME': '/Users/cltbld', 01:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:30 INFO - 'LOGNAME': 'cltbld', 01:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:30 INFO - 'MOZ_NO_REMOTE': '1', 01:17:30 INFO - 'NO_EM_RESTART': '1', 01:17:30 INFO - 'PAGER': '/bin/cat', 01:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:30 INFO - 'PWD': '/builds/slave/test', 01:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:30 INFO - 'SHELL': '/bin/bash', 01:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:30 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:30 INFO - 'USER': 'cltbld', 01:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:30 INFO - 'XPC_FLAGS': '0x0', 01:17:30 INFO - 'XPC_SERVICE_NAME': '0', 01:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:17:30 INFO - Cleaning up... 01:17:30 INFO - Return code: 0 01:17:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:17:30 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')]} 01:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:30 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')]} 01:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:30 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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:30 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 01:17:30 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 01:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:30 INFO - 'HOME': '/Users/cltbld', 01:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:30 INFO - 'LOGNAME': 'cltbld', 01:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:30 INFO - 'MOZ_NO_REMOTE': '1', 01:17:30 INFO - 'NO_EM_RESTART': '1', 01:17:30 INFO - 'PAGER': '/bin/cat', 01:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:30 INFO - 'PWD': '/builds/slave/test', 01:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:30 INFO - 'SHELL': '/bin/bash', 01:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:30 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:30 INFO - 'USER': 'cltbld', 01:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:30 INFO - 'XPC_FLAGS': '0x0', 01:17:30 INFO - 'XPC_SERVICE_NAME': '0', 01:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:31 INFO - Downloading/unpacking blobuploader==1.2.4 01:17: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 01:17: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 01:17: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 01:17: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 01:17: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 01:17: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 01:17:33 INFO - Downloading blobuploader-1.2.4.tar.gz 01:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:17:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:17:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:34 INFO - Downloading docopt-0.6.1.tar.gz 01:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:17:34 INFO - Installing collected packages: blobuploader, requests, docopt 01:17:34 INFO - Running setup.py install for blobuploader 01:17:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:17:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:17:34 INFO - Running setup.py install for requests 01:17:35 INFO - Running setup.py install for docopt 01:17:35 INFO - Successfully installed blobuploader requests docopt 01:17:35 INFO - Cleaning up... 01:17:35 INFO - Return code: 0 01:17:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17: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')]} 01:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17: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')]} 01:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:35 INFO - 'HOME': '/Users/cltbld', 01:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:35 INFO - 'LOGNAME': 'cltbld', 01:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:35 INFO - 'MOZ_NO_REMOTE': '1', 01:17:35 INFO - 'NO_EM_RESTART': '1', 01:17:35 INFO - 'PAGER': '/bin/cat', 01:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:35 INFO - 'PWD': '/builds/slave/test', 01:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:35 INFO - 'SHELL': '/bin/bash', 01:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:35 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:35 INFO - 'USER': 'cltbld', 01:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:35 INFO - 'XPC_FLAGS': '0x0', 01:17:35 INFO - 'XPC_SERVICE_NAME': '0', 01:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:17:35 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-3VENJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-IHQuwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Yb6Bcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-PRvP5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-RlS8x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-yjeoLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9bpwLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Lz0IkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Z0qhGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-deb8J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:17:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-pJYKjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-WyEX9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AUBOx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-TUTaU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zttlOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-RjvfZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:17:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:17:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-UY5jjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:17:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:17:37 INFO - Running setup.py install for manifestparser 01:17:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:17:37 INFO - Running setup.py install for mozcrash 01:17:38 INFO - Running setup.py install for mozdebug 01:17:38 INFO - Running setup.py install for mozdevice 01:17:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Running setup.py install for mozfile 01:17:38 INFO - Running setup.py install for mozhttpd 01:17:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Running setup.py install for mozinfo 01:17:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Running setup.py install for mozInstall 01:17:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:17:38 INFO - Running setup.py install for mozleak 01:17:39 INFO - Running setup.py install for mozlog 01:17:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Running setup.py install for moznetwork 01:17:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Running setup.py install for mozprocess 01:17:39 INFO - Running setup.py install for mozprofile 01:17:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Running setup.py install for mozrunner 01:17:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:17:39 INFO - Running setup.py install for mozscreenshot 01:17:39 INFO - Running setup.py install for moztest 01:17:40 INFO - Running setup.py install for mozversion 01:17:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:17:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:17:40 INFO - Cleaning up... 01:17:40 INFO - Return code: 0 01:17:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17:40 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')]} 01:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:40 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')]} 01:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:40 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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:40 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 01:17:40 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 01:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:40 INFO - 'HOME': '/Users/cltbld', 01:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:40 INFO - 'LOGNAME': 'cltbld', 01:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:40 INFO - 'MOZ_NO_REMOTE': '1', 01:17:40 INFO - 'NO_EM_RESTART': '1', 01:17:40 INFO - 'PAGER': '/bin/cat', 01:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:40 INFO - 'PWD': '/builds/slave/test', 01:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:40 INFO - 'SHELL': '/bin/bash', 01:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:40 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:40 INFO - 'USER': 'cltbld', 01:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:40 INFO - 'XPC_FLAGS': '0x0', 01:17:40 INFO - 'XPC_SERVICE_NAME': '0', 01:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:17:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-iIelP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:17:40 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)) 01:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:17:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EyT8qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:17:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-S8N2j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:17:40 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)) 01:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:17:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-dtmfLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-b7GXDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-98RDSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Mm7gvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-BvuKqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8y2blD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VxuDhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-UercY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-C5sTlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-031dgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:17:41 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)) 01:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:17:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-5xZozL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uDeOUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:42 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)) 01:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:17:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8k3Vv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:17:42 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)) 01:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:17:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-rB4bJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:17:42 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)) 01:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:17:42 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)) 01:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:17:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:17:42 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 01:17:42 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 01:17:42 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 01:17:42 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 01:17:42 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 01:17:42 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 01:17:44 INFO - Downloading blessings-1.6.tar.gz 01:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:17:45 INFO - Installing collected packages: blessings 01:17:45 INFO - Running setup.py install for blessings 01:17:45 INFO - Successfully installed blessings 01:17:45 INFO - Cleaning up... 01:17:45 INFO - Return code: 0 01:17:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:17:45 INFO - Reading from file tmpfile_stdout 01:17:45 INFO - Current package versions: 01:17:45 INFO - blessings == 1.6 01:17:45 INFO - blobuploader == 1.2.4 01:17:45 INFO - docopt == 0.6.1 01:17:45 INFO - functools32 == 3.2.3-2 01:17:45 INFO - jsonschema == 2.5.1 01:17:45 INFO - manifestparser == 1.1 01:17:45 INFO - mozInstall == 1.12 01:17:45 INFO - mozcrash == 0.17 01:17:45 INFO - mozdebug == 0.1 01:17:45 INFO - mozdevice == 0.48 01:17:45 INFO - mozfile == 1.2 01:17:45 INFO - mozhttpd == 0.7 01:17:45 INFO - mozinfo == 0.9 01:17:45 INFO - mozleak == 0.1 01:17:45 INFO - mozlog == 3.4 01:17:45 INFO - moznetwork == 0.27 01:17:45 INFO - mozprocess == 0.23 01:17:45 INFO - mozprofile == 0.28 01:17:45 INFO - mozrunner == 6.12 01:17:45 INFO - mozscreenshot == 0.1 01:17:45 INFO - mozsystemmonitor == 0.3 01:17:45 INFO - moztest == 0.7 01:17:45 INFO - mozversion == 1.4 01:17:45 INFO - psutil == 3.1.1 01:17:45 INFO - requests == 1.2.3 01:17:45 INFO - wsgiref == 0.1.2 01:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17:45 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')]} 01:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 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')]} 01:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:45 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 01:17:45 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 01:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:45 INFO - 'HOME': '/Users/cltbld', 01:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:45 INFO - 'LOGNAME': 'cltbld', 01:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:45 INFO - 'MOZ_NO_REMOTE': '1', 01:17:45 INFO - 'NO_EM_RESTART': '1', 01:17:45 INFO - 'PAGER': '/bin/cat', 01:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:45 INFO - 'PWD': '/builds/slave/test', 01:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:45 INFO - 'SHELL': '/bin/bash', 01:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:45 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:45 INFO - 'USER': 'cltbld', 01:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:45 INFO - 'XPC_FLAGS': '0x0', 01:17:45 INFO - 'XPC_SERVICE_NAME': '0', 01:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 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)) 01:17:45 INFO - Cleaning up... 01:17:45 INFO - Return code: 0 01:17:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:17:45 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')]} 01:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 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')]} 01:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 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 0x1096f0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109666570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd09bdb7cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, '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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:17:45 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 01:17:45 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 01:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:45 INFO - 'HOME': '/Users/cltbld', 01:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:45 INFO - 'LOGNAME': 'cltbld', 01:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:45 INFO - 'MOZ_NO_REMOTE': '1', 01:17:45 INFO - 'NO_EM_RESTART': '1', 01:17:45 INFO - 'PAGER': '/bin/cat', 01:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:45 INFO - 'PWD': '/builds/slave/test', 01:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:45 INFO - 'SHELL': '/bin/bash', 01:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:17:45 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:45 INFO - 'USER': 'cltbld', 01:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:45 INFO - 'XPC_FLAGS': '0x0', 01:17:45 INFO - 'XPC_SERVICE_NAME': '0', 01:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:17:46 INFO - Cleaning up... 01:17:46 INFO - Return code: 0 01:17:46 INFO - Running post-action listener: _resource_record_post_action 01:17:46 INFO - Running post-action listener: _start_resource_monitoring 01:17:46 INFO - Starting resource monitoring. 01:17:46 INFO - [mozharness: 2016-10-19 08:17:46.164326Z] Finished create-virtualenv step (success) 01:17:46 INFO - [mozharness: 2016-10-19 08:17:46.165288Z] Running install step. 01:17:46 INFO - Running pre-action listener: _resource_record_pre_action 01:17:46 INFO - Running main action method: install 01:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:17:46 INFO - Reading from file tmpfile_stdout 01:17:46 INFO - Detecting whether we're running mozinstall >=1.0... 01:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:17:46 INFO - Reading from file tmpfile_stdout 01:17:46 INFO - Output received: 01:17:46 INFO - Usage: mozinstall [options] installer 01:17:46 INFO - Options: 01:17:46 INFO - -h, --help show this help message and exit 01:17:46 INFO - -d DEST, --destination=DEST 01:17:46 INFO - Directory to install application into. [default: 01:17:46 INFO - "/builds/slave/test"] 01:17:46 INFO - --app=APP Application being installed. [default: firefox] 01:17:46 INFO - mkdir: /builds/slave/test/build/application 01:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:18:21 INFO - Reading from file tmpfile_stdout 01:18:21 INFO - Output received: 01:18:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:18:21 INFO - Running post-action listener: _resource_record_post_action 01:18:21 INFO - [mozharness: 2016-10-19 08:18:21.650786Z] Finished install step (success) 01:18:21 INFO - [mozharness: 2016-10-19 08:18:21.650923Z] Running run-tests step. 01:18:21 INFO - Running pre-action listener: _resource_record_pre_action 01:18:21 INFO - Running main action method: run_tests 01:18:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:18:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:18:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:18:21 INFO - 2016-10-19 01:18:21.665 screenresolution[1637:7409] starting screenresolution argv=screenresolution get 01:18:21 INFO - 2016-10-19 01:18:21.668 screenresolution[1637:7409] Display 0: 1600x1200x32@60 01:18:21 INFO - 2016-10-19 01:18:21.673 screenresolution[1638:7411] starting screenresolution argv=screenresolution list 01:18:21 INFO - Available Modes on Display 0 01:18:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:18:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:18:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:18:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:18:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:18:21 INFO - Intel Iris: 01:18:21 INFO - Chipset Model: Intel Iris 01:18:21 INFO - Type: GPU 01:18:21 INFO - Bus: Built-In 01:18:21 INFO - VRAM (Dynamic, Max): 1536 MB 01:18:21 INFO - Vendor: Intel (0x8086) 01:18:21 INFO - Device ID: 0x0a2e 01:18:21 INFO - Revision ID: 0x0009 01:18:21 INFO - Displays: 01:18:21 INFO - Display: 01:18:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:18:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:18:21 INFO - Display Serial Number: 1600x1200 60 01:18:21 INFO - Main Display: Yes 01:18:21 INFO - Mirror: Off 01:18:21 INFO - Online: Yes 01:18:21 INFO - Rotation: Supported 01:18:21 INFO - Return code: 0 01:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:18:21 INFO - Python 2.7.3 01:18:21 INFO - Return code: 0 01:18:21 INFO - grabbing minidump binary from tooltool 01:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:18:21 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 0x109504d30>, '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 0x10932adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094fcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:18:21 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 01:18:21 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 01:18:21 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 01:18:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:18:22 INFO - Return code: 0 01:18:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:18:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:18:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:18:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:18:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 01:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 01:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GVnCqMXSHw/Render', 01:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:18:22 INFO - 'HOME': '/Users/cltbld', 01:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:18:22 INFO - 'LOGNAME': 'cltbld', 01:18:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:18:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:22 INFO - 'MOZ_NO_REMOTE': '1', 01:18:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:18:22 INFO - 'NO_EM_RESTART': '1', 01:18:22 INFO - 'PAGER': '/bin/cat', 01:18:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:22 INFO - 'PWD': '/builds/slave/test', 01:18:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:18:22 INFO - 'SHELL': '/bin/bash', 01:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LO5a1ByKKK/Listeners', 01:18:22 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:18:22 INFO - 'USER': 'cltbld', 01:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:22 INFO - 'XPC_FLAGS': '0x0', 01:18:22 INFO - 'XPC_SERVICE_NAME': '0', 01:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:18:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 01:18:22 INFO - mozversion application_buildid: 20161018221023 01:18:22 INFO - mozversion application_changeset: e2511b7cd8d98cb9b4df844d439b9550bb357afc 01:18:22 INFO - mozversion application_display_name: Nightly 01:18:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:18:22 INFO - mozversion application_name: Firefox 01:18:22 INFO - mozversion application_remotingname: firefox 01:18:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:18:22 INFO - mozversion application_vendor: Mozilla 01:18:22 INFO - mozversion application_version: 52.0a1 01:18:22 INFO - mozversion platform_buildid: 20161018221023 01:18:22 INFO - mozversion platform_changeset: e2511b7cd8d98cb9b4df844d439b9550bb357afc 01:18:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:18:22 INFO - mozversion platform_version: 52.0a1 01:18:22 INFO - using testdate: 1476865102 01:18:22 INFO - actual date: 1476865102 01:18:22 INFO - starting webserver on 'localhost:49277' 01:18:22 INFO - SUITE-START | Running 2 tests 01:18:22 INFO - TEST-START | dromaeo_css 01:18:22 INFO - operating with platform_type : mac_ 01:18:22 INFO - Initialising browser for dromaeo_css test... 01:18:22 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpfDW5O_/profile http://localhost:49277/getInfo.html) 01:18:25 INFO - PROCESS | 1648 | 2016-10-19 01:18:25.127 plugin-container[1650:7588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 01:18:25 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:25 INFO - PROCESS | 1648 | 2016-10-19 01:18:25.129 plugin-container[1650:7588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9413, name = 'com.apple.CFPasteboardClient' 01:18:25 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:25 INFO - PROCESS | 1648 | 2016-10-19 01:18:25.130 plugin-container[1650:7588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:18:25 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 01:18:25 INFO - PROCESS | 1648 | colorDepth:24 01:18:25 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 01:18:25 INFO - PROCESS | 1648 | __metrics 01:18:25 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 01:18:26 INFO - TEST-INFO | 1648: exit 0 01:18:26 INFO - Browser initialized. 01:18:26 INFO - Running cycle 1/1 for dromaeo_css test... 01:18:26 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpfDW5O_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:18:28 INFO - PROCESS | 1651 | 2016-10-19 01:18:28.296 plugin-container[1652:7709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 01:18:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:28 INFO - PROCESS | 1651 | 2016-10-19 01:18:28.297 plugin-container[1652:7709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 01:18:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:18:28 INFO - PROCESS | 1651 | 2016-10-19 01:18:28.297 plugin-container[1652:7709] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:18:38 INFO - PROCESS | 1651 | RSS: Main: 236720128 01:18:38 INFO - PROCESS | 1651 | 01:19:27 INFO - PROCESS | 1651 | 1476865167678 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] 01:21:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 01:21:42 INFO - PROCESS | 1651 | RSS: Main: 251449344 01:21:42 INFO - PROCESS | 1651 | 01:24:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 01:24:45 INFO - PROCESS | 1651 | RSS: Main: 263872512 01:24:45 INFO - PROCESS | 1651 | 01:27:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 01:27:47 INFO - PROCESS | 1651 | RSS: Main: 268570624 01:27:47 INFO - PROCESS | 1651 | 01:30:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 01:30:50 INFO - PROCESS | 1651 | RSS: Main: 268148736 01:30:50 INFO - PROCESS | 1651 | 01:33:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 01:33:53 INFO - PROCESS | 1651 | RSS: Main: 274563072 01:33:53 INFO - PROCESS | 1651 | 01:36:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 01:36:57 INFO - PROCESS | 1651 | RSS: Main: 277393408 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | __start_tp_report 01:36:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 01:36:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 01:36:57 INFO - PROCESS | 1651 | |i|pagename|runs| 01:36:57 INFO - PROCESS | 1651 | |0;dojo.html;2690.4678452067724;2883.9567406488904;3065.386922615476;3113.828739419332;3404.9148771280697;5727.312722473299;5761.222234998274;5761.827145185641;5795.391483894183;5805.361410244875;5749.626274292168;5779.682117483537;5781.017227071397;5808.651480911149;5865.178874957509;5166.664166829163;5227.973860130694;5232.189010703328;5294.549963253128;5304.92042619361;5088.135017047092;5230.555402790767;5233.528982391584;5314.601404894629;5351.25082488452;5109.029284435946;5146.047981123485;5200.427952925175;5201.427842937273;5208.947910520884;5150.613703972217;5165.992631436871;5173.715445650488;5242.947570524284;5245;5224.791008359661;5225.973870130625;5246.422893481714;5257.316548848664;5260.9736951315;5437.8368648940595;5618;5756.222909907151;5762.855928601776;5763.625364351304;6192.969035154841;6219.751209951596;6222.844428889269;6259.217597800275;6283.654399008053;5401.78392864285;6148.231471066117;6202.255729312466;6250.874982500324;6255.374462553754;5817.912731309034;5834.679092649945;5879.470847623735;5891.675957822361;5967.075103358986;6244.12582238487;6267.4986001120715;6281.685915704196;6288.308286088527;6310.495160387158;6035.155078289043;6220;6227.003679411363;6231.3145553989025;6233.283172435137;6146.7399183167345;6159.876802463926;6165.84585385369;6222.937770622236;6277.121203031579;6125.877482450326;6180.381961803783;6185.567010309325;6258.468030217483;6259.0924315973925;6198.938010619926;6211.409916057967;6241.563090583706;6260.248770147611;6277.089821975788;4880.829170978998;4914.041761856403;4927.384076990376;4935.3090567320605;4940;4438.312061630452;4443.800028998703;4446.066326071496;4450.35469856869;4500;5912.17229587858;5984.670843103671;6003.369646187088;6010.939890601126;6017.037274036221;6149.354317796567;6150.831342044997;6208.658523781145;6214.8135555933395;6226.159468471789;5841.1238314253205;5856.912146317809;5862.794802181903;5864.501517370986;5873.442023007893;5437.374701909331;5440.972795136078;5442.455754424526;5458.399576046631;5466.480684334982;6227.750889964351;6230.626162430269;6261.123442718023;6270.247570291503;6272.341404152498;6162.907556386747;6193.597416167935;6227.003679411454;6229.813105606839;6229.875402491834;6346.555741098079;6359.459445947146;6366.745330186741;6382.425581697669;6492.577982431127;4727.361806156263;5105.785461407856;6209.316975132641;6225;6233.844153896189;4511.593956543796;5155.43290238066;5159.819406320761;5329.760160792696;6216.751329946932;5041.2438134280155;5136.280920671034;6191.133241346125;6201.348858369806;6210.503159747119;5252.474752524717;6068.878622427438;6175.197224360804;6182.876342473215;6219.689015549295;5737.8565535861935;5762.510186634183;5923.28920529522;5973.641581505123;6029.547783916311;6051.818445446644;6080.270367555921;6111.5110791137495;6180.258368995749;6183.227096612924;5005.4744251853035;6021.006533921854;6021.096835474714;6026.9839773331805;6047.637141771508 01:36:57 INFO - PROCESS | 1651 | |1;ext.html;468.67427138139004;477.55587303807465;501.7541404711692;524.2764984321635;539.5602583894129;38370.23259534811;40599.797001015024;41068.38397424041;41099.38350924738;41142.588574114285;36762.44856327157;40376.39435408471;41511.79244103775;42041.57958420415;42133;9399.791049103473;9560;9562.474063926482;9599.136077753;9599.808003839918;9456.57445414958;9528.618855245817;9577.425354478719;9593.136617704406;9613.038696130401;9456.432614043179;9575.233981281515;9579.52102394881;9598.42409455435;9619.855702164474;9231.384507711191;9427.340086193935;9496.715098547054;9564.282678799083;9578.377405468693;8534;9402.15380615741;9529.857052144223;9561.474118923456;9569.3779904306;39746.807595772174;39882.002949926195;39888.20223595512;40028;40167;3143.0256620447735;3242.7515406568405;3337.5160601712614;3424.503447000196;3529.294141171776;25425.872870635714;30584.8470757647;30738.69261307403;31521.84239078813;31875;3144.795588286777;3212.6305474870223;3213.469777486712;3233.401820663185;3260.9347813043605;471062.6446867778;476862.9999999965;478866;479762;480549;65722;67070.66464667645;67103;67280;67303;71017.93473097908;71104;71340.64329678318;71519;71677.64161179161;16681.666366672842;16815.495535133967;16843;16905.408310709066;16911.91544042272;12535.119732040266;12862.485500579873;12937.805932911015;12978.961683065332;12983.610491685264;16478.75281870773;19267;20539.4865128373;20563.79436205619;20722.06750696192;8558.400911936105;8658.87011694825;8680.392372533863;8794.648214071494;8806.251468625238;766.6550052476297;885.530668745553;927.2581934452411;1000.3597697473623;1008.606643409071;24704.752952470604;25693.743062569134;28872.855635721688;30074.39903665952;31344.1193528389;1032.4476405123223;1050.7443604892233;1063.4576366053213;1119.7368618374676;1205.355135002787;15332.463363782213;15864.44474443389;16015.919920400323;16041.919790400972;16329.42847000373;663479;667575;670479;670939;671537;57909;57955.71022144862;58019;58577;58643.4135658638;1343.6909510812372;1389.1595584671331;1405.292569528008;1407.7606806842657;1410.88007519362;26589.069382571517;29473.67368468467;30217.848910755307;30357.78568857221;30598.235044123183;51888;52592;52685;52714.73642631762;52716;601456;601983;605875;606218;606722;42690.35964460474;42822.14355712806;43138.78430607827;43604.56395436005;43690;46961;47461.28808067951;48324.7583762093;48569.27146092882;48756.5124348752;35368.93893183208;38839.22321553496;39665.40501892532;40115.398269025405;41502.792486037375;39083;39277.2144557113;39321.60678393179;39357.62248321295;39710.808675739776;33110.668893311726;33638.495422568194;36425.817870910476;38884.6111538881;39044.804775975936;32458;33503.32993340071;35337.82331088328;35412.60848661433;36156.63843361638;25419;25554;25787.87106064458;25977;26314 01:36:57 INFO - PROCESS | 1651 | |2;jquery.html;325.2746375582451;377.86208034067556;378.9317922773901;386.3779315302361;404.41158114942743;16963.897346672456;18209.544761380956;19836;20523.794762052392;20716.378508644728;20081.698774518394;20266.797332026676;20677.793222067776;20778.376648700563;20835.895820520913;5042.899142017167;5106;5656.717164141797;6119.5104391648565;6135.110408990694;5149.588032957355;5845.889281036611;5886.793962211324;5932.673702946337;6062.878742425149;5661.5187709044785;5765.509931655814;5974.103884417329;5981.102834574827;6027.758889644409;5739.655620662753;6014.639121652693;6051.243594550681;6060.151578778975;6070.908936365936;5023.372078490189;5327.600429967768;5334.1732031535175;5434.972825135849;5908.113782932552;12963.67590810228;13026.80459793104;13220.603381898558;20796.376108716762;23947.281581552583;2141.389703934387;2305.8155347572156;2339.415146213447;2403.9158629447884;2552.438463538019;9801.215902727765;10050.849237261447;10314.7421314467;12372.13395062346;14526.782098268535;1859.6373707127116;1917.9834687615587;1922.7981061988569;1925.643755905148;1945.5914258005835;115514;118606;119319.99999999913;119612.40193799062;120673.39663301715;9971.850422243671;10478.056974872297;10780.892191078146;11163.720906977229;11422.600208992646;12692.80960785589;14051.648708782363;14292.785113265281;14493.492727754478;14510.492132775302;7863.685452581947;8178.631961561625;8209.630566624517;8215.260626543639;8287.709930152416;7092.219855815856;7692.653830577638;7815.218478152253;7935.76192714208;8116.35069194463;4520.660950428731;4523.914260577438;4530.97734511332;4545.636349092065;4580.427446569179;1674.5227610131053;1733.3066773290532;1964.4204959536971;2003.779584245761;2045.5806559655234;760.7983884270673;880.907504711996;906.0395980260945;923.5936188077225;1004.2568499310457;16790.664186716196;19505.60988780245;21006.789932100484;21039.263625773332;21171.364859054254;902.2240872849344;907.7685190276569;913.1872633356318;939.6053657463882;953.9236861051099;10891.292066015842;11204.887951120547;11205;11232.438378081064;11270;548013;556832;564175;564739;571571;103576;120192.39903800425;120589;121156;122789;965.4931161140457;982.5824024789435;990.1336330710484;1004.8894621591619;1008.4503945349802;17870.017149056406;17877.73183402224;18313.542161446072;18704.7194292083;18721.25114995385;36692;48600;49065.754671226416;53067.4693253078;53726.462735372144;46303.07393852172;47438.76280618575;59740.80518389695;69822;77510.449791005;6792.694328755317;7819.413543984338;8008.799780005546;8084.636191371284;8300.626471808662;10688.465576721288;11642.76714465689;11665.775093615388;12562.566301603221;12970.22178669283;10686;10859.83710244331;11009.559617615527;11135.443227838738;11249.268797528135;10601.575936962743;10818.29681070728;10894.61868834587;10957.342559446459;11178.608748693756;11120;11283.928026837437;11353.37556434404;11388;11598.130140239684;6369.235691717024;6866.965665171642;7310.817229569303;7334.926650733424;8345.457545259538;9322.720318390458;10803.783924321313;11043.834342484708;11210;11447.966288933272 01:36:57 INFO - PROCESS | 1651 | |3;mootools.html;1491.5749011531714;1523.398257688213;1556.1907807939872;1887.7829049659292;1955.2472298165212;8053.355731541477;8335.874961875577;8479.491230526164;9004.414713043663;9023.639054437823;8537.743867683965;8828.77928051799;8863.822723545542;8892.466452012884;8897;2342.1099982006813;2345.3433038749176;2372.9288121356385;2384.1655420602738;2386.737458879522;2341.262502311775;2364.657124716915;2378.250850981935;2393.940151496218;2396.25716028031;1950.9512262193416;2111.9260825871097;2366.6331718583647;2381.9761802382013;2407.987960060206;2356.552255071531;2365.7989070928993;2368.2066507719933;2381.1665916929023;2381.8094552435937;2350.247920665388;2368.2066507719933;2373;2378.5124049569795;2384.2966324934014;8897.065808090187;8952.8657070144;8959.641614335418;9018.835479484942;9024.684136055272;5275.235090911835;5292.153255479105;5405.081136206815;5447.891042179174;5483.040467918138;3539.876104336336;3628.6734193922675;3684.6683798458266;3720.7303544837628;3726.217494326195;1047.1361127070197;1075.1828610256184;1098.4123493930788;1109.916756243277;1111.0833562311125;11522.539098436137;11661.416929153593;11804.940975295154;11816.645500634995;11824.645260642195;4555.567221113989;4663.790129444184;4845.103655823684;4857.611391088775;4878.975605121952;8280.461769984931;9838.212942964547;10058.446785426871;10070.597176112873;10076.949615251877;5454.61817672767;5467.972660136754;5473.863153421196;5474.2609747683555;5475.0418676731415;4440.178566965122;4639.30410438437;4803.663743537919;4971.701697898137;4984.177610694267;3875.1280961783937;3930;3945.980270098631;4024.094578719823;4071.5114186297833;1155.4973586489737;1303.9869601304056;1311.6917524381763;1325.8541560428346;1328.920264784116;746.6229554075157;790.7904405332591;879.4986857491297;899.5097671768771;901.4636291406603;5420.891582168335;5572.888542229132;5648.689322087324;5660.320761508563;5770.307563092372;838.4801423117706;851.7104184577273;911.3438324406417;931.6273490603812;967.2890425537224;3753.3431649461236;3778.962210377861;3783.2055268393947;3856.652901238902;3864.7294689371474;10391.688249352532;10870.836937445945;10885.673429797118;10886.618968336228;10907.29102608328;10766.623168189075;10859.34843909368;10867;10867.45662716861;10892.673219803419;8161.387895907831;8187.1403502631365;8270.387274481418;8338.082810890797;8442.62008209632;5516.972415137899;5628.634138780966;5659.858503537444;5682.346530149086;5728.570357223141;8708;9378.78075850135;9928.907820139635;10086.243531735296;10218.335808172742;10151.187904967437;10463.68608941733;10468.528916198942;10478.790424191626;10488.632897848538;6575.704093315908;6787.762428314985;6935;7105.253948335557;7223.9886415902865;8323.791905202419;8618.482891026559;8636.524991125298;8673.522956237468;8694.695685651224;7285.526440781332;7455.962720186364;7564.659590318339;7692.499987500794;7710.498817577064;7378.077740282465;7397.852042959003;7443.92556074454;7510;7582.052243469567;6955.478339124687;7095.467839911924;7101.822454438474;7102.289771022856;7111.466640002124;4789.404026033907;4915.164422048332;4937.9259311109645;4943.233798761198;4961.776720047535;4840.7337596432535;4854.563089321978;4860.295257187759;4920.753962301942;5107.336046313955 01:36:57 INFO - PROCESS | 1651 | |4;prototype.html;265.96941351744556;293.9353342264701;307.9498908721261;307.9645840728316;313.73019203485;4846.321514987904;4998.8502644391765;5414.648047876885;5633.380328163904;5778.393268706783;5394.244805727201;5546.306711661043;5598.608097433182;5627.212190293362;5637.436256374361;4026.9999999999927;4240.787960601973;4249.958760103779;4257.808398622069;4260.190563792885;3405.5572775539144;3792.8949433064777;3876.5269197704974;4228.6617070634275;4229.471316085489;3142.858571364294;3809.5714107209797;4139.965008747813;4199.496060472732;4233.449651545295;3352.396568617648;4137.524184718751;4150.667946564267;4216.662666986634;4244.766537840417;3132.342208136294;3681.742278040538;3702.129999450127;3702.6673195886688;3717.6096509866616;5227.738613069331;5342.973285133549;5449.038346195366;5479.890402191973;5508;4855.393075865517;4877.317175595419;4879.975205206911;4909.239067944474;4979.377577802775;5501.807436739695;5510.531604813596;5771.192033115367;5929.584929054968;5938.545056461195;3385.356782211372;3590.317839610466;4043.6967227457762;4067.4003506896315;4077.3476243801147;5336.546393556514;5387.542058925035;5546.584006199551;6162.506999440034;6182.84542886427;3894.6494815466745;4127.752334859918;4133.8966525837095;4138.958610413918;4139.834406623761;4049.4330793688905;4049.704094689671;4100.487439070116;4146.357314616239;4155.480564929384;4888.924436623937;4896.020795840846;4909.705417674951;4922.310876477296;4924.852254432372;5105.540501354896;5226.425093239741;5330.2804121443105;5692.743826527816;5899.46904778572;1202.6271855724754;1225.5159212111166;1279.2516377918835;1398.4056775870215;1417.3417101990697;937.803061357109;942.3403617467663;956.2588993529931;971.1745016735719;980;4646.651501137429;5562.3881373048935;5596.636218645856;5597.636153650081;5600.943990560042;5016.54851063406;5161.174212126117;5994.340622531518;6086.330503644595;6179.598326108789;5317.973410132925;5362.839114826562;5496.422875598005;5573.721313934287;5594.916076258859;2536.4800215955684;2727.345437095083;2743.0387273099827;2748.6564179477564;2763.5025695374625;5283.471652834686;5300.814471493479;5311.6455303897255;5325.3875804282225;5336.493033161843;4714.599259062949;5270.364817591204;6354.618722876549;6380.777707257898;6400.551961362753;6459.741610335722;6475;6499.765044641503;6499.805005849832;6510.837229069311;6027.427394397525;6171.765646870554;6308.810735677937;6309.747610095545;6380.522615174834;5065.772040258271;5641.520470759867;6148.538859585459;6156.384361563987;6179.598326108789;5624.381318055092;5693.957290323917;6178.907316390168;6403.487720982217;6511.413972742476;3543.7696549724187;3621.6378362163573;3962.980185099056;3996.3015718319016;4009.2382447334912;5914.290285165807;6148.569600127948;6218.440340369389;6254.437100660963;6258.968705156445;5234.895302094013;5802.912956305574;5885.882282354414;5978.671173085522;6043.456088952015;4457.707264893274;5016.097102521435;5552.333719953632;5945.554083443672;5959.970200148971;4742.857714268577;5003.773320872177;5526.530244929226;5529.557635389239;5843.970780146072;5596.3284405871555;5810.128480727924;6055.2733671957885;6080.695965201634;6081.452669259788;5632.5493960482245;5943.791967281124;5950.434708702666;5976.820695379146;6016.458518733335 01:36:57 INFO - PROCESS | 1651 | |5;yui.html;579.4582065768507;681.3356976947474;728.2753660108192;758.1281526244818;862.6635612111278;14980.775288370669;16008.119553424556;16096.1951902405;16139.19304034798;16148.919255403736;13969.58091257264;14263.643408914782;14329.71340573188;14345.856541434583;14346.497872574462;859.4241857955185;878.8154487557621;880.9691660791874;881.2201201936268;882.1619461511558;694.6387878303279;711.7366574367466;850.056437354536;876.6537217798966;876.7545087375545;927.4852456886425;930.5393830054132;931.2177770672668;931.3015238571072;933;921.6497730862229;923.0308176414771;924.3021518753366;924.3344791749929;924.3344791749929;669.0699927101333;685.3797313431353;686.6909890549273;687.2749249541731;689.4691087862368;14621.92689036548;14746.11523308605;14809;14860.62848428787;14871.03338283008;12207.694807629792;14428.701416872533;14435.494757683537;14482.34829432678;14485.71028579433;11799.587014454539;11829.526818927232;11841.5855445059;11893.345865977368;12251.387430628432;3751.381022131345;3787.3561494545993;3831.1379939513663;3844.7696737044157;3948.6643635290748;104113.43829842558;104809.47595261974;105286;105560;107929.46035269773;11133.665990020312;11174.21780475375;11254.099672026217;11254.662360129209;11509.597164099216;23194.76805231926;23248.4187895304;23286.417839554146;23296.3011109667;23312.76687233106;7447.702091916371;7759.888690121158;7778.688852445839;7992.280694737386;8140.185981401931;4288.48538175421;4424.756638384855;4458.888527786831;4488.9102217956115;4604.884877878013;3476.8435420406145;3580.8925732228076;3626.419772836386;3627.220147668264;3634.509341238952;585.7891159182691;585.8125399872;651.739304278283;665.3944910131756;678.0304165044034;560.9523190528769;569.7122952908837;613.352912677121;624.7750809708501;627.6861569215392;8785.209331160098;9004.729858104267;9101.362904596615;9155.313351498664;9162.129597688208;498.03381440006274;616.2574098211644;629.6820105846609;634.4004915354946;641.3618449642636;3372.9831350843087;3850.9229815404096;3945.033466800652;4437.733735975852;4607.262837945979;148103.51896480896;158765;161404;163931.360686394;164093;75911;87209;96127.51936240414;109770.45114774535;122338;84077.17189694993;104774;108916;110639;111030;9521.285903557122;9815.36200146988;9872.259580531632;10005.249606279413;10035.24735644844;43083.78458107689;57481;58368;58675.41324586699;60928.69535652471;86104.70842937236;88141.5592922057;89060.55469722609;91998.24005279853;93479;13018.2839943804;13026.934865325613;13148.802767958297;13227.470901163846;13336.19982801035;14853.034552754036;14933.62665933317;15061.548153555412;15359.308831102404;15478.5356439307;13345.532906348231;13586.980976427005;13795.931020344835;13814.861851381358;14264.928675356558;13417.530386436429;13597.116187447784;13699.315034248048;13709.314534273446;13984.951128665594;13076.869231307564;13146.34268286601;13246.20522768637;13667.79498307546;13727.29363071945;11146.665600032013;11200.495977680861;11269.380184089954;11306.547738090385;11742.882571174521;9804.607815687292;10528.473576321307;10782.946085269523;11574.247673901169;11646 01:36:57 INFO - PROCESS | 1651 | __end_tp_report 01:36:57 INFO - PROCESS | 1651 | __start_cc_report 01:36:57 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,361 01:36:57 INFO - PROCESS | 1651 | __end_cc_report 01:36:57 INFO - PROCESS | 1651 | __startTimestamp1476866217082__endTimestamp 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | ------- Summary: start ------- 01:36:57 INFO - PROCESS | 1651 | Number of tests: 6 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5693.55 Median:5912.17 stddev:692.94 (11.7%) stddev-sans-first:653.79 01:36:57 INFO - PROCESS | 1651 | Values: 2690.5 2884.0 3065.4 3113.8 3404.9 5727.3 5761.2 5761.8 5795.4 5805.4 5749.6 5779.7 5781.0 5808.7 5865.2 5166.7 5228.0 5232.2 5294.5 5304.9 5088.1 5230.6 5233.5 5314.6 5351.3 5109.0 5146.0 5200.4 5201.4 5208.9 5150.6 5166.0 5173.7 5242.9 5245.0 5224.8 5226.0 5246.4 5257.3 5261.0 5437.8 5618.0 5756.2 5762.9 5763.6 6193.0 6219.8 6222.8 6259.2 6283.7 5401.8 6148.2 6202.3 6250.9 6255.4 5817.9 5834.7 5879.5 5891.7 5967.1 6244.1 6267.5 6281.7 6288.3 6310.5 6035.2 6220.0 6227.0 6231.3 6233.3 6146.7 6159.9 6165.8 6222.9 6277.1 6125.9 6180.4 6185.6 6258.5 6259.1 6198.9 6211.4 6241.6 6260.2 6277.1 4880.8 4914.0 4927.4 4935.3 4940.0 4438.3 4443.8 4446.1 4450.4 4500.0 5912.2 5984.7 6003.4 6010.9 6017.0 6149.4 6150.8 6208.7 6214.8 6226.2 5841.1 5856.9 5862.8 5864.5 5873.4 5437.4 5441.0 5442.5 5458.4 5466.5 6227.8 6230.6 6261.1 6270.2 6272.3 6162.9 6193.6 6227.0 6229.8 6229.9 6346.6 6359.5 6366.7 6382.4 6492.6 4727.4 5105.8 6209.3 6225.0 6233.8 4511.6 5155.4 5159.8 5329.8 6216.8 5041.2 5136.3 6191.1 6201.3 6210.5 5252.5 6068.9 6175.2 6182.9 6219.7 5737.9 5762.5 5923.3 5973.6 6029.5 6051.8 6080.3 6111.5 6180.3 6183.2 5005.5 6021.0 6021.1 6027.0 6047.6 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:72394.30 Median:41083.88 stddev:157421.63 (383.2%) stddev-sans-first:157770.61 01:36:57 INFO - PROCESS | 1651 | Values: 468.7 477.6 501.8 524.3 539.6 38370.2 40599.8 41068.4 41099.4 41142.6 36762.4 40376.4 41511.8 42041.6 42133.0 9399.8 9560.0 9562.5 9599.1 9599.8 9456.6 9528.6 9577.4 9593.1 9613.0 9456.4 9575.2 9579.5 9598.4 9619.9 9231.4 9427.3 9496.7 9564.3 9578.4 8534.0 9402.2 9529.9 9561.5 9569.4 39746.8 39882.0 39888.2 40028.0 40167.0 3143.0 3242.8 3337.5 3424.5 3529.3 25425.9 30584.8 30738.7 31521.8 31875.0 3144.8 3212.6 3213.5 3233.4 3260.9 471062.6 476863.0 478866.0 479762.0 480549.0 65722.0 67070.7 67103.0 67280.0 67303.0 71017.9 71104.0 71340.6 71519.0 71677.6 16681.7 16815.5 16843.0 16905.4 16911.9 12535.1 12862.5 12937.8 12979.0 12983.6 16478.8 19267.0 20539.5 20563.8 20722.1 8558.4 8658.9 8680.4 8794.6 8806.3 766.7 885.5 927.3 1000.4 1008.6 24704.8 25693.7 28872.9 30074.4 31344.1 1032.4 1050.7 1063.5 1119.7 1205.4 15332.5 15864.4 16015.9 16041.9 16329.4 663479.0 667575.0 670479.0 670939.0 671537.0 57909.0 57955.7 58019.0 58577.0 58643.4 1343.7 1389.2 1405.3 1407.8 1410.9 26589.1 29473.7 30217.8 30357.8 30598.2 51888.0 52592.0 52685.0 52714.7 52716.0 601456.0 601983.0 605875.0 606218.0 606722.0 42690.4 42822.1 43138.8 43604.6 43690.0 46961.0 47461.3 48324.8 48569.3 48756.5 35368.9 38839.2 39665.4 40115.4 41502.8 39083.0 39277.2 39321.6 39357.6 39710.8 33110.7 33638.5 36425.8 38884.6 39044.8 32458.0 33503.3 35337.8 35412.6 36156.6 25419.0 25554.0 25787.9 25977.0 26314.0 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:32703.88 Median:2322.62 stddev:93647.06 (4032.0%) stddev-sans-first:93878.20 01:36:57 INFO - PROCESS | 1651 | Values: 325.3 377.9 378.9 386.4 404.4 16963.9 18209.5 19836.0 20523.8 20716.4 20081.7 20266.8 20677.8 20778.4 20835.9 5042.9 5106.0 5656.7 6119.5 6135.1 5149.6 5845.9 5886.8 5932.7 6062.9 5661.5 5765.5 5974.1 5981.1 6027.8 5739.7 6014.6 6051.2 6060.2 6070.9 5023.4 5327.6 5334.2 5435.0 5908.1 12963.7 13026.8 13220.6 20796.4 23947.3 2141.4 2305.8 2339.4 2403.9 2552.4 9801.2 10050.8 10314.7 12372.1 14526.8 1859.6 1918.0 1922.8 1925.6 1945.6 115514.0 118606.0 119320.0 119612.4 120673.4 9971.9 10478.1 10780.9 11163.7 11422.6 12692.8 14051.6 14292.8 14493.5 14510.5 7863.7 8178.6 8209.6 8215.3 8287.7 7092.2 7692.7 7815.2 7935.8 8116.4 4520.7 4523.9 4531.0 4545.6 4580.4 1674.5 1733.3 1964.4 2003.8 2045.6 760.8 880.9 906.0 923.6 1004.3 16790.7 19505.6 21006.8 21039.3 21171.4 902.2 907.8 913.2 939.6 953.9 10891.3 11204.9 11205.0 11232.4 11270.0 548013.0 556832.0 564175.0 564739.0 571571.0 103576.0 120192.4 120589.0 121156.0 122789.0 965.5 982.6 990.1 1004.9 1008.5 17870.0 17877.7 18313.5 18704.7 18721.3 36692.0 48600.0 49065.8 53067.5 53726.5 46303.1 47438.8 59740.8 69822.0 77510.4 6792.7 7819.4 8008.8 8084.6 8300.6 10688.5 11642.8 11665.8 12562.6 12970.2 10686.0 10859.8 11009.6 11135.4 11249.3 10601.6 10818.3 10894.6 10957.3 11178.6 11120.0 11283.9 11353.4 11388.0 11598.1 6369.2 6867.0 7310.8 7334.9 8345.5 9322.7 10803.8 11043.8 11210.0 11448.0 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5715.44 Median:4869.64 stddev:3218.75 (66.1%) stddev-sans-first:3212.12 01:36:57 INFO - PROCESS | 1651 | Values: 1491.6 1523.4 1556.2 1887.8 1955.2 8053.4 8335.9 8479.5 9004.4 9023.6 8537.7 8828.8 8863.8 8892.5 8897.0 2342.1 2345.3 2372.9 2384.2 2386.7 2341.3 2364.7 2378.3 2393.9 2396.3 1951.0 2111.9 2366.6 2382.0 2408.0 2356.6 2365.8 2368.2 2381.2 2381.8 2350.2 2368.2 2373.0 2378.5 2384.3 8897.1 8952.9 8959.6 9018.8 9024.7 5275.2 5292.2 5405.1 5447.9 5483.0 3539.9 3628.7 3684.7 3720.7 3726.2 1047.1 1075.2 1098.4 1109.9 1111.1 11522.5 11661.4 11804.9 11816.6 11824.6 4555.6 4663.8 4845.1 4857.6 4879.0 8280.5 9838.2 10058.4 10070.6 10076.9 5454.6 5468.0 5473.9 5474.3 5475.0 4440.2 4639.3 4803.7 4971.7 4984.2 3875.1 3930.0 3946.0 4024.1 4071.5 1155.5 1304.0 1311.7 1325.9 1328.9 746.6 790.8 879.5 899.5 901.5 5420.9 5572.9 5648.7 5660.3 5770.3 838.5 851.7 911.3 931.6 967.3 3753.3 3779.0 3783.2 3856.7 3864.7 10391.7 10870.8 10885.7 10886.6 10907.3 10766.6 10859.3 10867.0 10867.5 10892.7 8161.4 8187.1 8270.4 8338.1 8442.6 5517.0 5628.6 5659.9 5682.3 5728.6 8708.0 9378.8 9928.9 10086.2 10218.3 10151.2 10463.7 10468.5 10478.8 10488.6 6575.7 6787.8 6935.0 7105.3 7224.0 8323.8 8618.5 8636.5 8673.5 8694.7 7285.5 7456.0 7564.7 7692.5 7710.5 7378.1 7397.9 7443.9 7510.0 7582.1 6955.5 7095.5 7101.8 7102.3 7111.5 4789.4 4915.2 4937.9 4943.2 4961.8 4840.7 4854.6 4860.3 4920.8 5107.3 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4738.40 Median:5327.83 stddev:1505.82 (28.3%) stddev-sans-first:1472.15 01:36:57 INFO - PROCESS | 1651 | Values: 266.0 293.9 307.9 308.0 313.7 4846.3 4998.9 5414.6 5633.4 5778.4 5394.2 5546.3 5598.6 5627.2 5637.4 4027.0 4240.8 4250.0 4257.8 4260.2 3405.6 3792.9 3876.5 4228.7 4229.5 3142.9 3809.6 4140.0 4199.5 4233.4 3352.4 4137.5 4150.7 4216.7 4244.8 3132.3 3681.7 3702.1 3702.7 3717.6 5227.7 5343.0 5449.0 5479.9 5508.0 4855.4 4877.3 4880.0 4909.2 4979.4 5501.8 5510.5 5771.2 5929.6 5938.5 3385.4 3590.3 4043.7 4067.4 4077.3 5336.5 5387.5 5546.6 6162.5 6182.8 3894.6 4127.8 4133.9 4139.0 4139.8 4049.4 4049.7 4100.5 4146.4 4155.5 4888.9 4896.0 4909.7 4922.3 4924.9 5105.5 5226.4 5330.3 5692.7 5899.5 1202.6 1225.5 1279.3 1398.4 1417.3 937.8 942.3 956.3 971.2 980.0 4646.7 5562.4 5596.6 5597.6 5600.9 5016.5 5161.2 5994.3 6086.3 6179.6 5318.0 5362.8 5496.4 5573.7 5594.9 2536.5 2727.3 2743.0 2748.7 2763.5 5283.5 5300.8 5311.6 5325.4 5336.5 4714.6 5270.4 6354.6 6380.8 6400.6 6459.7 6475.0 6499.8 6499.8 6510.8 6027.4 6171.8 6308.8 6309.7 6380.5 5065.8 5641.5 6148.5 6156.4 6179.6 5624.4 5694.0 6178.9 6403.5 6511.4 3543.8 3621.6 3963.0 3996.3 4009.2 5914.3 6148.6 6218.4 6254.4 6259.0 5234.9 5802.9 5885.9 5978.7 6043.5 4457.7 5016.1 5552.3 5945.6 5960.0 4742.9 5003.8 5526.5 5529.6 5844.0 5596.3 5810.1 6055.3 6080.7 6081.5 5632.5 5943.8 5950.4 5976.8 6016.5 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:23847.41 Median:3626.82 stddev:37850.55 (1043.6%) stddev-sans-first:37916.41 01:36:57 INFO - PROCESS | 1651 | Values: 579.5 681.3 728.3 758.1 862.7 14980.8 16008.1 16096.2 16139.2 16148.9 13969.6 14263.6 14329.7 14345.9 14346.5 859.4 878.8 881.0 881.2 882.2 694.6 711.7 850.1 876.7 876.8 927.5 930.5 931.2 931.3 933.0 921.6 923.0 924.3 924.3 924.3 669.1 685.4 686.7 687.3 689.5 14621.9 14746.1 14809.0 14860.6 14871.0 12207.7 14428.7 14435.5 14482.3 14485.7 11799.6 11829.5 11841.6 11893.3 12251.4 3751.4 3787.4 3831.1 3844.8 3948.7 104113.4 104809.5 105286.0 105560.0 107929.5 11133.7 11174.2 11254.1 11254.7 11509.6 23194.8 23248.4 23286.4 23296.3 23312.8 7447.7 7759.9 7778.7 7992.3 8140.2 4288.5 4424.8 4458.9 4488.9 4604.9 3476.8 3580.9 3626.4 3627.2 3634.5 585.8 585.8 651.7 665.4 678.0 561.0 569.7 613.4 624.8 627.7 8785.2 9004.7 9101.4 9155.3 9162.1 498.0 616.3 629.7 634.4 641.4 3373.0 3850.9 3945.0 4437.7 4607.3 148103.5 158765.0 161404.0 163931.4 164093.0 75911.0 87209.0 96127.5 109770.5 122338.0 84077.2 104774.0 108916.0 110639.0 111030.0 9521.3 9815.4 9872.3 10005.2 10035.2 43083.8 57481.0 58368.0 58675.4 60928.7 86104.7 88141.6 89060.6 91998.2 93479.0 13018.3 13026.9 13148.8 13227.5 13336.2 14853.0 14933.6 15061.5 15359.3 15478.5 13345.5 13587.0 13795.9 13814.9 14264.9 13417.5 13597.1 13699.3 13709.3 13985.0 13076.9 13146.3 13246.2 13667.8 13727.3 11146.7 11200.5 11269.4 11306.5 11742.9 9804.6 10528.5 10782.9 11574.2 11646.0 01:36:57 INFO - PROCESS | 1651 | -------- Summary: end -------- 01:36:57 INFO - PROCESS | 1651 | 01:36:57 INFO - TEST-INFO | 1651: exit 0 01:36:57 INFO - TEST-OK | dromaeo_css | took 1115432ms 01:36:57 INFO - TEST-START | kraken 01:36:57 INFO - operating with platform_type : mac_ 01:36:57 INFO - Initialising browser for kraken test... 01:36:57 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpKUZ9FT/profile http://localhost:49277/getInfo.html) 01:37:00 INFO - PROCESS | 1663 | 2016-10-19 01:37:00.450 plugin-container[1664:9950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 01:37:00 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:00 INFO - PROCESS | 1663 | 2016-10-19 01:37:00.453 plugin-container[1664:9950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 01:37:00 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:00 INFO - PROCESS | 1663 | 2016-10-19 01:37:00.453 plugin-container[1664:9950] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:00 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 01:37:00 INFO - PROCESS | 1663 | colorDepth:24 01:37:00 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 01:37:00 INFO - PROCESS | 1663 | __metrics 01:37:00 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 01:37:01 INFO - TEST-INFO | 1663: exit 0 01:37:01 INFO - Browser initialized. 01:37:01 INFO - Running cycle 1/1 for kraken test... 01:37:01 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpKUZ9FT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:03 INFO - PROCESS | 1665 | 2016-10-19 01:37:03.734 plugin-container[1666:10066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 01:37:03 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:03 INFO - PROCESS | 1665 | 2016-10-19 01:37:03.735 plugin-container[1666:10066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 01:37:03 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:37:03 INFO - PROCESS | 1665 | 2016-10-19 01:37:03.735 plugin-container[1666:10066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:13 INFO - PROCESS | 1665 | RSS: Main: 237256704 01:37:13 INFO - PROCESS | 1665 | 01:37:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 01:37:17 INFO - PROCESS | 1665 | RSS: Main: 249753600 01:37:17 INFO - PROCESS | 1665 | 01:37:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 01:37:20 INFO - PROCESS | 1665 | RSS: Main: 257302528 01:37:20 INFO - PROCESS | 1665 | 01:37:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 01:37:24 INFO - PROCESS | 1665 | RSS: Main: 260349952 01:37:24 INFO - PROCESS | 1665 | 01:37:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 01:37:27 INFO - PROCESS | 1665 | RSS: Main: 262144000 01:37:27 INFO - PROCESS | 1665 | 01:37:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:37:29 INFO - PROCESS | 1665 | RSS: Main: 262303744 01:37:29 INFO - PROCESS | 1665 | 01:37:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 01:37:32 INFO - PROCESS | 1665 | RSS: Main: 263430144 01:37:32 INFO - PROCESS | 1665 | 01:37:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 01:37:36 INFO - PROCESS | 1665 | RSS: Main: 263274496 01:37:36 INFO - PROCESS | 1665 | 01:37:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 01:37:39 INFO - PROCESS | 1665 | RSS: Main: 263548928 01:37:39 INFO - PROCESS | 1665 | 01:37:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:37:42 INFO - PROCESS | 1665 | RSS: Main: 262852608 01:37:42 INFO - PROCESS | 1665 | 01:37:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:37:44 INFO - PROCESS | 1665 | RSS: Main: 262909952 01:37:44 INFO - PROCESS | 1665 | 01:37:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:37:47 INFO - PROCESS | 1665 | RSS: Main: 262922240 01:37:47 INFO - PROCESS | 1665 | 01:37:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:37:51 INFO - PROCESS | 1665 | RSS: Main: 263667712 01:37:51 INFO - PROCESS | 1665 | 01:37:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:37:54 INFO - PROCESS | 1665 | RSS: Main: 264015872 01:37:54 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 01:37:56 INFO - PROCESS | 1665 | RSS: Main: 265691136 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | __start_tp_report 01:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 01:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 01:37:56 INFO - PROCESS | 1665 | |i|pagename|runs| 01:37:56 INFO - PROCESS | 1665 | |0;ai-astar;106;118;117;118;116;116;117;120;117;119 01:37:56 INFO - PROCESS | 1665 | |1;audio-beat-detection;136;145;143;154;115;114;119;144;174;114 01:37:56 INFO - PROCESS | 1665 | |2;audio-dft;199;205;220;200;193;195;195;196;213;193 01:37:56 INFO - PROCESS | 1665 | |3;audio-fft;85;58;59;84;63;51;57;63;104;82 01:37:56 INFO - PROCESS | 1665 | |4;audio-oscillator;83;69;85;70;69;73;86;63;69;85 01:37:56 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;91;88;92;88;91;88;92;90;91;89 01:37:56 INFO - PROCESS | 1665 | |6;imaging-darkroom;101;103;102;122;103;102;102;102;103;101 01:37:56 INFO - PROCESS | 1665 | |7;imaging-desaturate;88;91;87;87;86;88;120;118;89;86 01:37:56 INFO - PROCESS | 1665 | |8;json-parse-financial;54;59;75;68;73;57;57;58;55;54 01:37:56 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;60;103;83;83;82;75;71;70;70;71 01:37:56 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;84;83;93;92;91;93;93;106;107;108 01:37:56 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;175;138;129;157;159;160;164;147;188;187 01:37:56 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;179;178;176;175;176;175;177;174;176;181 01:37:56 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;75;84;90;91;88;80;71;69;69;69 01:37:56 INFO - PROCESS | 1665 | __end_tp_report 01:37:56 INFO - PROCESS | 1665 | __start_cc_report 01:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,936 01:37:56 INFO - PROCESS | 1665 | __end_cc_report 01:37:56 INFO - PROCESS | 1665 | __startTimestamp1476866276984__endTimestamp 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | ------- Summary: start ------- 01:37:56 INFO - PROCESS | 1665 | Number of tests: 14 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:116.40 Median:117.50 stddev:3.86 (3.3%) stddev-sans-first:1.33 01:37:56 INFO - PROCESS | 1665 | Values: 106.0 118.0 117.0 118.0 116.0 116.0 117.0 120.0 117.0 119.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:143.50 stddev:20.16 (14.1%) stddev-sans-first:21.39 01:37:56 INFO - PROCESS | 1665 | Values: 136.0 145.0 143.0 154.0 115.0 114.0 119.0 144.0 174.0 114.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:200.90 Median:199.50 stddev:9.13 (4.6%) stddev-sans-first:9.66 01:37:56 INFO - PROCESS | 1665 | Values: 199.0 205.0 220.0 200.0 193.0 195.0 195.0 196.0 213.0 193.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:70.60 Median:63.00 stddev:17.03 (27.0%) stddev-sans-first:17.25 01:37:56 INFO - PROCESS | 1665 | Values: 85.0 58.0 59.0 84.0 63.0 51.0 57.0 63.0 104.0 82.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:78.00 stddev:8.60 (11.0%) stddev-sans-first:8.65 01:37:56 INFO - PROCESS | 1665 | Values: 83.0 69.0 85.0 70.0 69.0 73.0 86.0 63.0 69.0 85.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:91.00 stddev:1.63 (1.8%) stddev-sans-first:1.69 01:37:56 INFO - PROCESS | 1665 | Values: 91.0 88.0 92.0 88.0 91.0 88.0 92.0 90.0 91.0 89.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:102.50 stddev:6.33 (6.2%) stddev-sans-first:6.62 01:37:56 INFO - PROCESS | 1665 | Values: 101.0 103.0 102.0 122.0 103.0 102.0 102.0 102.0 103.0 101.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:87.50 stddev:13.27 (15.2%) stddev-sans-first:13.89 01:37:56 INFO - PROCESS | 1665 | Values: 88.0 91.0 87.0 87.0 86.0 88.0 120.0 118.0 89.0 86.0 01:37:56 INFO - PROCESS | 1665 | 01:37:56 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:58.50 stddev:7.94 (13.6%) stddev-sans-first:8.01 01:37:56 INFO - PROCESS | 1665 | Values: 54.0 59.0 75.0 68.0 73.0 57.0 57.0 58.0 55.0 54.0 01:37:56 INFO - PROCESS | 1665 | 01:37:57 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:73.00 stddev:11.72 (16.1%) stddev-sans-first:10.74 01:37:57 INFO - PROCESS | 1665 | Values: 60.0 103.0 83.0 83.0 82.0 75.0 71.0 70.0 70.0 71.0 01:37:57 INFO - PROCESS | 1665 | 01:37:57 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:91.50 stddev:9.04 (9.9%) stddev-sans-first:8.67 01:37:57 INFO - PROCESS | 1665 | Values: 84.0 83.0 93.0 92.0 91.0 93.0 93.0 106.0 107.0 108.0 01:37:57 INFO - PROCESS | 1665 | 01:37:57 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:162.00 stddev:19.37 (12.0%) stddev-sans-first:19.81 01:37:57 INFO - PROCESS | 1665 | Values: 175.0 138.0 129.0 157.0 159.0 160.0 164.0 147.0 188.0 187.0 01:37:57 INFO - PROCESS | 1665 | 01:37:57 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:176.50 stddev:2.11 (1.2%) stddev-sans-first:2.07 01:37:57 INFO - PROCESS | 1665 | Values: 179.0 178.0 176.0 175.0 176.0 175.0 177.0 174.0 176.0 181.0 01:37:57 INFO - PROCESS | 1665 | 01:37:57 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.60 Median:82.00 stddev:9.13 (11.1%) stddev-sans-first:9.59 01:37:57 INFO - PROCESS | 1665 | Values: 75.0 84.0 90.0 91.0 88.0 80.0 71.0 69.0 69.0 69.0 01:37:57 INFO - PROCESS | 1665 | -------- Summary: end -------- 01:37:57 INFO - PROCESS | 1665 | 01:37:57 INFO - TEST-INFO | 1665: exit 0 01:37:57 INFO - TEST-OK | kraken | took 60150ms 01:37:57 INFO - SUITE-END | took 1175s 01:37:58 INFO - Completed test suite (00:19:35) 01:37:58 INFO - javascript benchmark 01:37:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7368.0829432003065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.4678452067724, 2883.9567406488904, 3065.386922615476, 3113.828739419332, 3404.9148771280697, 5727.312722473299, 5761.222234998274, 5761.827145185641, 5795.391483894183, 5805.361410244875, 5749.626274292168, 5779.682117483537, 5781.017227071397, 5808.651480911149, 5865.178874957509, 5166.664166829163, 5227.973860130694, 5232.189010703328, 5294.549963253128, 5304.92042619361, 5088.135017047092, 5230.555402790767, 5233.528982391584, 5314.601404894629, 5351.25082488452, 5109.029284435946, 5146.047981123485, 5200.427952925175, 5201.427842937273, 5208.947910520884, 5150.613703972217, 5165.992631436871, 5173.715445650488, 5242.947570524284, 5245.0, 5224.791008359661, 5225.973870130625, 5246.422893481714, 5257.316548848664, 5260.9736951315, 5437.8368648940595, 5618.0, 5756.222909907151, 5762.855928601776, 5763.625364351304, 6192.969035154841, 6219.751209951596, 6222.844428889269, 6259.217597800275, 6283.654399008053, 5401.78392864285, 6148.231471066117, 6202.255729312466, 6250.874982500324, 6255.374462553754, 5817.912731309034, 5834.679092649945, 5879.470847623735, 5891.675957822361, 5967.075103358986, 6244.12582238487, 6267.4986001120715, 6281.685915704196, 6288.308286088527, 6310.495160387158, 6035.155078289043, 6220.0, 6227.003679411363, 6231.3145553989025, 6233.283172435137, 6146.7399183167345, 6159.876802463926, 6165.84585385369, 6222.937770622236, 6277.121203031579, 6125.877482450326, 6180.381961803783, 6185.567010309325, 6258.468030217483, 6259.0924315973925, 6198.938010619926, 6211.409916057967, 6241.563090583706, 6260.248770147611, 6277.089821975788, 4880.829170978998, 4914.041761856403, 4927.384076990376, 4935.3090567320605, 4940.0, 4438.312061630452, 4443.800028998703, 4446.066326071496, 4450.35469856869, 4500.0, 5912.17229587858, 5984.670843103671, 6003.369646187088, 6010.939890601126, 6017.037274036221, 6149.354317796567, 6150.831342044997, 6208.658523781145, 6214.8135555933395, 6226.159468471789, 5841.1238314253205, 5856.912146317809, 5862.794802181903, 5864.501517370986, 5873.442023007893, 5437.374701909331, 5440.972795136078, 5442.455754424526, 5458.399576046631, 5466.480684334982, 6227.750889964351, 6230.626162430269, 6261.123442718023, 6270.247570291503, 6272.341404152498, 6162.907556386747, 6193.597416167935, 6227.003679411454, 6229.813105606839, 6229.875402491834, 6346.555741098079, 6359.459445947146, 6366.745330186741, 6382.425581697669, 6492.577982431127, 4727.361806156263, 5105.785461407856, 6209.316975132641, 6225.0, 6233.844153896189, 4511.593956543796, 5155.43290238066, 5159.819406320761, 5329.760160792696, 6216.751329946932, 5041.2438134280155, 5136.280920671034, 6191.133241346125, 6201.348858369806, 6210.503159747119, 5252.474752524717, 6068.878622427438, 6175.197224360804, 6182.876342473215, 6219.689015549295, 5737.8565535861935, 5762.510186634183, 5923.28920529522, 5973.641581505123, 6029.547783916311, 6051.818445446644, 6080.270367555921, 6111.5110791137495, 6180.258368995749, 6183.227096612924, 5005.4744251853035, 6021.006533921854, 6021.096835474714, 6026.9839773331805, 6047.637141771508], "value": 5646.25627695127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.67427138139004, 477.55587303807465, 501.7541404711692, 524.2764984321635, 539.5602583894129, 38370.23259534811, 40599.797001015024, 41068.38397424041, 41099.38350924738, 41142.588574114285, 36762.44856327157, 40376.39435408471, 41511.79244103775, 42041.57958420415, 42133.0, 9399.791049103473, 9560.0, 9562.474063926482, 9599.136077753, 9599.808003839918, 9456.57445414958, 9528.618855245817, 9577.425354478719, 9593.136617704406, 9613.038696130401, 9456.432614043179, 9575.233981281515, 9579.52102394881, 9598.42409455435, 9619.855702164474, 9231.384507711191, 9427.340086193935, 9496.715098547054, 9564.282678799083, 9578.377405468693, 8534.0, 9402.15380615741, 9529.857052144223, 9561.474118923456, 9569.3779904306, 39746.807595772174, 39882.002949926195, 39888.20223595512, 40028.0, 40167.0, 3143.0256620447735, 3242.7515406568405, 3337.5160601712614, 3424.503447000196, 3529.294141171776, 25425.872870635714, 30584.8470757647, 30738.69261307403, 31521.84239078813, 31875.0, 3144.795588286777, 3212.6305474870223, 3213.469777486712, 3233.401820663185, 3260.9347813043605, 471062.6446867778, 476862.9999999965, 478866.0, 479762.0, 480549.0, 65722.0, 67070.66464667645, 67103.0, 67280.0, 67303.0, 71017.93473097908, 71104.0, 71340.64329678318, 71519.0, 71677.64161179161, 16681.666366672842, 16815.495535133967, 16843.0, 16905.408310709066, 16911.91544042272, 12535.119732040266, 12862.485500579873, 12937.805932911015, 12978.961683065332, 12983.610491685264, 16478.75281870773, 19267.0, 20539.4865128373, 20563.79436205619, 20722.06750696192, 8558.400911936105, 8658.87011694825, 8680.392372533863, 8794.648214071494, 8806.251468625238, 766.6550052476297, 885.530668745553, 927.2581934452411, 1000.3597697473623, 1008.606643409071, 24704.752952470604, 25693.743062569134, 28872.855635721688, 30074.39903665952, 31344.1193528389, 1032.4476405123223, 1050.7443604892233, 1063.4576366053213, 1119.7368618374676, 1205.355135002787, 15332.463363782213, 15864.44474443389, 16015.919920400323, 16041.919790400972, 16329.42847000373, 663479.0, 667575.0, 670479.0, 670939.0, 671537.0, 57909.0, 57955.71022144862, 58019.0, 58577.0, 58643.4135658638, 1343.6909510812372, 1389.1595584671331, 1405.292569528008, 1407.7606806842657, 1410.88007519362, 26589.069382571517, 29473.67368468467, 30217.848910755307, 30357.78568857221, 30598.235044123183, 51888.0, 52592.0, 52685.0, 52714.73642631762, 52716.0, 601456.0, 601983.0, 605875.0, 606218.0, 606722.0, 42690.35964460474, 42822.14355712806, 43138.78430607827, 43604.56395436005, 43690.0, 46961.0, 47461.28808067951, 48324.7583762093, 48569.27146092882, 48756.5124348752, 35368.93893183208, 38839.22321553496, 39665.40501892532, 40115.398269025405, 41502.792486037375, 39083.0, 39277.2144557113, 39321.60678393179, 39357.62248321295, 39710.808675739776, 33110.668893311726, 33638.495422568194, 36425.817870910476, 38884.6111538881, 39044.804775975936, 32458.0, 33503.32993340071, 35337.82331088328, 35412.60848661433, 36156.63843361638, 25419.0, 25554.0, 25787.87106064458, 25977.0, 26314.0], "value": 20478.722561594634, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.2746375582451, 377.86208034067556, 378.9317922773901, 386.3779315302361, 404.41158114942743, 16963.897346672456, 18209.544761380956, 19836.0, 20523.794762052392, 20716.378508644728, 20081.698774518394, 20266.797332026676, 20677.793222067776, 20778.376648700563, 20835.895820520913, 5042.899142017167, 5106.0, 5656.717164141797, 6119.5104391648565, 6135.110408990694, 5149.588032957355, 5845.889281036611, 5886.793962211324, 5932.673702946337, 6062.878742425149, 5661.5187709044785, 5765.509931655814, 5974.103884417329, 5981.102834574827, 6027.758889644409, 5739.655620662753, 6014.639121652693, 6051.243594550681, 6060.151578778975, 6070.908936365936, 5023.372078490189, 5327.600429967768, 5334.1732031535175, 5434.972825135849, 5908.113782932552, 12963.67590810228, 13026.80459793104, 13220.603381898558, 20796.376108716762, 23947.281581552583, 2141.389703934387, 2305.8155347572156, 2339.415146213447, 2403.9158629447884, 2552.438463538019, 9801.215902727765, 10050.849237261447, 10314.7421314467, 12372.13395062346, 14526.782098268535, 1859.6373707127116, 1917.9834687615587, 1922.7981061988569, 1925.643755905148, 1945.5914258005835, 115514.0, 118606.0, 119319.99999999913, 119612.40193799062, 120673.39663301715, 9971.850422243671, 10478.056974872297, 10780.892191078146, 11163.720906977229, 11422.600208992646, 12692.80960785589, 14051.648708782363, 14292.785113265281, 14493.492727754478, 14510.492132775302, 7863.685452581947, 8178.631961561625, 8209.630566624517, 8215.260626543639, 8287.709930152416, 7092.219855815856, 7692.653830577638, 7815.218478152253, 7935.76192714208, 8116.35069194463, 4520.660950428731, 4523.914260577438, 4530.97734511332, 4545.636349092065, 4580.427446569179, 1674.5227610131053, 1733.3066773290532, 1964.4204959536971, 2003.779584245761, 2045.5806559655234, 760.7983884270673, 880.907504711996, 906.0395980260945, 923.5936188077225, 1004.2568499310457, 16790.664186716196, 19505.60988780245, 21006.789932100484, 21039.263625773332, 21171.364859054254, 902.2240872849344, 907.7685190276569, 913.1872633356318, 939.6053657463882, 953.9236861051099, 10891.292066015842, 11204.887951120547, 11205.0, 11232.438378081064, 11270.0, 548013.0, 556832.0, 564175.0, 564739.0, 571571.0, 103576.0, 120192.39903800425, 120589.0, 121156.0, 122789.0, 965.4931161140457, 982.5824024789435, 990.1336330710484, 1004.8894621591619, 1008.4503945349802, 17870.017149056406, 17877.73183402224, 18313.542161446072, 18704.7194292083, 18721.25114995385, 36692.0, 48600.0, 49065.754671226416, 53067.4693253078, 53726.462735372144, 46303.07393852172, 47438.76280618575, 59740.80518389695, 69822.0, 77510.449791005, 6792.694328755317, 7819.413543984338, 8008.799780005546, 8084.636191371284, 8300.626471808662, 10688.465576721288, 11642.76714465689, 11665.775093615388, 12562.566301603221, 12970.22178669283, 10686.0, 10859.83710244331, 11009.559617615527, 11135.443227838738, 11249.268797528135, 10601.575936962743, 10818.29681070728, 10894.61868834587, 10957.342559446459, 11178.608748693756, 11120.0, 11283.928026837437, 11353.37556434404, 11388.0, 11598.130140239684, 6369.235691717024, 6866.965665171642, 7310.817229569303, 7334.926650733424, 8345.457545259538, 9322.720318390458, 10803.783924321313, 11043.834342484708, 11210.0, 11447.966288933272], "value": 9190.645786549818, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.5749011531714, 1523.398257688213, 1556.1907807939872, 1887.7829049659292, 1955.2472298165212, 8053.355731541477, 8335.874961875577, 8479.491230526164, 9004.414713043663, 9023.639054437823, 8537.743867683965, 8828.77928051799, 8863.822723545542, 8892.466452012884, 8897.0, 2342.1099982006813, 2345.3433038749176, 2372.9288121356385, 2384.1655420602738, 2386.737458879522, 2341.262502311775, 2364.657124716915, 2378.250850981935, 2393.940151496218, 2396.25716028031, 1950.9512262193416, 2111.9260825871097, 2366.6331718583647, 2381.9761802382013, 2407.987960060206, 2356.552255071531, 2365.7989070928993, 2368.2066507719933, 2381.1665916929023, 2381.8094552435937, 2350.247920665388, 2368.2066507719933, 2373.0, 2378.5124049569795, 2384.2966324934014, 8897.065808090187, 8952.8657070144, 8959.641614335418, 9018.835479484942, 9024.684136055272, 5275.235090911835, 5292.153255479105, 5405.081136206815, 5447.891042179174, 5483.040467918138, 3539.876104336336, 3628.6734193922675, 3684.6683798458266, 3720.7303544837628, 3726.217494326195, 1047.1361127070197, 1075.1828610256184, 1098.4123493930788, 1109.916756243277, 1111.0833562311125, 11522.539098436137, 11661.416929153593, 11804.940975295154, 11816.645500634995, 11824.645260642195, 4555.567221113989, 4663.790129444184, 4845.103655823684, 4857.611391088775, 4878.975605121952, 8280.461769984931, 9838.212942964547, 10058.446785426871, 10070.597176112873, 10076.949615251877, 5454.61817672767, 5467.972660136754, 5473.863153421196, 5474.2609747683555, 5475.0418676731415, 4440.178566965122, 4639.30410438437, 4803.663743537919, 4971.701697898137, 4984.177610694267, 3875.1280961783937, 3930.0, 3945.980270098631, 4024.094578719823, 4071.5114186297833, 1155.4973586489737, 1303.9869601304056, 1311.6917524381763, 1325.8541560428346, 1328.920264784116, 746.6229554075157, 790.7904405332591, 879.4986857491297, 899.5097671768771, 901.4636291406603, 5420.891582168335, 5572.888542229132, 5648.689322087324, 5660.320761508563, 5770.307563092372, 838.4801423117706, 851.7104184577273, 911.3438324406417, 931.6273490603812, 967.2890425537224, 3753.3431649461236, 3778.962210377861, 3783.2055268393947, 3856.652901238902, 3864.7294689371474, 10391.688249352532, 10870.836937445945, 10885.673429797118, 10886.618968336228, 10907.29102608328, 10766.623168189075, 10859.34843909368, 10867.0, 10867.45662716861, 10892.673219803419, 8161.387895907831, 8187.1403502631365, 8270.387274481418, 8338.082810890797, 8442.62008209632, 5516.972415137899, 5628.634138780966, 5659.858503537444, 5682.346530149086, 5728.570357223141, 8708.0, 9378.78075850135, 9928.907820139635, 10086.243531735296, 10218.335808172742, 10151.187904967437, 10463.68608941733, 10468.528916198942, 10478.790424191626, 10488.632897848538, 6575.704093315908, 6787.762428314985, 6935.0, 7105.253948335557, 7223.9886415902865, 8323.791905202419, 8618.482891026559, 8636.524991125298, 8673.522956237468, 8694.695685651224, 7285.526440781332, 7455.962720186364, 7564.659590318339, 7692.499987500794, 7710.498817577064, 7378.077740282465, 7397.852042959003, 7443.92556074454, 7510.0, 7582.052243469567, 6955.478339124687, 7095.467839911924, 7101.822454438474, 7102.289771022856, 7111.466640002124, 4789.404026033907, 4915.164422048332, 4937.9259311109645, 4943.233798761198, 4961.776720047535, 4840.7337596432535, 4854.563089321978, 4860.295257187759, 4920.753962301942, 5107.336046313955], "value": 4583.702030384143, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.96941351744556, 293.9353342264701, 307.9498908721261, 307.9645840728316, 313.73019203485, 4846.321514987904, 4998.8502644391765, 5414.648047876885, 5633.380328163904, 5778.393268706783, 5394.244805727201, 5546.306711661043, 5598.608097433182, 5627.212190293362, 5637.436256374361, 4026.9999999999927, 4240.787960601973, 4249.958760103779, 4257.808398622069, 4260.190563792885, 3405.5572775539144, 3792.8949433064777, 3876.5269197704974, 4228.6617070634275, 4229.471316085489, 3142.858571364294, 3809.5714107209797, 4139.965008747813, 4199.496060472732, 4233.449651545295, 3352.396568617648, 4137.524184718751, 4150.667946564267, 4216.662666986634, 4244.766537840417, 3132.342208136294, 3681.742278040538, 3702.129999450127, 3702.6673195886688, 3717.6096509866616, 5227.738613069331, 5342.973285133549, 5449.038346195366, 5479.890402191973, 5508.0, 4855.393075865517, 4877.317175595419, 4879.975205206911, 4909.239067944474, 4979.377577802775, 5501.807436739695, 5510.531604813596, 5771.192033115367, 5929.584929054968, 5938.545056461195, 3385.356782211372, 3590.317839610466, 4043.6967227457762, 4067.4003506896315, 4077.3476243801147, 5336.546393556514, 5387.542058925035, 5546.584006199551, 6162.506999440034, 6182.84542886427, 3894.6494815466745, 4127.752334859918, 4133.8966525837095, 4138.958610413918, 4139.834406623761, 4049.4330793688905, 4049.704094689671, 4100.487439070116, 4146.357314616239, 4155.480564929384, 4888.924436623937, 4896.020795840846, 4909.705417674951, 4922.310876477296, 4924.852254432372, 5105.540501354896, 5226.425093239741, 5330.2804121443105, 5692.743826527816, 5899.46904778572, 1202.6271855724754, 1225.5159212111166, 1279.2516377918835, 1398.4056775870215, 1417.3417101990697, 937.803061357109, 942.3403617467663, 956.2588993529931, 971.1745016735719, 980.0, 4646.651501137429, 5562.3881373048935, 5596.636218645856, 5597.636153650081, 5600.943990560042, 5016.54851063406, 5161.174212126117, 5994.340622531518, 6086.330503644595, 6179.598326108789, 5317.973410132925, 5362.839114826562, 5496.422875598005, 5573.721313934287, 5594.916076258859, 2536.4800215955684, 2727.345437095083, 2743.0387273099827, 2748.6564179477564, 2763.5025695374625, 5283.471652834686, 5300.814471493479, 5311.6455303897255, 5325.3875804282225, 5336.493033161843, 4714.599259062949, 5270.364817591204, 6354.618722876549, 6380.777707257898, 6400.551961362753, 6459.741610335722, 6475.0, 6499.765044641503, 6499.805005849832, 6510.837229069311, 6027.427394397525, 6171.765646870554, 6308.810735677937, 6309.747610095545, 6380.522615174834, 5065.772040258271, 5641.520470759867, 6148.538859585459, 6156.384361563987, 6179.598326108789, 5624.381318055092, 5693.957290323917, 6178.907316390168, 6403.487720982217, 6511.413972742476, 3543.7696549724187, 3621.6378362163573, 3962.980185099056, 3996.3015718319016, 4009.2382447334912, 5914.290285165807, 6148.569600127948, 6218.440340369389, 6254.437100660963, 6258.968705156445, 5234.895302094013, 5802.912956305574, 5885.882282354414, 5978.671173085522, 6043.456088952015, 4457.707264893274, 5016.097102521435, 5552.333719953632, 5945.554083443672, 5959.970200148971, 4742.857714268577, 5003.773320872177, 5526.530244929226, 5529.557635389239, 5843.970780146072, 5596.3284405871555, 5810.128480727924, 6055.2733671957885, 6080.695965201634, 6081.452669259788, 5632.5493960482245, 5943.791967281124, 5950.434708702666, 5976.820695379146, 6016.458518733335], "value": 4251.298259845355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.4582065768507, 681.3356976947474, 728.2753660108192, 758.1281526244818, 862.6635612111278, 14980.775288370669, 16008.119553424556, 16096.1951902405, 16139.19304034798, 16148.919255403736, 13969.58091257264, 14263.643408914782, 14329.71340573188, 14345.856541434583, 14346.497872574462, 859.4241857955185, 878.8154487557621, 880.9691660791874, 881.2201201936268, 882.1619461511558, 694.6387878303279, 711.7366574367466, 850.056437354536, 876.6537217798966, 876.7545087375545, 927.4852456886425, 930.5393830054132, 931.2177770672668, 931.3015238571072, 933.0, 921.6497730862229, 923.0308176414771, 924.3021518753366, 924.3344791749929, 924.3344791749929, 669.0699927101333, 685.3797313431353, 686.6909890549273, 687.2749249541731, 689.4691087862368, 14621.92689036548, 14746.11523308605, 14809.0, 14860.62848428787, 14871.03338283008, 12207.694807629792, 14428.701416872533, 14435.494757683537, 14482.34829432678, 14485.71028579433, 11799.587014454539, 11829.526818927232, 11841.5855445059, 11893.345865977368, 12251.387430628432, 3751.381022131345, 3787.3561494545993, 3831.1379939513663, 3844.7696737044157, 3948.6643635290748, 104113.43829842558, 104809.47595261974, 105286.0, 105560.0, 107929.46035269773, 11133.665990020312, 11174.21780475375, 11254.099672026217, 11254.662360129209, 11509.597164099216, 23194.76805231926, 23248.4187895304, 23286.417839554146, 23296.3011109667, 23312.76687233106, 7447.702091916371, 7759.888690121158, 7778.688852445839, 7992.280694737386, 8140.185981401931, 4288.48538175421, 4424.756638384855, 4458.888527786831, 4488.9102217956115, 4604.884877878013, 3476.8435420406145, 3580.8925732228076, 3626.419772836386, 3627.220147668264, 3634.509341238952, 585.7891159182691, 585.8125399872, 651.739304278283, 665.3944910131756, 678.0304165044034, 560.9523190528769, 569.7122952908837, 613.352912677121, 624.7750809708501, 627.6861569215392, 8785.209331160098, 9004.729858104267, 9101.362904596615, 9155.313351498664, 9162.129597688208, 498.03381440006274, 616.2574098211644, 629.6820105846609, 634.4004915354946, 641.3618449642636, 3372.9831350843087, 3850.9229815404096, 3945.033466800652, 4437.733735975852, 4607.262837945979, 148103.51896480896, 158765.0, 161404.0, 163931.360686394, 164093.0, 75911.0, 87209.0, 96127.51936240414, 109770.45114774535, 122338.0, 84077.17189694993, 104774.0, 108916.0, 110639.0, 111030.0, 9521.285903557122, 9815.36200146988, 9872.259580531632, 10005.249606279413, 10035.24735644844, 43083.78458107689, 57481.0, 58368.0, 58675.41324586699, 60928.69535652471, 86104.70842937236, 88141.5592922057, 89060.55469722609, 91998.24005279853, 93479.0, 13018.2839943804, 13026.934865325613, 13148.802767958297, 13227.470901163846, 13336.19982801035, 14853.034552754036, 14933.62665933317, 15061.548153555412, 15359.308831102404, 15478.5356439307, 13345.532906348231, 13586.980976427005, 13795.931020344835, 13814.861851381358, 14264.928675356558, 13417.530386436429, 13597.116187447784, 13699.315034248048, 13709.314534273446, 13984.951128665594, 13076.869231307564, 13146.34268286601, 13246.20522768637, 13667.79498307546, 13727.29363071945, 11146.665600032013, 11200.495977680861, 11269.380184089954, 11306.547738090385, 11742.882571174521, 9804.607815687292, 10528.473576321307, 10782.946085269523, 11574.247673901169, 11646.0], "value": 7725.645535369163, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 118.0, 116.0, 116.0, 117.0, 120.0, 117.0, 119.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 145.0, 143.0, 154.0, 115.0, 114.0, 119.0, 144.0, 174.0, 114.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 205.0, 220.0, 200.0, 193.0, 195.0, 195.0, 196.0, 213.0, 193.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 59.0, 84.0, 63.0, 51.0, 57.0, 63.0, 104.0, 82.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 85.0, 70.0, 69.0, 73.0, 86.0, 63.0, 69.0, 85.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 88.0, 91.0, 88.0, 92.0, 90.0, 91.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 102.0, 122.0, 103.0, 102.0, 102.0, 102.0, 103.0, 101.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 87.0, 87.0, 86.0, 88.0, 120.0, 118.0, 89.0, 86.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 59.0, 75.0, 68.0, 73.0, 57.0, 57.0, 58.0, 55.0, 54.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 103.0, 83.0, 83.0, 82.0, 75.0, 71.0, 70.0, 70.0, 71.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 93.0, 92.0, 91.0, 93.0, 93.0, 106.0, 107.0, 108.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 138.0, 129.0, 157.0, 159.0, 160.0, 164.0, 147.0, 188.0, 187.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 178.0, 176.0, 175.0, 176.0, 175.0, 177.0, 174.0, 176.0, 181.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 90.0, 91.0, 88.0, 80.0, 71.0, 69.0, 69.0, 69.0], "value": 78.6, "unit": "score"}]}]} 01:37:58 INFO - Return code: 0 01:37:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:37:58 ERROR - # TBPL SUCCESS # 01:37:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:37:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:37:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:37:58 INFO - 2016-10-19 01:37:58.335 screenresolution[1670:10446] starting screenresolution argv=screenresolution get 01:37:58 INFO - 2016-10-19 01:37:58.338 screenresolution[1670:10446] Display 0: 1600x1200x32@60 01:37:58 INFO - 2016-10-19 01:37:58.344 screenresolution[1671:10449] starting screenresolution argv=screenresolution list 01:37:58 INFO - Available Modes on Display 0 01:37:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:37:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:37:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:37:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:37:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:37:58 INFO - Intel Iris: 01:37:58 INFO - Chipset Model: Intel Iris 01:37:58 INFO - Type: GPU 01:37:58 INFO - Bus: Built-In 01:37:58 INFO - VRAM (Dynamic, Max): 1536 MB 01:37:58 INFO - Vendor: Intel (0x8086) 01:37:58 INFO - Device ID: 0x0a2e 01:37:58 INFO - Revision ID: 0x0009 01:37:58 INFO - Displays: 01:37:58 INFO - Display: 01:37:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:37:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:37:58 INFO - Display Serial Number: 1600x1200 60 01:37:58 INFO - Main Display: Yes 01:37:58 INFO - Mirror: Off 01:37:58 INFO - Online: Yes 01:37:58 INFO - Rotation: Supported 01:37:58 INFO - Return code: 0 01:37:58 INFO - Running post-action listener: _resource_record_post_action 01:37:58 INFO - [mozharness: 2016-10-19 08:37:58.591069Z] Finished run-tests step (success) 01:37:58 INFO - Running post-run listener: _resource_record_post_run 01:37:58 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38020608; Write bytes: 708936704; Read time: 243; Write time: 1986 01:37:58 INFO - TinderboxPrint: CPU usage
54.5% 01:37:58 INFO - TinderboxPrint: I/O read bytes / time
38,020,608 / 243 01:37:58 INFO - TinderboxPrint: I/O write bytes / time
708,936,704 / 1,986 01:37:58 INFO - TinderboxPrint: CPU idle
2,206.5 (45.5%) 01:37:58 INFO - TinderboxPrint: CPU system
978.8 (20.2%) 01:37:58 INFO - TinderboxPrint: CPU user
1,660.3 (34.3%) 01:37:58 INFO - TinderboxPrint: Swap in / out
444,956,672 / 0 01:37:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 399581696; Write bytes: 393713152; Read time: 30955; Write time: 784 01:37:58 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36021760; Write bytes: 298962432; Read time: 223; Write time: 1156 01:37:59 INFO - Running post-run listener: _upload_blobber_files 01:37:59 INFO - Blob upload gear active. 01:37:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:37:59 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 01:37:59 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:37:59 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:37:59 INFO - (blobuploader) - INFO - Open directory for files ... 01:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:38:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:00 INFO - (blobuploader) - INFO - Done attempting. 01:38:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:38:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:38:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:38:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:01 INFO - (blobuploader) - INFO - Done attempting. 01:38:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:38:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:38:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:38:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:38:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:38:03 INFO - (blobuploader) - INFO - Done attempting. 01:38:03 INFO - (blobuploader) - INFO - Iteration through files over. 01:38:03 INFO - Return code: 0 01:38:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:38:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:38:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c65825fab2c9995fd683a6a29ebfa091bcef34ce2a113b1960f4efe9ec0752ca0dd844dfcee39512344d564efea693e91987dde62e0b650df25ae680e542c7fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25a19a47a1f9d8f65f0925d8ecbbfc2370d5a155430e495519e6440ff50cd01b089e863a6088f6844c2e0aad5211ba34f512ebe9c2c9fd0eae25dd0c2cbc7568", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/790016ba68260e9283ac907d82ca97cfac77d8b28b063c06e1c7b783ee4406fe6776e1f044d64d7bba1182c307a4e2d270a1444ce764173c95f55772f36d1ca5"} 01:38:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:38:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:38:03 INFO - Contents: 01:38:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c65825fab2c9995fd683a6a29ebfa091bcef34ce2a113b1960f4efe9ec0752ca0dd844dfcee39512344d564efea693e91987dde62e0b650df25ae680e542c7fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25a19a47a1f9d8f65f0925d8ecbbfc2370d5a155430e495519e6440ff50cd01b089e863a6088f6844c2e0aad5211ba34f512ebe9c2c9fd0eae25dd0c2cbc7568", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/790016ba68260e9283ac907d82ca97cfac77d8b28b063c06e1c7b783ee4406fe6776e1f044d64d7bba1182c307a4e2d270a1444ce764173c95f55772f36d1ca5"} 01:38:03 INFO - Running post-run listener: copy_logs_to_upload_dir 01:38:03 INFO - Copying logs to upload dir... 01:38:03 INFO - mkdir: /builds/slave/test/build/upload/logs 01:38:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.797600 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-19 01:38:03.860963) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 01:38:03.864884) ========= 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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/Autoland/sha512/c65825fab2c9995fd683a6a29ebfa091bcef34ce2a113b1960f4efe9ec0752ca0dd844dfcee39512344d564efea693e91987dde62e0b650df25ae680e542c7fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25a19a47a1f9d8f65f0925d8ecbbfc2370d5a155430e495519e6440ff50cd01b089e863a6088f6844c2e0aad5211ba34f512ebe9c2c9fd0eae25dd0c2cbc7568", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/790016ba68260e9283ac907d82ca97cfac77d8b28b063c06e1c7b783ee4406fe6776e1f044d64d7bba1182c307a4e2d270a1444ce764173c95f55772f36d1ca5"} build_url:https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010913 build_url: 'https://queue.taskcluster.net/v1/task/QCmtb4OeQnuqIOuiJTjWDQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c65825fab2c9995fd683a6a29ebfa091bcef34ce2a113b1960f4efe9ec0752ca0dd844dfcee39512344d564efea693e91987dde62e0b650df25ae680e542c7fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25a19a47a1f9d8f65f0925d8ecbbfc2370d5a155430e495519e6440ff50cd01b089e863a6088f6844c2e0aad5211ba34f512ebe9c2c9fd0eae25dd0c2cbc7568", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/790016ba68260e9283ac907d82ca97cfac77d8b28b063c06e1c7b783ee4406fe6776e1f044d64d7bba1182c307a4e2d270a1444ce764173c95f55772f36d1ca5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 01:38:03.897374) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:38:03.897713) ========= 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.GVnCqMXSHw/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.LO5a1ByKKK/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 01:38:03.946569) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 01:38:03.946880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 01:38:03.947203) ========= ========= Total master_lag: 0.17 =========