builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1480478817.77 results: success (0) buildid: 20161129171714 builduid: 4c697f9685534775b1330889cfcae254 revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.765552) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.766000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.766291) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.792851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.793321) ========= 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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-29 20:06:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-11-29 20:06:57 (15.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116107 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.934085) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.934420) ========= 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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.028852 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.984749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:57.985116) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --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 adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-29 20:06:58,076 truncating revision to first 12 chars 2016-11-29 20:06:58,076 Setting DEBUG logging. 2016-11-29 20:06:58,076 attempt 1/10 2016-11-29 20:06:58,076 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/adcc39e3cad0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 20:06:58,779 unpacking tar archive at: mozilla-central-adcc39e3cad0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.976032 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:58.984185) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:58.984565) ========= script_repo_revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:58.985006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:58.985279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:06:59.005663) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-29 20:06:59.006153) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:06:59 INFO - MultiFileLogger online at 20161129 20:06:59 in /builds/slave/test 20:06:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:06:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:06:59 INFO - {'append_to_log': False, 20:06:59 INFO - 'base_work_dir': '/builds/slave/test', 20:06:59 INFO - 'blob_upload_branch': 'Firefox', 20:06:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:06:59 INFO - 'branch': 'Firefox', 20:06:59 INFO - 'buildbot_json_path': 'buildprops.json', 20:06:59 INFO - 'config_files': ('talos/mac_config.py',), 20:06:59 INFO - 'default_actions': ('clobber', 20:06:59 INFO - 'read-buildbot-config', 20:06:59 INFO - 'download-and-extract', 20:06:59 INFO - 'populate-webroot', 20:06:59 INFO - 'create-virtualenv', 20:06:59 INFO - 'install', 20:06:59 INFO - 'run-tests'), 20:06:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:06:59 INFO - 'download_minidump_stackwalk': True, 20:06:59 INFO - 'download_symbols': 'ondemand', 20:06:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:06:59 INFO - 'tooltool.py': '/tools/tooltool.py', 20:06:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:06:59 INFO - '/tools/misc-python/virtualenv.py')}, 20:06:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:06:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:06:59 INFO - 'installer_path': 'installer.exe', 20:06:59 INFO - 'log_level': 'info', 20:06:59 INFO - 'log_name': 'talos', 20:06:59 INFO - 'log_to_console': True, 20:06:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:06:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:06:59 INFO - 'opt_config_files': (), 20:06:59 INFO - 'pip_index': False, 20:06:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:06:59 INFO - 'cmd': ('bash', 20:06:59 INFO - '-c', 20:06:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:06:59 INFO - 'enabled': True, 20:06:59 INFO - 'halt_on_failure': False, 20:06:59 INFO - 'name': 'check_screen_resolution'},), 20:06:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:06:59 INFO - 'cmd': ('bash', 20:06:59 INFO - '-c', 20:06:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:06:59 INFO - 'enabled': True, 20:06:59 INFO - 'halt_on_failure': False, 20:06:59 INFO - 'name': 'check_screen_resolution'},), 20:06:59 INFO - 'run_cmd_checks_enabled': True, 20:06:59 INFO - 'sps_profile': False, 20:06:59 INFO - 'sps_profile_interval': 0, 20:06:59 INFO - 'suite': 'dromaeojs', 20:06:59 INFO - 'system_bits': '32', 20:06:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:06:59 INFO - 'title': 't-yosemite-r7-0104', 20:06:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:06:59 INFO - 'use_talos_json': True, 20:06:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:06:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:06:59 INFO - 'work_dir': 'build'} 20:06:59 INFO - [mozharness: 2016-11-30 04:06:59.210062Z] Running clobber step. 20:06:59 INFO - Running pre-action listener: _resource_record_pre_action 20:06:59 INFO - Running main action method: clobber 20:06:59 INFO - rmtree: /builds/slave/test/build 20:06:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:07:01 INFO - Running post-action listener: _resource_record_post_action 20:07:01 INFO - [mozharness: 2016-11-30 04:07:01.276375Z] Finished clobber step (success) 20:07:01 INFO - [mozharness: 2016-11-30 04:07:01.276559Z] Running read-buildbot-config step. 20:07:01 INFO - Running pre-action listener: _resource_record_pre_action 20:07:01 INFO - Running main action method: read_buildbot_config 20:07:01 INFO - Using buildbot properties: 20:07:01 INFO - { 20:07:01 INFO - "project": "", 20:07:01 INFO - "product": "firefox", 20:07:01 INFO - "script_repo_revision": "production", 20:07:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:07:01 INFO - "repository": "", 20:07:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:07:01 INFO - "buildid": "20161129171714", 20:07:01 INFO - "stage_platform": "macosx64", 20:07:01 INFO - "basedir": "/builds/slave/test", 20:07:01 INFO - "nightly_build": "True", 20:07:01 INFO - "buildnumber": 37, 20:07:01 INFO - "slavename": "t-yosemite-r7-0104", 20:07:01 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 20:07:01 INFO - "platform": "yosemite_r7", 20:07:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:07:01 INFO - "branch": "mozilla-central", 20:07:01 INFO - "repo_path": "mozilla-central", 20:07:01 INFO - "revision": "adcc39e3cad0f32aba0efb478cc4a023a5dfc43f", 20:07:01 INFO - "pgo_build": "False", 20:07:01 INFO - "builduid": "4c697f9685534775b1330889cfcae254", 20:07:01 INFO - "slavebuilddir": "test" 20:07:01 INFO - } 20:07:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:07:01 INFO - Running post-action listener: _resource_record_post_action 20:07:01 INFO - [mozharness: 2016-11-30 04:07:01.280143Z] Finished read-buildbot-config step (success) 20:07:01 INFO - [mozharness: 2016-11-30 04:07:01.280279Z] Running download-and-extract step. 20:07:01 INFO - Running pre-action listener: _resource_record_pre_action 20:07:01 INFO - Running main action method: download_and_extract 20:07:01 INFO - mkdir: /builds/slave/test/build/tests 20:07: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')]} 20:07:01 INFO - trying https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:07:02 INFO - Downloaded 1199 bytes. 20:07:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:07:02 INFO - Using the following test package requirements: 20:07:02 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:07:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:07:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:07:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'jsshell-mac.zip'], 20:07:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:07:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:07:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:07:02 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:07:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:07:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:07:02 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:07:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:07:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:07:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:07:02 INFO - Fetch https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:07:03 INFO - Http code: 200 20:07:03 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 20:07:03 INFO - x-amz-cf-id: Xt6yhzhfpT4hk1s-ioDI8bgswYUz4SQAg7Isoi6cAlWbRH-Tczk2jg== 20:07:03 INFO - x-amz-version-id: m7kb.rz.GIDnscEr5SzI0OjU4PqcSF3Z 20:07:03 INFO - Expected file size: 16285278 20:07:03 INFO - Obtained file size: 16285278 20:07:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:07:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:07:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:07:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:07:10 INFO - Http code: 200 20:07:10 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 20:07:10 INFO - x-amz-cf-id: gcfrXeffyvobMEJV7fBdGoGJfNKtywpLrYlNEiIKl_P0g8gh-rMvMw== 20:07:10 INFO - x-amz-version-id: m7kb.rz.GIDnscEr5SzI0OjU4PqcSF3Z 20:07:10 INFO - Expected file size: 16285278 20:07:10 INFO - Obtained file size: 16285278 20:07:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:07:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:07:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:07:18 INFO - Http code: 200 20:07:18 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 20:07:18 INFO - x-amz-cf-id: LDYJIWjjpn7V-2QY03vvedrn7MCtYZtMs_iYJZTjrMJabPhvH5Feuw== 20:07:18 INFO - x-amz-version-id: 7HEJE1x6RVnBRdMEcRuA1duca6IPENO0 20:07:18 INFO - Expected file size: 14409702 20:07:18 INFO - Obtained file size: 14409702 20:07: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')]} 20:07:18 INFO - trying https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:07:18 INFO - Downloading https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:07:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:07:21 INFO - Downloaded 107748427 bytes. 20:07:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:07:21 INFO - mkdir: /builds/slave/test/properties 20:07:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:07:21 INFO - Writing to file /builds/slave/test/properties/build_url 20:07:21 INFO - Contents: 20:07:21 INFO - build_url:https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:07:22 INFO - Running post-action listener: _resource_record_post_action 20:07:22 INFO - Running post-action listener: set_extra_try_arguments 20:07:22 INFO - [mozharness: 2016-11-30 04:07:22.144040Z] Finished download-and-extract step (success) 20:07:22 INFO - [mozharness: 2016-11-30 04:07:22.144187Z] Running populate-webroot step. 20:07:22 INFO - Running pre-action listener: _resource_record_pre_action 20:07:22 INFO - Running main action method: populate_webroot 20:07:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:07:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:07:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:07:22 INFO - '--tppagecycles', 20:07:22 INFO - '7'], 20:07:22 INFO - 'tests': ['tsvgm']}}, 20:07:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:07:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:07:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:07:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:07:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['damp', 'tps']}, 20:07:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'tests': ['damp', 'tps']}, 20:07:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['dromaeo_dom']}, 20:07:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:07:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['basic_compositor_video']}, 20:07:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:07:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'other-e10s': {'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['a11yr', 20:07:22 INFO - 'ts_paint', 20:07:22 INFO - 'tpaint', 20:07:22 INFO - 'sessionrestore', 20:07:22 INFO - 'sessionrestore_no_auto_restore', 20:07:22 INFO - 'tabpaint']}, 20:07:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['tsvgx', 20:07:22 INFO - 'tsvgr_opacity', 20:07:22 INFO - 'tart', 20:07:22 INFO - 'tscrollx', 20:07:22 INFO - 'cart', 20:07:22 INFO - 'tsvg_static']}, 20:07:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:07:22 INFO - 'tsvgr_opacity', 20:07:22 INFO - 'tart', 20:07:22 INFO - 'tscrollx', 20:07:22 INFO - 'cart', 20:07:22 INFO - 'tsvg_static']}, 20:07:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'talos_options': ['--disable-e10s'], 20:07:22 INFO - 'tests': ['tp5o']}, 20:07:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:07:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'talos_options': ['--disable-e10s', 20:07:22 INFO - '--xperf_path', 20:07:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:22 INFO - 'tests': ['tp5n']}, 20:07:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:07:22 INFO - 'talos_options': ['--xperf_path', 20:07:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:22 INFO - 'tests': ['tp5n']}}, 20:07:22 INFO - 'talos.zip': {'path': '', 20:07:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:07:22 INFO - Running post-action listener: _resource_record_post_action 20:07:22 INFO - [mozharness: 2016-11-30 04:07:22.162305Z] Finished populate-webroot step (success) 20:07:22 INFO - [mozharness: 2016-11-30 04:07:22.162443Z] Running create-virtualenv step. 20:07:22 INFO - Running pre-action listener: _resource_record_pre_action 20:07:22 INFO - Running main action method: create_virtualenv 20:07:22 INFO - Creating virtualenv /builds/slave/test/build/venv 20:07:22 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 20:07:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:07:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:07:22 INFO - Using real prefix '/tools/python27' 20:07:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:07:23 INFO - Installing distribute.............................................................................................................................................................................................done. 20:07:25 INFO - Installing pip.................done. 20:07:25 INFO - Return code: 0 20:07:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:07:26 INFO - Reading from file tmpfile_stdout 20:07:26 INFO - Output received: 20:07:26 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) 20:07:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:07: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')]} 20:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07: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')]} 20:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:26 INFO - 'HOME': '/Users/cltbld', 20:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:26 INFO - 'LOGNAME': 'cltbld', 20:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:26 INFO - 'MOZ_NO_REMOTE': '1', 20:07:26 INFO - 'NO_EM_RESTART': '1', 20:07:26 INFO - 'PAGER': '/bin/cat', 20:07:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:26 INFO - 'PWD': '/builds/slave/test', 20:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:26 INFO - 'SHELL': '/bin/bash', 20:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:26 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:26 INFO - 'USER': 'cltbld', 20:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:26 INFO - 'XPC_FLAGS': '0x0', 20:07:26 INFO - 'XPC_SERVICE_NAME': '0', 20:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:26 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 20:07:26 INFO - Cleaning up... 20:07:26 INFO - Return code: 0 20:07:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:07: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')]} 20:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07: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')]} 20:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:26 INFO - 'HOME': '/Users/cltbld', 20:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:26 INFO - 'LOGNAME': 'cltbld', 20:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:26 INFO - 'MOZ_NO_REMOTE': '1', 20:07:26 INFO - 'NO_EM_RESTART': '1', 20:07:26 INFO - 'PAGER': '/bin/cat', 20:07:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:26 INFO - 'PWD': '/builds/slave/test', 20:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:26 INFO - 'SHELL': '/bin/bash', 20:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:26 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:26 INFO - 'USER': 'cltbld', 20:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:26 INFO - 'XPC_FLAGS': '0x0', 20:07:26 INFO - 'XPC_SERVICE_NAME': '0', 20:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:26 INFO - Downloading/unpacking psutil>=3.1.1 20:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:07:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:07:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:07:30 INFO - Installing collected packages: psutil 20:07:30 INFO - Running setup.py install for psutil 20:07:30 INFO - building 'psutil._psutil_osx' extension 20:07:30 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 20:07:30 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 20:07:30 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 20:07:31 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 20:07:31 INFO - building 'psutil._psutil_posix' extension 20:07:31 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 20:07:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:07:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:07:31 INFO - ^ 20:07:31 INFO - 1 warning generated. 20:07:31 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 20:07:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:07:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:07:31 INFO - Successfully installed psutil 20:07:31 INFO - Cleaning up... 20:07:31 INFO - Return code: 0 20:07:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:07: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')]} 20:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07: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')]} 20:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:31 INFO - 'HOME': '/Users/cltbld', 20:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:31 INFO - 'LOGNAME': 'cltbld', 20:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:31 INFO - 'MOZ_NO_REMOTE': '1', 20:07:31 INFO - 'NO_EM_RESTART': '1', 20:07:31 INFO - 'PAGER': '/bin/cat', 20:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:31 INFO - 'PWD': '/builds/slave/test', 20:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:31 INFO - 'SHELL': '/bin/bash', 20:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:31 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:31 INFO - 'USER': 'cltbld', 20:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:31 INFO - 'XPC_FLAGS': '0x0', 20:07:31 INFO - 'XPC_SERVICE_NAME': '0', 20:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:07:34 INFO - Installing collected packages: mozsystemmonitor 20:07:34 INFO - Running setup.py install for mozsystemmonitor 20:07:34 INFO - Successfully installed mozsystemmonitor 20:07:34 INFO - Cleaning up... 20:07:34 INFO - Return code: 0 20:07:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:07:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:34 INFO - 'HOME': '/Users/cltbld', 20:07:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:34 INFO - 'LOGNAME': 'cltbld', 20:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:34 INFO - 'MOZ_NO_REMOTE': '1', 20:07:34 INFO - 'NO_EM_RESTART': '1', 20:07:34 INFO - 'PAGER': '/bin/cat', 20:07:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:34 INFO - 'PWD': '/builds/slave/test', 20:07:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:34 INFO - 'SHELL': '/bin/bash', 20:07:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:34 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:34 INFO - 'USER': 'cltbld', 20:07:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:34 INFO - 'XPC_FLAGS': '0x0', 20:07:34 INFO - 'XPC_SERVICE_NAME': '0', 20:07:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:34 INFO - Downloading/unpacking jsonschema==2.5.1 20:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:07:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - Downloading functools32-3.2.3-2.tar.gz 20:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:07:37 INFO - warning: no files found matching '*.txt' 20:07:37 INFO - no previously-included directories found matching 'build' 20:07:37 INFO - no previously-included directories found matching 'dist' 20:07:37 INFO - no previously-included directories found matching '.git*' 20:07:37 INFO - Installing collected packages: jsonschema, functools32 20:07:37 INFO - Running setup.py install for functools32 20:07:37 INFO - warning: no files found matching '*.txt' 20:07:37 INFO - no previously-included directories found matching 'build' 20:07:37 INFO - no previously-included directories found matching 'dist' 20:07:37 INFO - no previously-included directories found matching '.git*' 20:07:37 INFO - Successfully installed jsonschema functools32 20:07:37 INFO - Cleaning up... 20:07:38 INFO - Return code: 0 20:07:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07: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', '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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:07: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 functools32==3.2.3-2 20:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:38 INFO - 'HOME': '/Users/cltbld', 20:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:38 INFO - 'LOGNAME': 'cltbld', 20:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:38 INFO - 'MOZ_NO_REMOTE': '1', 20:07:38 INFO - 'NO_EM_RESTART': '1', 20:07:38 INFO - 'PAGER': '/bin/cat', 20:07:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:38 INFO - 'PWD': '/builds/slave/test', 20:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:38 INFO - 'SHELL': '/bin/bash', 20:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:38 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:38 INFO - 'USER': 'cltbld', 20:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:38 INFO - 'XPC_FLAGS': '0x0', 20:07:38 INFO - 'XPC_SERVICE_NAME': '0', 20:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:07:38 INFO - Cleaning up... 20:07:38 INFO - Return code: 0 20:07:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07: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', '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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:07: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 blobuploader==1.2.4 20:07:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:38 INFO - 'HOME': '/Users/cltbld', 20:07:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:38 INFO - 'LOGNAME': 'cltbld', 20:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:38 INFO - 'MOZ_NO_REMOTE': '1', 20:07:38 INFO - 'NO_EM_RESTART': '1', 20:07:38 INFO - 'PAGER': '/bin/cat', 20:07:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:38 INFO - 'PWD': '/builds/slave/test', 20:07:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:38 INFO - 'SHELL': '/bin/bash', 20:07:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:38 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:38 INFO - 'USER': 'cltbld', 20:07:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:38 INFO - 'XPC_FLAGS': '0x0', 20:07:38 INFO - 'XPC_SERVICE_NAME': '0', 20:07:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:38 INFO - Downloading/unpacking blobuploader==1.2.4 20:07:38 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 20:07:38 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 20:07:38 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 20:07:38 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 20:07:38 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 20:07:38 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 20:07:41 INFO - Downloading blobuploader-1.2.4.tar.gz 20:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:07:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:07:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:41 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 20:07:42 INFO - Downloading docopt-0.6.1.tar.gz 20:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:07:42 INFO - Installing collected packages: blobuploader, requests, docopt 20:07:42 INFO - Running setup.py install for blobuploader 20:07:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:07:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:07:42 INFO - Running setup.py install for requests 20:07:43 INFO - Running setup.py install for docopt 20:07:43 INFO - Successfully installed blobuploader requests docopt 20:07:43 INFO - Cleaning up... 20:07:43 INFO - Return code: 0 20:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:43 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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:43 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 20:07:43 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 20:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:43 INFO - 'HOME': '/Users/cltbld', 20:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:43 INFO - 'LOGNAME': 'cltbld', 20:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:43 INFO - 'MOZ_NO_REMOTE': '1', 20:07:43 INFO - 'NO_EM_RESTART': '1', 20:07:43 INFO - 'PAGER': '/bin/cat', 20:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:43 INFO - 'PWD': '/builds/slave/test', 20:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:43 INFO - 'SHELL': '/bin/bash', 20:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:43 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:43 INFO - 'USER': 'cltbld', 20:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:43 INFO - 'XPC_FLAGS': '0x0', 20:07:43 INFO - 'XPC_SERVICE_NAME': '0', 20:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:07:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8RMHS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:07:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_2OgT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:07:43 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XqsNtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Lgm42L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ki65vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-zOT4Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6AIO3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-92MZJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-e21Iub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Qh7Ovt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-C93xzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:07:44 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-GRZidH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:07:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8WSjkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:07:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FUV5nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-YFXipP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:07:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-dsajqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:07:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xFgGUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:07:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:07:45 INFO - Running setup.py install for manifestparser 20:07:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:07:45 INFO - Running setup.py install for mozcrash 20:07:45 INFO - Running setup.py install for mozdebug 20:07:45 INFO - Running setup.py install for mozdevice 20:07:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Running setup.py install for mozfile 20:07:46 INFO - Running setup.py install for mozhttpd 20:07:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Running setup.py install for mozinfo 20:07:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Running setup.py install for mozInstall 20:07:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:07:46 INFO - Running setup.py install for mozleak 20:07:46 INFO - Running setup.py install for mozlog 20:07:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Running setup.py install for moznetwork 20:07:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Running setup.py install for mozprocess 20:07:47 INFO - Running setup.py install for mozprofile 20:07:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Running setup.py install for mozrunner 20:07:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:07:47 INFO - Running setup.py install for mozscreenshot 20:07:47 INFO - Running setup.py install for moztest 20:07:47 INFO - Running setup.py install for mozversion 20:07:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:07:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:07:48 INFO - Cleaning up... 20:07:48 INFO - Return code: 0 20:07:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:48 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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:48 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 20:07:48 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 20:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:48 INFO - 'HOME': '/Users/cltbld', 20:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:48 INFO - 'LOGNAME': 'cltbld', 20:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:48 INFO - 'MOZ_NO_REMOTE': '1', 20:07:48 INFO - 'NO_EM_RESTART': '1', 20:07:48 INFO - 'PAGER': '/bin/cat', 20:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:48 INFO - 'PWD': '/builds/slave/test', 20:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:48 INFO - 'SHELL': '/bin/bash', 20:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:48 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:48 INFO - 'USER': 'cltbld', 20:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:48 INFO - 'XPC_FLAGS': '0x0', 20:07:48 INFO - 'XPC_SERVICE_NAME': '0', 20:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-TXEEZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:07:48 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)) 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ML3moJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IndZUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:07:48 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)) 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-43hgpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-CWgrqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:07:48 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)) 20:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:07:48 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8CRXhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-z5_arI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BDXzMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-aGtURB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BkmtAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-NPyAHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-wJq61c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-saRRN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:07:49 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)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7KiA1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:49 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-TmGSqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:50 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)) 20:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:07:50 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-TcTh6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:07:50 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)) 20:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:07:50 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-rZXcg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:07:50 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)) 20:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:07:50 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)) 20:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:07:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:07:50 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 20:07:50 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 20:07:50 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 20:07:50 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 20:07:50 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 20:07:50 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 20:07:52 INFO - Downloading blessings-1.6.tar.gz 20:07:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:07:52 INFO - Installing collected packages: blessings 20:07:52 INFO - Running setup.py install for blessings 20:07:53 INFO - Successfully installed blessings 20:07:53 INFO - Cleaning up... 20:07:53 INFO - Return code: 0 20:07:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:07:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:07:53 INFO - Reading from file tmpfile_stdout 20:07:53 INFO - Current package versions: 20:07:53 INFO - blessings == 1.6 20:07:53 INFO - blobuploader == 1.2.4 20:07:53 INFO - docopt == 0.6.1 20:07:53 INFO - functools32 == 3.2.3-2 20:07:53 INFO - jsonschema == 2.5.1 20:07:53 INFO - manifestparser == 1.1 20:07:53 INFO - mozInstall == 1.12 20:07:53 INFO - mozcrash == 1.0 20:07:53 INFO - mozdebug == 0.1 20:07:53 INFO - mozdevice == 0.49 20:07:53 INFO - mozfile == 1.2 20:07:53 INFO - mozhttpd == 0.7 20:07:53 INFO - mozinfo == 0.9 20:07:53 INFO - mozleak == 0.1 20:07:53 INFO - mozlog == 3.4 20:07:53 INFO - moznetwork == 0.27 20:07:53 INFO - mozprocess == 0.23 20:07:53 INFO - mozprofile == 0.28 20:07:53 INFO - mozrunner == 6.13 20:07:53 INFO - mozscreenshot == 0.1 20:07:53 INFO - mozsystemmonitor == 0.3 20:07:53 INFO - moztest == 0.7 20:07:53 INFO - mozversion == 1.4 20:07:53 INFO - psutil == 3.1.1 20:07:53 INFO - requests == 1.2.3 20:07:53 INFO - wsgiref == 0.1.2 20:07:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:54 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')]} 20:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:54 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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:54 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 20:07:54 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 20:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:54 INFO - 'HOME': '/Users/cltbld', 20:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:54 INFO - 'LOGNAME': 'cltbld', 20:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:54 INFO - 'MOZ_NO_REMOTE': '1', 20:07:54 INFO - 'NO_EM_RESTART': '1', 20:07:54 INFO - 'PAGER': '/bin/cat', 20:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:54 INFO - 'PWD': '/builds/slave/test', 20:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:54 INFO - 'SHELL': '/bin/bash', 20:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:54 INFO - 'USER': 'cltbld', 20:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:54 INFO - 'XPC_FLAGS': '0x0', 20:07:54 INFO - 'XPC_SERVICE_NAME': '0', 20:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 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)) 20:07:54 INFO - Cleaning up... 20:07:54 INFO - Return code: 0 20:07:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:07:54 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')]} 20:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:54 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')]} 20:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:55 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 0x1010303f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fa6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce80c9c960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, '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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 20:07:55 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 20:07:55 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 20:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:55 INFO - 'HOME': '/Users/cltbld', 20:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:55 INFO - 'LOGNAME': 'cltbld', 20:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:55 INFO - 'MOZ_NO_REMOTE': '1', 20:07:55 INFO - 'NO_EM_RESTART': '1', 20:07:55 INFO - 'PAGER': '/bin/cat', 20:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:55 INFO - 'PWD': '/builds/slave/test', 20:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:55 INFO - 'SHELL': '/bin/bash', 20:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:07:55 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:55 INFO - 'USER': 'cltbld', 20:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:55 INFO - 'XPC_FLAGS': '0x0', 20:07:55 INFO - 'XPC_SERVICE_NAME': '0', 20:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:07:55 INFO - Cleaning up... 20:07:55 INFO - Return code: 0 20:07:55 INFO - Running post-action listener: _resource_record_post_action 20:07:55 INFO - Running post-action listener: _start_resource_monitoring 20:07:55 INFO - Starting resource monitoring. 20:07:55 INFO - [mozharness: 2016-11-30 04:07:55.540302Z] Finished create-virtualenv step (success) 20:07:55 INFO - [mozharness: 2016-11-30 04:07:55.541272Z] Running install step. 20:07:55 INFO - Running pre-action listener: _resource_record_pre_action 20:07:55 INFO - Running main action method: install 20:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:07:55 INFO - Reading from file tmpfile_stdout 20:07:55 INFO - Detecting whether we're running mozinstall >=1.0... 20:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:07:55 INFO - Reading from file tmpfile_stdout 20:07:55 INFO - Output received: 20:07:55 INFO - Usage: mozinstall [options] installer 20:07:55 INFO - Options: 20:07:55 INFO - -h, --help show this help message and exit 20:07:55 INFO - -d DEST, --destination=DEST 20:07:55 INFO - Directory to install application into. [default: 20:07:55 INFO - "/builds/slave/test"] 20:07:55 INFO - --app=APP Application being installed. [default: firefox] 20:07:55 INFO - mkdir: /builds/slave/test/build/application 20:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:08:28 INFO - Reading from file tmpfile_stdout 20:08:28 INFO - Output received: 20:08:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 20:08:28 INFO - Running post-action listener: _resource_record_post_action 20:08:28 INFO - [mozharness: 2016-11-30 04:08:28.454639Z] Finished install step (success) 20:08:28 INFO - [mozharness: 2016-11-30 04:08:28.454829Z] Running run-tests step. 20:08:28 INFO - Running pre-action listener: _resource_record_pre_action 20:08:28 INFO - Running main action method: run_tests 20:08:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:08:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:08:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:08:28 INFO - 2016-11-29 20:08:28.469 screenresolution[1618:7539] starting screenresolution argv=screenresolution get 20:08:28 INFO - 2016-11-29 20:08:28.472 screenresolution[1618:7539] Display 0: 1600x1200x32@60 20:08:28 INFO - 2016-11-29 20:08:28.478 screenresolution[1619:7541] starting screenresolution argv=screenresolution list 20:08:28 INFO - Available Modes on Display 0 20:08:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:08:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:08:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:08:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:08:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:08:28 INFO - Intel Iris: 20:08:28 INFO - Chipset Model: Intel Iris 20:08:28 INFO - Type: GPU 20:08:28 INFO - Bus: Built-In 20:08:28 INFO - VRAM (Dynamic, Max): 1536 MB 20:08:28 INFO - Vendor: Intel (0x8086) 20:08:28 INFO - Device ID: 0x0a2e 20:08:28 INFO - Revision ID: 0x0009 20:08:28 INFO - Displays: 20:08:28 INFO - Display: 20:08:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:08:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:08:28 INFO - Display Serial Number: 1600x1200 60 20:08:28 INFO - Main Display: Yes 20:08:28 INFO - Mirror: Off 20:08:28 INFO - Online: Yes 20:08:28 INFO - Rotation: Supported 20:08:28 INFO - Return code: 0 20:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:08:28 INFO - Python 2.7.3 20:08:28 INFO - Return code: 0 20:08:28 INFO - grabbing minidump binary from tooltool 20:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:28 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 0x100e46d30>, '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 0x100caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e3eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:08:28 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 20:08:28 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 20:08:28 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 20:08:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:08:28 INFO - Return code: 0 20:08:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:08:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:08:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:08:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:08:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAZ6pXp2Gs/Render', 20:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:28 INFO - 'HOME': '/Users/cltbld', 20:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:28 INFO - 'LOGNAME': 'cltbld', 20:08:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:08:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:28 INFO - 'MOZ_NO_REMOTE': '1', 20:08:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:08:28 INFO - 'NO_EM_RESTART': '1', 20:08:28 INFO - 'PAGER': '/bin/cat', 20:08:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:28 INFO - 'PWD': '/builds/slave/test', 20:08:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:28 INFO - 'SHELL': '/bin/bash', 20:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uNxurQFXSh/Listeners', 20:08:28 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 20:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:28 INFO - 'USER': 'cltbld', 20:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:28 INFO - 'XPC_FLAGS': '0x0', 20:08:28 INFO - 'XPC_SERVICE_NAME': '0', 20:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:08:29 INFO - mozversion application_buildid: 20161129171714 20:08:29 INFO - mozversion application_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 20:08:29 INFO - mozversion application_display_name: Nightly 20:08:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:08:29 INFO - mozversion application_name: Firefox 20:08:29 INFO - mozversion application_remotingname: firefox 20:08:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:08:29 INFO - mozversion application_vendor: Mozilla 20:08:29 INFO - mozversion application_version: 53.0a1 20:08:29 INFO - mozversion platform_buildid: 20161129171714 20:08:29 INFO - mozversion platform_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 20:08:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:08:29 INFO - mozversion platform_version: 53.0a1 20:08:29 INFO - using testdate: 1480478909 20:08:29 INFO - actual date: 1480478909 20:08:29 INFO - starting webserver on 'localhost:49240' 20:08:29 INFO - SUITE-START | Running 2 tests 20:08:29 INFO - TEST-START | dromaeo_css 20:08:29 INFO - operating with platform_type : mac_ 20:08:29 INFO - Initialising browser for dromaeo_css test... 20:08:29 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpDeZLdy/profile http://localhost:49240/getInfo.html) 20:08:32 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 20:08:32 INFO - PROCESS | 1629 | colorDepth:24 20:08:32 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 20:08:32 INFO - PROCESS | 1629 | __metrics 20:08:32 INFO - PROCESS | 1629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:08:32 INFO - PROCESS | 1629 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:08:33 INFO - TEST-INFO | 1629: exit 0 20:08:33 INFO - Browser initialized. 20:08:33 INFO - Running cycle 1/1 for dromaeo_css test... 20:08:33 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpDeZLdy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:08:45 INFO - PROCESS | 1632 | RSS: Main: 244137984 20:08:45 INFO - PROCESS | 1632 | 20:09:36 INFO - PROCESS | 1632 | 1480478976939 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] 20:11:48 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 20:11:51 INFO - PROCESS | 1632 | RSS: Main: 495509504 20:11:51 INFO - PROCESS | 1632 | 20:14:54 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 20:14:55 INFO - PROCESS | 1632 | RSS: Main: 380076032 20:14:55 INFO - PROCESS | 1632 | 20:17:58 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 20:17:58 INFO - PROCESS | 1632 | RSS: Main: 365522944 20:17:58 INFO - PROCESS | 1632 | 20:21:01 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 20:21:01 INFO - PROCESS | 1632 | RSS: Main: 360706048 20:21:01 INFO - PROCESS | 1632 | 20:24:05 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 20:24:05 INFO - PROCESS | 1632 | RSS: Main: 330276864 20:24:05 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 20:27:09 INFO - PROCESS | 1632 | RSS: Main: 501608448 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | __start_tp_report 20:27:09 INFO - PROCESS | 1632 | _x_x_mozilla_page_load 20:27:09 INFO - PROCESS | 1632 | _x_x_mozilla_page_load_details 20:27:09 INFO - PROCESS | 1632 | |i|pagename|runs| 20:27:09 INFO - PROCESS | 1632 | |0;dojo.html;2779.652543432071;3320.850561724722;3328.467445208769;3359.8320083995795;3382.4757162639785;5528.75120619572;5558.138488534279;5612.803551875685;5642;5682.5738069644785;5590.18942253374;5632.549396048316;5644.491995720385;5644.6330988485715;5644.971775141129;5106.183010718295;5126.282320475137;5153.716545589991;5165.948340516603;5186.533212010918;5172.767225474844;5200.76596553156;5248.422673505911;5249.501297376743;5254.395744489375;5159.690418574879;5204.1413166827615;5214.243934629477;5224.764885580158;5255.290535777678;5244.423113457517;5258.737063146866;5260.342457192851;5286.629935904488;5311;3944.4280579316123;4996.025774973881;5046.167382381939;5177.559907407875;5180.3265575475325;5518.227448157261;5557.277553918006;5577.107662773937;5627.409122042209;5633.60564760467;6109.480694141003;6122.846928826771;6130.785422510236;6133.049377346519;6136.417040381157;6097.451229389332;6139.369480960597;6182.443580077769;6191.288001879797;6203.689815509251;5656.8868622627315;5717.2281741965135;5723.45627165427;5723.627964182315;5738.0532212184935;6207.255129384503;6264.342244064308;6268.937310626927;6280.7487700491465;6282.968585157045;6013.609115407485;6014.9699251504335;6042.697865106815;6078.75684972605;6117.816465506042;6034.758609655564;6101.908471372933;6192.752289908353;6193.938060619426;6199.163112979781;6083.847903802441;6107.786227482017;6137.846553836101;6151.815445536641;6174.043023331391;6110.4500594945775;6146.323904370515;6171.876562468726;6185.907211391833;6194.256689197326;4801.231802911518;4898.534639209341;4906.386701662293;4916.508349165055;4933.407991041026;4309.741415515079;4360;4377.5403582624;4393.802278897458;4394;5689.601727879091;5740.913886291709;5745.2818397700285;5749.770009199585;5772.740226689725;6234.532410069172;6255.812325630239;6261.342559031327;6265.216847894013;6266.216722909637;5583.330000399978;5583.497485226349;5636.070048441961;5688;5699.829005129853;4554.544545545486;5247.344081989751;5300.443453437333;5305.575553955751;5340.225667278223;6264.185655864709;6273.247210334789;6282.183316168869;6286.5599408041;6286.622802631765;6102.694865256631;6180.66006369636;6221.720022599085;6224.37756224374;6245.063240513778;6204.3795620437595;6230.968845155745;6259.561830671809;6268.028455589391;6285.308616052322;6113.233070740737;6133.51829027926;6138.894998900241;6139.553468857771;6151.569390142647;6073.0283154695035;6180.289266734383;6185.907211391923;6196.1015652731;6204.255489341308;5827.854303642443;5862.765489380377;5897.174395584536;5903.557233207613;5999.100134979613;5936.7922122725495;5940.851478713067;5945.821625351246;5955.166276721176;5963.761449541969;5810.070388737781;5819.330776960704;5821.825345239649;5831.854203644943;5833.82498525045;5891.351951285269;5940.554458415722;5953.76184952597;5954.166416701752;5959.22530071088;5770.769169233184;5781.988152073454;5786.942130578809;5787.797427090031;5794.478496935296 20:27:09 INFO - PROCESS | 1632 | |1;ext.html;441.8365204874197;462.7154300105435;528.5454509122156;535.0529562674067;546.2079984023165;29379;29834.850825745896;30952.845235773846;38233.42649860247;39729.60270397295;40014.79992600033;40357.19285614286;40419.39370909439;40428.393574096415;40574;8958.23854972328;9138.223251023672;9142.314326425512;9147.496887671176;9167.220786233145;8212.712555060574;8425.915740842574;9198.494082825442;10926.617568385109;11210.383428911407;11076.335419874833;11145.275557088764;11186.608468703596;11209.383483908381;11266.211365204439;11096.667099987013;11178.27341222818;11182.217244792868;11197.496112674868;11202.551897924073;8135.186481351877;8379;10749.785004299949;11053.557857685682;11070.723231919186;37325.626743732486;38348;38633.42049869254;38701.03247418809;38722.0319492015;3263;3263.216827961296;3338.5659864217496;3417.6411476795074;3575.4458059000895;28463.85768071167;29073.85463072671;29660.851695741385;29706.70293297061;29857.552136717968;3128.405602935458;3233.337165880989;3281.1633033576286;3303.355845610107;3317.767756257063;338137;436343;439294.80352598353;444773;445993;59247.40752592505;59439;59461;59545.404545953985;59781.701091494266;61904;61963.38036619666;62004.37995619986;62158.37841621616;62191;15070.246487675573;15189.240537973057;15410;15702.371905123895;15729.842701572838;10732;12093.879061209274;12162.817557736642;12185.45165467556;12208.389580520938;14931.552053438414;15368.233717930656;16653.916730416513;17255.74116388255;17327.527160191272;5789.305283366023;6383.776567820197;6422.678866056678;6461.967690161519;6480.351964803482;882.7440042387635;982.597135174575;1004.829179039565;1010.2069875147973;1016.3901659004686;29029;29425.705742942297;29512.795983008185;29531;29871.220604703143;1012.0790081026378;1017.4658304390255;1024.8411496218098;1121.097516499226;1121.513993958009;14757.92621036888;15130.621734456727;15239.847601523843;15560.455384061503;15589.84410155884;596808;600254;601395;602814.9859250676;604993;49187.75406122946;56282.71858640681;56340.15489767657;56456;56498.71750641303;1298.1951190262098;1346.6633341664583;1358.7282543491342;1362.9931850340686;1367.7538043152329;26446.94212231566;28403.43193136167;29063.56404653974;29555.261118472212;29709.554356684235;44843.55156448524;52902.941941161735;54225.644358891346;54324.45675543194;57619.71190144023;585567;588616;589204;590350;590921.9078092156;25606.231813045637;25949.481010380063;25993;26076.34809129787;26148.60777088307;44621.33068003917;45271.09457810891;46399.53600463952;46735.53264467448;46887.531124688314;36884.26231475302;37230.8138459306;37344.71778003263;38258.85223443301;38308.616913830505;37152.25695486021;37245.813770930974;37637.435438468994;38414.23171536609;38460.61539384571;36949.26101477902;37520.62479375171;37774;37822.243555129295;37899.67351142697;31844.8407757969;33052.83473582617;33229.66770332266;33679.15802104967;33976;19431.4170574883;22585.661215082117;23358.883205583865;24587.631185532588;24927.626085608368 20:27:09 INFO - PROCESS | 1632 | |2;jquery.html;349.83557727867924;366.56012784658407;369.7541135145129;382.6345839723064;398.8723608445297;14462.638434039154;14765.630859228511;15409.229538523085;19239.42281731547;20323;15539.766903496457;19167.712484312713;19217.84692918426;20300.289489867857;20530;5427.1316589345715;5700.857478563028;5705.229793977802;5718.313802343725;5724.398938111501;4992.850214493571;5548.334199896018;6366.267879193906;6439.774607888725;6494.090827284161;6196.566240363177;6402.551821372505;6456.451201647842;6467.320931302216;6486.286508484063;6227.937720622804;6249.593776404519;6303.243610766692;6366.681665916685;6385.201849768802;5302.522772950414;5513.255710479114;5532.197831314477;5544.417836127229;5558.666480011212;22145.778542214695;23687.526249474915;25129.62305565418;25165.62251566228;25433.364165895815;2308.134449581407;2353.8940747666397;2363.692719946413;2383.761623837602;2963.7996611372428;10132.34139780912;10349.120324772404;12531.18547294432;14357.712845743026;15265.23673816316;1820.1809185866414;1822.7903791064066;1896.6017136401372;1901.4295711286559;1957.0214892553722;108647.28381790519;129540.7045929538;129709;130076.34961825225;131879;9108.908910910806;11158.77253502114;11487;11501.13741469393;11665.24175928579;14496.492622758153;14518.564443066725;14546.854531454761;14547.19990400517;14561.92719036398;6898.344657257552;8104.351651867836;8292.875606865902;8344.457610255315;8449.239568438747;6654.633995130217;8099;8121.106678265386;8313.709020184386;8327.458715183493;4301.6773741968855;4401.955980440219;4513.977430112894;4522;4590.609798167193;1760.6390689908794;1792.2139771308237;1808.8462480689288;1859.414284500378;1917.453525745184;781.3631890009624;782.9373650107977;805.6213579617688;836.2139588786521;896.4800415758845;18422.986735729664;20075.096620652104;20325;20346.084426200858;20613.690794638092;866.7183165471121;881.0924747510074;881.110078820396;935.0135606934613;936.489613160829;8767.210951014311;9891.802163956681;10553.894461055444;10630.840537391945;10672.491964388362;522933;575218;583906;586732.0663396656;594109;130342.44863270585;135356;138803;138845;139403.60596394108;927.3879239701764;933.4772527384687;979.7991411760572;985.4530735441898;994.6121012805012;18376;18644;18863;19011.270082328017;19198.8080119197;52428;53516.92966140733;53703.46296536984;54329.64175895634;54504.18243726268;72803.63598181975;73085.6345718268;76434;79258.6037069811;80005.19994799978;7090.290970903075;7422;7866.331361834308;7963.800904977422;8476.23713865755;11242.381669008282;12914.67713307175;12970.35148242603;13436.462541498231;13483.325833708079;10491.895081049091;11089.33463992163;11103.50034248477;11516.30902145874;11687.240329378563;8798;9957.402555846673;11144.33134011962;11577;11583.478743456397;11227.27022743519;11239.60661376848;11808.527658893892;11867.58463453775;11898.940505297418;7321.414286857145;7511.586862722603;7525;7680.500767450098;7794.649240784065;9741.56162972682;10284.382937023802;10800.891991080303;11194.720131996764;11249.606263780728 20:27:09 INFO - PROCESS | 1632 | |3;mootools.html;1270.3457719274575;1328.4951718347027;1364.7338768940053;1743.7733094697687;1842.8986405747687;7969.721059762902;8367.205115514033;8372;8379.748607541784;8405.285550728187;7226.168990566087;7536.81157971051;7537.886931696029;7633.320669452725;7640.923590764105;2080.8855512946743;2116.0160525355705;2224.065892325219;2301.5742087713743;2307.0886999635136;2239.5296987632537;2243.674667173259;2252.5044490212126;2253.752087270399;2255.672927425522;2263.649134384165;2285.165914441234;2308.1690591387087;2310.5609934112463;2316.2124877541632;2274.510980139278;2276.4195130241847;2293.977060229393;2295.2081531871477;2296.7473577906417;2280.407094155515;2289.519200967799;2309.429587880241;2312.456572705413;2319.512902290521;8178.509289442593;8230.465019773696;8290.5854707265;8292.668293268262;8314.168583141638;4937.604991600663;5110.054639891632;5113.565346945514;5118.846434606968;5126.61550383719;3377.3414184234084;3419.350323438539;3444.621091679913;3451.620321764604;3550.094725844893;1000.489750227382;1040;1052.36857885269;1067.3009178987697;1076.7523469602036;10648.840267395995;10729.302595331357;10741.570337186504;10741.946290268499;10787.029167374896;4384.583464570861;4536.56902594253;4631.3516107744945;4635.490096089427;4695.084458530622;9365.953170234105;9377.43735375866;9386.436813791195;9399.10708482693;9401.90598094024;5165.199394093921;5167.793288268427;5168.53483186515;5172.844814655567;5174.767135478913;4494.415725955605;4639.907201855944;4706.411698537683;4733.9763301183275;4738.47876733559;3457.291255292659;3570.125319296737;3575.3385623659706;3665.9450108248398;3687.6681098701247;1241.0629974369474;1245.7072587941827;1252.292454763056;1261.6908857330013;1262.9621111366673;705.9047028651066;769.5651956644477;791.1060501633118;864.6368525219423;868.9261412779858;4745.193317136095;5282.366116066097;5499.707568721347;5547.278853748987;5602.38373778884;821.3511326052471;913.9177474027236;923.7136487688838;953.6137864165024;963.6819849449665;3580.3018411409525;3690.760100593453;3796.240751849641;3845.5000849889334;3858.344081506151;9593.616255349847;9769.120779129771;9980.80038399228;10124.949375253076;10138.898611013796;9790.902090979;10052.296339256181;10064.74838129038;10078.848817267746;10089.09198172168;7399.48203625741;7779.183185765526;7865.331446827084;7881.1724768899585;7896.60516974149;5360;5436.320459942507;5468.972655136698;5564.749586268708;5677.148427735708;9080.500572468314;9233.861492077758;9291.256699463891;9328.580213890527;9411.247100232102;9430.3398762086;9484.715458536255;9487.762805929908;9534.761630959281;9608.471534065693;6968.0941477607585;7056.788296351117;7157.212706602166;7172.533785303938;7176.067111275501;7375.557466552024;7654.234576542524;7721.5753133575945;7727.034120734908;7837.7648670539975;6874.518783685094;7007.474439416962;7014.228434872262;7085.893711594227;7186.209516953027;6157.72290246931;6744.7976560703255;6935.202451718117;7014.964925175342;7016.052832867702;6379.3301703320485;6579.934200658124;6582.3746744059235;6588.308227636029;6592.373724496165;4582.908341833078;4779.737114458738;4796.736179510161;4887.36464259644;4966.180580204225;3968;4065.9796701016307;4791.5687588117235;4830.758462076952;4971.726555039508 20:27:09 INFO - PROCESS | 1632 | |4;prototype.html;302.13589135073687;302.88036225690854;313.3716897620272;317.1452934341949;317.3240996677079;5032.263868965201;5191.766370513326;5198.4801519848;5255.947440525593;5279.577633789298;4420.778961051942;5106.66806657567;5170.612204084689;5177.5599074078655;5285.365756109272;3242.2866969266724;3994.820233089518;4024.839006439739;4044.9191016179657;4046.1907618476275;3311.3608763662023;3527.4532447470683;3596.7662101963288;3799.25914446683;4048.9835072870605;3170.6512283648894;3889.202713443737;4001.9599804002023;4024.3158663027357;4061.5938406159444;3536.8778732760265;3580.9283814323567;3738.15899916063;3809.847606095753;4003.2393845169327;3231.83840807961;3528.641473032875;3534.540509733718;3539.592946811124;3545.2200515886466;4153.06556024895;4847.418309802811;4947.575687912933;5135.589152867761;5169.819056333048;4147.6474499667265;4292.957070429287;4629.675922685414;4630.236011058171;4633.652476064309;4979.311171967133;5162.354705661793;5419.783208671648;5515.393306736256;5522.447755224485;3267.9346413071844;3280.950785738216;3863.9034024149623;3915.197384536163;3969.7221194516396;5594.384617692051;5743.770249190027;5753.884922301614;5771.595988280864;5782.624129431616;3344.5484859544135;3940.4877365942243;3944.684425245973;3948.664363529132;3951.940720889189;3840.347140985983;4003.5996400360314;4028.436018957348;4039.3537034074993;4049.817758200888;3994.420808982681;4297.5702429756775;4589.990202155521;4603.194440972817;4611.331356953223;5003.924941125886;5061.7469126544265;5301.5493683037375;5479.397266300703;5493.917591236134;1141.8743938166795;1181.7636472705494;1186.1732931285896;1264.7533730922382;1271.9300438475786;909.3180114913814;909.5815924674723;910.3491003932088;912.4525284829182;919.5402298850574;4152.584741525823;4776.737279449594;4902.2156454967035;4917.606591472673;4936.728479933567;4847.467617777389;5411.9729401353525;5424.945750542523;5452.291202143696;5502.881390664543;4606.700564463299;4879.950810575743;5287.894242115213;5309.8938021239355;5313.362396512366;2495.513374891879;2639.6304517367585;2712.5253080710795;2817.802753807214;2849.190763864919;5212.973935130376;5335.6394119500055;5466.945330546723;5639.43605639441;5736.77052917887;4722.881927951828;4802.031167221422;5430.782768689208;5521.585881058937;5603.592533205477;4874.90250194987;5010.146245888972;5661.943380566224;5692.857678558069;5699.575106223444;4690.03854209893;4965.4041515018425;5052.509509694575;5063.518965698253;5506.366767821752;4699.812007519661;5288.3918349390315;5336.905934283385;5359.996803580016;5425.782968681367;4967.218357719688;5309.522143007148;5622.297212848394;5651.886962260814;5674.120511320752;3496.3706532823317;3723.9759066256997;3752.399616061417;3812.8284227209288;3865.458835763052;5232.241325007929;5683.318001839806;5700.030994730822;5702.401247868914;5703.914441283467;4498.190325741398;5542.251796007648;5553.8333849984565;5568.638038527482;5571.4428557143965;5063.898722025465;5348.903474787581;5466.34403871522;5526.22632831412;5536.280283563111;4639.886427257501;5308.309919710413;5316.548093412103;5322.520973112439;5382.393796156663;4319.395284660087;4465.6204222640135;4747.596454301423;5366;5452.500562345387;5281.618518904274;5410.83767486976;5470.452954704498;5486.91769623448;5509.421510741315 20:27:09 INFO - PROCESS | 1632 | |5;yui.html;674.3627272227745;709.5033476566405;731.8729157098069;753.3143845282342;803.931665808406;14718.116912985228;15593.766093508606;15620.453284135043;15632.062076275417;15633.843661563382;13953.860461395383;14534.709305813903;15303.540893773177;15325.233738313096;15609.531714048568;804.6298702596813;835.4569529805644;839.8782176584392;842.7176895739934;844.2907957315854;660.3627499463017;682.4608559238196;687.3092541995313;810.9878351824727;839.2111415269658;879.1120967822482;892.5001998880648;894.6108442827391;895.3777124898198;896.5158814240302;843.3379796859466;844.6916875340518;845.3406343052399;881.625309243569;898.2454738020094;510.6884800271831;526.1555203897766;667.349334398962;671.1174805131237;671.6540981394586;11640.417979101012;11890.702732431671;11903.523859045628;11907.226030308002;14762;13422.597322080353;13766.174029558159;13951;13975.860241397557;14116.576502704935;11827.467763950643;11862;11883.881161188365;12114.939425302906;12427.502899883993;3626.4016437287814;3630;3630.2376500934834;3646.6353364663587;3795.3168429682646;116143.4192829039;117632.23551646646;118205;118443;120957;8304.418690691593;9751.561179746928;10338.483075846329;10426.478676066317;10774.892251077388;21182.15271389158;21327;21377.78622213758;21599.136034558756;21720.674189887166;7425.9628701856145;7439.070116235471;7534.0582427196605;7640.617969101522;7646.694132234648;3372.780769249991;4038.3288920647633;4041.8492354466553;4113.91772164561;4280.4863416390235;3325.2351959049556;3438.8796392126656;3464.3937310970477;3465.5321531592913;3475.218075932895;633.5248563577317;637.744902039188;645.2966266769245;648.0441349010266;649.7855707616475;416.21958827199;532.3930718980365;567.3447168520365;607.8966675484071;668.5053060735119;8438.451500652438;8907.242884354902;9080.636774528944;9085;9365.859512107323;617.1606615003592;645.3578689204272;648.7525132969567;651.9967400162968;664.5846346033729;4367.978160109179;4500.257457519538;4508.436445444319;4545.227311357077;4635.7450340230935;116683;117008;117719.41140294244;118065;121363;102717;103033;103306.48346758369;103317;106718.46640766902;72480;72527.63736181286;76876.61561692155;93361;96247.51876240574;9365.344425890395;9765.246974549425;9789.85315220258;9859.408435493893;9878.209743220381;47658.523414766794;51670.70823229449;53297;53930.73034634802;54591.98976225224;52335.69160771011;64931.67534162299;66705.66647166928;76491.23508764841;80172.19827801647;12451.875481245072;12494;12632.2420654761;12782.424790884246;13159.868401315864;14389.64025899319;14401.927990359982;14431.29928400001;14524.128552286897;14688.412463501767;11391.430428478709;13166.539171128965;13320.800187996994;13433.462661493822;13824.37790299459;12768.233905965672;12812.231266124063;13374.732505350034;13568;13712.314384280546;13153.539626113423;13301.600951971457;13343.799843002556;13551.119177253448;14000.929995349958;11065.834012489659;11116.544091966107;11407.657770266906;11469.31184128955;11691.36944187551;10180.16008723037;10499.317544359592;11513;11513.53945842157;11548.249363791327 20:27:09 INFO - PROCESS | 1632 | __end_tp_report 20:27:09 INFO - PROCESS | 1632 | __start_cc_report 20:27:09 INFO - PROCESS | 1632 | _x_x_mozilla_cycle_collect,2920 20:27:09 INFO - PROCESS | 1632 | __end_cc_report 20:27:09 INFO - PROCESS | 1632 | __startTimestamp1480480029431__endTimestamp 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | ------- Summary: start ------- 20:27:09 INFO - PROCESS | 1632 | Number of tests: 6 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#0] dojo.html Cycles:165 Average:5666.50 Median:5821.83 stddev:654.78 (11.2%) stddev-sans-first:616.38 20:27:09 INFO - PROCESS | 1632 | Values: 2779.7 3320.9 3328.5 3359.8 3382.5 5528.8 5558.1 5612.8 5642.0 5682.6 5590.2 5632.5 5644.5 5644.6 5645.0 5106.2 5126.3 5153.7 5165.9 5186.5 5172.8 5200.8 5248.4 5249.5 5254.4 5159.7 5204.1 5214.2 5224.8 5255.3 5244.4 5258.7 5260.3 5286.6 5311.0 3944.4 4996.0 5046.2 5177.6 5180.3 5518.2 5557.3 5577.1 5627.4 5633.6 6109.5 6122.8 6130.8 6133.0 6136.4 6097.5 6139.4 6182.4 6191.3 6203.7 5656.9 5717.2 5723.5 5723.6 5738.1 6207.3 6264.3 6268.9 6280.7 6283.0 6013.6 6015.0 6042.7 6078.8 6117.8 6034.8 6101.9 6192.8 6193.9 6199.2 6083.8 6107.8 6137.8 6151.8 6174.0 6110.5 6146.3 6171.9 6185.9 6194.3 4801.2 4898.5 4906.4 4916.5 4933.4 4309.7 4360.0 4377.5 4393.8 4394.0 5689.6 5740.9 5745.3 5749.8 5772.7 6234.5 6255.8 6261.3 6265.2 6266.2 5583.3 5583.5 5636.1 5688.0 5699.8 4554.5 5247.3 5300.4 5305.6 5340.2 6264.2 6273.2 6282.2 6286.6 6286.6 6102.7 6180.7 6221.7 6224.4 6245.1 6204.4 6231.0 6259.6 6268.0 6285.3 6113.2 6133.5 6138.9 6139.6 6151.6 6073.0 6180.3 6185.9 6196.1 6204.3 5827.9 5862.8 5897.2 5903.6 5999.1 5936.8 5940.9 5945.8 5955.2 5963.8 5810.1 5819.3 5821.8 5831.9 5833.8 5891.4 5940.6 5953.8 5954.2 5959.2 5770.8 5782.0 5786.9 5787.8 5794.5 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#1] ext.html Cycles:180 Average:66884.07 Median:3496.54 stddev:145353.63 (4157.1%) stddev-sans-first:145675.78 20:27:09 INFO - PROCESS | 1632 | Values: 441.8 462.7 528.5 535.1 546.2 29379.0 29834.9 30952.8 38233.4 39729.6 40014.8 40357.2 40419.4 40428.4 40574.0 8958.2 9138.2 9142.3 9147.5 9167.2 8212.7 8425.9 9198.5 10926.6 11210.4 11076.3 11145.3 11186.6 11209.4 11266.2 11096.7 11178.3 11182.2 11197.5 11202.6 8135.2 8379.0 10749.8 11053.6 11070.7 37325.6 38348.0 38633.4 38701.0 38722.0 3263.0 3263.2 3338.6 3417.6 3575.4 28463.9 29073.9 29660.9 29706.7 29857.6 3128.4 3233.3 3281.2 3303.4 3317.8 338137.0 436343.0 439294.8 444773.0 445993.0 59247.4 59439.0 59461.0 59545.4 59781.7 61904.0 61963.4 62004.4 62158.4 62191.0 15070.2 15189.2 15410.0 15702.4 15729.8 10732.0 12093.9 12162.8 12185.5 12208.4 14931.6 15368.2 16653.9 17255.7 17327.5 5789.3 6383.8 6422.7 6462.0 6480.4 882.7 982.6 1004.8 1010.2 1016.4 29029.0 29425.7 29512.8 29531.0 29871.2 1012.1 1017.5 1024.8 1121.1 1121.5 14757.9 15130.6 15239.8 15560.5 15589.8 596808.0 600254.0 601395.0 602815.0 604993.0 49187.8 56282.7 56340.2 56456.0 56498.7 1298.2 1346.7 1358.7 1363.0 1367.8 26446.9 28403.4 29063.6 29555.3 29709.6 44843.6 52902.9 54225.6 54324.5 57619.7 585567.0 588616.0 589204.0 590350.0 590921.9 25606.2 25949.5 25993.0 26076.3 26148.6 44621.3 45271.1 46399.5 46735.5 46887.5 36884.3 37230.8 37344.7 38258.9 38308.6 37152.3 37245.8 37637.4 38414.2 38460.6 36949.3 37520.6 37774.0 37822.2 37899.7 31844.8 33052.8 33229.7 33679.2 33976.0 19431.4 22585.7 23358.9 24587.6 24927.6 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#2] jquery.html Cycles:180 Average:34549.53 Median:368.16 stddev:96380.51 (26179.2%) stddev-sans-first:96616.68 20:27:09 INFO - PROCESS | 1632 | Values: 349.8 366.6 369.8 382.6 398.9 14462.6 14765.6 15409.2 19239.4 20323.0 15539.8 19167.7 19217.8 20300.3 20530.0 5427.1 5700.9 5705.2 5718.3 5724.4 4992.9 5548.3 6366.3 6439.8 6494.1 6196.6 6402.6 6456.5 6467.3 6486.3 6227.9 6249.6 6303.2 6366.7 6385.2 5302.5 5513.3 5532.2 5544.4 5558.7 22145.8 23687.5 25129.6 25165.6 25433.4 2308.1 2353.9 2363.7 2383.8 2963.8 10132.3 10349.1 12531.2 14357.7 15265.2 1820.2 1822.8 1896.6 1901.4 1957.0 108647.3 129540.7 129709.0 130076.3 131879.0 9108.9 11158.8 11487.0 11501.1 11665.2 14496.5 14518.6 14546.9 14547.2 14561.9 6898.3 8104.4 8292.9 8344.5 8449.2 6654.6 8099.0 8121.1 8313.7 8327.5 4301.7 4402.0 4514.0 4522.0 4590.6 1760.6 1792.2 1808.8 1859.4 1917.5 781.4 782.9 805.6 836.2 896.5 18423.0 20075.1 20325.0 20346.1 20613.7 866.7 881.1 881.1 935.0 936.5 8767.2 9891.8 10553.9 10630.8 10672.5 522933.0 575218.0 583906.0 586732.1 594109.0 130342.4 135356.0 138803.0 138845.0 139403.6 927.4 933.5 979.8 985.5 994.6 18376.0 18644.0 18863.0 19011.3 19198.8 52428.0 53516.9 53703.5 54329.6 54504.2 72803.6 73085.6 76434.0 79258.6 80005.2 7090.3 7422.0 7866.3 7963.8 8476.2 11242.4 12914.7 12970.4 13436.5 13483.3 10491.9 11089.3 11103.5 11516.3 11687.2 8798.0 9957.4 11144.3 11577.0 11583.5 11227.3 11239.6 11808.5 11867.6 11898.9 7321.4 7511.6 7525.0 7680.5 7794.6 9741.6 10284.4 10800.9 11194.7 11249.6 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#3] mootools.html Cycles:180 Average:5364.11 Median:5122.73 stddev:2957.02 (57.7%) stddev-sans-first:2949.31 20:27:09 INFO - PROCESS | 1632 | Values: 1270.3 1328.5 1364.7 1743.8 1842.9 7969.7 8367.2 8372.0 8379.7 8405.3 7226.2 7536.8 7537.9 7633.3 7640.9 2080.9 2116.0 2224.1 2301.6 2307.1 2239.5 2243.7 2252.5 2253.8 2255.7 2263.6 2285.2 2308.2 2310.6 2316.2 2274.5 2276.4 2294.0 2295.2 2296.7 2280.4 2289.5 2309.4 2312.5 2319.5 8178.5 8230.5 8290.6 8292.7 8314.2 4937.6 5110.1 5113.6 5118.8 5126.6 3377.3 3419.4 3444.6 3451.6 3550.1 1000.5 1040.0 1052.4 1067.3 1076.8 10648.8 10729.3 10741.6 10741.9 10787.0 4384.6 4536.6 4631.4 4635.5 4695.1 9366.0 9377.4 9386.4 9399.1 9401.9 5165.2 5167.8 5168.5 5172.8 5174.8 4494.4 4639.9 4706.4 4734.0 4738.5 3457.3 3570.1 3575.3 3665.9 3687.7 1241.1 1245.7 1252.3 1261.7 1263.0 705.9 769.6 791.1 864.6 868.9 4745.2 5282.4 5499.7 5547.3 5602.4 821.4 913.9 923.7 953.6 963.7 3580.3 3690.8 3796.2 3845.5 3858.3 9593.6 9769.1 9980.8 10124.9 10138.9 9790.9 10052.3 10064.7 10078.8 10089.1 7399.5 7779.2 7865.3 7881.2 7896.6 5360.0 5436.3 5469.0 5564.7 5677.1 9080.5 9233.9 9291.3 9328.6 9411.2 9430.3 9484.7 9487.8 9534.8 9608.5 6968.1 7056.8 7157.2 7172.5 7176.1 7375.6 7654.2 7721.6 7727.0 7837.8 6874.5 7007.5 7014.2 7085.9 7186.2 6157.7 6744.8 6935.2 7015.0 7016.1 6379.3 6579.9 6582.4 6588.3 6592.4 4582.9 4779.7 4796.7 4887.4 4966.2 3968.0 4066.0 4791.6 4830.8 4971.7 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#4] prototype.html Cycles:180 Average:4388.14 Median:4942.15 stddev:1341.44 (27.1%) stddev-sans-first:1309.68 20:27:09 INFO - PROCESS | 1632 | Values: 302.1 302.9 313.4 317.1 317.3 5032.3 5191.8 5198.5 5255.9 5279.6 4420.8 5106.7 5170.6 5177.6 5285.4 3242.3 3994.8 4024.8 4044.9 4046.2 3311.4 3527.5 3596.8 3799.3 4049.0 3170.7 3889.2 4002.0 4024.3 4061.6 3536.9 3580.9 3738.2 3809.8 4003.2 3231.8 3528.6 3534.5 3539.6 3545.2 4153.1 4847.4 4947.6 5135.6 5169.8 4147.6 4293.0 4629.7 4630.2 4633.7 4979.3 5162.4 5419.8 5515.4 5522.4 3267.9 3281.0 3863.9 3915.2 3969.7 5594.4 5743.8 5753.9 5771.6 5782.6 3344.5 3940.5 3944.7 3948.7 3951.9 3840.3 4003.6 4028.4 4039.4 4049.8 3994.4 4297.6 4590.0 4603.2 4611.3 5003.9 5061.7 5301.5 5479.4 5493.9 1141.9 1181.8 1186.2 1264.8 1271.9 909.3 909.6 910.3 912.5 919.5 4152.6 4776.7 4902.2 4917.6 4936.7 4847.5 5412.0 5424.9 5452.3 5502.9 4606.7 4880.0 5287.9 5309.9 5313.4 2495.5 2639.6 2712.5 2817.8 2849.2 5213.0 5335.6 5466.9 5639.4 5736.8 4722.9 4802.0 5430.8 5521.6 5603.6 4874.9 5010.1 5661.9 5692.9 5699.6 4690.0 4965.4 5052.5 5063.5 5506.4 4699.8 5288.4 5336.9 5360.0 5425.8 4967.2 5309.5 5622.3 5651.9 5674.1 3496.4 3724.0 3752.4 3812.8 3865.5 5232.2 5683.3 5700.0 5702.4 5703.9 4498.2 5542.3 5553.8 5568.6 5571.4 5063.9 5348.9 5466.3 5526.2 5536.3 4639.9 5308.3 5316.5 5322.5 5382.4 4319.4 4465.6 4747.6 5366.0 5452.5 5281.6 5410.8 5470.5 5486.9 5509.4 20:27:09 INFO - PROCESS | 1632 | 20:27:09 INFO - PROCESS | 1632 | [#5] yui.html Cycles:180 Average:21684.17 Median:3630.12 stddev:33058.55 (910.7%) stddev-sans-first:33113.65 20:27:09 INFO - PROCESS | 1632 | Values: 674.4 709.5 731.9 753.3 803.9 14718.1 15593.8 15620.5 15632.1 15633.8 13953.9 14534.7 15303.5 15325.2 15609.5 804.6 835.5 839.9 842.7 844.3 660.4 682.5 687.3 811.0 839.2 879.1 892.5 894.6 895.4 896.5 843.3 844.7 845.3 881.6 898.2 510.7 526.2 667.3 671.1 671.7 11640.4 11890.7 11903.5 11907.2 14762.0 13422.6 13766.2 13951.0 13975.9 14116.6 11827.5 11862.0 11883.9 12114.9 12427.5 3626.4 3630.0 3630.2 3646.6 3795.3 116143.4 117632.2 118205.0 118443.0 120957.0 8304.4 9751.6 10338.5 10426.5 10774.9 21182.2 21327.0 21377.8 21599.1 21720.7 7426.0 7439.1 7534.1 7640.6 7646.7 3372.8 4038.3 4041.8 4113.9 4280.5 3325.2 3438.9 3464.4 3465.5 3475.2 633.5 637.7 645.3 648.0 649.8 416.2 532.4 567.3 607.9 668.5 8438.5 8907.2 9080.6 9085.0 9365.9 617.2 645.4 648.8 652.0 664.6 4368.0 4500.3 4508.4 4545.2 4635.7 116683.0 117008.0 117719.4 118065.0 121363.0 102717.0 103033.0 103306.5 103317.0 106718.5 72480.0 72527.6 76876.6 93361.0 96247.5 9365.3 9765.2 9789.9 9859.4 9878.2 47658.5 51670.7 53297.0 53930.7 54592.0 52335.7 64931.7 66705.7 76491.2 80172.2 12451.9 12494.0 12632.2 12782.4 13159.9 14389.6 14401.9 14431.3 14524.1 14688.4 11391.4 13166.5 13320.8 13433.5 13824.4 12768.2 12812.2 13374.7 13568.0 13712.3 13153.5 13301.6 13343.8 13551.1 14000.9 11065.8 11116.5 11407.7 11469.3 11691.4 10180.2 10499.3 11513.0 11513.5 11548.2 20:27:09 INFO - PROCESS | 1632 | -------- Summary: end -------- 20:27:09 INFO - PROCESS | 1632 | 20:27:10 INFO - TEST-INFO | 1632: exit 0 20:27:10 INFO - TEST-OK | dromaeo_css | took 1121262ms 20:27:10 INFO - TEST-START | kraken 20:27:10 INFO - operating with platform_type : mac_ 20:27:10 INFO - Initialising browser for kraken test... 20:27:10 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp5rP9Kz/profile http://localhost:49240/getInfo.html) 20:27:13 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 20:27:13 INFO - PROCESS | 1638 | colorDepth:24 20:27:13 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 20:27:13 INFO - PROCESS | 1638 | __metrics 20:27:13 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:27:13 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:27:14 INFO - TEST-INFO | 1638: exit 0 20:27:14 INFO - Browser initialized. 20:27:14 INFO - Running cycle 1/1 for kraken test... 20:27:14 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp5rP9Kz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:26 INFO - PROCESS | 1640 | RSS: Main: 244154368 20:27:26 INFO - PROCESS | 1640 | 20:27:29 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 20:27:30 INFO - PROCESS | 1640 | RSS: Main: 295620608 20:27:30 INFO - PROCESS | 1640 | 20:27:32 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 20:27:33 INFO - PROCESS | 1640 | RSS: Main: 296599552 20:27:33 INFO - PROCESS | 1640 | 20:27:36 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 20:27:37 INFO - PROCESS | 1640 | RSS: Main: 388014080 20:27:37 INFO - PROCESS | 1640 | 20:27:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 20:27:40 INFO - PROCESS | 1640 | RSS: Main: 289804288 20:27:40 INFO - PROCESS | 1640 | 20:27:41 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:27:42 INFO - PROCESS | 1640 | RSS: Main: 293289984 20:27:42 INFO - PROCESS | 1640 | 20:27:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 20:27:45 INFO - PROCESS | 1640 | RSS: Main: 335532032 20:27:45 INFO - PROCESS | 1640 | 20:27:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 20:27:49 INFO - PROCESS | 1640 | RSS: Main: 338817024 20:27:49 INFO - PROCESS | 1640 | 20:27:52 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 20:27:52 INFO - PROCESS | 1640 | RSS: Main: 336498688 20:27:52 INFO - PROCESS | 1640 | 20:27:54 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:27:55 INFO - PROCESS | 1640 | RSS: Main: 309780480 20:27:55 INFO - PROCESS | 1640 | 20:27:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:27:57 INFO - PROCESS | 1640 | RSS: Main: 313704448 20:27:57 INFO - PROCESS | 1640 | 20:27:59 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:00 INFO - PROCESS | 1640 | RSS: Main: 315473920 20:28:00 INFO - PROCESS | 1640 | 20:28:03 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:28:04 INFO - PROCESS | 1640 | RSS: Main: 333520896 20:28:04 INFO - PROCESS | 1640 | 20:28:07 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:28:07 INFO - PROCESS | 1640 | RSS: Main: 327323648 20:28:07 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 20:28:09 INFO - PROCESS | 1640 | RSS: Main: 371306496 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | __start_tp_report 20:28:09 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 20:28:09 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 20:28:09 INFO - PROCESS | 1640 | |i|pagename|runs| 20:28:09 INFO - PROCESS | 1640 | |0;ai-astar;114;114;115;113;115;114;115;114;115;113 20:28:09 INFO - PROCESS | 1640 | |1;audio-beat-detection;144;115;112;148;115;114;112;112;134;115 20:28:09 INFO - PROCESS | 1640 | |2;audio-dft;185;184;184;186;178;183;184;181;183;185 20:28:09 INFO - PROCESS | 1640 | |3;audio-fft;79;59;59;92;62;59;57;59;75;75 20:28:09 INFO - PROCESS | 1640 | |4;audio-oscillator;85;70;86;70;68;70;80;68;69;84 20:28:09 INFO - PROCESS | 1640 | |5;imaging-gaussian-blur;87;83;86;84;86;83;86;84;86;83 20:28:09 INFO - PROCESS | 1640 | |6;imaging-darkroom;92;93;96;92;93;93;93;92;93;93 20:28:09 INFO - PROCESS | 1640 | |7;imaging-desaturate;86;85;85;85;87;87;86;84;86;86 20:28:09 INFO - PROCESS | 1640 | |8;json-parse-financial;56;54;54;54;56;55;56;57;55;55 20:28:09 INFO - PROCESS | 1640 | |9;json-stringify-tinderbox;63;67;78;75;71;69;69;69;71;72 20:28:09 INFO - PROCESS | 1640 | |10;stanford-crypto-aes;88;89;88;87;86;92;91;88;89;89 20:28:09 INFO - PROCESS | 1640 | |11;stanford-crypto-ccm;156;157;153;147;149;152;152;142;218;153 20:28:09 INFO - PROCESS | 1640 | |12;stanford-crypto-pbkdf2;179;177;176;174;182;177;194;178;180;177 20:28:09 INFO - PROCESS | 1640 | |13;stanford-crypto-sha256-iterative;70;69;71;69;71;69;70;70;68;69 20:28:09 INFO - PROCESS | 1640 | __end_tp_report 20:28:09 INFO - PROCESS | 1640 | __start_cc_report 20:28:09 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1986 20:28:09 INFO - PROCESS | 1640 | __end_cc_report 20:28:09 INFO - PROCESS | 1640 | __startTimestamp1480480089877__endTimestamp 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | ------- Summary: start ------- 20:28:09 INFO - PROCESS | 1640 | Number of tests: 14 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 20:28:09 INFO - PROCESS | 1640 | Values: 114.0 114.0 115.0 113.0 115.0 114.0 115.0 114.0 115.0 113.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:115.00 stddev:14.20 (12.3%) stddev-sans-first:12.66 20:28:09 INFO - PROCESS | 1640 | Values: 144.0 115.0 112.0 148.0 115.0 114.0 112.0 112.0 134.0 115.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.31 (1.3%) stddev-sans-first:2.37 20:28:09 INFO - PROCESS | 1640 | Values: 185.0 184.0 184.0 186.0 178.0 183.0 184.0 181.0 183.0 185.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#3] audio-fft Cycles:10 Average:67.60 Median:68.50 stddev:11.90 (17.4%) stddev-sans-first:11.88 20:28:09 INFO - PROCESS | 1640 | Values: 79.0 59.0 59.0 92.0 62.0 59.0 57.0 59.0 75.0 75.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.00 stddev:7.72 (10.3%) stddev-sans-first:7.29 20:28:09 INFO - PROCESS | 1640 | Values: 85.0 70.0 86.0 70.0 68.0 70.0 80.0 68.0 69.0 84.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.42 20:28:09 INFO - PROCESS | 1640 | Values: 87.0 83.0 86.0 84.0 86.0 83.0 86.0 84.0 86.0 83.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:1.15 (1.2%) stddev-sans-first:1.17 20:28:09 INFO - PROCESS | 1640 | Values: 92.0 93.0 96.0 92.0 93.0 93.0 93.0 92.0 93.0 93.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 20:28:09 INFO - PROCESS | 1640 | Values: 86.0 85.0 85.0 85.0 87.0 87.0 86.0 84.0 86.0 86.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.03 (1.9%) stddev-sans-first:1.05 20:28:09 INFO - PROCESS | 1640 | Values: 56.0 54.0 54.0 54.0 56.0 55.0 56.0 57.0 55.0 55.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:4.14 (5.8%) stddev-sans-first:3.42 20:28:09 INFO - PROCESS | 1640 | Values: 63.0 67.0 78.0 75.0 71.0 69.0 69.0 69.0 71.0 72.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.00 stddev:1.77 (2.0%) stddev-sans-first:1.86 20:28:09 INFO - PROCESS | 1640 | Values: 88.0 89.0 88.0 87.0 86.0 92.0 91.0 88.0 89.0 89.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:153.00 stddev:21.56 (14.1%) stddev-sans-first:22.86 20:28:09 INFO - PROCESS | 1640 | Values: 156.0 157.0 153.0 147.0 149.0 152.0 152.0 142.0 218.0 153.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:178.50 stddev:5.58 (3.1%) stddev-sans-first:5.92 20:28:09 INFO - PROCESS | 1640 | Values: 179.0 177.0 176.0 174.0 182.0 177.0 194.0 178.0 180.0 177.0 20:28:09 INFO - PROCESS | 1640 | 20:28:09 INFO - PROCESS | 1640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 20:28:09 INFO - PROCESS | 1640 | Values: 70.0 69.0 71.0 69.0 71.0 69.0 70.0 70.0 68.0 69.0 20:28:09 INFO - PROCESS | 1640 | -------- Summary: end -------- 20:28:09 INFO - PROCESS | 1640 | 20:28:10 INFO - TEST-INFO | 1640: exit 0 20:28:10 INFO - TEST-OK | kraken | took 60652ms 20:28:10 INFO - SUITE-END | took 1181s 20:28:11 INFO - Completed test suite (00:19:41) 20:28:11 INFO - javascript benchmark 20:28:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.652543432071, 3320.850561724722, 3328.467445208769, 3359.8320083995795, 3382.4757162639785, 5528.75120619572, 5558.138488534279, 5612.803551875685, 5642.0, 5682.5738069644785, 5590.18942253374, 5632.549396048316, 5644.491995720385, 5644.6330988485715, 5644.971775141129, 5106.183010718295, 5126.282320475137, 5153.716545589991, 5165.948340516603, 5186.533212010918, 5172.767225474844, 5200.76596553156, 5248.422673505911, 5249.501297376743, 5254.395744489375, 5159.690418574879, 5204.1413166827615, 5214.243934629477, 5224.764885580158, 5255.290535777678, 5244.423113457517, 5258.737063146866, 5260.342457192851, 5286.629935904488, 5311.0, 3944.4280579316123, 4996.025774973881, 5046.167382381939, 5177.559907407875, 5180.3265575475325, 5518.227448157261, 5557.277553918006, 5577.107662773937, 5627.409122042209, 5633.60564760467, 6109.480694141003, 6122.846928826771, 6130.785422510236, 6133.049377346519, 6136.417040381157, 6097.451229389332, 6139.369480960597, 6182.443580077769, 6191.288001879797, 6203.689815509251, 5656.8868622627315, 5717.2281741965135, 5723.45627165427, 5723.627964182315, 5738.0532212184935, 6207.255129384503, 6264.342244064308, 6268.937310626927, 6280.7487700491465, 6282.968585157045, 6013.609115407485, 6014.9699251504335, 6042.697865106815, 6078.75684972605, 6117.816465506042, 6034.758609655564, 6101.908471372933, 6192.752289908353, 6193.938060619426, 6199.163112979781, 6083.847903802441, 6107.786227482017, 6137.846553836101, 6151.815445536641, 6174.043023331391, 6110.4500594945775, 6146.323904370515, 6171.876562468726, 6185.907211391833, 6194.256689197326, 4801.231802911518, 4898.534639209341, 4906.386701662293, 4916.508349165055, 4933.407991041026, 4309.741415515079, 4360.0, 4377.5403582624, 4393.802278897458, 4394.0, 5689.601727879091, 5740.913886291709, 5745.2818397700285, 5749.770009199585, 5772.740226689725, 6234.532410069172, 6255.812325630239, 6261.342559031327, 6265.216847894013, 6266.216722909637, 5583.330000399978, 5583.497485226349, 5636.070048441961, 5688.0, 5699.829005129853, 4554.544545545486, 5247.344081989751, 5300.443453437333, 5305.575553955751, 5340.225667278223, 6264.185655864709, 6273.247210334789, 6282.183316168869, 6286.5599408041, 6286.622802631765, 6102.694865256631, 6180.66006369636, 6221.720022599085, 6224.37756224374, 6245.063240513778, 6204.3795620437595, 6230.968845155745, 6259.561830671809, 6268.028455589391, 6285.308616052322, 6113.233070740737, 6133.51829027926, 6138.894998900241, 6139.553468857771, 6151.569390142647, 6073.0283154695035, 6180.289266734383, 6185.907211391923, 6196.1015652731, 6204.255489341308, 5827.854303642443, 5862.765489380377, 5897.174395584536, 5903.557233207613, 5999.100134979613, 5936.7922122725495, 5940.851478713067, 5945.821625351246, 5955.166276721176, 5963.761449541969, 5810.070388737781, 5819.330776960704, 5821.825345239649, 5831.854203644943, 5833.82498525045, 5891.351951285269, 5940.554458415722, 5953.76184952597, 5954.166416701752, 5959.22530071088, 5770.769169233184, 5781.988152073454, 5786.942130578809, 5787.797427090031, 5794.478496935296], "value": 5622.57921828793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.8365204874197, 462.7154300105435, 528.5454509122156, 535.0529562674067, 546.2079984023165, 29379.0, 29834.850825745896, 30952.845235773846, 38233.42649860247, 39729.60270397295, 40014.79992600033, 40357.19285614286, 40419.39370909439, 40428.393574096415, 40574.0, 8958.23854972328, 9138.223251023672, 9142.314326425512, 9147.496887671176, 9167.220786233145, 8212.712555060574, 8425.915740842574, 9198.494082825442, 10926.617568385109, 11210.383428911407, 11076.335419874833, 11145.275557088764, 11186.608468703596, 11209.383483908381, 11266.211365204439, 11096.667099987013, 11178.27341222818, 11182.217244792868, 11197.496112674868, 11202.551897924073, 8135.186481351877, 8379.0, 10749.785004299949, 11053.557857685682, 11070.723231919186, 37325.626743732486, 38348.0, 38633.42049869254, 38701.03247418809, 38722.0319492015, 3263.0, 3263.216827961296, 3338.5659864217496, 3417.6411476795074, 3575.4458059000895, 28463.85768071167, 29073.85463072671, 29660.851695741385, 29706.70293297061, 29857.552136717968, 3128.405602935458, 3233.337165880989, 3281.1633033576286, 3303.355845610107, 3317.767756257063, 338137.0, 436343.0, 439294.80352598353, 444773.0, 445993.0, 59247.40752592505, 59439.0, 59461.0, 59545.404545953985, 59781.701091494266, 61904.0, 61963.38036619666, 62004.37995619986, 62158.37841621616, 62191.0, 15070.246487675573, 15189.240537973057, 15410.0, 15702.371905123895, 15729.842701572838, 10732.0, 12093.879061209274, 12162.817557736642, 12185.45165467556, 12208.389580520938, 14931.552053438414, 15368.233717930656, 16653.916730416513, 17255.74116388255, 17327.527160191272, 5789.305283366023, 6383.776567820197, 6422.678866056678, 6461.967690161519, 6480.351964803482, 882.7440042387635, 982.597135174575, 1004.829179039565, 1010.2069875147973, 1016.3901659004686, 29029.0, 29425.705742942297, 29512.795983008185, 29531.0, 29871.220604703143, 1012.0790081026378, 1017.4658304390255, 1024.8411496218098, 1121.097516499226, 1121.513993958009, 14757.92621036888, 15130.621734456727, 15239.847601523843, 15560.455384061503, 15589.84410155884, 596808.0, 600254.0, 601395.0, 602814.9859250676, 604993.0, 49187.75406122946, 56282.71858640681, 56340.15489767657, 56456.0, 56498.71750641303, 1298.1951190262098, 1346.6633341664583, 1358.7282543491342, 1362.9931850340686, 1367.7538043152329, 26446.94212231566, 28403.43193136167, 29063.56404653974, 29555.261118472212, 29709.554356684235, 44843.55156448524, 52902.941941161735, 54225.644358891346, 54324.45675543194, 57619.71190144023, 585567.0, 588616.0, 589204.0, 590350.0, 590921.9078092156, 25606.231813045637, 25949.481010380063, 25993.0, 26076.34809129787, 26148.60777088307, 44621.33068003917, 45271.09457810891, 46399.53600463952, 46735.53264467448, 46887.531124688314, 36884.26231475302, 37230.8138459306, 37344.71778003263, 38258.85223443301, 38308.616913830505, 37152.25695486021, 37245.813770930974, 37637.435438468994, 38414.23171536609, 38460.61539384571, 36949.26101477902, 37520.62479375171, 37774.0, 37822.243555129295, 37899.67351142697, 31844.8407757969, 33052.83473582617, 33229.66770332266, 33679.15802104967, 33976.0, 19431.4170574883, 22585.661215082117, 23358.883205583865, 24587.631185532588, 24927.626085608368], "value": 19423.46150036481, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.83557727867924, 366.56012784658407, 369.7541135145129, 382.6345839723064, 398.8723608445297, 14462.638434039154, 14765.630859228511, 15409.229538523085, 19239.42281731547, 20323.0, 15539.766903496457, 19167.712484312713, 19217.84692918426, 20300.289489867857, 20530.0, 5427.1316589345715, 5700.857478563028, 5705.229793977802, 5718.313802343725, 5724.398938111501, 4992.850214493571, 5548.334199896018, 6366.267879193906, 6439.774607888725, 6494.090827284161, 6196.566240363177, 6402.551821372505, 6456.451201647842, 6467.320931302216, 6486.286508484063, 6227.937720622804, 6249.593776404519, 6303.243610766692, 6366.681665916685, 6385.201849768802, 5302.522772950414, 5513.255710479114, 5532.197831314477, 5544.417836127229, 5558.666480011212, 22145.778542214695, 23687.526249474915, 25129.62305565418, 25165.62251566228, 25433.364165895815, 2308.134449581407, 2353.8940747666397, 2363.692719946413, 2383.761623837602, 2963.7996611372428, 10132.34139780912, 10349.120324772404, 12531.18547294432, 14357.712845743026, 15265.23673816316, 1820.1809185866414, 1822.7903791064066, 1896.6017136401372, 1901.4295711286559, 1957.0214892553722, 108647.28381790519, 129540.7045929538, 129709.0, 130076.34961825225, 131879.0, 9108.908910910806, 11158.77253502114, 11487.0, 11501.13741469393, 11665.24175928579, 14496.492622758153, 14518.564443066725, 14546.854531454761, 14547.19990400517, 14561.92719036398, 6898.344657257552, 8104.351651867836, 8292.875606865902, 8344.457610255315, 8449.239568438747, 6654.633995130217, 8099.0, 8121.106678265386, 8313.709020184386, 8327.458715183493, 4301.6773741968855, 4401.955980440219, 4513.977430112894, 4522.0, 4590.609798167193, 1760.6390689908794, 1792.2139771308237, 1808.8462480689288, 1859.414284500378, 1917.453525745184, 781.3631890009624, 782.9373650107977, 805.6213579617688, 836.2139588786521, 896.4800415758845, 18422.986735729664, 20075.096620652104, 20325.0, 20346.084426200858, 20613.690794638092, 866.7183165471121, 881.0924747510074, 881.110078820396, 935.0135606934613, 936.489613160829, 8767.210951014311, 9891.802163956681, 10553.894461055444, 10630.840537391945, 10672.491964388362, 522933.0, 575218.0, 583906.0, 586732.0663396656, 594109.0, 130342.44863270585, 135356.0, 138803.0, 138845.0, 139403.60596394108, 927.3879239701764, 933.4772527384687, 979.7991411760572, 985.4530735441898, 994.6121012805012, 18376.0, 18644.0, 18863.0, 19011.270082328017, 19198.8080119197, 52428.0, 53516.92966140733, 53703.46296536984, 54329.64175895634, 54504.18243726268, 72803.63598181975, 73085.6345718268, 76434.0, 79258.6037069811, 80005.19994799978, 7090.290970903075, 7422.0, 7866.331361834308, 7963.800904977422, 8476.23713865755, 11242.381669008282, 12914.67713307175, 12970.35148242603, 13436.462541498231, 13483.325833708079, 10491.895081049091, 11089.33463992163, 11103.50034248477, 11516.30902145874, 11687.240329378563, 8798.0, 9957.402555846673, 11144.33134011962, 11577.0, 11583.478743456397, 11227.27022743519, 11239.60661376848, 11808.527658893892, 11867.58463453775, 11898.940505297418, 7321.414286857145, 7511.586862722603, 7525.0, 7680.500767450098, 7794.649240784065, 9741.56162972682, 10284.382937023802, 10800.891991080303, 11194.720131996764, 11249.606263780728], "value": 9441.65093201173, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.3457719274575, 1328.4951718347027, 1364.7338768940053, 1743.7733094697687, 1842.8986405747687, 7969.721059762902, 8367.205115514033, 8372.0, 8379.748607541784, 8405.285550728187, 7226.168990566087, 7536.81157971051, 7537.886931696029, 7633.320669452725, 7640.923590764105, 2080.8855512946743, 2116.0160525355705, 2224.065892325219, 2301.5742087713743, 2307.0886999635136, 2239.5296987632537, 2243.674667173259, 2252.5044490212126, 2253.752087270399, 2255.672927425522, 2263.649134384165, 2285.165914441234, 2308.1690591387087, 2310.5609934112463, 2316.2124877541632, 2274.510980139278, 2276.4195130241847, 2293.977060229393, 2295.2081531871477, 2296.7473577906417, 2280.407094155515, 2289.519200967799, 2309.429587880241, 2312.456572705413, 2319.512902290521, 8178.509289442593, 8230.465019773696, 8290.5854707265, 8292.668293268262, 8314.168583141638, 4937.604991600663, 5110.054639891632, 5113.565346945514, 5118.846434606968, 5126.61550383719, 3377.3414184234084, 3419.350323438539, 3444.621091679913, 3451.620321764604, 3550.094725844893, 1000.489750227382, 1040.0, 1052.36857885269, 1067.3009178987697, 1076.7523469602036, 10648.840267395995, 10729.302595331357, 10741.570337186504, 10741.946290268499, 10787.029167374896, 4384.583464570861, 4536.56902594253, 4631.3516107744945, 4635.490096089427, 4695.084458530622, 9365.953170234105, 9377.43735375866, 9386.436813791195, 9399.10708482693, 9401.90598094024, 5165.199394093921, 5167.793288268427, 5168.53483186515, 5172.844814655567, 5174.767135478913, 4494.415725955605, 4639.907201855944, 4706.411698537683, 4733.9763301183275, 4738.47876733559, 3457.291255292659, 3570.125319296737, 3575.3385623659706, 3665.9450108248398, 3687.6681098701247, 1241.0629974369474, 1245.7072587941827, 1252.292454763056, 1261.6908857330013, 1262.9621111366673, 705.9047028651066, 769.5651956644477, 791.1060501633118, 864.6368525219423, 868.9261412779858, 4745.193317136095, 5282.366116066097, 5499.707568721347, 5547.278853748987, 5602.38373778884, 821.3511326052471, 913.9177474027236, 923.7136487688838, 953.6137864165024, 963.6819849449665, 3580.3018411409525, 3690.760100593453, 3796.240751849641, 3845.5000849889334, 3858.344081506151, 9593.616255349847, 9769.120779129771, 9980.80038399228, 10124.949375253076, 10138.898611013796, 9790.902090979, 10052.296339256181, 10064.74838129038, 10078.848817267746, 10089.09198172168, 7399.48203625741, 7779.183185765526, 7865.331446827084, 7881.1724768899585, 7896.60516974149, 5360.0, 5436.320459942507, 5468.972655136698, 5564.749586268708, 5677.148427735708, 9080.500572468314, 9233.861492077758, 9291.256699463891, 9328.580213890527, 9411.247100232102, 9430.3398762086, 9484.715458536255, 9487.762805929908, 9534.761630959281, 9608.471534065693, 6968.0941477607585, 7056.788296351117, 7157.212706602166, 7172.533785303938, 7176.067111275501, 7375.557466552024, 7654.234576542524, 7721.5753133575945, 7727.034120734908, 7837.7648670539975, 6874.518783685094, 7007.474439416962, 7014.228434872262, 7085.893711594227, 7186.209516953027, 6157.72290246931, 6744.7976560703255, 6935.202451718117, 7014.964925175342, 7016.052832867702, 6379.3301703320485, 6579.934200658124, 6582.3746744059235, 6588.308227636029, 6592.373724496165, 4582.908341833078, 4779.737114458738, 4796.736179510161, 4887.36464259644, 4966.180580204225, 3968.0, 4065.9796701016307, 4791.5687588117235, 4830.758462076952, 4971.726555039508], "value": 4337.171397344859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.13589135073687, 302.88036225690854, 313.3716897620272, 317.1452934341949, 317.3240996677079, 5032.263868965201, 5191.766370513326, 5198.4801519848, 5255.947440525593, 5279.577633789298, 4420.778961051942, 5106.66806657567, 5170.612204084689, 5177.5599074078655, 5285.365756109272, 3242.2866969266724, 3994.820233089518, 4024.839006439739, 4044.9191016179657, 4046.1907618476275, 3311.3608763662023, 3527.4532447470683, 3596.7662101963288, 3799.25914446683, 4048.9835072870605, 3170.6512283648894, 3889.202713443737, 4001.9599804002023, 4024.3158663027357, 4061.5938406159444, 3536.8778732760265, 3580.9283814323567, 3738.15899916063, 3809.847606095753, 4003.2393845169327, 3231.83840807961, 3528.641473032875, 3534.540509733718, 3539.592946811124, 3545.2200515886466, 4153.06556024895, 4847.418309802811, 4947.575687912933, 5135.589152867761, 5169.819056333048, 4147.6474499667265, 4292.957070429287, 4629.675922685414, 4630.236011058171, 4633.652476064309, 4979.311171967133, 5162.354705661793, 5419.783208671648, 5515.393306736256, 5522.447755224485, 3267.9346413071844, 3280.950785738216, 3863.9034024149623, 3915.197384536163, 3969.7221194516396, 5594.384617692051, 5743.770249190027, 5753.884922301614, 5771.595988280864, 5782.624129431616, 3344.5484859544135, 3940.4877365942243, 3944.684425245973, 3948.664363529132, 3951.940720889189, 3840.347140985983, 4003.5996400360314, 4028.436018957348, 4039.3537034074993, 4049.817758200888, 3994.420808982681, 4297.5702429756775, 4589.990202155521, 4603.194440972817, 4611.331356953223, 5003.924941125886, 5061.7469126544265, 5301.5493683037375, 5479.397266300703, 5493.917591236134, 1141.8743938166795, 1181.7636472705494, 1186.1732931285896, 1264.7533730922382, 1271.9300438475786, 909.3180114913814, 909.5815924674723, 910.3491003932088, 912.4525284829182, 919.5402298850574, 4152.584741525823, 4776.737279449594, 4902.2156454967035, 4917.606591472673, 4936.728479933567, 4847.467617777389, 5411.9729401353525, 5424.945750542523, 5452.291202143696, 5502.881390664543, 4606.700564463299, 4879.950810575743, 5287.894242115213, 5309.8938021239355, 5313.362396512366, 2495.513374891879, 2639.6304517367585, 2712.5253080710795, 2817.802753807214, 2849.190763864919, 5212.973935130376, 5335.6394119500055, 5466.945330546723, 5639.43605639441, 5736.77052917887, 4722.881927951828, 4802.031167221422, 5430.782768689208, 5521.585881058937, 5603.592533205477, 4874.90250194987, 5010.146245888972, 5661.943380566224, 5692.857678558069, 5699.575106223444, 4690.03854209893, 4965.4041515018425, 5052.509509694575, 5063.518965698253, 5506.366767821752, 4699.812007519661, 5288.3918349390315, 5336.905934283385, 5359.996803580016, 5425.782968681367, 4967.218357719688, 5309.522143007148, 5622.297212848394, 5651.886962260814, 5674.120511320752, 3496.3706532823317, 3723.9759066256997, 3752.399616061417, 3812.8284227209288, 3865.458835763052, 5232.241325007929, 5683.318001839806, 5700.030994730822, 5702.401247868914, 5703.914441283467, 4498.190325741398, 5542.251796007648, 5553.8333849984565, 5568.638038527482, 5571.4428557143965, 5063.898722025465, 5348.903474787581, 5466.34403871522, 5526.22632831412, 5536.280283563111, 4639.886427257501, 5308.309919710413, 5316.548093412103, 5322.520973112439, 5382.393796156663, 4319.395284660087, 4465.6204222640135, 4747.596454301423, 5366.0, 5452.500562345387, 5281.618518904274, 5410.83767486976, 5470.452954704498, 5486.91769623448, 5509.421510741315], "value": 3965.15031205862, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.3627272227745, 709.5033476566405, 731.8729157098069, 753.3143845282342, 803.931665808406, 14718.116912985228, 15593.766093508606, 15620.453284135043, 15632.062076275417, 15633.843661563382, 13953.860461395383, 14534.709305813903, 15303.540893773177, 15325.233738313096, 15609.531714048568, 804.6298702596813, 835.4569529805644, 839.8782176584392, 842.7176895739934, 844.2907957315854, 660.3627499463017, 682.4608559238196, 687.3092541995313, 810.9878351824727, 839.2111415269658, 879.1120967822482, 892.5001998880648, 894.6108442827391, 895.3777124898198, 896.5158814240302, 843.3379796859466, 844.6916875340518, 845.3406343052399, 881.625309243569, 898.2454738020094, 510.6884800271831, 526.1555203897766, 667.349334398962, 671.1174805131237, 671.6540981394586, 11640.417979101012, 11890.702732431671, 11903.523859045628, 11907.226030308002, 14762.0, 13422.597322080353, 13766.174029558159, 13951.0, 13975.860241397557, 14116.576502704935, 11827.467763950643, 11862.0, 11883.881161188365, 12114.939425302906, 12427.502899883993, 3626.4016437287814, 3630.0, 3630.2376500934834, 3646.6353364663587, 3795.3168429682646, 116143.4192829039, 117632.23551646646, 118205.0, 118443.0, 120957.0, 8304.418690691593, 9751.561179746928, 10338.483075846329, 10426.478676066317, 10774.892251077388, 21182.15271389158, 21327.0, 21377.78622213758, 21599.136034558756, 21720.674189887166, 7425.9628701856145, 7439.070116235471, 7534.0582427196605, 7640.617969101522, 7646.694132234648, 3372.780769249991, 4038.3288920647633, 4041.8492354466553, 4113.91772164561, 4280.4863416390235, 3325.2351959049556, 3438.8796392126656, 3464.3937310970477, 3465.5321531592913, 3475.218075932895, 633.5248563577317, 637.744902039188, 645.2966266769245, 648.0441349010266, 649.7855707616475, 416.21958827199, 532.3930718980365, 567.3447168520365, 607.8966675484071, 668.5053060735119, 8438.451500652438, 8907.242884354902, 9080.636774528944, 9085.0, 9365.859512107323, 617.1606615003592, 645.3578689204272, 648.7525132969567, 651.9967400162968, 664.5846346033729, 4367.978160109179, 4500.257457519538, 4508.436445444319, 4545.227311357077, 4635.7450340230935, 116683.0, 117008.0, 117719.41140294244, 118065.0, 121363.0, 102717.0, 103033.0, 103306.48346758369, 103317.0, 106718.46640766902, 72480.0, 72527.63736181286, 76876.61561692155, 93361.0, 96247.51876240574, 9365.344425890395, 9765.246974549425, 9789.85315220258, 9859.408435493893, 9878.209743220381, 47658.523414766794, 51670.70823229449, 53297.0, 53930.73034634802, 54591.98976225224, 52335.69160771011, 64931.67534162299, 66705.66647166928, 76491.23508764841, 80172.19827801647, 12451.875481245072, 12494.0, 12632.2420654761, 12782.424790884246, 13159.868401315864, 14389.64025899319, 14401.927990359982, 14431.29928400001, 14524.128552286897, 14688.412463501767, 11391.430428478709, 13166.539171128965, 13320.800187996994, 13433.462661493822, 13824.37790299459, 12768.233905965672, 12812.231266124063, 13374.732505350034, 13568.0, 13712.314384280546, 13153.539626113423, 13301.600951971457, 13343.799843002556, 13551.119177253448, 14000.929995349958, 11065.834012489659, 11116.544091966107, 11407.657770266906, 11469.31184128955, 11691.36944187551, 10180.16008723037, 10499.317544359592, 11513.0, 11513.53945842157, 11548.249363791327], "value": 7370.60316662071, "unit": "score"}], "name": "dromaeo_css", "value": 7123.957214415471, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 115.0, 114.0, 115.0, 114.0, 115.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 112.0, 148.0, 115.0, 114.0, 112.0, 112.0, 134.0, 115.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 186.0, 178.0, 183.0, 184.0, 181.0, 183.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 92.0, 62.0, 59.0, 57.0, 59.0, 75.0, 75.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 86.0, 70.0, 68.0, 70.0, 80.0, 68.0, 69.0, 84.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 96.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 87.0, 87.0, 86.0, 84.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 56.0, 55.0, 56.0, 57.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 78.0, 75.0, 71.0, 69.0, 69.0, 69.0, 71.0, 72.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 87.0, 86.0, 92.0, 91.0, 88.0, 89.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 153.0, 147.0, 149.0, 152.0, 152.0, 142.0, 218.0, 153.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 176.0, 174.0, 182.0, 177.0, 194.0, 178.0, 180.0, 177.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 69.0, 71.0, 69.0, 70.0, 70.0, 68.0, 69.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1446.9, "alertThreshold": 2.0}]} 20:28:11 INFO - Return code: 0 20:28:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:28:11 INFO - # TBPL SUCCESS # 20:28:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:28:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:28:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:28:11 INFO - 2016-11-29 20:28:11.198 screenresolution[1648:10045] starting screenresolution argv=screenresolution get 20:28:11 INFO - 2016-11-29 20:28:11.201 screenresolution[1648:10045] Display 0: 1600x1200x32@60 20:28:11 INFO - 2016-11-29 20:28:11.207 screenresolution[1649:10047] starting screenresolution argv=screenresolution list 20:28:11 INFO - Available Modes on Display 0 20:28:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:28:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:28:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:28:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:28:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:28:11 INFO - Intel Iris: 20:28:11 INFO - Chipset Model: Intel Iris 20:28:11 INFO - Type: GPU 20:28:11 INFO - Bus: Built-In 20:28:11 INFO - VRAM (Dynamic, Max): 1536 MB 20:28:11 INFO - Vendor: Intel (0x8086) 20:28:11 INFO - Device ID: 0x0a2e 20:28:11 INFO - Revision ID: 0x0009 20:28:11 INFO - Displays: 20:28:11 INFO - Display: 20:28:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:28:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:28:11 INFO - Display Serial Number: 1600x1200 60 20:28:11 INFO - Main Display: Yes 20:28:11 INFO - Mirror: Off 20:28:11 INFO - Online: Yes 20:28:11 INFO - Rotation: Supported 20:28:11 INFO - Return code: 0 20:28:11 INFO - Running post-action listener: _resource_record_post_action 20:28:11 INFO - [mozharness: 2016-11-30 04:28:11.453232Z] Finished run-tests step (success) 20:28:11 INFO - Running post-run listener: _resource_record_post_run 20:28:11 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 38839296; Write bytes: 695172608; Read time: 384; Write time: 2036 20:28:11 INFO - TinderboxPrint: CPU usage
53.5% 20:28:11 INFO - TinderboxPrint: I/O read bytes / time
38,839,296 / 384 20:28:11 INFO - TinderboxPrint: I/O write bytes / time
695,172,608 / 2,036 20:28:11 INFO - TinderboxPrint: CPU idle
2,259.3 (46.5%) 20:28:11 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 20:28:11 INFO - TinderboxPrint: CPU user
1,631.9 (33.6%) 20:28:11 INFO - TinderboxPrint: Swap in / out
422,051,840 / 0 20:28:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 365036032; Write bytes: 359015936; Read time: 27538; Write time: 798 20:28:11 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 37848064; Write bytes: 309135360; Read time: 367; Write time: 1176 20:28:11 INFO - Running post-run listener: _upload_blobber_files 20:28:11 INFO - Blob upload gear active. 20:28:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:28:11 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 20:28:11 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'] 20:28:11 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 20:28:12 INFO - (blobuploader) - INFO - Open directory for files ... 20:28:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:28:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:28:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:13 INFO - (blobuploader) - INFO - Done attempting. 20:28:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:28:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:28:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:14 INFO - (blobuploader) - INFO - Done attempting. 20:28:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:28:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:28:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:16 INFO - (blobuploader) - INFO - Done attempting. 20:28:16 INFO - (blobuploader) - INFO - Iteration through files over. 20:28:16 INFO - Return code: 0 20:28:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:28:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:28:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6fd8a77c344728e802b50ee8c7cea2ccf5b8057480aa3572acc6d48d7045456e97f8157583488b438be886e1bc721ce8a70ea849a7ff22b85daa20b869d0a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/790f7f3f9f4fe06d2f80fa4669962fbce94ce16c8ab219cd1d5e0f13f9857c62498dbf08f88cc3140c7538f37ede62418c925c84c1ee300a05d643f87ab96934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/747c798e2a3db484716c7c42398dcd8242a5d8c6c259bc6b016bda863acc77b71fd4f32344b06c33e5c91a8015eb090ae9372fefa14e7c2e485895be2f2d9928"} 20:28:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:28:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:28:16 INFO - Contents: 20:28:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6fd8a77c344728e802b50ee8c7cea2ccf5b8057480aa3572acc6d48d7045456e97f8157583488b438be886e1bc721ce8a70ea849a7ff22b85daa20b869d0a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/790f7f3f9f4fe06d2f80fa4669962fbce94ce16c8ab219cd1d5e0f13f9857c62498dbf08f88cc3140c7538f37ede62418c925c84c1ee300a05d643f87ab96934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/747c798e2a3db484716c7c42398dcd8242a5d8c6c259bc6b016bda863acc77b71fd4f32344b06c33e5c91a8015eb090ae9372fefa14e7c2e485895be2f2d9928"} 20:28:16 INFO - Running post-run listener: copy_logs_to_upload_dir 20:28:16 INFO - Copying logs to upload dir... 20:28:16 INFO - mkdir: /builds/slave/test/build/upload/logs 20:28:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.787294 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-29 20:28:16.969846) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:28:16.973368) ========= 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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6fd8a77c344728e802b50ee8c7cea2ccf5b8057480aa3572acc6d48d7045456e97f8157583488b438be886e1bc721ce8a70ea849a7ff22b85daa20b869d0a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/790f7f3f9f4fe06d2f80fa4669962fbce94ce16c8ab219cd1d5e0f13f9857c62498dbf08f88cc3140c7538f37ede62418c925c84c1ee300a05d643f87ab96934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/747c798e2a3db484716c7c42398dcd8242a5d8c6c259bc6b016bda863acc77b71fd4f32344b06c33e5c91a8015eb090ae9372fefa14e7c2e485895be2f2d9928"} build_url:https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011131 build_url: 'https://queue.taskcluster.net/v1/task/HrJByPdgRUG-3XehV70Lqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6fd8a77c344728e802b50ee8c7cea2ccf5b8057480aa3572acc6d48d7045456e97f8157583488b438be886e1bc721ce8a70ea849a7ff22b85daa20b869d0a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/790f7f3f9f4fe06d2f80fa4669962fbce94ce16c8ab219cd1d5e0f13f9857c62498dbf08f88cc3140c7538f37ede62418c925c84c1ee300a05d643f87ab96934", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/747c798e2a3db484716c7c42398dcd8242a5d8c6c259bc6b016bda863acc77b71fd4f32344b06c33e5c91a8015eb090ae9372fefa14e7c2e485895be2f2d9928"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:28:17.008166) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:28:17.008489) ========= 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.ZAZ6pXp2Gs/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.uNxurQFXSh/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005043 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:28:17.056814) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 20:28:17.057139) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 20:28:17.057547) ========= ========= Total master_lag: 0.31 =========