builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0221 starttime: 1478263180.83 results: success (0) buildid: 20161104031921 builduid: 10e0142511e243baa1f549e9a2e78873 revision: e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:40.827882) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:40.828290) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:40.828566) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:40.857348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:40.857670) ========= 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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-11-04 05:39:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-11-04 05:39:40 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100146 ========= master_lag: 0.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:41.515225) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:41.515593) ========= 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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.024953 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:41.571420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:41.572096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-11-04 05:39:41,656 truncating revision to first 12 chars 2016-11-04 05:39:41,656 Setting DEBUG logging. 2016-11-04 05:39:41,656 attempt 1/10 2016-11-04 05:39:41,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e00ed3bd29f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-04 05:39:42,294 unpacking tar archive at: mozilla-central-e00ed3bd29f9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:42.507220) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:42.507546) ========= script_repo_revision: e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:42.507963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:42.508277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-04 05:39:42.526562) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-04 05:39:42.526925) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:39:42 INFO - MultiFileLogger online at 20161104 05:39:42 in /builds/slave/test 05:39:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:39:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:42 INFO - {'append_to_log': False, 05:39:42 INFO - 'base_work_dir': '/builds/slave/test', 05:39:42 INFO - 'blob_upload_branch': 'Firefox', 05:39:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:42 INFO - 'branch': 'Firefox', 05:39:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:42 INFO - 'config_files': ('talos/mac_config.py',), 05:39:42 INFO - 'default_actions': ('clobber', 05:39:42 INFO - 'read-buildbot-config', 05:39:42 INFO - 'download-and-extract', 05:39:42 INFO - 'populate-webroot', 05:39:42 INFO - 'create-virtualenv', 05:39:42 INFO - 'install', 05:39:42 INFO - 'run-tests'), 05:39:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:42 INFO - 'download_minidump_stackwalk': True, 05:39:42 INFO - 'download_symbols': 'ondemand', 05:39:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:42 INFO - 'installer_path': 'installer.exe', 05:39:42 INFO - 'log_level': 'info', 05:39:42 INFO - 'log_name': 'talos', 05:39:42 INFO - 'log_to_console': True, 05:39:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:39:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:39:42 INFO - 'opt_config_files': (), 05:39:42 INFO - 'pip_index': False, 05:39:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:42 INFO - 'cmd': ('bash', 05:39:42 INFO - '-c', 05:39:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:42 INFO - 'enabled': True, 05:39:42 INFO - 'halt_on_failure': False, 05:39:42 INFO - 'name': 'check_screen_resolution'},), 05:39:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:42 INFO - 'cmd': ('bash', 05:39:42 INFO - '-c', 05:39:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:42 INFO - 'enabled': True, 05:39:42 INFO - 'halt_on_failure': False, 05:39:42 INFO - 'name': 'check_screen_resolution'},), 05:39:42 INFO - 'run_cmd_checks_enabled': True, 05:39:42 INFO - 'sps_profile': False, 05:39:42 INFO - 'sps_profile_interval': 0, 05:39:42 INFO - 'suite': 'dromaeojs-e10s', 05:39:42 INFO - 'system_bits': '32', 05:39:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:42 INFO - 'title': 't-yosemite-r7-0221', 05:39:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:42 INFO - 'use_talos_json': True, 05:39:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:42 INFO - 'work_dir': 'build'} 05:39:42 INFO - [mozharness: 2016-11-04 12:39:42.736102Z] Running clobber step. 05:39:42 INFO - Running pre-action listener: _resource_record_pre_action 05:39:42 INFO - Running main action method: clobber 05:39:42 INFO - rmtree: /builds/slave/test/build 05:39:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:44 INFO - Running post-action listener: _resource_record_post_action 05:39:44 INFO - [mozharness: 2016-11-04 12:39:44.817166Z] Finished clobber step (success) 05:39:44 INFO - [mozharness: 2016-11-04 12:39:44.817292Z] Running read-buildbot-config step. 05:39:44 INFO - Running pre-action listener: _resource_record_pre_action 05:39:44 INFO - Running main action method: read_buildbot_config 05:39:44 INFO - Using buildbot properties: 05:39:44 INFO - { 05:39:44 INFO - "project": "", 05:39:44 INFO - "product": "firefox", 05:39:44 INFO - "script_repo_revision": "production", 05:39:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:39:44 INFO - "repository": "", 05:39:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:39:44 INFO - "buildid": "20161104031921", 05:39:44 INFO - "stage_platform": "macosx64", 05:39:44 INFO - "basedir": "/builds/slave/test", 05:39:44 INFO - "nightly_build": "True", 05:39:44 INFO - "buildnumber": 4, 05:39:44 INFO - "slavename": "t-yosemite-r7-0221", 05:39:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:39:44 INFO - "platform": "yosemite_r7", 05:39:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:39:44 INFO - "branch": "mozilla-central", 05:39:44 INFO - "repo_path": "mozilla-central", 05:39:44 INFO - "revision": "e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f", 05:39:44 INFO - "pgo_build": "False", 05:39:44 INFO - "builduid": "10e0142511e243baa1f549e9a2e78873", 05:39:44 INFO - "slavebuilddir": "test" 05:39:44 INFO - } 05:39:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:39:44 INFO - Running post-action listener: _resource_record_post_action 05:39:44 INFO - [mozharness: 2016-11-04 12:39:44.820572Z] Finished read-buildbot-config step (success) 05:39:44 INFO - [mozharness: 2016-11-04 12:39:44.820681Z] Running download-and-extract step. 05:39:44 INFO - Running pre-action listener: _resource_record_pre_action 05:39:44 INFO - Running main action method: download_and_extract 05:39:44 INFO - mkdir: /builds/slave/test/build/tests 05:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:44 INFO - trying https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:39:44 INFO - Downloading https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/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 05:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/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 05:39:45 INFO - Downloaded 1199 bytes. 05:39:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:39:45 INFO - Using the following test package requirements: 05:39:45 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:39:45 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:39:45 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:39:45 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'jsshell-mac.zip'], 05:39:45 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:39:45 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:39:45 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:39:45 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:39:45 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:39:45 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:39:45 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:39:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:39:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:39:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:39:45 INFO - Fetch https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:39:46 INFO - Http code: 200 05:39:46 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 05:39:46 INFO - x-amz-cf-id: ozg_4Ztn2K47tay8WGl8-VbsgtKsrzjP_SbFmm4fXxBJ1E_vx4G8bw== 05:39:46 INFO - x-amz-version-id: udkt78_qbSYkniyyldvtRcjDaXR7v.yl 05:39:46 INFO - Expected file size: 16398020 05:39:46 INFO - Obtained file size: 16398020 05:39:53 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 05:39:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:39:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:39:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:39:53 INFO - Http code: 200 05:39:53 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 05:39:53 INFO - x-amz-cf-id: rFzHAFCCt8wj_fbgcQ0IgtSEnQHp3aG7oJwI0-xOQOGRjo-4BhG4xA== 05:39:53 INFO - x-amz-version-id: udkt78_qbSYkniyyldvtRcjDaXR7v.yl 05:39:54 INFO - Expected file size: 16398020 05:39:54 INFO - Obtained file size: 16398020 05:40:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:40:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:40:00 INFO - Fetch https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:40:00 INFO - Http code: 200 05:40:00 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 05:40:00 INFO - x-amz-cf-id: c_wLVOFpzdEK-bBXfG05wlzhEkQTjgm2bKggA-cP22jStKMBXPETyA== 05:40:00 INFO - x-amz-version-id: HrlBGahQZoTlGb6OVzjl0LFMPrH9jkRx 05:40:01 INFO - Expected file size: 14409038 05:40:01 INFO - Obtained file size: 14409038 05:40:01 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')]} 05:40:01 INFO - trying https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:01 INFO - Downloading https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:40:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/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 05:40:04 INFO - Downloaded 111961432 bytes. 05:40:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:04 INFO - mkdir: /builds/slave/test/properties 05:40:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:04 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:04 INFO - Contents: 05:40:04 INFO - build_url:https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:40:06 INFO - Running post-action listener: _resource_record_post_action 05:40:06 INFO - Running post-action listener: set_extra_try_arguments 05:40:06 INFO - [mozharness: 2016-11-04 12:40:06.053172Z] Finished download-and-extract step (success) 05:40:06 INFO - [mozharness: 2016-11-04 12:40:06.053298Z] Running populate-webroot step. 05:40:06 INFO - Running pre-action listener: _resource_record_pre_action 05:40:06 INFO - Running main action method: populate_webroot 05:40:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:06 INFO - '--tppagecycles', 05:40:06 INFO - '7'], 05:40:06 INFO - 'tests': ['tsvgm']}}, 05:40:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['damp', 'tps']}, 05:40:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'tests': ['damp', 'tps']}, 05:40:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['dromaeo_dom']}, 05:40:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['basic_compositor_video']}, 05:40:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:40:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['a11yr', 05:40:06 INFO - 'ts_paint', 05:40:06 INFO - 'tpaint', 05:40:06 INFO - 'sessionrestore', 05:40:06 INFO - 'sessionrestore_no_auto_restore', 05:40:06 INFO - 'tabpaint']}, 05:40:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['tsvgx', 05:40:06 INFO - 'tsvgr_opacity', 05:40:06 INFO - 'tart', 05:40:06 INFO - 'tscrollx', 05:40:06 INFO - 'cart']}, 05:40:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:06 INFO - 'tsvgr_opacity', 05:40:06 INFO - 'tart', 05:40:06 INFO - 'tscrollx', 05:40:06 INFO - 'cart']}, 05:40:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'talos_options': ['--disable-e10s'], 05:40:06 INFO - 'tests': ['tp5o']}, 05:40:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'tests': ['tp5o']}, 05:40:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'talos_options': ['--disable-e10s', 05:40:06 INFO - '--xperf_path', 05:40:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:06 INFO - 'tests': ['tp5n']}, 05:40:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:06 INFO - 'talos_options': ['--xperf_path', 05:40:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:06 INFO - 'tests': ['tp5n']}}, 05:40:06 INFO - 'talos.zip': {'path': '', 05:40:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:06 INFO - Running post-action listener: _resource_record_post_action 05:40:06 INFO - [mozharness: 2016-11-04 12:40:06.071775Z] Finished populate-webroot step (success) 05:40:06 INFO - [mozharness: 2016-11-04 12:40:06.071915Z] Running create-virtualenv step. 05:40:06 INFO - Running pre-action listener: _resource_record_pre_action 05:40:06 INFO - Running main action method: create_virtualenv 05:40:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:06 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 05:40:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:06 INFO - Using real prefix '/tools/python27' 05:40:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:09 INFO - Installing pip.................done. 05:40:09 INFO - Return code: 0 05:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:40:10 INFO - Reading from file tmpfile_stdout 05:40:10 INFO - Output received: 05:40:10 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) 05:40:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:10 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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:10 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 05:40:10 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 05:40:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:10 INFO - 'HOME': '/Users/cltbld', 05:40:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:10 INFO - 'LOGNAME': 'cltbld', 05:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:10 INFO - 'MOZ_NO_REMOTE': '1', 05:40:10 INFO - 'NO_EM_RESTART': '1', 05:40:10 INFO - 'PAGER': '/bin/cat', 05:40:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:10 INFO - 'PWD': '/builds/slave/test', 05:40:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:10 INFO - 'SHELL': '/bin/bash', 05:40:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:10 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:10 INFO - 'USER': 'cltbld', 05:40:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:10 INFO - 'XPC_FLAGS': '0x0', 05:40:10 INFO - 'XPC_SERVICE_NAME': '0', 05:40:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:10 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 05:40:10 INFO - Cleaning up... 05:40:10 INFO - Return code: 0 05:40:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:10 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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:10 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 05:40:10 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 05:40:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:10 INFO - 'HOME': '/Users/cltbld', 05:40:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:10 INFO - 'LOGNAME': 'cltbld', 05:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:10 INFO - 'MOZ_NO_REMOTE': '1', 05:40:10 INFO - 'NO_EM_RESTART': '1', 05:40:10 INFO - 'PAGER': '/bin/cat', 05:40:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:10 INFO - 'PWD': '/builds/slave/test', 05:40:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:10 INFO - 'SHELL': '/bin/bash', 05:40:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:10 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:10 INFO - 'USER': 'cltbld', 05:40:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:10 INFO - 'XPC_FLAGS': '0x0', 05:40:10 INFO - 'XPC_SERVICE_NAME': '0', 05:40:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:10 INFO - Downloading/unpacking psutil>=3.1.1 05:40:10 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 05:40:10 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 05:40:10 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 05:40:10 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 05:40:10 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 05:40:10 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 05:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:13 INFO - Installing collected packages: psutil 05:40:13 INFO - Running setup.py install for psutil 05:40:13 INFO - building 'psutil._psutil_osx' extension 05:40:13 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 05:40:14 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 05:40:14 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 05:40:14 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 05:40:14 INFO - building 'psutil._psutil_posix' extension 05:40:14 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 05:40:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:14 INFO - ^ 05:40:14 INFO - 1 warning generated. 05:40:14 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 05:40:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:15 INFO - Successfully installed psutil 05:40:15 INFO - Cleaning up... 05:40:15 INFO - Return code: 0 05:40:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:40:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:15 INFO - 'HOME': '/Users/cltbld', 05:40:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:15 INFO - 'LOGNAME': 'cltbld', 05:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:15 INFO - 'MOZ_NO_REMOTE': '1', 05:40:15 INFO - 'NO_EM_RESTART': '1', 05:40:15 INFO - 'PAGER': '/bin/cat', 05:40:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:15 INFO - 'PWD': '/builds/slave/test', 05:40:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:15 INFO - 'SHELL': '/bin/bash', 05:40:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:15 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:15 INFO - 'USER': 'cltbld', 05:40:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:15 INFO - 'XPC_FLAGS': '0x0', 05:40:15 INFO - 'XPC_SERVICE_NAME': '0', 05:40:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:15 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 05:40:15 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 05:40:15 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 05:40:15 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 05:40:15 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 05:40:15 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 05:40:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:17 INFO - Installing collected packages: mozsystemmonitor 05:40:17 INFO - Running setup.py install for mozsystemmonitor 05:40:18 INFO - Successfully installed mozsystemmonitor 05:40:18 INFO - Cleaning up... 05:40:18 INFO - Return code: 0 05:40:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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', '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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:40: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 jsonschema==2.5.1 05:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:18 INFO - 'HOME': '/Users/cltbld', 05:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:18 INFO - 'LOGNAME': 'cltbld', 05:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:18 INFO - 'MOZ_NO_REMOTE': '1', 05:40:18 INFO - 'NO_EM_RESTART': '1', 05:40:18 INFO - 'PAGER': '/bin/cat', 05:40:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:18 INFO - 'PWD': '/builds/slave/test', 05:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:18 INFO - 'SHELL': '/bin/bash', 05:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:18 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:18 INFO - 'USER': 'cltbld', 05:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:18 INFO - 'XPC_FLAGS': '0x0', 05:40:18 INFO - 'XPC_SERVICE_NAME': '0', 05:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:18 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:20 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 05:40:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:21 INFO - warning: no files found matching '*.txt' 05:40:21 INFO - no previously-included directories found matching 'build' 05:40:21 INFO - no previously-included directories found matching 'dist' 05:40:21 INFO - no previously-included directories found matching '.git*' 05:40:21 INFO - Installing collected packages: jsonschema, functools32 05:40:21 INFO - Running setup.py install for functools32 05:40:21 INFO - warning: no files found matching '*.txt' 05:40:21 INFO - no previously-included directories found matching 'build' 05:40:21 INFO - no previously-included directories found matching 'dist' 05:40:21 INFO - no previously-included directories found matching '.git*' 05:40:21 INFO - Successfully installed jsonschema functools32 05:40:21 INFO - Cleaning up... 05:40:21 INFO - Return code: 0 05:40:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:21 INFO - 'HOME': '/Users/cltbld', 05:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:21 INFO - 'LOGNAME': 'cltbld', 05:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:21 INFO - 'MOZ_NO_REMOTE': '1', 05:40:21 INFO - 'NO_EM_RESTART': '1', 05:40:21 INFO - 'PAGER': '/bin/cat', 05:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:21 INFO - 'PWD': '/builds/slave/test', 05:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:21 INFO - 'SHELL': '/bin/bash', 05:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:21 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:21 INFO - 'USER': 'cltbld', 05:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:21 INFO - 'XPC_FLAGS': '0x0', 05:40:21 INFO - 'XPC_SERVICE_NAME': '0', 05:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:40:21 INFO - Cleaning up... 05:40:21 INFO - Return code: 0 05:40:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:40:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:21 INFO - 'HOME': '/Users/cltbld', 05:40:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:21 INFO - 'LOGNAME': 'cltbld', 05:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:21 INFO - 'MOZ_NO_REMOTE': '1', 05:40:21 INFO - 'NO_EM_RESTART': '1', 05:40:21 INFO - 'PAGER': '/bin/cat', 05:40:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:21 INFO - 'PWD': '/builds/slave/test', 05:40:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:21 INFO - 'SHELL': '/bin/bash', 05:40:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:21 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:21 INFO - 'USER': 'cltbld', 05:40:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:21 INFO - 'XPC_FLAGS': '0x0', 05:40:21 INFO - 'XPC_SERVICE_NAME': '0', 05:40:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:24 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:25 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 05:40:25 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 05:40:25 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 05:40:25 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 05:40:25 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 05:40:25 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 05:40:25 INFO - Downloading docopt-0.6.1.tar.gz 05:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:26 INFO - Running setup.py install for blobuploader 05:40:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:26 INFO - Running setup.py install for requests 05:40:26 INFO - Running setup.py install for docopt 05:40:26 INFO - Successfully installed blobuploader requests docopt 05:40:26 INFO - Cleaning up... 05:40:26 INFO - Return code: 0 05:40:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:26 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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:26 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 05:40:26 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 05:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:26 INFO - 'HOME': '/Users/cltbld', 05:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:26 INFO - 'LOGNAME': 'cltbld', 05:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:26 INFO - 'MOZ_NO_REMOTE': '1', 05:40:26 INFO - 'NO_EM_RESTART': '1', 05:40:26 INFO - 'PAGER': '/bin/cat', 05:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:26 INFO - 'PWD': '/builds/slave/test', 05:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:26 INFO - 'SHELL': '/bin/bash', 05:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:26 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:26 INFO - 'USER': 'cltbld', 05:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:26 INFO - 'XPC_FLAGS': '0x0', 05:40:26 INFO - 'XPC_SERVICE_NAME': '0', 05:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-OrjS1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-fNkc6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-XqpHrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-BRiK5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-2QCpb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-x8JMil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-YMgt7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-GvpLuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-48QJYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-9yKoUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NkTy3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Xq9Yih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-qQHeHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-C8cmaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-0e1mdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Dbuh2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-avzaED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:28 INFO - Running setup.py install for manifestparser 05:40:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:29 INFO - Running setup.py install for mozcrash 05:40:29 INFO - Running setup.py install for mozdebug 05:40:29 INFO - Running setup.py install for mozdevice 05:40:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:29 INFO - Running setup.py install for mozfile 05:40:29 INFO - Running setup.py install for mozhttpd 05:40:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:29 INFO - Running setup.py install for mozinfo 05:40:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Running setup.py install for mozInstall 05:40:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Running setup.py install for mozleak 05:40:30 INFO - Running setup.py install for mozlog 05:40:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Running setup.py install for moznetwork 05:40:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Running setup.py install for mozprocess 05:40:30 INFO - Running setup.py install for mozprofile 05:40:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:30 INFO - Running setup.py install for mozrunner 05:40:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:31 INFO - Running setup.py install for mozscreenshot 05:40:31 INFO - Running setup.py install for moztest 05:40:31 INFO - Running setup.py install for mozversion 05:40:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:31 INFO - Cleaning up... 05:40:31 INFO - Return code: 0 05:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:40:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:31 INFO - 'HOME': '/Users/cltbld', 05:40:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:31 INFO - 'LOGNAME': 'cltbld', 05:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:31 INFO - 'MOZ_NO_REMOTE': '1', 05:40:31 INFO - 'NO_EM_RESTART': '1', 05:40:31 INFO - 'PAGER': '/bin/cat', 05:40:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:31 INFO - 'PWD': '/builds/slave/test', 05:40:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:31 INFO - 'SHELL': '/bin/bash', 05:40:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:31 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:31 INFO - 'USER': 'cltbld', 05:40:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:31 INFO - 'XPC_FLAGS': '0x0', 05:40:31 INFO - 'XPC_SERVICE_NAME': '0', 05:40:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ae1YV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Tmr3_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-yZ8m0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-QcnvKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-LCAjau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-QnCqNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-pHWpqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-0kM5uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:32 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)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-nyZZMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-i5GQ_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:32 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NJF5RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-u_BB68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:33 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)) 05:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-D3GFIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:33 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)) 05:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-dkbn_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:33 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)) 05:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-evpfOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:33 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)) 05:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ptFBBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:33 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)) 05:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:33 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-m2vQD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:33 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)) 05:40:33 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)) 05:40:33 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)) 05:40:33 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)) 05:40:33 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)) 05:40:33 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)) 05:40:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:36 INFO - Downloading blessings-1.6.tar.gz 05:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:36 INFO - Installing collected packages: blessings 05:40:36 INFO - Running setup.py install for blessings 05:40:36 INFO - Successfully installed blessings 05:40:36 INFO - Cleaning up... 05:40:36 INFO - Return code: 0 05:40:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:36 INFO - Reading from file tmpfile_stdout 05:40:36 INFO - Current package versions: 05:40:36 INFO - blessings == 1.6 05:40:36 INFO - blobuploader == 1.2.4 05:40:36 INFO - docopt == 0.6.1 05:40:36 INFO - functools32 == 3.2.3-2 05:40:36 INFO - jsonschema == 2.5.1 05:40:36 INFO - manifestparser == 1.1 05:40:36 INFO - mozInstall == 1.12 05:40:36 INFO - mozcrash == 0.17 05:40:36 INFO - mozdebug == 0.1 05:40:36 INFO - mozdevice == 0.48 05:40:36 INFO - mozfile == 1.2 05:40:36 INFO - mozhttpd == 0.7 05:40:36 INFO - mozinfo == 0.9 05:40:36 INFO - mozleak == 0.1 05:40:36 INFO - mozlog == 3.4 05:40:36 INFO - moznetwork == 0.27 05:40:36 INFO - mozprocess == 0.23 05:40:36 INFO - mozprofile == 0.28 05:40:36 INFO - mozrunner == 6.12 05:40:36 INFO - mozscreenshot == 0.1 05:40:36 INFO - mozsystemmonitor == 0.3 05:40:36 INFO - moztest == 0.7 05:40:36 INFO - mozversion == 1.4 05:40:36 INFO - psutil == 3.1.1 05:40:36 INFO - requests == 1.2.3 05:40:36 INFO - wsgiref == 0.1.2 05:40:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:37 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 05:40:37 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 05:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:37 INFO - 'HOME': '/Users/cltbld', 05:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:37 INFO - 'LOGNAME': 'cltbld', 05:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:37 INFO - 'MOZ_NO_REMOTE': '1', 05:40:37 INFO - 'NO_EM_RESTART': '1', 05:40:37 INFO - 'PAGER': '/bin/cat', 05:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:37 INFO - 'PWD': '/builds/slave/test', 05:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:37 INFO - 'SHELL': '/bin/bash', 05:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:37 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:37 INFO - 'USER': 'cltbld', 05:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:37 INFO - 'XPC_FLAGS': '0x0', 05:40:37 INFO - 'XPC_SERVICE_NAME': '0', 05:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 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)) 05:40:37 INFO - Cleaning up... 05:40:37 INFO - Return code: 0 05:40:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:38 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 0x10c76c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf7b554500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, '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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 05:40:38 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 05:40:38 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 05:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:38 INFO - 'HOME': '/Users/cltbld', 05:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:38 INFO - 'LOGNAME': 'cltbld', 05:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:38 INFO - 'MOZ_NO_REMOTE': '1', 05:40:38 INFO - 'NO_EM_RESTART': '1', 05:40:38 INFO - 'PAGER': '/bin/cat', 05:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:38 INFO - 'PWD': '/builds/slave/test', 05:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:38 INFO - 'SHELL': '/bin/bash', 05:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:40:38 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:38 INFO - 'USER': 'cltbld', 05:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:38 INFO - 'XPC_FLAGS': '0x0', 05:40:38 INFO - 'XPC_SERVICE_NAME': '0', 05:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:38 INFO - Cleaning up... 05:40:38 INFO - Return code: 0 05:40:38 INFO - Running post-action listener: _resource_record_post_action 05:40:38 INFO - Running post-action listener: _start_resource_monitoring 05:40:38 INFO - Starting resource monitoring. 05:40:38 INFO - [mozharness: 2016-11-04 12:40:38.422036Z] Finished create-virtualenv step (success) 05:40:38 INFO - [mozharness: 2016-11-04 12:40:38.423037Z] Running install step. 05:40:38 INFO - Running pre-action listener: _resource_record_pre_action 05:40:38 INFO - Running main action method: install 05:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:38 INFO - Reading from file tmpfile_stdout 05:40:38 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:38 INFO - Reading from file tmpfile_stdout 05:40:38 INFO - Output received: 05:40:38 INFO - Usage: mozinstall [options] installer 05:40:38 INFO - Options: 05:40:38 INFO - -h, --help show this help message and exit 05:40:38 INFO - -d DEST, --destination=DEST 05:40:38 INFO - Directory to install application into. [default: 05:40:38 INFO - "/builds/slave/test"] 05:40:38 INFO - --app=APP Application being installed. [default: firefox] 05:40:38 INFO - mkdir: /builds/slave/test/build/application 05:40:38 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'] 05:40:38 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 05:41:13 INFO - Reading from file tmpfile_stdout 05:41:13 INFO - Output received: 05:41:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:41:13 INFO - Running post-action listener: _resource_record_post_action 05:41:13 INFO - [mozharness: 2016-11-04 12:41:13.239057Z] Finished install step (success) 05:41:13 INFO - [mozharness: 2016-11-04 12:41:13.239196Z] Running run-tests step. 05:41:13 INFO - Running pre-action listener: _resource_record_pre_action 05:41:13 INFO - Running main action method: run_tests 05:41:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:13 INFO - 2016-11-04 05:41:13.253 screenresolution[1630:7396] starting screenresolution argv=screenresolution get 05:41:13 INFO - 2016-11-04 05:41:13.256 screenresolution[1630:7396] Display 0: 1600x1200x32@60 05:41:13 INFO - 2016-11-04 05:41:13.261 screenresolution[1631:7398] starting screenresolution argv=screenresolution list 05:41:13 INFO - Available Modes on Display 0 05:41:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:13 INFO - Intel Iris: 05:41:13 INFO - Chipset Model: Intel Iris 05:41:13 INFO - Type: GPU 05:41:13 INFO - Bus: Built-In 05:41:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:13 INFO - Vendor: Intel (0x8086) 05:41:13 INFO - Device ID: 0x0a2e 05:41:13 INFO - Revision ID: 0x0009 05:41:13 INFO - Displays: 05:41:13 INFO - Display: 05:41:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:13 INFO - Display Serial Number: 1600x1200 60 05:41:13 INFO - Main Display: Yes 05:41:13 INFO - Mirror: Off 05:41:13 INFO - Online: Yes 05:41:13 INFO - Rotation: Supported 05:41:13 INFO - Return code: 0 05:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:13 INFO - Python 2.7.3 05:41:13 INFO - Return code: 0 05:41:13 INFO - grabbing minidump binary from tooltool 05:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:13 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 0x10c580d30>, '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 0x10c3a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c578b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:13 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 05:41:13 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 05:41:13 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 05:41:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:13 INFO - Return code: 0 05:41:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:41:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:41:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 05:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --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 05:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMvAOzoQqF/Render', 05:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:13 INFO - 'HOME': '/Users/cltbld', 05:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:13 INFO - 'LOGNAME': 'cltbld', 05:41:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:13 INFO - 'MOZ_NO_REMOTE': '1', 05:41:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:13 INFO - 'NO_EM_RESTART': '1', 05:41:13 INFO - 'PAGER': '/bin/cat', 05:41:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:13 INFO - 'PWD': '/builds/slave/test', 05:41:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:13 INFO - 'SHELL': '/bin/bash', 05:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XK9MG2Audw/Listeners', 05:41:13 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:13 INFO - 'USER': 'cltbld', 05:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:13 INFO - 'XPC_FLAGS': '0x0', 05:41:13 INFO - 'XPC_SERVICE_NAME': '0', 05:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 05:41:13 INFO - mozversion application_buildid: 20161104031921 05:41:13 INFO - mozversion application_changeset: e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f 05:41:13 INFO - mozversion application_display_name: Nightly 05:41:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:13 INFO - mozversion application_name: Firefox 05:41:13 INFO - mozversion application_remotingname: firefox 05:41:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:41:13 INFO - mozversion application_vendor: Mozilla 05:41:13 INFO - mozversion application_version: 52.0a1 05:41:13 INFO - mozversion platform_buildid: 20161104031921 05:41:13 INFO - mozversion platform_changeset: e00ed3bd29f9cc2ba1dff27acce50d65b57e8c0f 05:41:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:41:13 INFO - mozversion platform_version: 52.0a1 05:41:13 INFO - using testdate: 1478263273 05:41:13 INFO - actual date: 1478263273 05:41:13 INFO - starting webserver on 'localhost:49273' 05:41:13 INFO - SUITE-START | Running 2 tests 05:41:13 INFO - TEST-START | dromaeo_css 05:41:13 INFO - operating with platform_type : mac_ 05:41:13 INFO - Initialising browser for dromaeo_css test... 05:41:13 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpUEJSr4/profile http://localhost:49273/getInfo.html) 05:41:16 INFO - PROCESS | 1641 | 2016-11-04 05:41:16.722 plugin-container[1643:7569] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 05:41:16 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:16 INFO - PROCESS | 1641 | 2016-11-04 05:41:16.724 plugin-container[1643:7569] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9503, name = 'com.apple.CFPasteboardClient' 05:41:16 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:16 INFO - PROCESS | 1641 | 2016-11-04 05:41:16.724 plugin-container[1643:7569] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:41:16 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 05:41:16 INFO - PROCESS | 1641 | colorDepth:24 05:41:16 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 05:41:16 INFO - PROCESS | 1641 | __metrics 05:41:16 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:41:17 INFO - TEST-INFO | 1641: exit 0 05:41:17 INFO - Browser initialized. 05:41:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:17 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpUEJSr4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:19 INFO - PROCESS | 1644 | 2016-11-04 05:41:19.926 plugin-container[1645:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 05:41:19 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:19 INFO - PROCESS | 1644 | 2016-11-04 05:41:19.926 plugin-container[1645:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 05:41:19 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:19 INFO - PROCESS | 1644 | 2016-11-04 05:41:19.927 plugin-container[1645:7690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:41:29 INFO - PROCESS | 1644 | RSS: Main: 239177728 05:41:29 INFO - PROCESS | 1644 | 05:42:19 INFO - PROCESS | 1644 | 1478263339165 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] 05:44:32 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 05:44:34 INFO - PROCESS | 1644 | RSS: Main: 253497344 05:44:34 INFO - PROCESS | 1644 | 05:47:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 05:47:37 INFO - PROCESS | 1644 | RSS: Main: 263577600 05:47:37 INFO - PROCESS | 1644 | 05:50:39 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 05:50:39 INFO - PROCESS | 1644 | RSS: Main: 267407360 05:50:39 INFO - PROCESS | 1644 | 05:53:41 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 05:53:42 INFO - PROCESS | 1644 | RSS: Main: 267640832 05:53:42 INFO - PROCESS | 1644 | 05:56:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 05:56:45 INFO - PROCESS | 1644 | RSS: Main: 271958016 05:56:45 INFO - PROCESS | 1644 | 05:59:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 05:59:49 INFO - PROCESS | 1644 | RSS: Main: 274489344 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | __start_tp_report 05:59:49 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 05:59:49 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 05:59:49 INFO - PROCESS | 1644 | |i|pagename|runs| 05:59:49 INFO - PROCESS | 1644 | |0;dojo.html;2839.3327568021514;3396.592408910931;3401.5918089829224;3416.333814906093;3421;5691.772329106836;5776.133579963002;5792.478676919077;5793.797217097397;5830.883382332351;5647;5732.140178973156;5753.827385178452;5755.913661295084;5758.5393168546525;5148.974255128728;5234.6335756497065;5253.133233016567;5275.920861187085;5293.153095504701;5267.55225805807;5341.172118321647;5380.973095134519;5425.430329815372;5426.484483974016;5110.054639891613;5179.067767801795;5307.6019298552765;5316.441773613773;5325.733713314357;5159.664621799571;5240.34495688039;5282.577393808486;5312.973435132838;5335.759890804923;5231.163013917793;5247.527722504955;5274.630775845692;5276.5251127398715;5308.124159513675;5584.581156413245;5617.157426386034;5657.603967722261;5796.13058041293;5821.854453638651;5632.549396048306;5848.356680765112;6171.197744293258;6266.3420340864;6274.466670333027;6283.340249273851;6286.528510361742;6304.905426418607;6308.022256550196;6309.400606942333;5551.861203469945;5705.82882513517;5796.840631873642;5931.525477961753;5938.8812223755285;6220.34686357935;6224.439800417984;6234.657093859791;6276.46650034743;6294.370562943761;6176.845578860564;6211.782587609412;6229.750809967551;6229.7508099676415;6243.344448832898;5200.427952925175;6228.159198508234;6292.087647291209;6306.274778400542;6319.96840015797;6130.7650488535955;6133.754649813957;6153.387641565631;6164.815055548341;6170.598911070766;5122.974385128051;6255.343188965184;6261.530385221126;6277.654728989858;6279.152314437584;4948.703077815343;4965.329680493087;4970.2793095001025;4973.950260497349;5018.648694591344;4348.065165989327;4409.294512877988;4442;4501.279795232747;4540.636749060067;5759.308882933991;5770.336411312669;6030.819075427744;6056.212692350055;6084.574079814371;5303.814366497154;6183.690815459209;6206.84512713437;6276;6345.302016778151;5808.82573522795;5835.5915085944425;6038.577299589075;6047.758089676452;6051.243594550681;5409.486098820606;5416.891662166735;5448.645838020516;5466.098093814476;5500.50495455411;6355.968220158962;6359.300476947439;6362.745490180341;6372.299047104908;6403.359664033559;6167.044108163242;6286.622802631857;6286.937130628635;6352.809415717536;6369.20384951881;6233.376662333912;6260.373962603703;6260.780872669435;6293.496520278458;6324.810255692337;6233.314335423009;6235.189425374672;6252.718627661675;6256.0303153011355;6263.96868015657;5090.338256026693;6199.94600917854;6276.874462510816;6292.2134733158355;6306.747730090745;5977.671228082497;6096.2243283589;6110.725017374318;6112.0171844544475;6117.632942023492;6111.786087487095;6123.9232566596775;6124.846878828065;6131.957816599936;6134.222293197161;5148.794048238028;5876.970615146897;5881.117832325014;5916.585838991401;6033.577649564489;4918.901621967612;6118.071005858243;6119.8776024478375;6183.134361189527;6190.2571691397325;6006.009008513546;6027.498036564725;6049.818505444844;6057.363976782374;6071.392860713893 05:59:49 INFO - PROCESS | 1644 | |1;ext.html;512.2163090471578;517.1880148167379;548.9670619762815;553.8117040206329;560.1541672075163;29881.850590747043;39595.01012474686;39610.81167564978;39617.009574760574;39719.2056158877;39960.60039399605;40436.19127617446;40489.39265911013;40503.98740031493;40562.18875622486;8021.318187954002;9327.906720932806;9548.427094374325;9556.426614403123;9591.520423978807;9437.292203084762;9521.71434856952;9521.761955951122;9525.047495250454;9545.14093731564;9501.477418741966;9524.619015239383;9527.42835429873;9532.428054316728;9559.378640388353;9501.904980950205;9516.714498565054;9535.332526723132;9537.618495260182;9554.996725343843;7393.741219057361;7815.8436831263625;8773.736787896374;9285.767855803591;9471.816022997125;39169.216615667814;39200.21599568021;39244.411333830016;39309;39344.80327598372;2690.5695088785897;2927.5901373807683;3231.1760501072313;3393.881214157517;3397.796132232074;24809;30194.396112077637;30261.394772104435;30394.696053039406;31332.843335783175;2652.8010399219943;3058.6023816903885;3187.2509960159346;3205.4710972689477;3310.933781324384;467209;467591;470739;470816.64591676823;471651.64174179255;53048;53051.46948530465;53205.73397133067;53211.73394133004;53295;56561;56657.433425665215;56811;56911;57054;15094.245287735788;15106.697866042838;15304;15316.081035137704;15319.23403829804;11881.643550693492;11892.524299028115;11937.582184623496;11959;11965.641030769091;15154.090754554541;15626.45307414235;15898.364065437252;15923.840761592235;15938.203089845692;6184.196054512884;6253.218347706536;6255.874882502416;6276;6382.489400848014;868.4615538366127;870.2950610005915;1062.9574817007388;1079.1636481726725;1094.7591529863416;28128;28215.294617634725;28738.568921466194;29319.706802932124;29449.11652650424;1045.1638689048882;1049.7690508088208;1066.36018388966;1067.5302866738766;1116.7096554895786;15106.622334441507;15140.848591513944;15163.166025868462;15230.933834631469;15455.14079895223;608465;612998;616916;617379;617746;54155.45844541583;54206.728966354916;54240;54292.45707542874;54390.18414723782;1312.1012106706937;1351.6080336702246;1358.4430383542897;1364.4133022800092;1372.6842826150041;22186.556268874854;29076.12771616855;29248.707512924597;29258;30119.548206777356;40715.1856962865;46918.76540617275;48024.79938001578;48217.75891120522;48297.551073467854;454178.4582154136;454694;455476;457989;458302;38570.80714596409;39059.80470097745;39383.21233575255;39428.80285598554;39660.40509392419;40996.79501602592;44604.77697611491;46271;46277.38029168963;47627;36474.8176259117;37303.06742331355;38095;38211.80894095512;38363.23273534458;34382;37468.43797342988;37621.81189094037;37951.81024094862;39882;36645;37253.62746372502;37384.81307593445;39074;39155.216895662496;29459.263518411353;29943.55084673775;31672.841635791672;33491.33017339591;34027.14932126716;24883.377915552257;25424.110156145187;25630.97476100935;25652.615210772226;26163.869180653975 05:59:49 INFO - PROCESS | 1644 | |2;jquery.html;342.39738061012616;369.85760482214357;375.8590528551794;382.7645997711408;395.51351837240196;11222.831657525143;11743.119266055048;11838.526458941653;11840.940795296012;19079.71380429293;20392.694109588367;21072.367828965118;21113.894430527827;21216.89391553044;21375.251866184688;6205.596636218631;6904.930950690479;6908.620025898574;6916.965415172917;6923.342282483181;6449.484041276686;6887.27683593223;6930.584164950119;6950.89573656393;6970.581765094111;6604.438622717074;6796.898046529306;6923.342282483156;6929.965350173242;6946.652667366611;6740.359665831738;6789.93210067898;6805.727770889159;6808.353206445379;6819.761308354208;6323.810285691437;6666.600003999727;6698.296678848699;6700.128983232197;6715.9328406715795;22051.448713782123;26120.085796997202;27745.722542774514;28009.859950700116;28027.719722802714;2328.6739856419945;2355.740868504463;2405.5068710914397;2488.14159115105;3033.4994725870206;16265.349386024545;16629.91685041567;16676.16619169037;16784.916075419667;17626.647467050716;1636.4190712297202;2013.5368865161095;2016.8285695715736;2036.665983780626;2089.1643342662906;134997;137753;141743;150280;155965;9306.953465232631;9619.855702164474;9726.270529710158;9806.803863922682;20421.774693518437;12299.893009629177;12429.192102513307;12435.005199584011;12458.252504849737;12463.875361246271;7758.456908016385;7851.960740196262;7917.960410198028;7922.4850384724805;8061.153578874251;7865;7961.840763184704;8193.180681931759;8203.507789532528;8227.588620568948;3220.339830334776;3797.886063418102;3872.786996715208;3874.903127421837;3931.8230679619487;1910.4746194796542;1957.2171131547495;1984.1567333883042;1990.6814909614393;2000.4998750312423;670.199112061092;764.8565394734907;799.9200079991954;823.2467292427361;873.7291439653727;20873.89563052175;21067.367978960654;21190.576188476145;21651;21822.345329640135;910.8763206960846;934.8971613122551;943.3443756589105;979.7060881735594;1029.8043371759343;11047.558097676003;11389.316641001566;11443.542258309575;11460.713482162846;11565;511962;561671;566146;566696;587222.5370033468;128438;134350;134839.30321393517;135650;135654;959.5298303831072;963.8554216867386;977.2572844637984;981.3130808434124;984.675057231112;17581.912090439466;18690.25238990425;19009.714854277474;19053.714194287375;19382;45467.772661136485;47081;51982.74008629932;55592;57213.14180287216;57947;76266;78507.82238266316;78839;79945.60027199826;7380.96309518449;7454.329110380092;7980.08229053643;8057;8481.703140390056;11923.10576706733;12668.493260269855;12959.546415875398;13067.738645226853;13106.60680179596;9695.030496950249;10668.359898406121;10738.677839664822;11210.607628733282;11319.490622922152;8657.95671021641;9640.469774162488;10468.947655261674;10685.946570267099;11241;11025.338479691245;11168.776624467628;11311.151663625096;11541;11633.069354451793;7937.2459616336355;8122.918770812216;8193.098759136372;8335;8361.372897032868;10778.784424311627;11065.391403472886;11092;11344.262622929484;11475 05:59:49 INFO - PROCESS | 1644 | |3;mootools.html;1483.68842543066;1505.7063872544852;1684.7472879068139;1763.6825371433154;1803.9549011274717;6964.582125072499;7486.962565187167;7729.4975826571235;8004.079530853954;8486.151384861518;7929.722459713911;8207.425480216372;8221.465604735702;8325.167483251671;8350.248477637011;1930.4884205685505;2295.988520057397;2307.7692230776956;2320.628699408095;2324.244620498336;2280.464090938628;2346.6597343385197;2348.9295321140394;2353.0705371378294;2359.327591636384;2295.116380193621;2301.068067432684;2307.1924826310824;2325.3489023073566;2327.441414060622;2263.2757517594378;2273.3407311879537;2278.498730279336;2302.7812357826147;2311.7341505726804;2304.596695578267;2320.582295186866;2321.5124823787023;2330.6387509935985;2332.953340933172;8200.302974247137;8301.584920753938;8360.665573377119;8365.038020627644;8369.916300836974;5054.82308119214;5220.269162317279;5220.556252718524;5233.450487698775;5235.214717792323;3250.8049517029053;3300.504924261356;3594.029612004744;3601.603823579404;3605.6574625410544;988.5996171550534;1031.2265800649513;1062.8830828608848;1088.5264909764278;1096.6490722968654;9661.565229564687;9743.658971935949;10897.564097436092;10937.726556836064;10951.123910087173;3785.6782173515007;4004.699647526389;4555;4623.514530974267;4624.121416930772;7647.388208943271;9270.72187834366;9286.674966376144;9345.0654934506;9376.484293363794;5098.97450512745;5160.251763494272;5168.173092305213;5176.042432150064;5183.2743415921805;4266.850660226877;4523.977380113144;4714.151452738472;4724.6692731508465;4737.597304229179;3035.074302337764;3786.2427514497213;3797.886063418102;3815.313243616176;3906.6950499205605;1250.249850089957;1250.737345157509;1256.6544200344795;1266.170658218868;1275.3495717184303;847.6778824046946;863.7322430046705;868.7306934850217;897.6364572348209;919.9080091990882;5009.373828271466;5682.715864206856;5754;5805.303363596396;6006.459418652343;853.9064480374595;914.0585197246713;916.8853893263342;948.6964171465065;965.5365424596233;3025.969740302613;3817.6373244541724;3831.7317787755146;3904.5509766376663;3907.0623050467684;9802.509874506246;10169.338992965582;10171.694849154537;10175.185985121172;10181.694549163536;10036.799264014679;10097.5456104474;10141.087302142843;10148.340357876714;10185.08334249921;7569;7596.936428899959;7713.3443657288635;7936.4841285315115;7944.443888927835;5473.75368108444;5498.752556135064;5562.972185139049;5593.748281327432;5712.685802280915;7748.961255193688;9245.537723113683;9338.206252468617;9368.110029547182;9473.100055494717;8080.959595202222;9467.952660236655;9477.431354118775;9496.952515237379;9607.087326703951;5401.594880383908;6223.471004964629;6442.935570644421;6683.699233534406;6816.625085620339;7742.993410856552;8003.31971782405;8003.799905002422;8004.519728816057;8007.679692812222;7043.612601306977;7130.465215108784;7146.464015198777;7219.278072192738;7279.745208917662;6941.132358455193;7011.404030657452;7033.331833475811;7054.894176587252;7119.715211391486;5597.328320601391;6534.803955881332;6629.337066293525;6634.83412914681;6636.2700102987665;4785.760711964457;4820.25286080658;4857.53853383928;4878.658493905535;4927.852164435073;4779.163646361803;4847.030593881167;4863.732494712684;4961.355023846877;4963.354763880672 05:59:49 INFO - PROCESS | 1644 | |4;prototype.html;295.13819646631833;301.3280384742025;307.55558218374443;308.5433558333665;312.20076603894034;4764.237721964487;4976.502349765022;5488.972555137224;5566.777328906844;5631.746571404281;5089.643724939247;5430.402655707869;5480.534154596864;5481.479259470354;5548;3052.9389412211744;3388.542546756187;4019.3167161582455;4172.019575399779;4186.267403204427;4017.8392864285393;4194.538600753915;4211.789410529476;4249.915001699979;4256.2338779019765;4099.3850922361735;4146.958530414702;4156.792160391983;4159.729617574848;4161.895952601194;3488.232588830453;4105.404716316148;4129.380592911058;4135.7932103395015;4145.917081658365;2996.5654980027775;3276.099862079988;3398.303347813692;3647;3654.086478380405;4181.490666493701;5313.058855264602;5323.083689871643;5381.757820898029;5382.811601593965;4146.792660366969;4320.33034879591;4390.78046097697;4686.851721328297;4816.759162041919;5017.598592112622;5252.527272545451;5256.92114618281;5815.98220311448;5837.824865254049;3871.6709079728257;3927.8232479538487;3967.1867267210155;3992.441058251818;4028.8589899353674;5254.896471740977;5482.424345443711;6046.516278697738;6051.667158306292;6070.480750488542;3860.9613903861164;4044.6541006216326;4079.938800917989;4080.959190408058;4083.7958102094776;3903.765774053566;4081.9591804082174;4099.97950010248;4146.129312844276;4172.91654166921;3723.1622884851236;4155.688323375759;4718.764061796896;4745.83389581363;4770.212914869007;4321.438213032286;5099.311592934981;5326.5472434842695;5914.64512129265;5917.970410147922;1123.7471568896933;1182.8999030901343;1273.3060482037313;1290.9548165814151;1363.6840448966796;741.907261592301;869.7434256894231;938.6104766521953;944.9433034017981;950.9096635819586;5448.673079615236;5448.91826622601;5460.890782184334;5461.563074953994;5500.697461639648;5905.021817691036;5922.2597175353085;5971.790987315423;6014.398560143951;6030.48740857032;4502.797374118172;4534.45586529612;5135.743212839343;5433;5475.589330800206;2779.43021680555;2941;3074.3697542003833;3172.27037781307;3178.2201966898147;4632.72203667781;4846.878828029327;5220.530152286313;5561.026820306431;6234.5635805494085;5709.4878253568295;5911.793087242012;5964.433378829004;5977;6005.459508644243;5509.752061157257;5728.484436400744;6015.639061656315;6124;6297.433231009231;5534.418886016911;5717.5425965921795;5911.645301281937;5915.515483496855;6003.538549194809;5149.253358263106;5171.534561889448;5392;5848.777464563439;5926.318473375617;5797.449242321973;5883.176355310175;6095.4761309672585;6156.199694039746;6232.136075633137;3125.7801967343903;3895.2988462077096;4042;4076.470058892325;4107.281225785416;4729.739864307496;5964.850878728066;5997.520198384206;6010.368911264429;6020.8795824084145;5379.488948549882;5609.523190528851;5713.657180569179;5883.588148829541;5979.342272349951;5291.074062039051;5777.191193233035;5844.07796101949;5850.180974663465;5929.377415371324;4049.696272779612;4914.94328719327;5594.964931487688;5784.797532086525;5800.59395842287;4532.773361331986;5604.99110160175;5785.652860828195;5921.61509501881;6052.096397823291;5018.573421259234;5339.1046178607585;5411.945880541144;5433.2665090213895;5833.4749872511675 05:59:49 INFO - PROCESS | 1644 | |5;yui.html;640.9391107844755;690.6788343420309;716.2228981555011;740.7664503205519;762.6263131065776;14366;15213.4675286365;15215.23923803808;15273.694526109484;15308.923455382735;13717.931410342959;13900.582982510516;13906.582802515915;13914.165150091001;13944.790828137586;650.6779144323543;669.2003056347667;802.2338666573438;811.5617566514076;819.1194465949098;814.6741303478626;816.6855760532178;826.6238861318116;827.7971896885272;828.9129641387658;866.9393142480029;884.0938038510513;887.3433659092258;887.8135591525787;888.3248730964481;880.9119088091204;883.6642076011138;884.0717246890771;884.8893888263967;885.8626912828522;638.2883085359837;655.4625207329991;655.6885479397296;656.0618315808392;656.8390744267638;14364;14830.33263503145;15019.924900375538;15044.473443429428;15069.773953390708;12264.38678066102;12461.875381246162;13883.791743123862;14019.50931717385;14323.355449004715;12216.511339546407;12257.325847078408;12286.692832679166;12343.716412463724;12454.564090256797;3604.3331983582857;3730.8134593270497;4431.26884064129;4441.622462090726;4557.111363284161;81752;90656;91018.5449072757;91304.08695912956;91461;10690.251682382159;10706.464676766287;10774.730631734112;10904.727381815359;11207.103431725605;18548.629027419374;19165.712514312298;20254.594908101757;20392.796072039386;20628.484287892923;7565.167831538526;7665.965094712143;7793.142754297023;7826.452148349561;7917.722879699298;4250.277452833026;4261.914761704748;4361.865914862159;4380.342948557678;4391.978040109779;3441.1225137589754;3503.141730276048;3522.418800897823;3536.929261414757;3550.6981906537717;640.5196102922808;642.1780121444558;649.6621756686591;660.313274194832;664.0769330630427;578.4851482180862;636.5098873867097;653.7613770973655;664.3124366280877;674.1202730436729;7628.732994345171;8105.7436097405;8218.342532597379;8679.262262707614;9019;615.1449485215554;632.4940047961704;638.8881945659491;658.7595527632379;661.394823736276;3670.596234414212;4276.914461710749;4395.91110490876;4453.265211240174;4592.563706447882;119858;120093.39953300178;120840;121093;125081.37459312828;83678;107091;108112;108467.91532084579;109127;92946;94223;96805;97078.5146074265;97100.02899971051;8476.618552165291;8608.78478038054;8647.697330593399;8704.738857834274;8849.823003540021;46309;48224.55326340215;59238.40761592501;60101.69949150401;61054.08418873631;80020.59989700014;82796.58601706952;84161;85403;87682.12317876739;11303.660890173307;11773;11839.822402663794;11850.348230847387;12060.758784824078;11639.709007274885;12228.816567751313;13717.245551494365;14146.938979576778;14352.282385880455;9451.574679139318;11108.833367499656;12530.248185108923;12836.422360993964;12933.159344642569;12982.415791289226;13287.800682989957;13470.259135747629;13573.389197486335;13600.727985440039;12688.302143382203;12865.742685146057;13369;13473;13533.932330338286;10106.595736170471;10284.228682848667;10301.9484902575;10314.071733544017;10342.848093748906;8712.433691810013;9542.379745316532;9561.713148605553;10275.383476991405;11168.431260809897 05:59:49 INFO - PROCESS | 1644 | __end_tp_report 05:59:49 INFO - PROCESS | 1644 | __start_cc_report 05:59:49 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,373 05:59:49 INFO - PROCESS | 1644 | __end_cc_report 05:59:49 INFO - PROCESS | 1644 | __startTimestamp1478264389100__endTimestamp 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | ------- Summary: start ------- 05:59:49 INFO - PROCESS | 1644 | Number of tests: 6 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5749.00 Median:6027.50 stddev:650.97 (10.8%) stddev-sans-first:611.64 05:59:49 INFO - PROCESS | 1644 | Values: 2839.3 3396.6 3401.6 3416.3 3421.0 5691.8 5776.1 5792.5 5793.8 5830.9 5647.0 5732.1 5753.8 5755.9 5758.5 5149.0 5234.6 5253.1 5275.9 5293.2 5267.6 5341.2 5381.0 5425.4 5426.5 5110.1 5179.1 5307.6 5316.4 5325.7 5159.7 5240.3 5282.6 5313.0 5335.8 5231.2 5247.5 5274.6 5276.5 5308.1 5584.6 5617.2 5657.6 5796.1 5821.9 5632.5 5848.4 6171.2 6266.3 6274.5 6283.3 6286.5 6304.9 6308.0 6309.4 5551.9 5705.8 5796.8 5931.5 5938.9 6220.3 6224.4 6234.7 6276.5 6294.4 6176.8 6211.8 6229.8 6229.8 6243.3 5200.4 6228.2 6292.1 6306.3 6320.0 6130.8 6133.8 6153.4 6164.8 6170.6 5123.0 6255.3 6261.5 6277.7 6279.2 4948.7 4965.3 4970.3 4974.0 5018.6 4348.1 4409.3 4442.0 4501.3 4540.6 5759.3 5770.3 6030.8 6056.2 6084.6 5303.8 6183.7 6206.8 6276.0 6345.3 5808.8 5835.6 6038.6 6047.8 6051.2 5409.5 5416.9 5448.6 5466.1 5500.5 6356.0 6359.3 6362.7 6372.3 6403.4 6167.0 6286.6 6286.9 6352.8 6369.2 6233.4 6260.4 6260.8 6293.5 6324.8 6233.3 6235.2 6252.7 6256.0 6264.0 5090.3 6199.9 6276.9 6292.2 6306.7 5977.7 6096.2 6110.7 6112.0 6117.6 6111.8 6123.9 6124.8 6132.0 6134.2 5148.8 5877.0 5881.1 5916.6 6033.6 4918.9 6118.1 6119.9 6183.1 6190.3 6006.0 6027.5 6049.8 6057.4 6071.4 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:64725.07 Median:39406.01 stddev:138300.98 (351.0%) stddev-sans-first:138604.92 05:59:49 INFO - PROCESS | 1644 | Values: 512.2 517.2 549.0 553.8 560.2 29881.9 39595.0 39610.8 39617.0 39719.2 39960.6 40436.2 40489.4 40504.0 40562.2 8021.3 9327.9 9548.4 9556.4 9591.5 9437.3 9521.7 9521.8 9525.0 9545.1 9501.5 9524.6 9527.4 9532.4 9559.4 9501.9 9516.7 9535.3 9537.6 9555.0 7393.7 7815.8 8773.7 9285.8 9471.8 39169.2 39200.2 39244.4 39309.0 39344.8 2690.6 2927.6 3231.2 3393.9 3397.8 24809.0 30194.4 30261.4 30394.7 31332.8 2652.8 3058.6 3187.3 3205.5 3310.9 467209.0 467591.0 470739.0 470816.6 471651.6 53048.0 53051.5 53205.7 53211.7 53295.0 56561.0 56657.4 56811.0 56911.0 57054.0 15094.2 15106.7 15304.0 15316.1 15319.2 11881.6 11892.5 11937.6 11959.0 11965.6 15154.1 15626.5 15898.4 15923.8 15938.2 6184.2 6253.2 6255.9 6276.0 6382.5 868.5 870.3 1063.0 1079.2 1094.8 28128.0 28215.3 28738.6 29319.7 29449.1 1045.2 1049.8 1066.4 1067.5 1116.7 15106.6 15140.8 15163.2 15230.9 15455.1 608465.0 612998.0 616916.0 617379.0 617746.0 54155.5 54206.7 54240.0 54292.5 54390.2 1312.1 1351.6 1358.4 1364.4 1372.7 22186.6 29076.1 29248.7 29258.0 30119.5 40715.2 46918.8 48024.8 48217.8 48297.6 454178.5 454694.0 455476.0 457989.0 458302.0 38570.8 39059.8 39383.2 39428.8 39660.4 40996.8 44604.8 46271.0 46277.4 47627.0 36474.8 37303.1 38095.0 38211.8 38363.2 34382.0 37468.4 37621.8 37951.8 39882.0 36645.0 37253.6 37384.8 39074.0 39155.2 29459.3 29943.6 31672.8 33491.3 34027.1 24883.4 25424.1 25631.0 25652.6 26163.9 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34742.89 Median:356.13 stddev:94599.08 (26563.3%) stddev-sans-first:94829.19 05:59:49 INFO - PROCESS | 1644 | Values: 342.4 369.9 375.9 382.8 395.5 11222.8 11743.1 11838.5 11840.9 19079.7 20392.7 21072.4 21113.9 21216.9 21375.3 6205.6 6904.9 6908.6 6917.0 6923.3 6449.5 6887.3 6930.6 6950.9 6970.6 6604.4 6796.9 6923.3 6930.0 6946.7 6740.4 6789.9 6805.7 6808.4 6819.8 6323.8 6666.6 6698.3 6700.1 6715.9 22051.4 26120.1 27745.7 28009.9 28027.7 2328.7 2355.7 2405.5 2488.1 3033.5 16265.3 16629.9 16676.2 16784.9 17626.6 1636.4 2013.5 2016.8 2036.7 2089.2 134997.0 137753.0 141743.0 150280.0 155965.0 9307.0 9619.9 9726.3 9806.8 20421.8 12299.9 12429.2 12435.0 12458.3 12463.9 7758.5 7852.0 7918.0 7922.5 8061.2 7865.0 7961.8 8193.2 8203.5 8227.6 3220.3 3797.9 3872.8 3874.9 3931.8 1910.5 1957.2 1984.2 1990.7 2000.5 670.2 764.9 799.9 823.2 873.7 20873.9 21067.4 21190.6 21651.0 21822.3 910.9 934.9 943.3 979.7 1029.8 11047.6 11389.3 11443.5 11460.7 11565.0 511962.0 561671.0 566146.0 566696.0 587222.5 128438.0 134350.0 134839.3 135650.0 135654.0 959.5 963.9 977.3 981.3 984.7 17581.9 18690.3 19009.7 19053.7 19382.0 45467.8 47081.0 51982.7 55592.0 57213.1 57947.0 76266.0 78507.8 78839.0 79945.6 7381.0 7454.3 7980.1 8057.0 8481.7 11923.1 12668.5 12959.5 13067.7 13106.6 9695.0 10668.4 10738.7 11210.6 11319.5 8658.0 9640.5 10468.9 10685.9 11241.0 11025.3 11168.8 11311.2 11541.0 11633.1 7937.2 8122.9 8193.1 8335.0 8361.4 10778.8 11065.4 11092.0 11344.3 11475.0 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5363.80 Median:5172.11 stddev:2922.53 (56.5%) stddev-sans-first:2916.18 05:59:49 INFO - PROCESS | 1644 | Values: 1483.7 1505.7 1684.7 1763.7 1804.0 6964.6 7487.0 7729.5 8004.1 8486.2 7929.7 8207.4 8221.5 8325.2 8350.2 1930.5 2296.0 2307.8 2320.6 2324.2 2280.5 2346.7 2348.9 2353.1 2359.3 2295.1 2301.1 2307.2 2325.3 2327.4 2263.3 2273.3 2278.5 2302.8 2311.7 2304.6 2320.6 2321.5 2330.6 2333.0 8200.3 8301.6 8360.7 8365.0 8369.9 5054.8 5220.3 5220.6 5233.5 5235.2 3250.8 3300.5 3594.0 3601.6 3605.7 988.6 1031.2 1062.9 1088.5 1096.6 9661.6 9743.7 10897.6 10937.7 10951.1 3785.7 4004.7 4555.0 4623.5 4624.1 7647.4 9270.7 9286.7 9345.1 9376.5 5099.0 5160.3 5168.2 5176.0 5183.3 4266.9 4524.0 4714.2 4724.7 4737.6 3035.1 3786.2 3797.9 3815.3 3906.7 1250.2 1250.7 1256.7 1266.2 1275.3 847.7 863.7 868.7 897.6 919.9 5009.4 5682.7 5754.0 5805.3 6006.5 853.9 914.1 916.9 948.7 965.5 3026.0 3817.6 3831.7 3904.6 3907.1 9802.5 10169.3 10171.7 10175.2 10181.7 10036.8 10097.5 10141.1 10148.3 10185.1 7569.0 7596.9 7713.3 7936.5 7944.4 5473.8 5498.8 5563.0 5593.7 5712.7 7749.0 9245.5 9338.2 9368.1 9473.1 8081.0 9468.0 9477.4 9497.0 9607.1 5401.6 6223.5 6442.9 6683.7 6816.6 7743.0 8003.3 8003.8 8004.5 8007.7 7043.6 7130.5 7146.5 7219.3 7279.7 6941.1 7011.4 7033.3 7054.9 7119.7 5597.3 6534.8 6629.3 6634.8 6636.3 4785.8 4820.3 4857.5 4878.7 4927.9 4779.2 4847.0 4863.7 4961.4 4963.4 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4624.19 Median:5253.71 stddev:1447.75 (27.6%) stddev-sans-first:1414.88 05:59:49 INFO - PROCESS | 1644 | Values: 295.1 301.3 307.6 308.5 312.2 4764.2 4976.5 5489.0 5566.8 5631.7 5089.6 5430.4 5480.5 5481.5 5548.0 3052.9 3388.5 4019.3 4172.0 4186.3 4017.8 4194.5 4211.8 4249.9 4256.2 4099.4 4147.0 4156.8 4159.7 4161.9 3488.2 4105.4 4129.4 4135.8 4145.9 2996.6 3276.1 3398.3 3647.0 3654.1 4181.5 5313.1 5323.1 5381.8 5382.8 4146.8 4320.3 4390.8 4686.9 4816.8 5017.6 5252.5 5256.9 5816.0 5837.8 3871.7 3927.8 3967.2 3992.4 4028.9 5254.9 5482.4 6046.5 6051.7 6070.5 3861.0 4044.7 4079.9 4081.0 4083.8 3903.8 4082.0 4100.0 4146.1 4172.9 3723.2 4155.7 4718.8 4745.8 4770.2 4321.4 5099.3 5326.5 5914.6 5918.0 1123.7 1182.9 1273.3 1291.0 1363.7 741.9 869.7 938.6 944.9 950.9 5448.7 5448.9 5460.9 5461.6 5500.7 5905.0 5922.3 5971.8 6014.4 6030.5 4502.8 4534.5 5135.7 5433.0 5475.6 2779.4 2941.0 3074.4 3172.3 3178.2 4632.7 4846.9 5220.5 5561.0 6234.6 5709.5 5911.8 5964.4 5977.0 6005.5 5509.8 5728.5 6015.6 6124.0 6297.4 5534.4 5717.5 5911.6 5915.5 6003.5 5149.3 5171.5 5392.0 5848.8 5926.3 5797.4 5883.2 6095.5 6156.2 6232.1 3125.8 3895.3 4042.0 4076.5 4107.3 4729.7 5964.9 5997.5 6010.4 6020.9 5379.5 5609.5 5713.7 5883.6 5979.3 5291.1 5777.2 5844.1 5850.2 5929.4 4049.7 4914.9 5595.0 5784.8 5800.6 4532.8 5605.0 5785.7 5921.6 6052.1 5018.6 5339.1 5411.9 5433.3 5833.5 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21661.82 Median:4436.45 stddev:32852.76 (740.5%) stddev-sans-first:32907.01 05:59:49 INFO - PROCESS | 1644 | Values: 640.9 690.7 716.2 740.8 762.6 14366.0 15213.5 15215.2 15273.7 15308.9 13717.9 13900.6 13906.6 13914.2 13944.8 650.7 669.2 802.2 811.6 819.1 814.7 816.7 826.6 827.8 828.9 866.9 884.1 887.3 887.8 888.3 880.9 883.7 884.1 884.9 885.9 638.3 655.5 655.7 656.1 656.8 14364.0 14830.3 15019.9 15044.5 15069.8 12264.4 12461.9 13883.8 14019.5 14323.4 12216.5 12257.3 12286.7 12343.7 12454.6 3604.3 3730.8 4431.3 4441.6 4557.1 81752.0 90656.0 91018.5 91304.1 91461.0 10690.3 10706.5 10774.7 10904.7 11207.1 18548.6 19165.7 20254.6 20392.8 20628.5 7565.2 7666.0 7793.1 7826.5 7917.7 4250.3 4261.9 4361.9 4380.3 4392.0 3441.1 3503.1 3522.4 3536.9 3550.7 640.5 642.2 649.7 660.3 664.1 578.5 636.5 653.8 664.3 674.1 7628.7 8105.7 8218.3 8679.3 9019.0 615.1 632.5 638.9 658.8 661.4 3670.6 4276.9 4395.9 4453.3 4592.6 119858.0 120093.4 120840.0 121093.0 125081.4 83678.0 107091.0 108112.0 108467.9 109127.0 92946.0 94223.0 96805.0 97078.5 97100.0 8476.6 8608.8 8647.7 8704.7 8849.8 46309.0 48224.6 59238.4 60101.7 61054.1 80020.6 82796.6 84161.0 85403.0 87682.1 11303.7 11773.0 11839.8 11850.3 12060.8 11639.7 12228.8 13717.2 14146.9 14352.3 9451.6 11108.8 12530.2 12836.4 12933.2 12982.4 13287.8 13470.3 13573.4 13600.7 12688.3 12865.7 13369.0 13473.0 13533.9 10106.6 10284.2 10301.9 10314.1 10342.8 8712.4 9542.4 9561.7 10275.4 11168.4 05:59:49 INFO - PROCESS | 1644 | -------- Summary: end -------- 05:59:49 INFO - PROCESS | 1644 | 05:59:49 INFO - TEST-INFO | 1644: exit 0 05:59:49 INFO - TEST-OK | dromaeo_css | took 1116026ms 05:59:49 INFO - TEST-START | kraken 05:59:49 INFO - operating with platform_type : mac_ 05:59:49 INFO - Initialising browser for kraken test... 05:59:49 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpRymq31/profile http://localhost:49273/getInfo.html) 05:59:52 INFO - PROCESS | 1655 | 2016-11-04 05:59:52.814 plugin-container[1656:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:59:52 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:59:52 INFO - PROCESS | 1655 | 2016-11-04 05:59:52.817 plugin-container[1656:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:59:52 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:59:52 INFO - PROCESS | 1655 | 2016-11-04 05:59:52.817 plugin-container[1656:9876] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:59:52 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:59:52 INFO - PROCESS | 1655 | colorDepth:24 05:59:52 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:59:52 INFO - PROCESS | 1655 | __metrics 05:59:53 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:59:53 INFO - TEST-INFO | 1655: exit 0 05:59:53 INFO - Browser initialized. 05:59:53 INFO - Running cycle 1/1 for kraken test... 05:59:53 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpRymq31/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:56 INFO - PROCESS | 1657 | 2016-11-04 05:59:56.032 plugin-container[1658:9993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:59:56 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:59:56 INFO - PROCESS | 1657 | 2016-11-04 05:59:56.033 plugin-container[1658:9993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 05:59:56 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:59:56 INFO - PROCESS | 1657 | 2016-11-04 05:59:56.033 plugin-container[1658:9993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:00:05 INFO - PROCESS | 1657 | RSS: Main: 238895104 06:00:05 INFO - PROCESS | 1657 | 06:00:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:09 INFO - PROCESS | 1657 | RSS: Main: 251166720 06:00:09 INFO - PROCESS | 1657 | 06:00:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 06:00:13 INFO - PROCESS | 1657 | RSS: Main: 259780608 06:00:13 INFO - PROCESS | 1657 | 06:00:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 06:00:17 INFO - PROCESS | 1657 | RSS: Main: 261894144 06:00:17 INFO - PROCESS | 1657 | 06:00:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 06:00:20 INFO - PROCESS | 1657 | RSS: Main: 262676480 06:00:20 INFO - PROCESS | 1657 | 06:00:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:22 INFO - PROCESS | 1657 | RSS: Main: 263495680 06:00:22 INFO - PROCESS | 1657 | 06:00:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:26 INFO - PROCESS | 1657 | RSS: Main: 263053312 06:00:26 INFO - PROCESS | 1657 | 06:00:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:29 INFO - PROCESS | 1657 | RSS: Main: 264511488 06:00:29 INFO - PROCESS | 1657 | 06:00:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 06:00:33 INFO - PROCESS | 1657 | RSS: Main: 264630272 06:00:33 INFO - PROCESS | 1657 | 06:00:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:35 INFO - PROCESS | 1657 | RSS: Main: 265043968 06:00:35 INFO - PROCESS | 1657 | 06:00:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:38 INFO - PROCESS | 1657 | RSS: Main: 265048064 06:00:38 INFO - PROCESS | 1657 | 06:00:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:41 INFO - PROCESS | 1657 | RSS: Main: 265089024 06:00:41 INFO - PROCESS | 1657 | 06:00:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:45 INFO - PROCESS | 1657 | RSS: Main: 264986624 06:00:45 INFO - PROCESS | 1657 | 06:00:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:48 INFO - PROCESS | 1657 | RSS: Main: 264679424 06:00:48 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 06:00:50 INFO - PROCESS | 1657 | RSS: Main: 266436608 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | __start_tp_report 06:00:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 06:00:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 06:00:50 INFO - PROCESS | 1657 | |i|pagename|runs| 06:00:50 INFO - PROCESS | 1657 | |0;ai-astar;105;116;124;120;124;171;177;124;117;119 06:00:50 INFO - PROCESS | 1657 | |1;audio-beat-detection;146;115;113;151;116;149;147;148;143;115 06:00:50 INFO - PROCESS | 1657 | |2;audio-dft;204;240;188;270;212;250;218;216;274;250 06:00:50 INFO - PROCESS | 1657 | |3;audio-fft;84;61;62;86;63;62;77;85;81;59 06:00:50 INFO - PROCESS | 1657 | |4;audio-oscillator;82;72;88;73;70;67;88;69;72;90 06:00:50 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;136;133;89;86;111;87;91;87;90;90 06:00:50 INFO - PROCESS | 1657 | |6;imaging-darkroom;100;97;105;97;97;96;97;107;133;96 06:00:50 INFO - PROCESS | 1657 | |7;imaging-desaturate;88;94;86;110;114;117;94;110;122;141 06:00:50 INFO - PROCESS | 1657 | |8;json-parse-financial;57;56;56;57;59;58;58;56;58;59 06:00:50 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;71;76;71;81;97;83;107;115;74;73 06:00:50 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;88;89;87;93;86;91;90;90;113;111 06:00:50 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;149;147;147;159;167;164;166;166;205;168 06:00:50 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;183;176;176;177;183;177;178;182;177;176 06:00:50 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;69;69;75;81;89;94;73;67;68;71 06:00:50 INFO - PROCESS | 1657 | __end_tp_report 06:00:50 INFO - PROCESS | 1657 | __start_cc_report 06:00:50 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,962 06:00:50 INFO - PROCESS | 1657 | __end_cc_report 06:00:50 INFO - PROCESS | 1657 | __startTimestamp1478264450736__endTimestamp 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | ------- Summary: start ------- 06:00:50 INFO - PROCESS | 1657 | Number of tests: 14 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:129.70 Median:124.00 stddev:24.06 (19.4%) stddev-sans-first:23.80 06:00:50 INFO - PROCESS | 1657 | Values: 105.0 116.0 124.0 120.0 124.0 171.0 177.0 124.0 117.0 119.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:146.50 stddev:16.96 (11.6%) stddev-sans-first:17.46 06:00:50 INFO - PROCESS | 1657 | Values: 146.0 115.0 113.0 151.0 116.0 149.0 147.0 148.0 143.0 115.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:232.20 Median:245.00 stddev:28.85 (11.8%) stddev-sans-first:28.74 06:00:50 INFO - PROCESS | 1657 | Values: 204.0 240.0 188.0 270.0 212.0 250.0 218.0 216.0 274.0 250.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:72.00 Median:79.00 stddev:11.48 (14.5%) stddev-sans-first:11.32 06:00:50 INFO - PROCESS | 1657 | Values: 84.0 61.0 62.0 86.0 63.0 62.0 77.0 85.0 81.0 59.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:8.91 (11.5%) stddev-sans-first:9.28 06:00:50 INFO - PROCESS | 1657 | Values: 82.0 72.0 88.0 73.0 70.0 67.0 88.0 69.0 72.0 90.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:88.00 stddev:19.56 (22.2%) stddev-sans-first:15.82 06:00:50 INFO - PROCESS | 1657 | Values: 136.0 133.0 89.0 86.0 111.0 87.0 91.0 87.0 90.0 90.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:96.50 stddev:11.39 (11.8%) stddev-sans-first:12.05 06:00:50 INFO - PROCESS | 1657 | Values: 100.0 97.0 105.0 97.0 97.0 96.0 97.0 107.0 133.0 96.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:107.60 Median:113.50 stddev:17.27 (15.2%) stddev-sans-first:16.80 06:00:50 INFO - PROCESS | 1657 | Values: 88.0 94.0 86.0 110.0 114.0 117.0 94.0 110.0 122.0 141.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.24 06:00:50 INFO - PROCESS | 1657 | Values: 57.0 56.0 56.0 57.0 59.0 58.0 58.0 56.0 58.0 59.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:84.80 Median:75.00 stddev:15.94 (21.2%) stddev-sans-first:16.10 06:00:50 INFO - PROCESS | 1657 | Values: 71.0 76.0 71.0 81.0 97.0 83.0 107.0 115.0 74.0 73.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:89.50 stddev:9.81 (11.0%) stddev-sans-first:10.17 06:00:50 INFO - PROCESS | 1657 | Values: 88.0 89.0 87.0 93.0 86.0 91.0 90.0 90.0 113.0 111.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:166.00 stddev:16.80 (10.1%) stddev-sans-first:16.95 06:00:50 INFO - PROCESS | 1657 | Values: 149.0 147.0 147.0 159.0 167.0 164.0 166.0 166.0 205.0 168.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:177.50 stddev:2.95 (1.7%) stddev-sans-first:2.65 06:00:50 INFO - PROCESS | 1657 | Values: 183.0 176.0 176.0 177.0 183.0 177.0 178.0 182.0 177.0 176.0 06:00:50 INFO - PROCESS | 1657 | 06:00:50 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:74.00 stddev:9.40 (12.7%) stddev-sans-first:9.66 06:00:50 INFO - PROCESS | 1657 | Values: 69.0 69.0 75.0 81.0 89.0 94.0 73.0 67.0 68.0 71.0 06:00:50 INFO - PROCESS | 1657 | -------- Summary: end -------- 06:00:50 INFO - PROCESS | 1657 | 06:00:51 INFO - TEST-INFO | 1657: exit 0 06:00:51 INFO - TEST-OK | kraken | took 61809ms 06:00:51 INFO - SUITE-END | took 1177s 06:00:51 INFO - Completed test suite (00:19:38) 06:00:51 INFO - javascript benchmark 06:00:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7224.493269680538, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2839.3327568021514, 3396.592408910931, 3401.5918089829224, 3416.333814906093, 3421.0, 5691.772329106836, 5776.133579963002, 5792.478676919077, 5793.797217097397, 5830.883382332351, 5647.0, 5732.140178973156, 5753.827385178452, 5755.913661295084, 5758.5393168546525, 5148.974255128728, 5234.6335756497065, 5253.133233016567, 5275.920861187085, 5293.153095504701, 5267.55225805807, 5341.172118321647, 5380.973095134519, 5425.430329815372, 5426.484483974016, 5110.054639891613, 5179.067767801795, 5307.6019298552765, 5316.441773613773, 5325.733713314357, 5159.664621799571, 5240.34495688039, 5282.577393808486, 5312.973435132838, 5335.759890804923, 5231.163013917793, 5247.527722504955, 5274.630775845692, 5276.5251127398715, 5308.124159513675, 5584.581156413245, 5617.157426386034, 5657.603967722261, 5796.13058041293, 5821.854453638651, 5632.549396048306, 5848.356680765112, 6171.197744293258, 6266.3420340864, 6274.466670333027, 6283.340249273851, 6286.528510361742, 6304.905426418607, 6308.022256550196, 6309.400606942333, 5551.861203469945, 5705.82882513517, 5796.840631873642, 5931.525477961753, 5938.8812223755285, 6220.34686357935, 6224.439800417984, 6234.657093859791, 6276.46650034743, 6294.370562943761, 6176.845578860564, 6211.782587609412, 6229.750809967551, 6229.7508099676415, 6243.344448832898, 5200.427952925175, 6228.159198508234, 6292.087647291209, 6306.274778400542, 6319.96840015797, 6130.7650488535955, 6133.754649813957, 6153.387641565631, 6164.815055548341, 6170.598911070766, 5122.974385128051, 6255.343188965184, 6261.530385221126, 6277.654728989858, 6279.152314437584, 4948.703077815343, 4965.329680493087, 4970.2793095001025, 4973.950260497349, 5018.648694591344, 4348.065165989327, 4409.294512877988, 4442.0, 4501.279795232747, 4540.636749060067, 5759.308882933991, 5770.336411312669, 6030.819075427744, 6056.212692350055, 6084.574079814371, 5303.814366497154, 6183.690815459209, 6206.84512713437, 6276.0, 6345.302016778151, 5808.82573522795, 5835.5915085944425, 6038.577299589075, 6047.758089676452, 6051.243594550681, 5409.486098820606, 5416.891662166735, 5448.645838020516, 5466.098093814476, 5500.50495455411, 6355.968220158962, 6359.300476947439, 6362.745490180341, 6372.299047104908, 6403.359664033559, 6167.044108163242, 6286.622802631857, 6286.937130628635, 6352.809415717536, 6369.20384951881, 6233.376662333912, 6260.373962603703, 6260.780872669435, 6293.496520278458, 6324.810255692337, 6233.314335423009, 6235.189425374672, 6252.718627661675, 6256.0303153011355, 6263.96868015657, 5090.338256026693, 6199.94600917854, 6276.874462510816, 6292.2134733158355, 6306.747730090745, 5977.671228082497, 6096.2243283589, 6110.725017374318, 6112.0171844544475, 6117.632942023492, 6111.786087487095, 6123.9232566596775, 6124.846878828065, 6131.957816599936, 6134.222293197161, 5148.794048238028, 5876.970615146897, 5881.117832325014, 5916.585838991401, 6033.577649564489, 4918.901621967612, 6118.071005858243, 6119.8776024478375, 6183.134361189527, 6190.2571691397325, 6006.009008513546, 6027.498036564725, 6049.818505444844, 6057.363976782374, 6071.392860713893], "value": 5708.55238883928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.2163090471578, 517.1880148167379, 548.9670619762815, 553.8117040206329, 560.1541672075163, 29881.850590747043, 39595.01012474686, 39610.81167564978, 39617.009574760574, 39719.2056158877, 39960.60039399605, 40436.19127617446, 40489.39265911013, 40503.98740031493, 40562.18875622486, 8021.318187954002, 9327.906720932806, 9548.427094374325, 9556.426614403123, 9591.520423978807, 9437.292203084762, 9521.71434856952, 9521.761955951122, 9525.047495250454, 9545.14093731564, 9501.477418741966, 9524.619015239383, 9527.42835429873, 9532.428054316728, 9559.378640388353, 9501.904980950205, 9516.714498565054, 9535.332526723132, 9537.618495260182, 9554.996725343843, 7393.741219057361, 7815.8436831263625, 8773.736787896374, 9285.767855803591, 9471.816022997125, 39169.216615667814, 39200.21599568021, 39244.411333830016, 39309.0, 39344.80327598372, 2690.5695088785897, 2927.5901373807683, 3231.1760501072313, 3393.881214157517, 3397.796132232074, 24809.0, 30194.396112077637, 30261.394772104435, 30394.696053039406, 31332.843335783175, 2652.8010399219943, 3058.6023816903885, 3187.2509960159346, 3205.4710972689477, 3310.933781324384, 467209.0, 467591.0, 470739.0, 470816.64591676823, 471651.64174179255, 53048.0, 53051.46948530465, 53205.73397133067, 53211.73394133004, 53295.0, 56561.0, 56657.433425665215, 56811.0, 56911.0, 57054.0, 15094.245287735788, 15106.697866042838, 15304.0, 15316.081035137704, 15319.23403829804, 11881.643550693492, 11892.524299028115, 11937.582184623496, 11959.0, 11965.641030769091, 15154.090754554541, 15626.45307414235, 15898.364065437252, 15923.840761592235, 15938.203089845692, 6184.196054512884, 6253.218347706536, 6255.874882502416, 6276.0, 6382.489400848014, 868.4615538366127, 870.2950610005915, 1062.9574817007388, 1079.1636481726725, 1094.7591529863416, 28128.0, 28215.294617634725, 28738.568921466194, 29319.706802932124, 29449.11652650424, 1045.1638689048882, 1049.7690508088208, 1066.36018388966, 1067.5302866738766, 1116.7096554895786, 15106.622334441507, 15140.848591513944, 15163.166025868462, 15230.933834631469, 15455.14079895223, 608465.0, 612998.0, 616916.0, 617379.0, 617746.0, 54155.45844541583, 54206.728966354916, 54240.0, 54292.45707542874, 54390.18414723782, 1312.1012106706937, 1351.6080336702246, 1358.4430383542897, 1364.4133022800092, 1372.6842826150041, 22186.556268874854, 29076.12771616855, 29248.707512924597, 29258.0, 30119.548206777356, 40715.1856962865, 46918.76540617275, 48024.79938001578, 48217.75891120522, 48297.551073467854, 454178.4582154136, 454694.0, 455476.0, 457989.0, 458302.0, 38570.80714596409, 39059.80470097745, 39383.21233575255, 39428.80285598554, 39660.40509392419, 40996.79501602592, 44604.77697611491, 46271.0, 46277.38029168963, 47627.0, 36474.8176259117, 37303.06742331355, 38095.0, 38211.80894095512, 38363.23273534458, 34382.0, 37468.43797342988, 37621.81189094037, 37951.81024094862, 39882.0, 36645.0, 37253.62746372502, 37384.81307593445, 39074.0, 39155.216895662496, 29459.263518411353, 29943.55084673775, 31672.841635791672, 33491.33017339591, 34027.14932126716, 24883.377915552257, 25424.110156145187, 25630.97476100935, 25652.615210772226, 26163.869180653975], "value": 19175.960849453968, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.39738061012616, 369.85760482214357, 375.8590528551794, 382.7645997711408, 395.51351837240196, 11222.831657525143, 11743.119266055048, 11838.526458941653, 11840.940795296012, 19079.71380429293, 20392.694109588367, 21072.367828965118, 21113.894430527827, 21216.89391553044, 21375.251866184688, 6205.596636218631, 6904.930950690479, 6908.620025898574, 6916.965415172917, 6923.342282483181, 6449.484041276686, 6887.27683593223, 6930.584164950119, 6950.89573656393, 6970.581765094111, 6604.438622717074, 6796.898046529306, 6923.342282483156, 6929.965350173242, 6946.652667366611, 6740.359665831738, 6789.93210067898, 6805.727770889159, 6808.353206445379, 6819.761308354208, 6323.810285691437, 6666.600003999727, 6698.296678848699, 6700.128983232197, 6715.9328406715795, 22051.448713782123, 26120.085796997202, 27745.722542774514, 28009.859950700116, 28027.719722802714, 2328.6739856419945, 2355.740868504463, 2405.5068710914397, 2488.14159115105, 3033.4994725870206, 16265.349386024545, 16629.91685041567, 16676.16619169037, 16784.916075419667, 17626.647467050716, 1636.4190712297202, 2013.5368865161095, 2016.8285695715736, 2036.665983780626, 2089.1643342662906, 134997.0, 137753.0, 141743.0, 150280.0, 155965.0, 9306.953465232631, 9619.855702164474, 9726.270529710158, 9806.803863922682, 20421.774693518437, 12299.893009629177, 12429.192102513307, 12435.005199584011, 12458.252504849737, 12463.875361246271, 7758.456908016385, 7851.960740196262, 7917.960410198028, 7922.4850384724805, 8061.153578874251, 7865.0, 7961.840763184704, 8193.180681931759, 8203.507789532528, 8227.588620568948, 3220.339830334776, 3797.886063418102, 3872.786996715208, 3874.903127421837, 3931.8230679619487, 1910.4746194796542, 1957.2171131547495, 1984.1567333883042, 1990.6814909614393, 2000.4998750312423, 670.199112061092, 764.8565394734907, 799.9200079991954, 823.2467292427361, 873.7291439653727, 20873.89563052175, 21067.367978960654, 21190.576188476145, 21651.0, 21822.345329640135, 910.8763206960846, 934.8971613122551, 943.3443756589105, 979.7060881735594, 1029.8043371759343, 11047.558097676003, 11389.316641001566, 11443.542258309575, 11460.713482162846, 11565.0, 511962.0, 561671.0, 566146.0, 566696.0, 587222.5370033468, 128438.0, 134350.0, 134839.30321393517, 135650.0, 135654.0, 959.5298303831072, 963.8554216867386, 977.2572844637984, 981.3130808434124, 984.675057231112, 17581.912090439466, 18690.25238990425, 19009.714854277474, 19053.714194287375, 19382.0, 45467.772661136485, 47081.0, 51982.74008629932, 55592.0, 57213.14180287216, 57947.0, 76266.0, 78507.82238266316, 78839.0, 79945.60027199826, 7380.96309518449, 7454.329110380092, 7980.08229053643, 8057.0, 8481.703140390056, 11923.10576706733, 12668.493260269855, 12959.546415875398, 13067.738645226853, 13106.60680179596, 9695.030496950249, 10668.359898406121, 10738.677839664822, 11210.607628733282, 11319.490622922152, 8657.95671021641, 9640.469774162488, 10468.947655261674, 10685.946570267099, 11241.0, 11025.338479691245, 11168.776624467628, 11311.151663625096, 11541.0, 11633.069354451793, 7937.2459616336355, 8122.918770812216, 8193.098759136372, 8335.0, 8361.372897032868, 10778.784424311627, 11065.391403472886, 11092.0, 11344.262622929484, 11475.0], "value": 9710.047001934367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.68842543066, 1505.7063872544852, 1684.7472879068139, 1763.6825371433154, 1803.9549011274717, 6964.582125072499, 7486.962565187167, 7729.4975826571235, 8004.079530853954, 8486.151384861518, 7929.722459713911, 8207.425480216372, 8221.465604735702, 8325.167483251671, 8350.248477637011, 1930.4884205685505, 2295.988520057397, 2307.7692230776956, 2320.628699408095, 2324.244620498336, 2280.464090938628, 2346.6597343385197, 2348.9295321140394, 2353.0705371378294, 2359.327591636384, 2295.116380193621, 2301.068067432684, 2307.1924826310824, 2325.3489023073566, 2327.441414060622, 2263.2757517594378, 2273.3407311879537, 2278.498730279336, 2302.7812357826147, 2311.7341505726804, 2304.596695578267, 2320.582295186866, 2321.5124823787023, 2330.6387509935985, 2332.953340933172, 8200.302974247137, 8301.584920753938, 8360.665573377119, 8365.038020627644, 8369.916300836974, 5054.82308119214, 5220.269162317279, 5220.556252718524, 5233.450487698775, 5235.214717792323, 3250.8049517029053, 3300.504924261356, 3594.029612004744, 3601.603823579404, 3605.6574625410544, 988.5996171550534, 1031.2265800649513, 1062.8830828608848, 1088.5264909764278, 1096.6490722968654, 9661.565229564687, 9743.658971935949, 10897.564097436092, 10937.726556836064, 10951.123910087173, 3785.6782173515007, 4004.699647526389, 4555.0, 4623.514530974267, 4624.121416930772, 7647.388208943271, 9270.72187834366, 9286.674966376144, 9345.0654934506, 9376.484293363794, 5098.97450512745, 5160.251763494272, 5168.173092305213, 5176.042432150064, 5183.2743415921805, 4266.850660226877, 4523.977380113144, 4714.151452738472, 4724.6692731508465, 4737.597304229179, 3035.074302337764, 3786.2427514497213, 3797.886063418102, 3815.313243616176, 3906.6950499205605, 1250.249850089957, 1250.737345157509, 1256.6544200344795, 1266.170658218868, 1275.3495717184303, 847.6778824046946, 863.7322430046705, 868.7306934850217, 897.6364572348209, 919.9080091990882, 5009.373828271466, 5682.715864206856, 5754.0, 5805.303363596396, 6006.459418652343, 853.9064480374595, 914.0585197246713, 916.8853893263342, 948.6964171465065, 965.5365424596233, 3025.969740302613, 3817.6373244541724, 3831.7317787755146, 3904.5509766376663, 3907.0623050467684, 9802.509874506246, 10169.338992965582, 10171.694849154537, 10175.185985121172, 10181.694549163536, 10036.799264014679, 10097.5456104474, 10141.087302142843, 10148.340357876714, 10185.08334249921, 7569.0, 7596.936428899959, 7713.3443657288635, 7936.4841285315115, 7944.443888927835, 5473.75368108444, 5498.752556135064, 5562.972185139049, 5593.748281327432, 5712.685802280915, 7748.961255193688, 9245.537723113683, 9338.206252468617, 9368.110029547182, 9473.100055494717, 8080.959595202222, 9467.952660236655, 9477.431354118775, 9496.952515237379, 9607.087326703951, 5401.594880383908, 6223.471004964629, 6442.935570644421, 6683.699233534406, 6816.625085620339, 7742.993410856552, 8003.31971782405, 8003.799905002422, 8004.519728816057, 8007.679692812222, 7043.612601306977, 7130.465215108784, 7146.464015198777, 7219.278072192738, 7279.745208917662, 6941.132358455193, 7011.404030657452, 7033.331833475811, 7054.894176587252, 7119.715211391486, 5597.328320601391, 6534.803955881332, 6629.337066293525, 6634.83412914681, 6636.2700102987665, 4785.760711964457, 4820.25286080658, 4857.53853383928, 4878.658493905535, 4927.852164435073, 4779.163646361803, 4847.030593881167, 4863.732494712684, 4961.355023846877, 4963.354763880672], "value": 4368.332797924512, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.13819646631833, 301.3280384742025, 307.55558218374443, 308.5433558333665, 312.20076603894034, 4764.237721964487, 4976.502349765022, 5488.972555137224, 5566.777328906844, 5631.746571404281, 5089.643724939247, 5430.402655707869, 5480.534154596864, 5481.479259470354, 5548.0, 3052.9389412211744, 3388.542546756187, 4019.3167161582455, 4172.019575399779, 4186.267403204427, 4017.8392864285393, 4194.538600753915, 4211.789410529476, 4249.915001699979, 4256.2338779019765, 4099.3850922361735, 4146.958530414702, 4156.792160391983, 4159.729617574848, 4161.895952601194, 3488.232588830453, 4105.404716316148, 4129.380592911058, 4135.7932103395015, 4145.917081658365, 2996.5654980027775, 3276.099862079988, 3398.303347813692, 3647.0, 3654.086478380405, 4181.490666493701, 5313.058855264602, 5323.083689871643, 5381.757820898029, 5382.811601593965, 4146.792660366969, 4320.33034879591, 4390.78046097697, 4686.851721328297, 4816.759162041919, 5017.598592112622, 5252.527272545451, 5256.92114618281, 5815.98220311448, 5837.824865254049, 3871.6709079728257, 3927.8232479538487, 3967.1867267210155, 3992.441058251818, 4028.8589899353674, 5254.896471740977, 5482.424345443711, 6046.516278697738, 6051.667158306292, 6070.480750488542, 3860.9613903861164, 4044.6541006216326, 4079.938800917989, 4080.959190408058, 4083.7958102094776, 3903.765774053566, 4081.9591804082174, 4099.97950010248, 4146.129312844276, 4172.91654166921, 3723.1622884851236, 4155.688323375759, 4718.764061796896, 4745.83389581363, 4770.212914869007, 4321.438213032286, 5099.311592934981, 5326.5472434842695, 5914.64512129265, 5917.970410147922, 1123.7471568896933, 1182.8999030901343, 1273.3060482037313, 1290.9548165814151, 1363.6840448966796, 741.907261592301, 869.7434256894231, 938.6104766521953, 944.9433034017981, 950.9096635819586, 5448.673079615236, 5448.91826622601, 5460.890782184334, 5461.563074953994, 5500.697461639648, 5905.021817691036, 5922.2597175353085, 5971.790987315423, 6014.398560143951, 6030.48740857032, 4502.797374118172, 4534.45586529612, 5135.743212839343, 5433.0, 5475.589330800206, 2779.43021680555, 2941.0, 3074.3697542003833, 3172.27037781307, 3178.2201966898147, 4632.72203667781, 4846.878828029327, 5220.530152286313, 5561.026820306431, 6234.5635805494085, 5709.4878253568295, 5911.793087242012, 5964.433378829004, 5977.0, 6005.459508644243, 5509.752061157257, 5728.484436400744, 6015.639061656315, 6124.0, 6297.433231009231, 5534.418886016911, 5717.5425965921795, 5911.645301281937, 5915.515483496855, 6003.538549194809, 5149.253358263106, 5171.534561889448, 5392.0, 5848.777464563439, 5926.318473375617, 5797.449242321973, 5883.176355310175, 6095.4761309672585, 6156.199694039746, 6232.136075633137, 3125.7801967343903, 3895.2988462077096, 4042.0, 4076.470058892325, 4107.281225785416, 4729.739864307496, 5964.850878728066, 5997.520198384206, 6010.368911264429, 6020.8795824084145, 5379.488948549882, 5609.523190528851, 5713.657180569179, 5883.588148829541, 5979.342272349951, 5291.074062039051, 5777.191193233035, 5844.07796101949, 5850.180974663465, 5929.377415371324, 4049.696272779612, 4914.94328719327, 5594.964931487688, 5784.797532086525, 5800.59395842287, 4532.773361331986, 5604.99110160175, 5785.652860828195, 5921.61509501881, 6052.096397823291, 5018.573421259234, 5339.1046178607585, 5411.945880541144, 5433.2665090213895, 5833.4749872511675], "value": 4159.1748272605755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.9391107844755, 690.6788343420309, 716.2228981555011, 740.7664503205519, 762.6263131065776, 14366.0, 15213.4675286365, 15215.23923803808, 15273.694526109484, 15308.923455382735, 13717.931410342959, 13900.582982510516, 13906.582802515915, 13914.165150091001, 13944.790828137586, 650.6779144323543, 669.2003056347667, 802.2338666573438, 811.5617566514076, 819.1194465949098, 814.6741303478626, 816.6855760532178, 826.6238861318116, 827.7971896885272, 828.9129641387658, 866.9393142480029, 884.0938038510513, 887.3433659092258, 887.8135591525787, 888.3248730964481, 880.9119088091204, 883.6642076011138, 884.0717246890771, 884.8893888263967, 885.8626912828522, 638.2883085359837, 655.4625207329991, 655.6885479397296, 656.0618315808392, 656.8390744267638, 14364.0, 14830.33263503145, 15019.924900375538, 15044.473443429428, 15069.773953390708, 12264.38678066102, 12461.875381246162, 13883.791743123862, 14019.50931717385, 14323.355449004715, 12216.511339546407, 12257.325847078408, 12286.692832679166, 12343.716412463724, 12454.564090256797, 3604.3331983582857, 3730.8134593270497, 4431.26884064129, 4441.622462090726, 4557.111363284161, 81752.0, 90656.0, 91018.5449072757, 91304.08695912956, 91461.0, 10690.251682382159, 10706.464676766287, 10774.730631734112, 10904.727381815359, 11207.103431725605, 18548.629027419374, 19165.712514312298, 20254.594908101757, 20392.796072039386, 20628.484287892923, 7565.167831538526, 7665.965094712143, 7793.142754297023, 7826.452148349561, 7917.722879699298, 4250.277452833026, 4261.914761704748, 4361.865914862159, 4380.342948557678, 4391.978040109779, 3441.1225137589754, 3503.141730276048, 3522.418800897823, 3536.929261414757, 3550.6981906537717, 640.5196102922808, 642.1780121444558, 649.6621756686591, 660.313274194832, 664.0769330630427, 578.4851482180862, 636.5098873867097, 653.7613770973655, 664.3124366280877, 674.1202730436729, 7628.732994345171, 8105.7436097405, 8218.342532597379, 8679.262262707614, 9019.0, 615.1449485215554, 632.4940047961704, 638.8881945659491, 658.7595527632379, 661.394823736276, 3670.596234414212, 4276.914461710749, 4395.91110490876, 4453.265211240174, 4592.563706447882, 119858.0, 120093.39953300178, 120840.0, 121093.0, 125081.37459312828, 83678.0, 107091.0, 108112.0, 108467.91532084579, 109127.0, 92946.0, 94223.0, 96805.0, 97078.5146074265, 97100.02899971051, 8476.618552165291, 8608.78478038054, 8647.697330593399, 8704.738857834274, 8849.823003540021, 46309.0, 48224.55326340215, 59238.40761592501, 60101.69949150401, 61054.08418873631, 80020.59989700014, 82796.58601706952, 84161.0, 85403.0, 87682.12317876739, 11303.660890173307, 11773.0, 11839.822402663794, 11850.348230847387, 12060.758784824078, 11639.709007274885, 12228.816567751313, 13717.245551494365, 14146.938979576778, 14352.282385880455, 9451.574679139318, 11108.833367499656, 12530.248185108923, 12836.422360993964, 12933.159344642569, 12982.415791289226, 13287.800682989957, 13470.259135747629, 13573.389197486335, 13600.727985440039, 12688.302143382203, 12865.742685146057, 13369.0, 13473.0, 13533.932330338286, 10106.595736170471, 10284.228682848667, 10301.9484902575, 10314.071733544017, 10342.848093748906, 8712.433691810013, 9542.379745316532, 9561.713148605553, 10275.383476991405, 11168.431260809897], "value": 7361.586301323562, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 124.0, 120.0, 124.0, 171.0, 177.0, 124.0, 117.0, 119.0], "value": 129.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 113.0, 151.0, 116.0, 149.0, 147.0, 148.0, 143.0, 115.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 240.0, 188.0, 270.0, 212.0, 250.0, 218.0, 216.0, 274.0, 250.0], "value": 232.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 62.0, 86.0, 63.0, 62.0, 77.0, 85.0, 81.0, 59.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 88.0, 73.0, 70.0, 67.0, 88.0, 69.0, 72.0, 90.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 89.0, 86.0, 111.0, 87.0, 91.0, 87.0, 90.0, 90.0], "value": 100.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 97.0, 105.0, 97.0, 97.0, 96.0, 97.0, 107.0, 133.0, 96.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 86.0, 110.0, 114.0, 117.0, 94.0, 110.0, 122.0, 141.0], "value": 107.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 57.0, 59.0, 58.0, 58.0, 56.0, 58.0, 59.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 71.0, 81.0, 97.0, 83.0, 107.0, 115.0, 74.0, 73.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 93.0, 86.0, 91.0, 90.0, 90.0, 113.0, 111.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 147.0, 159.0, 167.0, 164.0, 166.0, 166.0, 205.0, 168.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 176.0, 176.0, 177.0, 183.0, 177.0, 178.0, 182.0, 177.0, 176.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 75.0, 81.0, 89.0, 94.0, 73.0, 67.0, 68.0, 71.0], "value": 75.6, "unit": "score"}]}]} 06:00:51 INFO - Return code: 0 06:00:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:52 ERROR - # TBPL SUCCESS # 06:00:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:52 INFO - 2016-11-04 06:00:52.065 screenresolution[1662:10379] starting screenresolution argv=screenresolution get 06:00:52 INFO - 2016-11-04 06:00:52.068 screenresolution[1662:10379] Display 0: 1600x1200x32@60 06:00:52 INFO - 2016-11-04 06:00:52.074 screenresolution[1663:10382] starting screenresolution argv=screenresolution list 06:00:52 INFO - Available Modes on Display 0 06:00:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:52 INFO - Intel Iris: 06:00:52 INFO - Chipset Model: Intel Iris 06:00:52 INFO - Type: GPU 06:00:52 INFO - Bus: Built-In 06:00:52 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:52 INFO - Vendor: Intel (0x8086) 06:00:52 INFO - Device ID: 0x0a2e 06:00:52 INFO - Revision ID: 0x0009 06:00:52 INFO - Displays: 06:00:52 INFO - Display: 06:00:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:52 INFO - Display Serial Number: 1600x1200 60 06:00:52 INFO - Main Display: Yes 06:00:52 INFO - Mirror: Off 06:00:52 INFO - Online: Yes 06:00:52 INFO - Rotation: Supported 06:00:52 INFO - Return code: 0 06:00:52 INFO - Running post-action listener: _resource_record_post_action 06:00:52 INFO - [mozharness: 2016-11-04 13:00:52.319724Z] Finished run-tests step (success) 06:00:52 INFO - Running post-run listener: _resource_record_post_run 06:00:52 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38418944; Write bytes: 709796864; Read time: 246; Write time: 2007 06:00:52 INFO - TinderboxPrint: CPU usage
54.5% 06:00:52 INFO - TinderboxPrint: I/O read bytes / time
38,418,944 / 246 06:00:52 INFO - TinderboxPrint: I/O write bytes / time
709,796,864 / 2,007 06:00:52 INFO - TinderboxPrint: CPU idle
2,210.4 (45.5%) 06:00:52 INFO - TinderboxPrint: CPU system
979.7 (20.2%) 06:00:52 INFO - TinderboxPrint: CPU user
1,663.7 (34.3%) 06:00:52 INFO - TinderboxPrint: Swap in / out
444,915,712 / 0 06:00:52 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388766208; Write bytes: 383481856; Read time: 29003; Write time: 801 06:00:52 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37456384; Write bytes: 300358656; Read time: 233; Write time: 1137 06:00:52 INFO - Running post-run listener: _upload_blobber_files 06:00:52 INFO - Blob upload gear active. 06:00:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:52 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 06:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:00:53 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:00:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:54 INFO - (blobuploader) - INFO - Done attempting. 06:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:00:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:55 INFO - (blobuploader) - INFO - Done attempting. 06:00:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:00:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:57 INFO - (blobuploader) - INFO - Done attempting. 06:00:57 INFO - (blobuploader) - INFO - Iteration through files over. 06:00:57 INFO - Return code: 0 06:00:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:00:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:00:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8542ee95f674a8c90be2d54738feb59c357f47bba7e851509df2115dc4162e5bf197ecfeacc1be85ef780d1d703bf29db678286d303644f886aaf3993925da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf05fb00318b94e00d5ed06e7db6f54ae65abe7feba8ac83f047cf1cb05e0aad48fe2fe98865a80eec734b0182365c6ee5419f02dd3b463522f5390c7566e617", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32345380b87b4859a9a550b758579a558ee4baec2c118f6f67222c22ad050e865d28e4f435e83fba9c943d3b18cec9a488d270b27f0de4b1230bddc9cbff3a2"} 06:00:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:00:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:00:57 INFO - Contents: 06:00:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8542ee95f674a8c90be2d54738feb59c357f47bba7e851509df2115dc4162e5bf197ecfeacc1be85ef780d1d703bf29db678286d303644f886aaf3993925da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf05fb00318b94e00d5ed06e7db6f54ae65abe7feba8ac83f047cf1cb05e0aad48fe2fe98865a80eec734b0182365c6ee5419f02dd3b463522f5390c7566e617", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32345380b87b4859a9a550b758579a558ee4baec2c118f6f67222c22ad050e865d28e4f435e83fba9c943d3b18cec9a488d270b27f0de4b1230bddc9cbff3a2"} 06:00:57 INFO - Running post-run listener: copy_logs_to_upload_dir 06:00:57 INFO - Copying logs to upload dir... 06:00:57 INFO - mkdir: /builds/slave/test/build/upload/logs 06:00:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.978289 ========= master_lag: 4.96 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-04 06:01:02.469487) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 06:01:02.470695) ========= 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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/Firefox/sha512/7a8542ee95f674a8c90be2d54738feb59c357f47bba7e851509df2115dc4162e5bf197ecfeacc1be85ef780d1d703bf29db678286d303644f886aaf3993925da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf05fb00318b94e00d5ed06e7db6f54ae65abe7feba8ac83f047cf1cb05e0aad48fe2fe98865a80eec734b0182365c6ee5419f02dd3b463522f5390c7566e617", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32345380b87b4859a9a550b758579a558ee4baec2c118f6f67222c22ad050e865d28e4f435e83fba9c943d3b18cec9a488d270b27f0de4b1230bddc9cbff3a2"} build_url:https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/aPNalCWbRiO4DZ11p8zeNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8542ee95f674a8c90be2d54738feb59c357f47bba7e851509df2115dc4162e5bf197ecfeacc1be85ef780d1d703bf29db678286d303644f886aaf3993925da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf05fb00318b94e00d5ed06e7db6f54ae65abe7feba8ac83f047cf1cb05e0aad48fe2fe98865a80eec734b0182365c6ee5419f02dd3b463522f5390c7566e617", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32345380b87b4859a9a550b758579a558ee4baec2c118f6f67222c22ad050e865d28e4f435e83fba9c943d3b18cec9a488d270b27f0de4b1230bddc9cbff3a2"}' ========= master_lag: 0.78 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-04 06:01:03.263129) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 06:01:03.263489) ========= 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.ZMvAOzoQqF/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.XK9MG2Audw/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005185 ========= master_lag: 1.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-11-04 06:01:04.422434) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-11-04 06:01:04.423164) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-11-04 06:01:07.270918) ========= ========= Total master_lag: 7.51 =========