builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1480515346.94 results: success (0) buildid: 20161130030206 builduid: d192b762b9f94a39ac57046b273479a1 revision: 13736e2db6eb94b02dd28cc88f2943b8109aa374 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:46.940805) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:46.941280) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:46.941631) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:47.266081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:47.266565) ========= 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.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-30 06:15:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.3M=0s 2016-11-30 06:15:47 (38.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.469997 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:47.790380) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:47.790763) ========= 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.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.026370 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:47.872589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-30 06:15:47.873003) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13736e2db6eb94b02dd28cc88f2943b8109aa374 --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 13736e2db6eb94b02dd28cc88f2943b8109aa374 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-30 06:15:47,979 truncating revision to first 12 chars 2016-11-30 06:15:47,979 Setting DEBUG logging. 2016-11-30 06:15:47,980 attempt 1/10 2016-11-30 06:15:47,980 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13736e2db6eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 06:15:49,654 unpacking tar archive at: mozilla-central-13736e2db6eb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.635518 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-30 06:15:50.541656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:50.542022) ========= script_repo_revision: 13736e2db6eb94b02dd28cc88f2943b8109aa374 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:50.542462) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:50.542766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 06:15:50.568137) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 06:15:50.568509) ========= /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.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:15:50 INFO - MultiFileLogger online at 20161130 06:15:50 in /builds/slave/test 06:15:50 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 06:15:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:15:50 INFO - {'append_to_log': False, 06:15:50 INFO - 'base_work_dir': '/builds/slave/test', 06:15:50 INFO - 'blob_upload_branch': 'Firefox', 06:15:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:15:50 INFO - 'branch': 'Firefox', 06:15:50 INFO - 'buildbot_json_path': 'buildprops.json', 06:15:50 INFO - 'config_files': ('talos/mac_config.py',), 06:15:50 INFO - 'default_actions': ('clobber', 06:15:50 INFO - 'read-buildbot-config', 06:15:50 INFO - 'download-and-extract', 06:15:50 INFO - 'populate-webroot', 06:15:50 INFO - 'create-virtualenv', 06:15:50 INFO - 'install', 06:15:50 INFO - 'run-tests'), 06:15:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:15:50 INFO - 'download_minidump_stackwalk': True, 06:15:50 INFO - 'download_symbols': 'ondemand', 06:15:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:15:50 INFO - 'tooltool.py': '/tools/tooltool.py', 06:15:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:15:50 INFO - '/tools/misc-python/virtualenv.py')}, 06:15:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:15:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:15:50 INFO - 'installer_path': 'installer.exe', 06:15:50 INFO - 'log_level': 'info', 06:15:50 INFO - 'log_name': 'talos', 06:15:50 INFO - 'log_to_console': True, 06:15:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:15:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:15:50 INFO - 'opt_config_files': (), 06:15:50 INFO - 'pip_index': False, 06:15:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:15:50 INFO - 'cmd': ('bash', 06:15:50 INFO - '-c', 06:15:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:15:50 INFO - 'enabled': True, 06:15:50 INFO - 'halt_on_failure': False, 06:15:50 INFO - 'name': 'check_screen_resolution'},), 06:15:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:15:50 INFO - 'cmd': ('bash', 06:15:50 INFO - '-c', 06:15:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:15:50 INFO - 'enabled': True, 06:15:50 INFO - 'halt_on_failure': False, 06:15:50 INFO - 'name': 'check_screen_resolution'},), 06:15:50 INFO - 'run_cmd_checks_enabled': True, 06:15:50 INFO - 'sps_profile': False, 06:15:50 INFO - 'sps_profile_interval': 0, 06:15:50 INFO - 'suite': 'dromaeojs', 06:15:50 INFO - 'system_bits': '32', 06:15:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:15:50 INFO - 'title': 't-yosemite-r7-0029', 06:15:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:15:50 INFO - 'use_talos_json': True, 06:15:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:15:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:15:50 INFO - 'work_dir': 'build'} 06:15:50 INFO - [mozharness: 2016-11-30 14:15:50.786244Z] Running clobber step. 06:15:50 INFO - Running pre-action listener: _resource_record_pre_action 06:15:50 INFO - Running main action method: clobber 06:15:50 INFO - rmtree: /builds/slave/test/build 06:15:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:15:53 INFO - Running post-action listener: _resource_record_post_action 06:15:53 INFO - [mozharness: 2016-11-30 14:15:53.207960Z] Finished clobber step (success) 06:15:53 INFO - [mozharness: 2016-11-30 14:15:53.208083Z] Running read-buildbot-config step. 06:15:53 INFO - Running pre-action listener: _resource_record_pre_action 06:15:53 INFO - Running main action method: read_buildbot_config 06:15:53 INFO - Using buildbot properties: 06:15:53 INFO - { 06:15:53 INFO - "project": "", 06:15:53 INFO - "product": "firefox", 06:15:53 INFO - "script_repo_revision": "production", 06:15:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:15:53 INFO - "repository": "", 06:15:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:15:53 INFO - "buildid": "20161130030206", 06:15:53 INFO - "stage_platform": "macosx64", 06:15:53 INFO - "basedir": "/builds/slave/test", 06:15:53 INFO - "nightly_build": "True", 06:15:53 INFO - "buildnumber": 6, 06:15:53 INFO - "slavename": "t-yosemite-r7-0029", 06:15:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:15:53 INFO - "platform": "yosemite_r7", 06:15:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:15:53 INFO - "branch": "mozilla-central", 06:15:53 INFO - "repo_path": "mozilla-central", 06:15:53 INFO - "revision": "13736e2db6eb94b02dd28cc88f2943b8109aa374", 06:15:53 INFO - "pgo_build": "False", 06:15:53 INFO - "builduid": "d192b762b9f94a39ac57046b273479a1", 06:15:53 INFO - "slavebuilddir": "test" 06:15:53 INFO - } 06:15:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:15:53 INFO - Running post-action listener: _resource_record_post_action 06:15:53 INFO - [mozharness: 2016-11-30 14:15:53.211306Z] Finished read-buildbot-config step (success) 06:15:53 INFO - [mozharness: 2016-11-30 14:15:53.211440Z] Running download-and-extract step. 06:15:53 INFO - Running pre-action listener: _resource_record_pre_action 06:15:53 INFO - Running main action method: download_and_extract 06:15:53 INFO - mkdir: /builds/slave/test/build/tests 06:15: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')]} 06:15:53 INFO - trying https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/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 06:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/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 06:15:54 INFO - Downloaded 1199 bytes. 06:15:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:15:54 INFO - Using the following test package requirements: 06:15:54 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:15:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:15:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:15:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'jsshell-mac.zip'], 06:15:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:15:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:15:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:15:54 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:15:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:15:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:15:54 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:15:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:15:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:15:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:15:54 INFO - Fetch https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:15:55 INFO - Http code: 200 06:15:55 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 06:15:55 INFO - x-amz-cf-id: eHIf6OuwCb0DOo4JsIa-K5Jwa9CQSdUrxyfuzWWhbgn_F5NQtbbAUA== 06:15:55 INFO - x-amz-version-id: fhRICl3rRgFgoeeQ16xcxEty.YDA94VT 06:15:55 INFO - Expected file size: 16286806 06:15:55 INFO - Obtained file size: 16286806 06:16:02 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 06:16:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:16:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:16:02 INFO - Fetch https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:16:02 INFO - Http code: 200 06:16:02 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 06:16:02 INFO - x-amz-cf-id: NqpUGqjQhTXGLtlcVTmvJEqhuHnDIVlcj2Bdvu_6Yx_fmP3HlP-31Q== 06:16:02 INFO - x-amz-version-id: fhRICl3rRgFgoeeQ16xcxEty.YDA94VT 06:16:02 INFO - Expected file size: 16286806 06:16:02 INFO - Obtained file size: 16286806 06:16:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:16:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:16:09 INFO - Fetch https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:16:10 INFO - Http code: 200 06:16:10 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 06:16:10 INFO - x-amz-cf-id: M_zG9e_K4gayP9XQL9RxD3xzkeW8Wx_EJT80_fE8_oj8UUvf5mfpgg== 06:16:10 INFO - x-amz-version-id: frESVM0QLoUyVGct.8fcImWRrYJEUqId 06:16:10 INFO - Expected file size: 14409702 06:16:10 INFO - Obtained file size: 14409702 06:16:11 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')]} 06:16:11 INFO - trying https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:16:11 INFO - Downloading https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/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 06:16:14 INFO - Downloaded 107727494 bytes. 06:16:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:16:14 INFO - mkdir: /builds/slave/test/properties 06:16:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:16:14 INFO - Writing to file /builds/slave/test/properties/build_url 06:16:14 INFO - Contents: 06:16:14 INFO - build_url:https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:16:14 INFO - Running post-action listener: _resource_record_post_action 06:16:14 INFO - Running post-action listener: set_extra_try_arguments 06:16:14 INFO - [mozharness: 2016-11-30 14:16:14.901028Z] Finished download-and-extract step (success) 06:16:14 INFO - [mozharness: 2016-11-30 14:16:14.901152Z] Running populate-webroot step. 06:16:14 INFO - Running pre-action listener: _resource_record_pre_action 06:16:14 INFO - Running main action method: populate_webroot 06:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:16:14 INFO - '--tppagecycles', 06:16:14 INFO - '7'], 06:16:14 INFO - 'tests': ['tsvgm']}}, 06:16:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:16:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:16:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:16:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:16:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['damp', 'tps']}, 06:16:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'tests': ['damp', 'tps']}, 06:16:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['dromaeo_dom']}, 06:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:16:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['basic_compositor_video']}, 06:16:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:16:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'other-e10s': {'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['a11yr', 06:16:14 INFO - 'ts_paint', 06:16:14 INFO - 'tpaint', 06:16:14 INFO - 'sessionrestore', 06:16:14 INFO - 'sessionrestore_no_auto_restore', 06:16:14 INFO - 'tabpaint']}, 06:16:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['tsvgx', 06:16:14 INFO - 'tsvgr_opacity', 06:16:14 INFO - 'tart', 06:16:14 INFO - 'tscrollx', 06:16:14 INFO - 'cart', 06:16:14 INFO - 'tsvg_static']}, 06:16:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:16:14 INFO - 'tsvgr_opacity', 06:16:14 INFO - 'tart', 06:16:14 INFO - 'tscrollx', 06:16:14 INFO - 'cart', 06:16:14 INFO - 'tsvg_static']}, 06:16:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'talos_options': ['--disable-e10s'], 06:16:14 INFO - 'tests': ['tp5o']}, 06:16:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:16:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'talos_options': ['--disable-e10s', 06:16:14 INFO - '--xperf_path', 06:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:14 INFO - 'tests': ['tp5n']}, 06:16:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:16:14 INFO - 'talos_options': ['--xperf_path', 06:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:14 INFO - 'tests': ['tp5n']}}, 06:16:14 INFO - 'talos.zip': {'path': '', 06:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:16:14 INFO - Running post-action listener: _resource_record_post_action 06:16:14 INFO - [mozharness: 2016-11-30 14:16:14.919932Z] Finished populate-webroot step (success) 06:16:14 INFO - [mozharness: 2016-11-30 14:16:14.920064Z] Running create-virtualenv step. 06:16:14 INFO - Running pre-action listener: _resource_record_pre_action 06:16:14 INFO - Running main action method: create_virtualenv 06:16:14 INFO - Creating virtualenv /builds/slave/test/build/venv 06:16:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:16:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:16:15 INFO - Using real prefix '/tools/python27' 06:16:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:16:15 INFO - Installing distribute.............................................................................................................................................................................................done. 06:16:18 INFO - Installing pip.................done. 06:16:18 INFO - Return code: 0 06:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:16:18 INFO - Reading from file tmpfile_stdout 06:16:18 INFO - Output received: 06:16:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:16:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:18 INFO - 'HOME': '/Users/cltbld', 06:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:18 INFO - 'LOGNAME': 'cltbld', 06:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:18 INFO - 'MOZ_NO_REMOTE': '1', 06:16:18 INFO - 'NO_EM_RESTART': '1', 06:16:18 INFO - 'PAGER': '/bin/cat', 06:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:18 INFO - 'PWD': '/builds/slave/test', 06:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:18 INFO - 'SHELL': '/bin/bash', 06:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:18 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:18 INFO - 'USER': 'cltbld', 06:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:18 INFO - 'XPC_FLAGS': '0x0', 06:16:18 INFO - 'XPC_SERVICE_NAME': '0', 06:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:19 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 06:16:19 INFO - Cleaning up... 06:16:19 INFO - Return code: 0 06:16:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:19 INFO - 'HOME': '/Users/cltbld', 06:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:19 INFO - 'LOGNAME': 'cltbld', 06:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:19 INFO - 'MOZ_NO_REMOTE': '1', 06:16:19 INFO - 'NO_EM_RESTART': '1', 06:16:19 INFO - 'PAGER': '/bin/cat', 06:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:19 INFO - 'PWD': '/builds/slave/test', 06:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:19 INFO - 'SHELL': '/bin/bash', 06:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:19 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:19 INFO - 'USER': 'cltbld', 06:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:19 INFO - 'XPC_FLAGS': '0x0', 06:16:19 INFO - 'XPC_SERVICE_NAME': '0', 06:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:19 INFO - Downloading/unpacking psutil>=3.1.1 06:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:16:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:23 INFO - Installing collected packages: psutil 06:16:23 INFO - Running setup.py install for psutil 06:16:23 INFO - building 'psutil._psutil_osx' extension 06:16:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:16:24 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 06:16:24 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 06:16:24 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 06:16:24 INFO - building 'psutil._psutil_posix' extension 06:16:24 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 06:16:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:16:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:16:24 INFO - ^ 06:16:24 INFO - 1 warning generated. 06:16:24 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 06:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:24 INFO - Successfully installed psutil 06:16:24 INFO - Cleaning up... 06:16:24 INFO - Return code: 0 06:16:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:24 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:24 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 06:16:24 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 06:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:24 INFO - 'HOME': '/Users/cltbld', 06:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:24 INFO - 'LOGNAME': 'cltbld', 06:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:24 INFO - 'MOZ_NO_REMOTE': '1', 06:16:24 INFO - 'NO_EM_RESTART': '1', 06:16:24 INFO - 'PAGER': '/bin/cat', 06:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:24 INFO - 'PWD': '/builds/slave/test', 06:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:24 INFO - 'SHELL': '/bin/bash', 06:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:24 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:24 INFO - 'USER': 'cltbld', 06:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:24 INFO - 'XPC_FLAGS': '0x0', 06:16:24 INFO - 'XPC_SERVICE_NAME': '0', 06:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:16:27 INFO - Installing collected packages: mozsystemmonitor 06:16:27 INFO - Running setup.py install for mozsystemmonitor 06:16:27 INFO - Successfully installed mozsystemmonitor 06:16:27 INFO - Cleaning up... 06:16:27 INFO - Return code: 0 06:16:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:16:27 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')]} 06:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:27 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')]} 06:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:27 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:27 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 06:16:27 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 06:16:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:27 INFO - 'HOME': '/Users/cltbld', 06:16:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:27 INFO - 'LOGNAME': 'cltbld', 06:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:27 INFO - 'MOZ_NO_REMOTE': '1', 06:16:27 INFO - 'NO_EM_RESTART': '1', 06:16:27 INFO - 'PAGER': '/bin/cat', 06:16:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:27 INFO - 'PWD': '/builds/slave/test', 06:16:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:27 INFO - 'SHELL': '/bin/bash', 06:16:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:27 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:27 INFO - 'USER': 'cltbld', 06:16:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:27 INFO - 'XPC_FLAGS': '0x0', 06:16:27 INFO - 'XPC_SERVICE_NAME': '0', 06:16:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:28 INFO - Downloading/unpacking jsonschema==2.5.1 06:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:16:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:31 INFO - Downloading functools32-3.2.3-2.tar.gz 06:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:16:31 INFO - warning: no files found matching '*.txt' 06:16:31 INFO - no previously-included directories found matching 'build' 06:16:31 INFO - no previously-included directories found matching 'dist' 06:16:31 INFO - no previously-included directories found matching '.git*' 06:16:31 INFO - Installing collected packages: jsonschema, functools32 06:16:31 INFO - Running setup.py install for functools32 06:16:31 INFO - warning: no files found matching '*.txt' 06:16:31 INFO - no previously-included directories found matching 'build' 06:16:31 INFO - no previously-included directories found matching 'dist' 06:16:31 INFO - no previously-included directories found matching '.git*' 06:16:31 INFO - Successfully installed jsonschema functools32 06:16:31 INFO - Cleaning up... 06:16:31 INFO - Return code: 0 06:16:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:31 INFO - 'HOME': '/Users/cltbld', 06:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:31 INFO - 'LOGNAME': 'cltbld', 06:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:31 INFO - 'MOZ_NO_REMOTE': '1', 06:16:31 INFO - 'NO_EM_RESTART': '1', 06:16:31 INFO - 'PAGER': '/bin/cat', 06:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:31 INFO - 'PWD': '/builds/slave/test', 06:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:31 INFO - 'SHELL': '/bin/bash', 06:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:31 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:31 INFO - 'USER': 'cltbld', 06:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:31 INFO - 'XPC_FLAGS': '0x0', 06:16:31 INFO - 'XPC_SERVICE_NAME': '0', 06:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:16:31 INFO - Cleaning up... 06:16:31 INFO - Return code: 0 06:16:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:31 INFO - 'HOME': '/Users/cltbld', 06:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:31 INFO - 'LOGNAME': 'cltbld', 06:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:31 INFO - 'MOZ_NO_REMOTE': '1', 06:16:31 INFO - 'NO_EM_RESTART': '1', 06:16:31 INFO - 'PAGER': '/bin/cat', 06:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:31 INFO - 'PWD': '/builds/slave/test', 06:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:31 INFO - 'SHELL': '/bin/bash', 06:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:31 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:31 INFO - 'USER': 'cltbld', 06:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:31 INFO - 'XPC_FLAGS': '0x0', 06:16:31 INFO - 'XPC_SERVICE_NAME': '0', 06:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:32 INFO - Downloading/unpacking blobuploader==1.2.4 06:16:32 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 06:16:32 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 06:16:32 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 06:16:32 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 06:16:32 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 06:16:32 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 06:16:34 INFO - Downloading blobuploader-1.2.4.tar.gz 06:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:16:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:16:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:16:35 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 06:16:35 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 06:16:35 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 06:16:35 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 06:16:35 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 06:16:35 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 06:16:35 INFO - Downloading docopt-0.6.1.tar.gz 06:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:16:35 INFO - Installing collected packages: blobuploader, requests, docopt 06:16:35 INFO - Running setup.py install for blobuploader 06:16:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:16:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:16:36 INFO - Running setup.py install for requests 06:16:36 INFO - Running setup.py install for docopt 06:16:36 INFO - Successfully installed blobuploader requests docopt 06:16:36 INFO - Cleaning up... 06:16:36 INFO - Return code: 0 06:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:36 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:36 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 06:16:36 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 06:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:36 INFO - 'HOME': '/Users/cltbld', 06:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:36 INFO - 'LOGNAME': 'cltbld', 06:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:36 INFO - 'MOZ_NO_REMOTE': '1', 06:16:36 INFO - 'NO_EM_RESTART': '1', 06:16:36 INFO - 'PAGER': '/bin/cat', 06:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:36 INFO - 'PWD': '/builds/slave/test', 06:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:36 INFO - 'SHELL': '/bin/bash', 06:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:36 INFO - 'USER': 'cltbld', 06:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:36 INFO - 'XPC_FLAGS': '0x0', 06:16:36 INFO - 'XPC_SERVICE_NAME': '0', 06:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2rvHQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Wwjpn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-09i7rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-azMiSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-viqQul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-f9ViPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-aXy_2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KraQ3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GGTKFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:37 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GOXwlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LN0Xho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EYVI5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-v5pq4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XOFzpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Lp9cqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-n5ZsKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Ma3L6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:16:38 INFO - Running setup.py install for manifestparser 06:16:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:16:38 INFO - Running setup.py install for mozcrash 06:16:39 INFO - Running setup.py install for mozdebug 06:16:39 INFO - Running setup.py install for mozdevice 06:16:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Running setup.py install for mozfile 06:16:39 INFO - Running setup.py install for mozhttpd 06:16:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Running setup.py install for mozinfo 06:16:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Running setup.py install for mozInstall 06:16:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:16:39 INFO - Running setup.py install for mozleak 06:16:40 INFO - Running setup.py install for mozlog 06:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Running setup.py install for moznetwork 06:16:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Running setup.py install for mozprocess 06:16:40 INFO - Running setup.py install for mozprofile 06:16:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Running setup.py install for mozrunner 06:16:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:16:40 INFO - Running setup.py install for mozscreenshot 06:16:41 INFO - Running setup.py install for moztest 06:16:41 INFO - Running setup.py install for mozversion 06:16:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:16:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:16:41 INFO - Cleaning up... 06:16:41 INFO - Return code: 0 06:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:41 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')]} 06:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:41 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')]} 06:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:41 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:41 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 06:16:41 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 06:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:41 INFO - 'HOME': '/Users/cltbld', 06:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:41 INFO - 'LOGNAME': 'cltbld', 06:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:41 INFO - 'MOZ_NO_REMOTE': '1', 06:16:41 INFO - 'NO_EM_RESTART': '1', 06:16:41 INFO - 'PAGER': '/bin/cat', 06:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:41 INFO - 'PWD': '/builds/slave/test', 06:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:41 INFO - 'SHELL': '/bin/bash', 06:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:41 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:41 INFO - 'USER': 'cltbld', 06:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:41 INFO - 'XPC_FLAGS': '0x0', 06:16:41 INFO - 'XPC_SERVICE_NAME': '0', 06:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-g6Rx7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:41 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)) 06:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UpNvqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:41 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)) 06:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-djyuzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:41 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)) 06:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:41 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-E2xGdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-beO5lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Oj3DhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-QBtcjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OLYGcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AMFXoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jK0hjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mfMiKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7H90DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Xu0NpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:42 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)) 06:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:42 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5wdgED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:43 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)) 06:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:43 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7RT3rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:43 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)) 06:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:43 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pQoZEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:43 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)) 06:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:43 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-co3P_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16:43 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)) 06:16:43 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)) 06:16:43 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)) 06:16:43 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)) 06:16:43 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)) 06:16:43 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)) 06:16:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:16:43 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 06:16:43 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 06:16:43 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 06:16:43 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 06:16:43 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 06:16:43 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 06:16:45 INFO - Downloading blessings-1.6.tar.gz 06:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:16:46 INFO - Installing collected packages: blessings 06:16:46 INFO - Running setup.py install for blessings 06:16:46 INFO - Successfully installed blessings 06:16:46 INFO - Cleaning up... 06:16:46 INFO - Return code: 0 06:16:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:16:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:46 INFO - Reading from file tmpfile_stdout 06:16:46 INFO - Current package versions: 06:16:46 INFO - blessings == 1.6 06:16:46 INFO - blobuploader == 1.2.4 06:16:46 INFO - docopt == 0.6.1 06:16:46 INFO - functools32 == 3.2.3-2 06:16:46 INFO - jsonschema == 2.5.1 06:16:46 INFO - manifestparser == 1.1 06:16:46 INFO - mozInstall == 1.12 06:16:46 INFO - mozcrash == 1.0 06:16:46 INFO - mozdebug == 0.1 06:16:46 INFO - mozdevice == 0.49 06:16:46 INFO - mozfile == 1.2 06:16:46 INFO - mozhttpd == 0.7 06:16:46 INFO - mozinfo == 0.9 06:16:46 INFO - mozleak == 0.1 06:16:46 INFO - mozlog == 3.4 06:16:46 INFO - moznetwork == 0.27 06:16:46 INFO - mozprocess == 0.23 06:16:46 INFO - mozprofile == 0.28 06:16:46 INFO - mozrunner == 6.13 06:16:46 INFO - mozscreenshot == 0.1 06:16:46 INFO - mozsystemmonitor == 0.3 06:16:46 INFO - moztest == 0.7 06:16:46 INFO - mozversion == 1.4 06:16:46 INFO - psutil == 3.1.1 06:16:46 INFO - requests == 1.2.3 06:16:46 INFO - wsgiref == 0.1.2 06:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:46 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')]} 06:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:46 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')]} 06:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:46 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:46 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 06:16:46 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 06:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:46 INFO - 'HOME': '/Users/cltbld', 06:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:46 INFO - 'LOGNAME': 'cltbld', 06:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:46 INFO - 'MOZ_NO_REMOTE': '1', 06:16:46 INFO - 'NO_EM_RESTART': '1', 06:16:46 INFO - 'PAGER': '/bin/cat', 06:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:46 INFO - 'PWD': '/builds/slave/test', 06:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:46 INFO - 'SHELL': '/bin/bash', 06:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:46 INFO - 'USER': 'cltbld', 06:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:46 INFO - 'XPC_FLAGS': '0x0', 06:16:46 INFO - 'XPC_SERVICE_NAME': '0', 06:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 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)) 06:16:46 INFO - Cleaning up... 06:16:46 INFO - Return code: 0 06:16:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:16:46 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')]} 06:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:46 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')]} 06:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:46 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 0x102b583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea325c9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, '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.6fc3dpGn6G/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.0amjclpHQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 06:16:46 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 06:16:46 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 06:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:16:46 INFO - 'HOME': '/Users/cltbld', 06:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:16:46 INFO - 'LOGNAME': 'cltbld', 06:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:46 INFO - 'MOZ_NO_REMOTE': '1', 06:16:46 INFO - 'NO_EM_RESTART': '1', 06:16:46 INFO - 'PAGER': '/bin/cat', 06:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:46 INFO - 'PWD': '/builds/slave/test', 06:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:16:46 INFO - 'SHELL': '/bin/bash', 06:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:16:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:16:46 INFO - 'USER': 'cltbld', 06:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:46 INFO - 'XPC_FLAGS': '0x0', 06:16:46 INFO - 'XPC_SERVICE_NAME': '0', 06:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:16:47 INFO - Cleaning up... 06:16:47 INFO - Return code: 0 06:16:47 INFO - Running post-action listener: _resource_record_post_action 06:16:47 INFO - Running post-action listener: _start_resource_monitoring 06:16:47 INFO - Starting resource monitoring. 06:16:47 INFO - [mozharness: 2016-11-30 14:16:47.191191Z] Finished create-virtualenv step (success) 06:16:47 INFO - [mozharness: 2016-11-30 14:16:47.192181Z] Running install step. 06:16:47 INFO - Running pre-action listener: _resource_record_pre_action 06:16:47 INFO - Running main action method: install 06:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:47 INFO - Reading from file tmpfile_stdout 06:16:47 INFO - Detecting whether we're running mozinstall >=1.0... 06:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:16:47 INFO - Reading from file tmpfile_stdout 06:16:47 INFO - Output received: 06:16:47 INFO - Usage: mozinstall [options] installer 06:16:47 INFO - Options: 06:16:47 INFO - -h, --help show this help message and exit 06:16:47 INFO - -d DEST, --destination=DEST 06:16:47 INFO - Directory to install application into. [default: 06:16:47 INFO - "/builds/slave/test"] 06:16:47 INFO - --app=APP Application being installed. [default: firefox] 06:16:47 INFO - mkdir: /builds/slave/test/build/application 06:16:47 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'] 06:16:47 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 06:17:20 INFO - Reading from file tmpfile_stdout 06:17:20 INFO - Output received: 06:17:20 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:17:20 INFO - Running post-action listener: _resource_record_post_action 06:17:20 INFO - [mozharness: 2016-11-30 14:17:20.290266Z] Finished install step (success) 06:17:20 INFO - [mozharness: 2016-11-30 14:17:20.290404Z] Running run-tests step. 06:17:20 INFO - Running pre-action listener: _resource_record_pre_action 06:17:20 INFO - Running main action method: run_tests 06:17:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:17:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:17:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:17:20 INFO - 2016-11-30 06:17:20.304 screenresolution[1631:9000] starting screenresolution argv=screenresolution get 06:17:20 INFO - 2016-11-30 06:17:20.307 screenresolution[1631:9000] Display 0: 1600x1200x32@60 06:17:20 INFO - 2016-11-30 06:17:20.313 screenresolution[1632:9002] starting screenresolution argv=screenresolution list 06:17:20 INFO - Available Modes on Display 0 06:17:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:17:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:17:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:17:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:20 INFO - Intel Iris: 06:17:20 INFO - Chipset Model: Intel Iris 06:17:20 INFO - Type: GPU 06:17:20 INFO - Bus: Built-In 06:17:20 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:20 INFO - Vendor: Intel (0x8086) 06:17:20 INFO - Device ID: 0x0a2e 06:17:20 INFO - Revision ID: 0x0009 06:17:20 INFO - Displays: 06:17:20 INFO - Display: 06:17:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:20 INFO - Display Serial Number: 1600x1200 60 06:17:20 INFO - Main Display: Yes 06:17:20 INFO - Mirror: Off 06:17:20 INFO - Online: Yes 06:17:20 INFO - Rotation: Supported 06:17:20 INFO - Return code: 0 06:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:17:20 INFO - Python 2.7.3 06:17:20 INFO - Return code: 0 06:17:20 INFO - grabbing minidump binary from tooltool 06:17:20 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')]} 06:17:20 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 0x10296ed30>, '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 0x1027d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102966b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:17:20 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 06:17:20 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 06:17:20 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 06:17:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:17:20 INFO - Return code: 0 06:17:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:17:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:17:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:17:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:17:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:17:20 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/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 06:17:20 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/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 06:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6fc3dpGn6G/Render', 06:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:17:20 INFO - 'HOME': '/Users/cltbld', 06:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:17:20 INFO - 'LOGNAME': 'cltbld', 06:17:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:17:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:20 INFO - 'MOZ_NO_REMOTE': '1', 06:17:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:17:20 INFO - 'NO_EM_RESTART': '1', 06:17:20 INFO - 'PAGER': '/bin/cat', 06:17:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:17:20 INFO - 'PWD': '/builds/slave/test', 06:17:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:17:20 INFO - 'SHELL': '/bin/bash', 06:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0amjclpHQc/Listeners', 06:17:20 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 06:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:17:20 INFO - 'USER': 'cltbld', 06:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:20 INFO - 'XPC_FLAGS': '0x0', 06:17:20 INFO - 'XPC_SERVICE_NAME': '0', 06:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:17:20 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/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 06:17:20 INFO - mozversion application_buildid: 20161130030206 06:17:20 INFO - mozversion application_changeset: 13736e2db6eb94b02dd28cc88f2943b8109aa374 06:17:20 INFO - mozversion application_display_name: Nightly 06:17:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:17:20 INFO - mozversion application_name: Firefox 06:17:20 INFO - mozversion application_remotingname: firefox 06:17:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:17:20 INFO - mozversion application_vendor: Mozilla 06:17:20 INFO - mozversion application_version: 53.0a1 06:17:20 INFO - mozversion platform_buildid: 20161130030206 06:17:20 INFO - mozversion platform_changeset: 13736e2db6eb94b02dd28cc88f2943b8109aa374 06:17:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:17:20 INFO - mozversion platform_version: 53.0a1 06:17:20 INFO - using testdate: 1480515440 06:17:20 INFO - actual date: 1480515440 06:17:20 INFO - starting webserver on 'localhost:49247' 06:17:20 INFO - SUITE-START | Running 2 tests 06:17:20 INFO - TEST-START | dromaeo_css 06:17:20 INFO - operating with platform_type : mac_ 06:17:20 INFO - Initialising browser for dromaeo_css test... 06:17:20 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpGgU7e5/profile http://localhost:49247/getInfo.html) 06:17:23 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 06:17:23 INFO - PROCESS | 1642 | colorDepth:24 06:17:23 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 06:17:23 INFO - PROCESS | 1642 | __metrics 06:17:23 INFO - PROCESS | 1642 | 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" 06:17:23 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:17:24 INFO - TEST-INFO | 1642: exit 0 06:17:24 INFO - Browser initialized. 06:17:24 INFO - Running cycle 1/1 for dromaeo_css test... 06:17:24 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpGgU7e5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:36 INFO - PROCESS | 1646 | RSS: Main: 262516736 06:17:36 INFO - PROCESS | 1646 | 06:18:28 INFO - PROCESS | 1646 | 1480515508154 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] 06:20:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 06:20:42 INFO - PROCESS | 1646 | RSS: Main: 378191872 06:20:42 INFO - PROCESS | 1646 | 06:23:46 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 06:23:46 INFO - PROCESS | 1646 | RSS: Main: 386711552 06:23:46 INFO - PROCESS | 1646 | 06:26:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 06:26:49 INFO - PROCESS | 1646 | RSS: Main: 361385984 06:26:49 INFO - PROCESS | 1646 | 06:29:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 06:29:52 INFO - PROCESS | 1646 | RSS: Main: 354660352 06:29:52 INFO - PROCESS | 1646 | 06:32:56 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 06:32:56 INFO - PROCESS | 1646 | RSS: Main: 344858624 06:32:56 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 06:36:00 INFO - PROCESS | 1646 | RSS: Main: 491159552 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | __start_tp_report 06:36:00 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 06:36:00 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 06:36:00 INFO - PROCESS | 1646 | |i|pagename|runs| 06:36:00 INFO - PROCESS | 1646 | |0;dojo.html;2815.2680303121188;3347.7489188310883;3353.1449480382507;3383.035834787086;3406.9659303406956;5570.359408668;5588.91616625751;5645.633033852797;5691.715414229293;5697.515711164551;5639.943600563993;5660.801871934483;5668.914966275499;5684.715764211784;5685.289338832646;5141.4087379951225;5165.560927321182;5173;5195.818146364878;5213.165893457048;5249.685018898878;5253.7110458924935;5260.92108618371;5260.947390526103;5266.025785229725;5160.380754309488;5166.225066240057;5201.68789872607;5201.791928322881;5250.396204436482;5233.555147812441;5242.2136679498;5248;5253.9474605253845;5318.414974352818;5078.390593128848;5078.5937125029905;5129;5178.741062946875;5182.352205974254;5551.333839939233;5597.552195824324;5626.493615574618;5638.238837756891;5639.943600563983;6174.598651087665;6180.660063696495;6181.690915454209;6190.907136392912;6199.690015499252;6111.227117700316;6275.058741188858;6300.747970081191;6306.432421082079;6308.93691063088;5414.756335964891;5662.773489060432;5673.375928647845;5694.459026392569;5774.566907481955;6324.019776934582;6353.110564520971;6359.046143078575;6368.872622547523;6371.980483122771;5921.02303119989;6065.787697430569;6067.908981365283;6070.332263451017;6076.696165191811;6060.424259695322;6140.416660417253;6144.539159562962;6164.753409863645;6175.876482470326;6172.043333283348;6183.84540386494;6186.721597528122;6191.845203869849;6196.318404975449;6180.845478863064;6186.19579454677;6194.876102477925;6199.907001394983;6205.751769929152;4745.572898439157;4757.500462451462;4816.95183048172;4845.491223421561;4912.72979986097;4314.590113939171;4376.715513491613;4393.29707246839;4409.603135717801;4447.08834688888;5576.470235327637;5608.214849921014;5667.971660141673;5694.914576281359;5720.628159169641;6071.51427885768;6105.877882442326;6264.561480696308;6284.308726040132;6305.4009869062365;5560.527355174855;5610;5620.718964051781;5625.971870140623;5638.887222255532;5325.281087053276;5328.067588172132;5334.306540149833;5342.332208473941;5343.465653434703;6340.968295158495;6355.5551111421755;6359;6360.650164241011;6373.553851230369;6136.447719705248;6146.0639898431855;6168.506519478341;6170.6914654268;6202.131701561875;6248.968755156377;6316.336784637547;6354.3963323484195;6377.74489020434;6390.48876089921;6070.6357618543025;6083.6045657032155;6086.847828804315;6110.2973158085615;6121.571489995836;6067.848303792441;6110.315755985069;6137.723802428991;6202.317745048132;6226.346233645583;5948.10778383246;5980.192673988956;5998.310194327708;6014.48876845473;6042.305134909541;5960.0761881908375;5977.282726072899;5985.820425387245;6001.129836173817;6023.247094113236;5645.858853528694;5700.3444603871085;5831.7375718093635;5833.212516310414;5840.386759390203;5558.249636299048;5978.342382338026;5982.88034239304;6056.091586262096;6128.295246046761;5871.1486834407915;5871.354151043467;5883.294004719461;5886.499647530008;5915.142304365929 06:36:00 INFO - PROCESS | 1646 | |1;ext.html;463.85659349702956;467.5464799144829;474.459116607068;480.1788940911042;511.6648595170165;38923;40413.59586404135;40437.393439098436;40460.59539404609;40480;39132.60867391325;39499.80250098753;39609;39671.40492892609;39830.60169398305;7661.923380766205;8468.983721953344;8744.387892847502;9333.06669333068;9457.243420526376;8470.195331443534;11261.324320540753;11505.367204803733;11526.827097593543;11677.649670509856;11345.09239260857;11402.372869492174;11408.258463199865;11424.600138995136;11466;8460.280876125536;11321.773564528705;11354.716132096764;11394.259373140761;11396.088312934946;8444.451110677786;8579.914200858037;8583.82832343356;8593.355498337649;10988.230823842334;38447.231055379016;38730.80634596809;38848.41727374092;38882.222355553014;38885.22229555393;3205.6473787883315;3233.1917020744813;3301.273719781644;3329.6170940341926;3638.6907112895437;22236.88881555598;27419.588706169423;29448.263793405124;29992.400151997055;30091;3215.6302025266928;3244.853981570835;3313.287643156733;3350.9832450837835;3353.815540145291;469070;473098;474962.6251868753;475012;475476;59175.70412147912;59408.70295648494;59515.80968380608;59572;59607.10589341163;52968.47031529713;59764;60757.08864367038;62238;62244.37755622386;15237.780977521772;15532.767008494882;15536;15540.533783986499;15570.377184912477;10927.235093543535;11145.275557088926;11839.467223974942;11871.109666775026;12026.699332516582;16314.67370652604;16690.666186676197;16757.32970681159;16797.664046718997;16868.072256026036;5737.385447168257;5841.9415805842245;6111.266648002268;6136.325004249529;6136.938630613726;756.8259300360837;868.891388576428;919.6965001549472;961.8316794561064;991.8710567626163;27876.44247115046;28776;28954.420911581652;28982.855085724437;29497.85251073731;1008.3748076192808;1051;1060.4644654449608;1110.9501521062518;1125.9493322800495;14645.92677036608;14957.401703931939;15065.397384104732;15309.540713778604;15376.077435353915;610497;685682;692254;693085;897360;56898.14652780211;57812.13281800776;57819.71090144522;57833.710831445576;57934.71032644894;1309.1032642639823;1342.8052932324758;1352.51985545134;1355.6746380868522;1366.364640442199;28027.019054333;29810;29890.17141657443;30228.33601005513;30467.39065218639;43955.78022109997;50712;50713;50758.7310317245;50939.98120037504;577694;580103;580937;581093;582125;22500;24300.51398972046;25438;25521.234362969142;25645.230643080737;35377.646223537435;41135.58864411317;42829.357559637254;45092.32361514645;46243.76878115588;37150.9566973819;37283;37330.62669373272;38219;38999.610003900736;35233.00519455875;37007.62992370042;37664.246715066096;37848.24303513969;38083.428748568236;31929.521057183698;36359.90920272396;37167;37214.8139259302;37303.44044839275;31621.683783161872;33022.74431392152;33380.33239335248;33477.66522334745;33580.49629255612;24498.51002979966;25153.74846251514;25231;25807.096751613604;26067.543391919837 06:36:00 INFO - PROCESS | 1646 | |2;jquery.html;350.05834305717605;368.9520362352895;372.48782923480195;381.12721866924744;399.1000294336271;14455.855441445596;14778.187199704025;15199.708752873834;20065.1973921043;20157.798422015774;19119.04404779759;19586.314478993238;20325.186992520317;20332;20608.8969555152;5572.91328191003;5591.692456914868;5904.084866845646;5909.054551271797;5914.290285165786;4634.930476042845;5822.475977162055;5862.560307976897;5863.677497737623;5874.97062514689;5470.890582188374;5826.067829147337;5830.008898487267;5832.1834943108;5837;5655.886882262353;5795.1886735857015;5814.331351894544;5814.7092645367775;5820.010598198316;5084.593232541388;5089.872753181163;5097.490250974873;7130.714771409137;7497.938067031555;26248.606270905762;27188.912443502235;27292.863535682394;27470.862645686844;27686.73951949123;2313.144136669426;2386.7613238676167;2403.218953840009;2412.593140491363;2621.921342359732;15804.841951580453;15865.341481163998;16231.675366492604;16345.591360216089;16951.321947122102;1889.725989731495;1917.7698676158811;1963.577830766392;1964.4401345616432;2046.9590608187902;82747;120096.39951800273;123763;129305;136705;16061.196940152946;17667.911660441616;19362.25509796093;20691;20697.68953465699;29391;33898.98303050912;33904;34023.65976340205;34033;9965.501724913725;10105.595776168871;10260.71741032371;10291.176705863512;10502.05481506668;8304.147602795863;8386.077531471534;8398;8564.272036876811;8574.571271436404;9349.97150313465;9576.712698619052;9611.038896110333;9621.326507144433;9659.32384733062;3111.6110486189227;3608.404613238786;3644.817759112034;3648.288583726148;3756.962430375716;560.2828379674024;620.5780069552658;664.0338307762195;720.9387202087881;957.2246480350938;20262.89868550648;20898.477538061365;21143.682844757343;21421.67867481989;21688.783112168676;927.536231884058;953.9744774367445;954.4130359828794;963.6241865672393;990.321629683669;9112.952010518742;10304.175665946856;10704.411257380763;11296.774064518664;11372.260803047775;588280;588558;592717;611968;612088;109958.90041099646;112121;118043;120401.39799300948;128017.35991319983;988.2736188901296;1028.2442404832443;1064.302881612545;1068.7435015596357;1069.2194697870443;18444.63110737751;18642.72035919435;18677.533061673024;18993.810061899203;19043.255223248416;51732.48267517427;52401.213981789544;54032;54484;55306.17040744312;70989;72059.63970180115;72487;73980.63009685132;74988.56517848177;6995.160580730345;7806.453548251568;7963.442559020813;8062.4356295061125;8283.751487455385;10385.376877387382;12303.261804291771;12319.242630997633;12979.480820767063;13158;10331.535080921227;10750.46247687628;11076.280041797258;11104.500297486471;11389.715257118307;10626.415547144981;10711.25021248505;11024;11232.775344493228;11297.209195356574;10912.399818009768;10939.12487001022;11075.169362298022;11204.383758893338;11497.367644779617;6831.6242606657115;7143.357097861218;7312.341889229995;7339.816504587215;7506.28690274423;8574.22831945128;9338.879334479634;10590.311629744043;10992.120630349713;11054.889451105386 06:36:00 INFO - PROCESS | 1646 | |3;mootools.html;1263.778838703227;1490.4336352186167;1587.6983373159098;1773.618671985523;1830.2313028528026;7474.738384156555;8112.634931428085;8477.237048665618;8525.19010693983;8531.27484163846;7552.509086909347;7716.614169291541;7969.123396426403;8152.5516096614665;8198.139195384474;2259.1076524772748;2289.53064621753;2292.358139720881;2300.723913130427;2300.9539809203807;2141.1970511058353;2155.6335422978054;2180.6728990651372;2187.1907394264144;2189.879556624385;1973.279752890192;2047.8566500344982;2051.33331667208;2058.3001779395004;2063.6285468615642;1768.4164225805519;2224.6218142915664;2303.2974942642486;2306.423394151462;2308.03062713659;2290.393045842853;2312.5606134834497;2320.2691152286975;2322.744498105207;2323.93028209154;8199.303059239972;8280.420370574062;8314.41799074065;8334.083250842403;8426.957865210634;5025.246213068032;5181.274621552986;5182;5191.844244672666;5196.740162991873;3405.1827561385335;3455.4947662944164;3462.1344663834043;3503.2292895562937;3543.3976224041976;916.3310783128287;1000.6247657128577;1052.0689190066846;1052.9157667386667;1064.0529928363762;10653.627123050734;10783.46082695862;10790.244682872202;10795.190360722978;10812.78374432507;4410.691251612357;4523.705959112647;4598.218302888517;4616.3537104805355;4705.12955103307;9487.098725621056;9487.66793162236;9491.905080949102;9493.193078588398;9504.239660827117;5050.090983622909;5193.246979187997;5195.766190521435;5206.0368831766245;5206.479352064764;4486.2597671383855;4726.432828060586;4752.405949256343;4752.928706069411;4758.143534163814;3645.854165833337;3710.1466662667735;3748.2878253131817;3763.2473505298503;3789.886303410902;1217.6834023154045;1221.126894270583;1221.969450763738;1237.5977807212691;1240.6526172671665;751.3913729878816;812.8455593437228;869.1265278395299;872.6901949807853;913.8949020862553;5518.2826232589505;5566.248556444828;5658;5666;5765.221695071195;418.32440608417187;424.7196850078933;427.55961359799454;435.5165765999738;446.9329600559942;3621.2214373909737;3658.8536458541894;3677.5035370224678;3703.333399988028;3859.3439115350457;9576.281778866613;10140.087392134743;10146.188304935587;10165.695029149138;10172.237082218864;10074.647387341407;10076.193904487624;10098.242631802497;10113.69658910234;10115.645952391631;7346.9346944692725;7692.499987500906;7701.1143718473095;7784.260495252942;7813.531188128731;4528.592426681615;5661.547076233974;5677.9495793278375;5776.480116789509;5918.674472904032;9072.95463522678;9234.907650923406;9251.427257366398;9296;9448.149666529771;9086.681966131153;9418.670346537838;9538.23694104456;9598.616055357708;9607;7126.928730712834;7225.458090643117;7298.744543940937;7304.744333948286;7316.853662926818;7786.883196752166;7790.766277011699;7812.218778122142;7827.45207835446;7925.920740792519;5750.194972703741;7010.789676309719;7047.823804404931;7140.750073747394;7246.782596522113;6693.665316734046;6785.117934668461;6829.69266383004;7053.541519801196;7072.434205263669;5708.914366284592;6624.138861947916;6654.434373078343;6684.6991885364305;6694.230163531256;4748.192807222849;4781.306710526885;4820.373351464287;4837.830675926326;4959.082569724613;4806.975965120152;4859.635527335535;4860.149473841992;4878.56092951636;4963.057019166346 06:36:00 INFO - PROCESS | 1646 | |4;prototype.html;301.38367039404415;302.56279675868365;309.53260576529436;315.41647951290133;316.48729058924545;5171.948280517194;5230.555402790763;5277.234800953859;5297.841064768063;5305.973470132653;4593.680191503295;4791.065742180276;5050.979608156739;5218.608604354679;5221.921671174926;3332.500124981248;4057.9797101014456;4070.7557546547155;4073.246449406869;4081.653059489947;3659.9752069420606;3885.261800257942;4005.3791662292247;4044.292248856453;4052.7568345899194;3480.129967508123;3654.689351405134;3740.28934502445;3934.527856657191;4074.4109797178976;3464;3983.342748446503;4021.853771675088;4036.2936486114813;4056.5132184137797;2624.448865738178;3372.7470439717117;3424.9725082475156;3426.2462258303135;3465.6014558325865;4197.8320867165585;4769.666123371366;5054.772535235923;5070.98580283941;5073.518015788494;3767.981160094182;4195.860111343206;4349.5646436675825;4462.665300102472;4587.357769912215;5299.470052994709;5530.364008139075;5536.750846211889;5544.334679838405;5556.361018482846;3407.505911642798;3785.2429514097016;3800.2969450651717;3801.82891769871;3812.7712337259854;4326.675499337531;4868.707877527359;5155.407128180233;5815.139155470209;5981.8803623927715;3368.1916340078205;3781.3949768036982;3816.3702989007056;3835.5780864104927;3839.865604703822;3582.0865679251624;3849.7882616456363;3895.941560876589;3901.921961560753;3902.9609703902597;4363.803628836647;4479.259818051898;4489.124620698933;4515.683902126885;4540.659450541222;4652.813887444532;5492.423295553909;5504.2018907258225;5526.281583394133;5536.9203005414365;1232.8582213045615;1248.3196657821509;1249.3877999780043;1250.3060801255208;1264.4373253902108;710.523948954202;744.9068866391701;792.9722459713997;804.3565147881671;850.6597361055627;4321.114171594815;4335.82656693735;4661.294271054519;4794.544518270759;4828.348172996601;4795.784189711471;5009.998000399862;5160.509751573594;5376.9941393661975;5425.426626278335;4363.258246098171;4444.355568442623;4638.744869032235;4754.168020596382;4838.903102014713;2414.625733011386;2582.9741702582733;2781.9443611127663;2784.4431113777327;2789.3305606654258;4921.483244259372;5245.370555533361;5467.343918729778;5536.058869992111;5545.445455454503;4673.766311684402;5137.357830271216;5212.921806172911;5407.432219616883;5451.509364157245;4517.351166824085;5041.974790126173;5452.809151679672;5472.878059997691;5497.505224529731;4777.546133117348;4908.6073114151495;4926.901461970669;4983.9003219934675;5060;4966.652334336562;5233.7383130842545;5245.279248676069;5261.815836445706;5268.341457317835;4558.931616025696;5304.23088652151;5433.293671822637;5475.479829416199;5480.369757477782;3210.4221240176057;3321.6617299613736;3395.541601883714;3579.803110828929;3615.7468977171347;4852.1508735971765;5023.518062171595;5039.6220283479615;5606.327240730975;5758.725303505175;4970.6023518117745;5107.770150343169;5525.938435250007;5583.944160558342;5609;5256.159014557652;5410.702411367255;5598.460423383602;5608;5708.372079071216;5398.568114550748;5418.83743487696;5464.91802622953;5499.1476321170285;5532.446755324436;4379.102284031829;4873.537013983666;5291.523762861361;5463.8201775642465;5474.945250547444;4273.97863010683;5407.486288802557;5424.593155513273;5433.646812957145;5443.510084092451 06:36:00 INFO - PROCESS | 1646 | |5;yui.html;701.277683985495;725.8185453636587;761.4783873046963;793.2622660925338;810.6230602769713;15185.544433666993;15749.448769293078;15751.763723544156;15768.92115539422;15802.841971580281;12074;14155.716885662281;14218.942927270635;14370.640733981656;14382;649.681655988567;676.5129107042922;678.2403707847193;808.4785313472806;808.6563210635471;830.8089139497919;838.3544670603632;850.1286181663783;853.7438768369495;855.7988872614931;841.486693117198;841.6212704283066;869.3045563549167;906.1708536688922;912.4114945859916;692.1693967239333;716.9318914703094;904.3579058868212;905.108468158863;906.9660586930871;639.4916041746823;662.2979641579941;671.4997326991422;673.4713250098649;674.1438373265973;12693.619191424272;14586.186558435691;14767.26163691811;14804.185769782658;14834.925825370912;13469;13668;13712.588622341345;13741.725165496735;13784.517541886085;12240.938795306056;12315.26108433488;12346.876531234753;12501.3749312534;12875.935620321838;4284.057507348379;4295.634871035965;4377.912441751179;4481.5070342262325;4632.884177895546;83769;100704.49647751714;101036;101265;101540.49229753803;10028.899711002943;10305.690829275134;10874.293170943864;10917.945410272898;10921.743999440167;18223.635527289644;21474.24840130588;21507.67738483924;21514.78485215128;21559.029843657074;7600;7708.113566939874;7797.493162944391;8016;8020.879686804703;4266.722663026893;4299;4300.311950087971;4471.217536931024;4486.237339652267;3398.320335932823;3506.8421921013614;3512.121969507623;3517.261375111204;3525.2244506208594;602.7588964414182;615.1326629452451;615.7506209984963;615.7690865925279;615.8737458820931;499.69019208091214;541.7047708998526;564.5511818104617;570.9172170035322;633.5311869216746;8732.519711415805;8828.161324674145;8846.203841654282;8922.221890220264;8933.687320943736;577.5091172503338;623.7349126621168;625.671522450717;637.5463942211765;637.7704026550438;3491.4239150540384;4067.2694466320227;4252.045597594205;4313.439252897166;4410.8090815479645;110550;113857.86142138473;115836;117414.41292793481;120786;90918.54540727254;92779;94799.05200948041;95540;97041.02958970462;88980;89594;89801;90725;92321.53839230895;8107.675692972215;8374.162583741821;8409.453385529921;8518.403711740119;8528.360372971927;45843;46080.161583033296;53762.731186343815;55960.44039559715;56008.15987760268;63872.041919372175;68099.63800724057;79796;80012;80398;11487.597934072268;11654.475548600163;11861.644150675493;11885.702857428634;12089.939550302191;12072.39638018078;12171.391430428266;12184.329861857683;12567.685807854878;12648.936755316532;11691.356975366436;11763.117766167743;11786.705332366759;11950.87998409089;12138.696532586757;11617.941910290394;11908.76182476363;11931.284122952651;11943.76112477763;12242.632721018384;11513.30920144794;11574.247673901169;11857.16999810005;11883.287002779862;12179.330136842558;9345.439273643604;9349.906500934903;9553.474558899326;9903.962183239651;10010.8498372523;7974.561399123104;9896.257780666623;9917.504124793588;9927.503624818875;9938.254630902567 06:36:00 INFO - PROCESS | 1646 | __end_tp_report 06:36:00 INFO - PROCESS | 1646 | __start_cc_report 06:36:00 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1686 06:36:00 INFO - PROCESS | 1646 | __end_cc_report 06:36:00 INFO - PROCESS | 1646 | __startTimestamp1480516560464__endTimestamp 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | ------- Summary: start ------- 06:36:00 INFO - PROCESS | 1646 | Number of tests: 6 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5693.89 Median:5871.35 stddev:646.60 (11.0%) stddev-sans-first:607.87 06:36:00 INFO - PROCESS | 1646 | Values: 2815.3 3347.7 3353.1 3383.0 3407.0 5570.4 5588.9 5645.6 5691.7 5697.5 5639.9 5660.8 5668.9 5684.7 5685.3 5141.4 5165.6 5173.0 5195.8 5213.2 5249.7 5253.7 5260.9 5260.9 5266.0 5160.4 5166.2 5201.7 5201.8 5250.4 5233.6 5242.2 5248.0 5253.9 5318.4 5078.4 5078.6 5129.0 5178.7 5182.4 5551.3 5597.6 5626.5 5638.2 5639.9 6174.6 6180.7 6181.7 6190.9 6199.7 6111.2 6275.1 6300.7 6306.4 6308.9 5414.8 5662.8 5673.4 5694.5 5774.6 6324.0 6353.1 6359.0 6368.9 6372.0 5921.0 6065.8 6067.9 6070.3 6076.7 6060.4 6140.4 6144.5 6164.8 6175.9 6172.0 6183.8 6186.7 6191.8 6196.3 6180.8 6186.2 6194.9 6199.9 6205.8 4745.6 4757.5 4817.0 4845.5 4912.7 4314.6 4376.7 4393.3 4409.6 4447.1 5576.5 5608.2 5668.0 5694.9 5720.6 6071.5 6105.9 6264.6 6284.3 6305.4 5560.5 5610.0 5620.7 5626.0 5638.9 5325.3 5328.1 5334.3 5342.3 5343.5 6341.0 6355.6 6359.0 6360.7 6373.6 6136.4 6146.1 6168.5 6170.7 6202.1 6249.0 6316.3 6354.4 6377.7 6390.5 6070.6 6083.6 6086.8 6110.3 6121.6 6067.8 6110.3 6137.7 6202.3 6226.3 5948.1 5980.2 5998.3 6014.5 6042.3 5960.1 5977.3 5985.8 6001.1 6023.2 5645.9 5700.3 5831.7 5833.2 5840.4 5558.2 5978.3 5982.9 6056.1 6128.3 5871.1 5871.4 5883.3 5886.5 5915.1 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:71189.77 Median:37190.91 stddev:161170.67 (433.4%) stddev-sans-first:161535.31 06:36:00 INFO - PROCESS | 1646 | Values: 463.9 467.5 474.5 480.2 511.7 38923.0 40413.6 40437.4 40460.6 40480.0 39132.6 39499.8 39609.0 39671.4 39830.6 7661.9 8469.0 8744.4 9333.1 9457.2 8470.2 11261.3 11505.4 11526.8 11677.6 11345.1 11402.4 11408.3 11424.6 11466.0 8460.3 11321.8 11354.7 11394.3 11396.1 8444.5 8579.9 8583.8 8593.4 10988.2 38447.2 38730.8 38848.4 38882.2 38885.2 3205.6 3233.2 3301.3 3329.6 3638.7 22236.9 27419.6 29448.3 29992.4 30091.0 3215.6 3244.9 3313.3 3351.0 3353.8 469070.0 473098.0 474962.6 475012.0 475476.0 59175.7 59408.7 59515.8 59572.0 59607.1 52968.5 59764.0 60757.1 62238.0 62244.4 15237.8 15532.8 15536.0 15540.5 15570.4 10927.2 11145.3 11839.5 11871.1 12026.7 16314.7 16690.7 16757.3 16797.7 16868.1 5737.4 5841.9 6111.3 6136.3 6136.9 756.8 868.9 919.7 961.8 991.9 27876.4 28776.0 28954.4 28982.9 29497.9 1008.4 1051.0 1060.5 1111.0 1125.9 14645.9 14957.4 15065.4 15309.5 15376.1 610497.0 685682.0 692254.0 693085.0 897360.0 56898.1 57812.1 57819.7 57833.7 57934.7 1309.1 1342.8 1352.5 1355.7 1366.4 28027.0 29810.0 29890.2 30228.3 30467.4 43955.8 50712.0 50713.0 50758.7 50940.0 577694.0 580103.0 580937.0 581093.0 582125.0 22500.0 24300.5 25438.0 25521.2 25645.2 35377.6 41135.6 42829.4 45092.3 46243.8 37151.0 37283.0 37330.6 38219.0 38999.6 35233.0 37007.6 37664.2 37848.2 38083.4 31929.5 36359.9 37167.0 37214.8 37303.4 31621.7 33022.7 33380.3 33477.7 33580.5 24498.5 25153.7 25231.0 25807.1 26067.5 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:35631.16 Median:18505.30 stddev:99527.28 (537.8%) stddev-sans-first:99771.23 06:36:00 INFO - PROCESS | 1646 | Values: 350.1 369.0 372.5 381.1 399.1 14455.9 14778.2 15199.7 20065.2 20157.8 19119.0 19586.3 20325.2 20332.0 20608.9 5572.9 5591.7 5904.1 5909.1 5914.3 4634.9 5822.5 5862.6 5863.7 5875.0 5470.9 5826.1 5830.0 5832.2 5837.0 5655.9 5795.2 5814.3 5814.7 5820.0 5084.6 5089.9 5097.5 7130.7 7497.9 26248.6 27188.9 27292.9 27470.9 27686.7 2313.1 2386.8 2403.2 2412.6 2621.9 15804.8 15865.3 16231.7 16345.6 16951.3 1889.7 1917.8 1963.6 1964.4 2047.0 82747.0 120096.4 123763.0 129305.0 136705.0 16061.2 17667.9 19362.3 20691.0 20697.7 29391.0 33899.0 33904.0 34023.7 34033.0 9965.5 10105.6 10260.7 10291.2 10502.1 8304.1 8386.1 8398.0 8564.3 8574.6 9350.0 9576.7 9611.0 9621.3 9659.3 3111.6 3608.4 3644.8 3648.3 3757.0 560.3 620.6 664.0 720.9 957.2 20262.9 20898.5 21143.7 21421.7 21688.8 927.5 954.0 954.4 963.6 990.3 9113.0 10304.2 10704.4 11296.8 11372.3 588280.0 588558.0 592717.0 611968.0 612088.0 109958.9 112121.0 118043.0 120401.4 128017.4 988.3 1028.2 1064.3 1068.7 1069.2 18444.6 18642.7 18677.5 18993.8 19043.3 51732.5 52401.2 54032.0 54484.0 55306.2 70989.0 72059.6 72487.0 73980.6 74988.6 6995.2 7806.5 7963.4 8062.4 8283.8 10385.4 12303.3 12319.2 12979.5 13158.0 10331.5 10750.5 11076.3 11104.5 11389.7 10626.4 10711.3 11024.0 11232.8 11297.2 10912.4 10939.1 11075.2 11204.4 11497.4 6831.6 7143.4 7312.3 7339.8 7506.3 8574.2 9338.9 10590.3 10992.1 11054.9 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5387.90 Median:4869.36 stddev:3006.52 (61.7%) stddev-sans-first:2998.97 06:36:00 INFO - PROCESS | 1646 | Values: 1263.8 1490.4 1587.7 1773.6 1830.2 7474.7 8112.6 8477.2 8525.2 8531.3 7552.5 7716.6 7969.1 8152.6 8198.1 2259.1 2289.5 2292.4 2300.7 2301.0 2141.2 2155.6 2180.7 2187.2 2189.9 1973.3 2047.9 2051.3 2058.3 2063.6 1768.4 2224.6 2303.3 2306.4 2308.0 2290.4 2312.6 2320.3 2322.7 2323.9 8199.3 8280.4 8314.4 8334.1 8427.0 5025.2 5181.3 5182.0 5191.8 5196.7 3405.2 3455.5 3462.1 3503.2 3543.4 916.3 1000.6 1052.1 1052.9 1064.1 10653.6 10783.5 10790.2 10795.2 10812.8 4410.7 4523.7 4598.2 4616.4 4705.1 9487.1 9487.7 9491.9 9493.2 9504.2 5050.1 5193.2 5195.8 5206.0 5206.5 4486.3 4726.4 4752.4 4752.9 4758.1 3645.9 3710.1 3748.3 3763.2 3789.9 1217.7 1221.1 1222.0 1237.6 1240.7 751.4 812.8 869.1 872.7 913.9 5518.3 5566.2 5658.0 5666.0 5765.2 418.3 424.7 427.6 435.5 446.9 3621.2 3658.9 3677.5 3703.3 3859.3 9576.3 10140.1 10146.2 10165.7 10172.2 10074.6 10076.2 10098.2 10113.7 10115.6 7346.9 7692.5 7701.1 7784.3 7813.5 4528.6 5661.5 5677.9 5776.5 5918.7 9073.0 9234.9 9251.4 9296.0 9448.1 9086.7 9418.7 9538.2 9598.6 9607.0 7126.9 7225.5 7298.7 7304.7 7316.9 7786.9 7790.8 7812.2 7827.5 7925.9 5750.2 7010.8 7047.8 7140.8 7246.8 6693.7 6785.1 6829.7 7053.5 7072.4 5708.9 6624.1 6654.4 6684.7 6694.2 4748.2 4781.3 4820.4 4837.8 4959.1 4807.0 4859.6 4860.1 4878.6 4963.1 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4325.76 Median:4812.07 stddev:1327.48 (27.6%) stddev-sans-first:1296.39 06:36:00 INFO - PROCESS | 1646 | Values: 301.4 302.6 309.5 315.4 316.5 5171.9 5230.6 5277.2 5297.8 5306.0 4593.7 4791.1 5051.0 5218.6 5221.9 3332.5 4058.0 4070.8 4073.2 4081.7 3660.0 3885.3 4005.4 4044.3 4052.8 3480.1 3654.7 3740.3 3934.5 4074.4 3464.0 3983.3 4021.9 4036.3 4056.5 2624.4 3372.7 3425.0 3426.2 3465.6 4197.8 4769.7 5054.8 5071.0 5073.5 3768.0 4195.9 4349.6 4462.7 4587.4 5299.5 5530.4 5536.8 5544.3 5556.4 3407.5 3785.2 3800.3 3801.8 3812.8 4326.7 4868.7 5155.4 5815.1 5981.9 3368.2 3781.4 3816.4 3835.6 3839.9 3582.1 3849.8 3895.9 3901.9 3903.0 4363.8 4479.3 4489.1 4515.7 4540.7 4652.8 5492.4 5504.2 5526.3 5536.9 1232.9 1248.3 1249.4 1250.3 1264.4 710.5 744.9 793.0 804.4 850.7 4321.1 4335.8 4661.3 4794.5 4828.3 4795.8 5010.0 5160.5 5377.0 5425.4 4363.3 4444.4 4638.7 4754.2 4838.9 2414.6 2583.0 2781.9 2784.4 2789.3 4921.5 5245.4 5467.3 5536.1 5545.4 4673.8 5137.4 5212.9 5407.4 5451.5 4517.4 5042.0 5452.8 5472.9 5497.5 4777.5 4908.6 4926.9 4983.9 5060.0 4966.7 5233.7 5245.3 5261.8 5268.3 4558.9 5304.2 5433.3 5475.5 5480.4 3210.4 3321.7 3395.5 3579.8 3615.7 4852.2 5023.5 5039.6 5606.3 5758.7 4970.6 5107.8 5525.9 5583.9 5609.0 5256.2 5410.7 5598.5 5608.0 5708.4 5398.6 5418.8 5464.9 5499.1 5532.4 4379.1 4873.5 5291.5 5463.8 5474.9 4274.0 5407.5 5424.6 5433.6 5443.5 06:36:00 INFO - PROCESS | 1646 | 06:36:00 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20890.65 Median:25356.52 stddev:31395.60 (123.8%) stddev-sans-first:31447.07 06:36:00 INFO - PROCESS | 1646 | Values: 701.3 725.8 761.5 793.3 810.6 15185.5 15749.4 15751.8 15768.9 15802.8 12074.0 14155.7 14218.9 14370.6 14382.0 649.7 676.5 678.2 808.5 808.7 830.8 838.4 850.1 853.7 855.8 841.5 841.6 869.3 906.2 912.4 692.2 716.9 904.4 905.1 907.0 639.5 662.3 671.5 673.5 674.1 12693.6 14586.2 14767.3 14804.2 14834.9 13469.0 13668.0 13712.6 13741.7 13784.5 12240.9 12315.3 12346.9 12501.4 12875.9 4284.1 4295.6 4377.9 4481.5 4632.9 83769.0 100704.5 101036.0 101265.0 101540.5 10028.9 10305.7 10874.3 10917.9 10921.7 18223.6 21474.2 21507.7 21514.8 21559.0 7600.0 7708.1 7797.5 8016.0 8020.9 4266.7 4299.0 4300.3 4471.2 4486.2 3398.3 3506.8 3512.1 3517.3 3525.2 602.8 615.1 615.8 615.8 615.9 499.7 541.7 564.6 570.9 633.5 8732.5 8828.2 8846.2 8922.2 8933.7 577.5 623.7 625.7 637.5 637.8 3491.4 4067.3 4252.0 4313.4 4410.8 110550.0 113857.9 115836.0 117414.4 120786.0 90918.5 92779.0 94799.1 95540.0 97041.0 88980.0 89594.0 89801.0 90725.0 92321.5 8107.7 8374.2 8409.5 8518.4 8528.4 45843.0 46080.2 53762.7 55960.4 56008.2 63872.0 68099.6 79796.0 80012.0 80398.0 11487.6 11654.5 11861.6 11885.7 12089.9 12072.4 12171.4 12184.3 12567.7 12648.9 11691.4 11763.1 11786.7 11950.9 12138.7 11617.9 11908.8 11931.3 11943.8 12242.6 11513.3 11574.2 11857.2 11883.3 12179.3 9345.4 9349.9 9553.5 9904.0 10010.8 7974.6 9896.3 9917.5 9927.5 9938.3 06:36:00 INFO - PROCESS | 1646 | -------- Summary: end -------- 06:36:00 INFO - PROCESS | 1646 | 06:36:01 INFO - TEST-INFO | 1646: exit 0 06:36:01 INFO - TEST-OK | dromaeo_css | took 1120270ms 06:36:01 INFO - TEST-START | kraken 06:36:01 INFO - operating with platform_type : mac_ 06:36:01 INFO - Initialising browser for kraken test... 06:36:01 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpb9e7FG/profile http://localhost:49247/getInfo.html) 06:36:03 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 06:36:03 INFO - PROCESS | 1652 | colorDepth:24 06:36:03 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 06:36:03 INFO - PROCESS | 1652 | __metrics 06:36:03 INFO - PROCESS | 1652 | 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" 06:36:03 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:36:04 INFO - TEST-INFO | 1652: exit 0 06:36:04 INFO - Browser initialized. 06:36:04 INFO - Running cycle 1/1 for kraken test... 06:36:04 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpb9e7FG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:36:17 INFO - PROCESS | 1654 | RSS: Main: 259346432 06:36:17 INFO - PROCESS | 1654 | 06:36:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 06:36:20 INFO - PROCESS | 1654 | RSS: Main: 308367360 06:36:20 INFO - PROCESS | 1654 | 06:36:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 06:36:23 INFO - PROCESS | 1654 | RSS: Main: 308359168 06:36:23 INFO - PROCESS | 1654 | 06:36:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 06:36:27 INFO - PROCESS | 1654 | RSS: Main: 399237120 06:36:27 INFO - PROCESS | 1654 | 06:36:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 06:36:30 INFO - PROCESS | 1654 | RSS: Main: 300425216 06:36:30 INFO - PROCESS | 1654 | 06:36:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:36:33 INFO - PROCESS | 1654 | RSS: Main: 303755264 06:36:33 INFO - PROCESS | 1654 | 06:36:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 06:36:36 INFO - PROCESS | 1654 | RSS: Main: 346300416 06:36:36 INFO - PROCESS | 1654 | 06:36:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 06:36:40 INFO - PROCESS | 1654 | RSS: Main: 347807744 06:36:40 INFO - PROCESS | 1654 | 06:36:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 06:36:43 INFO - PROCESS | 1654 | RSS: Main: 344121344 06:36:43 INFO - PROCESS | 1654 | 06:36:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:36:45 INFO - PROCESS | 1654 | RSS: Main: 321142784 06:36:45 INFO - PROCESS | 1654 | 06:36:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:36:48 INFO - PROCESS | 1654 | RSS: Main: 325840896 06:36:48 INFO - PROCESS | 1654 | 06:36:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:36:51 INFO - PROCESS | 1654 | RSS: Main: 328146944 06:36:51 INFO - PROCESS | 1654 | 06:36:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:36:55 INFO - PROCESS | 1654 | RSS: Main: 342626304 06:36:55 INFO - PROCESS | 1654 | 06:36:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:36:58 INFO - PROCESS | 1654 | RSS: Main: 336486400 06:36:58 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 06:37:00 INFO - PROCESS | 1654 | RSS: Main: 380473344 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | __start_tp_report 06:37:00 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 06:37:00 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 06:37:00 INFO - PROCESS | 1654 | |i|pagename|runs| 06:37:00 INFO - PROCESS | 1654 | |0;ai-astar;113;113;114;110;107;114;114;114;114;114 06:37:00 INFO - PROCESS | 1654 | |1;audio-beat-detection;155;138;142;149;117;115;115;114;135;114 06:37:00 INFO - PROCESS | 1654 | |2;audio-dft;184;185;183;186;183;182;182;204;252;182 06:37:00 INFO - PROCESS | 1654 | |3;audio-fft;80;59;58;85;63;78;84;79;74;57 06:37:00 INFO - PROCESS | 1654 | |4;audio-oscillator;89;71;83;69;69;69;82;65;68;83 06:37:00 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;87;84;87;83;86;83;87;84;85;84 06:37:00 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;91;93;96;93;92;93;97;126;148 06:37:00 INFO - PROCESS | 1654 | |7;imaging-desaturate;87;86;88;88;86;87;89;86;87;86 06:37:00 INFO - PROCESS | 1654 | |8;json-parse-financial;56;55;54;54;55;56;57;55;55;54 06:37:00 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;65;72;75;71;72;70;69;71;69;70 06:37:00 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;90;88;89;88;87;75;101;98;108;106 06:37:00 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;150;157;153;158;151;146;166;151;216;172 06:37:00 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;175;175;172;226;190;181;238;209;174;176 06:37:00 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;70;69;70;71;70;70;67;70;69;69 06:37:00 INFO - PROCESS | 1654 | __end_tp_report 06:37:00 INFO - PROCESS | 1654 | __start_cc_report 06:37:00 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1971 06:37:00 INFO - PROCESS | 1654 | __end_cc_report 06:37:00 INFO - PROCESS | 1654 | __startTimestamp1480516620930__endTimestamp 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | ------- Summary: start ------- 06:37:00 INFO - PROCESS | 1654 | Number of tests: 14 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:2.36 (2.1%) stddev-sans-first:2.50 06:37:00 INFO - PROCESS | 1654 | Values: 113.0 113.0 114.0 110.0 107.0 114.0 114.0 114.0 114.0 114.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:136.50 stddev:16.15 (11.8%) stddev-sans-first:14.22 06:37:00 INFO - PROCESS | 1654 | Values: 155.0 138.0 142.0 149.0 117.0 115.0 115.0 114.0 135.0 114.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:192.30 Median:184.50 stddev:22.00 (11.9%) stddev-sans-first:23.12 06:37:00 INFO - PROCESS | 1654 | Values: 184.0 185.0 183.0 186.0 183.0 182.0 182.0 204.0 252.0 182.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:71.70 Median:78.50 stddev:11.24 (14.3%) stddev-sans-first:11.51 06:37:00 INFO - PROCESS | 1654 | Values: 80.0 59.0 58.0 85.0 63.0 78.0 84.0 79.0 74.0 57.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:76.50 stddev:8.47 (11.1%) stddev-sans-first:7.26 06:37:00 INFO - PROCESS | 1654 | Values: 89.0 71.0 83.0 69.0 69.0 69.0 82.0 65.0 68.0 83.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:85.50 stddev:1.63 (1.9%) stddev-sans-first:1.56 06:37:00 INFO - PROCESS | 1654 | Values: 87.0 84.0 87.0 83.0 86.0 83.0 87.0 84.0 85.0 84.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:93.00 stddev:19.14 (20.6%) stddev-sans-first:20.01 06:37:00 INFO - PROCESS | 1654 | Values: 93.0 91.0 93.0 96.0 93.0 92.0 93.0 97.0 126.0 148.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:87.00 stddev:1.05 (1.2%) stddev-sans-first:1.12 06:37:00 INFO - PROCESS | 1654 | Values: 87.0 86.0 88.0 88.0 86.0 87.0 89.0 86.0 87.0 86.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 06:37:00 INFO - PROCESS | 1654 | Values: 56.0 55.0 54.0 54.0 55.0 56.0 57.0 55.0 55.0 54.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.59 (3.6%) stddev-sans-first:1.87 06:37:00 INFO - PROCESS | 1654 | Values: 65.0 72.0 75.0 71.0 72.0 70.0 69.0 71.0 69.0 70.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:88.00 stddev:10.10 (11.5%) stddev-sans-first:10.65 06:37:00 INFO - PROCESS | 1654 | Values: 90.0 88.0 89.0 88.0 87.0 75.0 101.0 98.0 108.0 106.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:162.00 Median:157.50 stddev:20.54 (13.0%) stddev-sans-first:21.32 06:37:00 INFO - PROCESS | 1654 | Values: 150.0 157.0 153.0 158.0 151.0 146.0 166.0 151.0 216.0 172.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:185.50 stddev:24.13 (13.0%) stddev-sans-first:24.84 06:37:00 INFO - PROCESS | 1654 | Values: 175.0 175.0 172.0 226.0 190.0 181.0 238.0 209.0 174.0 176.0 06:37:00 INFO - PROCESS | 1654 | 06:37:00 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.08 (1.5%) stddev-sans-first:1.13 06:37:00 INFO - PROCESS | 1654 | Values: 70.0 69.0 70.0 71.0 70.0 70.0 67.0 70.0 69.0 69.0 06:37:00 INFO - PROCESS | 1654 | -------- Summary: end -------- 06:37:00 INFO - PROCESS | 1654 | 06:37:01 INFO - TEST-INFO | 1654: exit 0 06:37:02 INFO - TEST-OK | kraken | took 60840ms 06:37:02 INFO - SUITE-END | took 1181s 06:37:02 INFO - Completed test suite (00:19:41) 06:37:02 INFO - javascript benchmark 06:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2815.2680303121188, 3347.7489188310883, 3353.1449480382507, 3383.035834787086, 3406.9659303406956, 5570.359408668, 5588.91616625751, 5645.633033852797, 5691.715414229293, 5697.515711164551, 5639.943600563993, 5660.801871934483, 5668.914966275499, 5684.715764211784, 5685.289338832646, 5141.4087379951225, 5165.560927321182, 5173.0, 5195.818146364878, 5213.165893457048, 5249.685018898878, 5253.7110458924935, 5260.92108618371, 5260.947390526103, 5266.025785229725, 5160.380754309488, 5166.225066240057, 5201.68789872607, 5201.791928322881, 5250.396204436482, 5233.555147812441, 5242.2136679498, 5248.0, 5253.9474605253845, 5318.414974352818, 5078.390593128848, 5078.5937125029905, 5129.0, 5178.741062946875, 5182.352205974254, 5551.333839939233, 5597.552195824324, 5626.493615574618, 5638.238837756891, 5639.943600563983, 6174.598651087665, 6180.660063696495, 6181.690915454209, 6190.907136392912, 6199.690015499252, 6111.227117700316, 6275.058741188858, 6300.747970081191, 6306.432421082079, 6308.93691063088, 5414.756335964891, 5662.773489060432, 5673.375928647845, 5694.459026392569, 5774.566907481955, 6324.019776934582, 6353.110564520971, 6359.046143078575, 6368.872622547523, 6371.980483122771, 5921.02303119989, 6065.787697430569, 6067.908981365283, 6070.332263451017, 6076.696165191811, 6060.424259695322, 6140.416660417253, 6144.539159562962, 6164.753409863645, 6175.876482470326, 6172.043333283348, 6183.84540386494, 6186.721597528122, 6191.845203869849, 6196.318404975449, 6180.845478863064, 6186.19579454677, 6194.876102477925, 6199.907001394983, 6205.751769929152, 4745.572898439157, 4757.500462451462, 4816.95183048172, 4845.491223421561, 4912.72979986097, 4314.590113939171, 4376.715513491613, 4393.29707246839, 4409.603135717801, 4447.08834688888, 5576.470235327637, 5608.214849921014, 5667.971660141673, 5694.914576281359, 5720.628159169641, 6071.51427885768, 6105.877882442326, 6264.561480696308, 6284.308726040132, 6305.4009869062365, 5560.527355174855, 5610.0, 5620.718964051781, 5625.971870140623, 5638.887222255532, 5325.281087053276, 5328.067588172132, 5334.306540149833, 5342.332208473941, 5343.465653434703, 6340.968295158495, 6355.5551111421755, 6359.0, 6360.650164241011, 6373.553851230369, 6136.447719705248, 6146.0639898431855, 6168.506519478341, 6170.6914654268, 6202.131701561875, 6248.968755156377, 6316.336784637547, 6354.3963323484195, 6377.74489020434, 6390.48876089921, 6070.6357618543025, 6083.6045657032155, 6086.847828804315, 6110.2973158085615, 6121.571489995836, 6067.848303792441, 6110.315755985069, 6137.723802428991, 6202.317745048132, 6226.346233645583, 5948.10778383246, 5980.192673988956, 5998.310194327708, 6014.48876845473, 6042.305134909541, 5960.0761881908375, 5977.282726072899, 5985.820425387245, 6001.129836173817, 6023.247094113236, 5645.858853528694, 5700.3444603871085, 5831.7375718093635, 5833.212516310414, 5840.386759390203, 5558.249636299048, 5978.342382338026, 5982.88034239304, 6056.091586262096, 6128.295246046761, 5871.1486834407915, 5871.354151043467, 5883.294004719461, 5886.499647530008, 5915.142304365929], "value": 5650.59083901974, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.85659349702956, 467.5464799144829, 474.459116607068, 480.1788940911042, 511.6648595170165, 38923.0, 40413.59586404135, 40437.393439098436, 40460.59539404609, 40480.0, 39132.60867391325, 39499.80250098753, 39609.0, 39671.40492892609, 39830.60169398305, 7661.923380766205, 8468.983721953344, 8744.387892847502, 9333.06669333068, 9457.243420526376, 8470.195331443534, 11261.324320540753, 11505.367204803733, 11526.827097593543, 11677.649670509856, 11345.09239260857, 11402.372869492174, 11408.258463199865, 11424.600138995136, 11466.0, 8460.280876125536, 11321.773564528705, 11354.716132096764, 11394.259373140761, 11396.088312934946, 8444.451110677786, 8579.914200858037, 8583.82832343356, 8593.355498337649, 10988.230823842334, 38447.231055379016, 38730.80634596809, 38848.41727374092, 38882.222355553014, 38885.22229555393, 3205.6473787883315, 3233.1917020744813, 3301.273719781644, 3329.6170940341926, 3638.6907112895437, 22236.88881555598, 27419.588706169423, 29448.263793405124, 29992.400151997055, 30091.0, 3215.6302025266928, 3244.853981570835, 3313.287643156733, 3350.9832450837835, 3353.815540145291, 469070.0, 473098.0, 474962.6251868753, 475012.0, 475476.0, 59175.70412147912, 59408.70295648494, 59515.80968380608, 59572.0, 59607.10589341163, 52968.47031529713, 59764.0, 60757.08864367038, 62238.0, 62244.37755622386, 15237.780977521772, 15532.767008494882, 15536.0, 15540.533783986499, 15570.377184912477, 10927.235093543535, 11145.275557088926, 11839.467223974942, 11871.109666775026, 12026.699332516582, 16314.67370652604, 16690.666186676197, 16757.32970681159, 16797.664046718997, 16868.072256026036, 5737.385447168257, 5841.9415805842245, 6111.266648002268, 6136.325004249529, 6136.938630613726, 756.8259300360837, 868.891388576428, 919.6965001549472, 961.8316794561064, 991.8710567626163, 27876.44247115046, 28776.0, 28954.420911581652, 28982.855085724437, 29497.85251073731, 1008.3748076192808, 1051.0, 1060.4644654449608, 1110.9501521062518, 1125.9493322800495, 14645.92677036608, 14957.401703931939, 15065.397384104732, 15309.540713778604, 15376.077435353915, 610497.0, 685682.0, 692254.0, 693085.0, 897360.0, 56898.14652780211, 57812.13281800776, 57819.71090144522, 57833.710831445576, 57934.71032644894, 1309.1032642639823, 1342.8052932324758, 1352.51985545134, 1355.6746380868522, 1366.364640442199, 28027.019054333, 29810.0, 29890.17141657443, 30228.33601005513, 30467.39065218639, 43955.78022109997, 50712.0, 50713.0, 50758.7310317245, 50939.98120037504, 577694.0, 580103.0, 580937.0, 581093.0, 582125.0, 22500.0, 24300.51398972046, 25438.0, 25521.234362969142, 25645.230643080737, 35377.646223537435, 41135.58864411317, 42829.357559637254, 45092.32361514645, 46243.76878115588, 37150.9566973819, 37283.0, 37330.62669373272, 38219.0, 38999.610003900736, 35233.00519455875, 37007.62992370042, 37664.246715066096, 37848.24303513969, 38083.428748568236, 31929.521057183698, 36359.90920272396, 37167.0, 37214.8139259302, 37303.44044839275, 31621.683783161872, 33022.74431392152, 33380.33239335248, 33477.66522334745, 33580.49629255612, 24498.51002979966, 25153.74846251514, 25231.0, 25807.096751613604, 26067.543391919837], "value": 19449.433991280548, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.05834305717605, 368.9520362352895, 372.48782923480195, 381.12721866924744, 399.1000294336271, 14455.855441445596, 14778.187199704025, 15199.708752873834, 20065.1973921043, 20157.798422015774, 19119.04404779759, 19586.314478993238, 20325.186992520317, 20332.0, 20608.8969555152, 5572.91328191003, 5591.692456914868, 5904.084866845646, 5909.054551271797, 5914.290285165786, 4634.930476042845, 5822.475977162055, 5862.560307976897, 5863.677497737623, 5874.97062514689, 5470.890582188374, 5826.067829147337, 5830.008898487267, 5832.1834943108, 5837.0, 5655.886882262353, 5795.1886735857015, 5814.331351894544, 5814.7092645367775, 5820.010598198316, 5084.593232541388, 5089.872753181163, 5097.490250974873, 7130.714771409137, 7497.938067031555, 26248.606270905762, 27188.912443502235, 27292.863535682394, 27470.862645686844, 27686.73951949123, 2313.144136669426, 2386.7613238676167, 2403.218953840009, 2412.593140491363, 2621.921342359732, 15804.841951580453, 15865.341481163998, 16231.675366492604, 16345.591360216089, 16951.321947122102, 1889.725989731495, 1917.7698676158811, 1963.577830766392, 1964.4401345616432, 2046.9590608187902, 82747.0, 120096.39951800273, 123763.0, 129305.0, 136705.0, 16061.196940152946, 17667.911660441616, 19362.25509796093, 20691.0, 20697.68953465699, 29391.0, 33898.98303050912, 33904.0, 34023.65976340205, 34033.0, 9965.501724913725, 10105.595776168871, 10260.71741032371, 10291.176705863512, 10502.05481506668, 8304.147602795863, 8386.077531471534, 8398.0, 8564.272036876811, 8574.571271436404, 9349.97150313465, 9576.712698619052, 9611.038896110333, 9621.326507144433, 9659.32384733062, 3111.6110486189227, 3608.404613238786, 3644.817759112034, 3648.288583726148, 3756.962430375716, 560.2828379674024, 620.5780069552658, 664.0338307762195, 720.9387202087881, 957.2246480350938, 20262.89868550648, 20898.477538061365, 21143.682844757343, 21421.67867481989, 21688.783112168676, 927.536231884058, 953.9744774367445, 954.4130359828794, 963.6241865672393, 990.321629683669, 9112.952010518742, 10304.175665946856, 10704.411257380763, 11296.774064518664, 11372.260803047775, 588280.0, 588558.0, 592717.0, 611968.0, 612088.0, 109958.90041099646, 112121.0, 118043.0, 120401.39799300948, 128017.35991319983, 988.2736188901296, 1028.2442404832443, 1064.302881612545, 1068.7435015596357, 1069.2194697870443, 18444.63110737751, 18642.72035919435, 18677.533061673024, 18993.810061899203, 19043.255223248416, 51732.48267517427, 52401.213981789544, 54032.0, 54484.0, 55306.17040744312, 70989.0, 72059.63970180115, 72487.0, 73980.63009685132, 74988.56517848177, 6995.160580730345, 7806.453548251568, 7963.442559020813, 8062.4356295061125, 8283.751487455385, 10385.376877387382, 12303.261804291771, 12319.242630997633, 12979.480820767063, 13158.0, 10331.535080921227, 10750.46247687628, 11076.280041797258, 11104.500297486471, 11389.715257118307, 10626.415547144981, 10711.25021248505, 11024.0, 11232.775344493228, 11297.209195356574, 10912.399818009768, 10939.12487001022, 11075.169362298022, 11204.383758893338, 11497.367644779617, 6831.6242606657115, 7143.357097861218, 7312.341889229995, 7339.816504587215, 7506.28690274423, 8574.22831945128, 9338.879334479634, 10590.311629744043, 10992.120630349713, 11054.889451105386], "value": 10293.54758920444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.778838703227, 1490.4336352186167, 1587.6983373159098, 1773.618671985523, 1830.2313028528026, 7474.738384156555, 8112.634931428085, 8477.237048665618, 8525.19010693983, 8531.27484163846, 7552.509086909347, 7716.614169291541, 7969.123396426403, 8152.5516096614665, 8198.139195384474, 2259.1076524772748, 2289.53064621753, 2292.358139720881, 2300.723913130427, 2300.9539809203807, 2141.1970511058353, 2155.6335422978054, 2180.6728990651372, 2187.1907394264144, 2189.879556624385, 1973.279752890192, 2047.8566500344982, 2051.33331667208, 2058.3001779395004, 2063.6285468615642, 1768.4164225805519, 2224.6218142915664, 2303.2974942642486, 2306.423394151462, 2308.03062713659, 2290.393045842853, 2312.5606134834497, 2320.2691152286975, 2322.744498105207, 2323.93028209154, 8199.303059239972, 8280.420370574062, 8314.41799074065, 8334.083250842403, 8426.957865210634, 5025.246213068032, 5181.274621552986, 5182.0, 5191.844244672666, 5196.740162991873, 3405.1827561385335, 3455.4947662944164, 3462.1344663834043, 3503.2292895562937, 3543.3976224041976, 916.3310783128287, 1000.6247657128577, 1052.0689190066846, 1052.9157667386667, 1064.0529928363762, 10653.627123050734, 10783.46082695862, 10790.244682872202, 10795.190360722978, 10812.78374432507, 4410.691251612357, 4523.705959112647, 4598.218302888517, 4616.3537104805355, 4705.12955103307, 9487.098725621056, 9487.66793162236, 9491.905080949102, 9493.193078588398, 9504.239660827117, 5050.090983622909, 5193.246979187997, 5195.766190521435, 5206.0368831766245, 5206.479352064764, 4486.2597671383855, 4726.432828060586, 4752.405949256343, 4752.928706069411, 4758.143534163814, 3645.854165833337, 3710.1466662667735, 3748.2878253131817, 3763.2473505298503, 3789.886303410902, 1217.6834023154045, 1221.126894270583, 1221.969450763738, 1237.5977807212691, 1240.6526172671665, 751.3913729878816, 812.8455593437228, 869.1265278395299, 872.6901949807853, 913.8949020862553, 5518.2826232589505, 5566.248556444828, 5658.0, 5666.0, 5765.221695071195, 418.32440608417187, 424.7196850078933, 427.55961359799454, 435.5165765999738, 446.9329600559942, 3621.2214373909737, 3658.8536458541894, 3677.5035370224678, 3703.333399988028, 3859.3439115350457, 9576.281778866613, 10140.087392134743, 10146.188304935587, 10165.695029149138, 10172.237082218864, 10074.647387341407, 10076.193904487624, 10098.242631802497, 10113.69658910234, 10115.645952391631, 7346.9346944692725, 7692.499987500906, 7701.1143718473095, 7784.260495252942, 7813.531188128731, 4528.592426681615, 5661.547076233974, 5677.9495793278375, 5776.480116789509, 5918.674472904032, 9072.95463522678, 9234.907650923406, 9251.427257366398, 9296.0, 9448.149666529771, 9086.681966131153, 9418.670346537838, 9538.23694104456, 9598.616055357708, 9607.0, 7126.928730712834, 7225.458090643117, 7298.744543940937, 7304.744333948286, 7316.853662926818, 7786.883196752166, 7790.766277011699, 7812.218778122142, 7827.45207835446, 7925.920740792519, 5750.194972703741, 7010.789676309719, 7047.823804404931, 7140.750073747394, 7246.782596522113, 6693.665316734046, 6785.117934668461, 6829.69266383004, 7053.541519801196, 7072.434205263669, 5708.914366284592, 6624.138861947916, 6654.434373078343, 6684.6991885364305, 6694.230163531256, 4748.192807222849, 4781.306710526885, 4820.373351464287, 4837.830675926326, 4959.082569724613, 4806.975965120152, 4859.635527335535, 4860.149473841992, 4878.56092951636, 4963.057019166346], "value": 4267.267526707536, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.38367039404415, 302.56279675868365, 309.53260576529436, 315.41647951290133, 316.48729058924545, 5171.948280517194, 5230.555402790763, 5277.234800953859, 5297.841064768063, 5305.973470132653, 4593.680191503295, 4791.065742180276, 5050.979608156739, 5218.608604354679, 5221.921671174926, 3332.500124981248, 4057.9797101014456, 4070.7557546547155, 4073.246449406869, 4081.653059489947, 3659.9752069420606, 3885.261800257942, 4005.3791662292247, 4044.292248856453, 4052.7568345899194, 3480.129967508123, 3654.689351405134, 3740.28934502445, 3934.527856657191, 4074.4109797178976, 3464.0, 3983.342748446503, 4021.853771675088, 4036.2936486114813, 4056.5132184137797, 2624.448865738178, 3372.7470439717117, 3424.9725082475156, 3426.2462258303135, 3465.6014558325865, 4197.8320867165585, 4769.666123371366, 5054.772535235923, 5070.98580283941, 5073.518015788494, 3767.981160094182, 4195.860111343206, 4349.5646436675825, 4462.665300102472, 4587.357769912215, 5299.470052994709, 5530.364008139075, 5536.750846211889, 5544.334679838405, 5556.361018482846, 3407.505911642798, 3785.2429514097016, 3800.2969450651717, 3801.82891769871, 3812.7712337259854, 4326.675499337531, 4868.707877527359, 5155.407128180233, 5815.139155470209, 5981.8803623927715, 3368.1916340078205, 3781.3949768036982, 3816.3702989007056, 3835.5780864104927, 3839.865604703822, 3582.0865679251624, 3849.7882616456363, 3895.941560876589, 3901.921961560753, 3902.9609703902597, 4363.803628836647, 4479.259818051898, 4489.124620698933, 4515.683902126885, 4540.659450541222, 4652.813887444532, 5492.423295553909, 5504.2018907258225, 5526.281583394133, 5536.9203005414365, 1232.8582213045615, 1248.3196657821509, 1249.3877999780043, 1250.3060801255208, 1264.4373253902108, 710.523948954202, 744.9068866391701, 792.9722459713997, 804.3565147881671, 850.6597361055627, 4321.114171594815, 4335.82656693735, 4661.294271054519, 4794.544518270759, 4828.348172996601, 4795.784189711471, 5009.998000399862, 5160.509751573594, 5376.9941393661975, 5425.426626278335, 4363.258246098171, 4444.355568442623, 4638.744869032235, 4754.168020596382, 4838.903102014713, 2414.625733011386, 2582.9741702582733, 2781.9443611127663, 2784.4431113777327, 2789.3305606654258, 4921.483244259372, 5245.370555533361, 5467.343918729778, 5536.058869992111, 5545.445455454503, 4673.766311684402, 5137.357830271216, 5212.921806172911, 5407.432219616883, 5451.509364157245, 4517.351166824085, 5041.974790126173, 5452.809151679672, 5472.878059997691, 5497.505224529731, 4777.546133117348, 4908.6073114151495, 4926.901461970669, 4983.9003219934675, 5060.0, 4966.652334336562, 5233.7383130842545, 5245.279248676069, 5261.815836445706, 5268.341457317835, 4558.931616025696, 5304.23088652151, 5433.293671822637, 5475.479829416199, 5480.369757477782, 3210.4221240176057, 3321.6617299613736, 3395.541601883714, 3579.803110828929, 3615.7468977171347, 4852.1508735971765, 5023.518062171595, 5039.6220283479615, 5606.327240730975, 5758.725303505175, 4970.6023518117745, 5107.770150343169, 5525.938435250007, 5583.944160558342, 5609.0, 5256.159014557652, 5410.702411367255, 5598.460423383602, 5608.0, 5708.372079071216, 5398.568114550748, 5418.83743487696, 5464.91802622953, 5499.1476321170285, 5532.446755324436, 4379.102284031829, 4873.537013983666, 5291.523762861361, 5463.8201775642465, 5474.945250547444, 4273.97863010683, 5407.486288802557, 5424.593155513273, 5433.646812957145, 5443.510084092451], "value": 3901.178835078847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.277683985495, 725.8185453636587, 761.4783873046963, 793.2622660925338, 810.6230602769713, 15185.544433666993, 15749.448769293078, 15751.763723544156, 15768.92115539422, 15802.841971580281, 12074.0, 14155.716885662281, 14218.942927270635, 14370.640733981656, 14382.0, 649.681655988567, 676.5129107042922, 678.2403707847193, 808.4785313472806, 808.6563210635471, 830.8089139497919, 838.3544670603632, 850.1286181663783, 853.7438768369495, 855.7988872614931, 841.486693117198, 841.6212704283066, 869.3045563549167, 906.1708536688922, 912.4114945859916, 692.1693967239333, 716.9318914703094, 904.3579058868212, 905.108468158863, 906.9660586930871, 639.4916041746823, 662.2979641579941, 671.4997326991422, 673.4713250098649, 674.1438373265973, 12693.619191424272, 14586.186558435691, 14767.26163691811, 14804.185769782658, 14834.925825370912, 13469.0, 13668.0, 13712.588622341345, 13741.725165496735, 13784.517541886085, 12240.938795306056, 12315.26108433488, 12346.876531234753, 12501.3749312534, 12875.935620321838, 4284.057507348379, 4295.634871035965, 4377.912441751179, 4481.5070342262325, 4632.884177895546, 83769.0, 100704.49647751714, 101036.0, 101265.0, 101540.49229753803, 10028.899711002943, 10305.690829275134, 10874.293170943864, 10917.945410272898, 10921.743999440167, 18223.635527289644, 21474.24840130588, 21507.67738483924, 21514.78485215128, 21559.029843657074, 7600.0, 7708.113566939874, 7797.493162944391, 8016.0, 8020.879686804703, 4266.722663026893, 4299.0, 4300.311950087971, 4471.217536931024, 4486.237339652267, 3398.320335932823, 3506.8421921013614, 3512.121969507623, 3517.261375111204, 3525.2244506208594, 602.7588964414182, 615.1326629452451, 615.7506209984963, 615.7690865925279, 615.8737458820931, 499.69019208091214, 541.7047708998526, 564.5511818104617, 570.9172170035322, 633.5311869216746, 8732.519711415805, 8828.161324674145, 8846.203841654282, 8922.221890220264, 8933.687320943736, 577.5091172503338, 623.7349126621168, 625.671522450717, 637.5463942211765, 637.7704026550438, 3491.4239150540384, 4067.2694466320227, 4252.045597594205, 4313.439252897166, 4410.8090815479645, 110550.0, 113857.86142138473, 115836.0, 117414.41292793481, 120786.0, 90918.54540727254, 92779.0, 94799.05200948041, 95540.0, 97041.02958970462, 88980.0, 89594.0, 89801.0, 90725.0, 92321.53839230895, 8107.675692972215, 8374.162583741821, 8409.453385529921, 8518.403711740119, 8528.360372971927, 45843.0, 46080.161583033296, 53762.731186343815, 55960.44039559715, 56008.15987760268, 63872.041919372175, 68099.63800724057, 79796.0, 80012.0, 80398.0, 11487.597934072268, 11654.475548600163, 11861.644150675493, 11885.702857428634, 12089.939550302191, 12072.39638018078, 12171.391430428266, 12184.329861857683, 12567.685807854878, 12648.936755316532, 11691.356975366436, 11763.117766167743, 11786.705332366759, 11950.87998409089, 12138.696532586757, 11617.941910290394, 11908.76182476363, 11931.284122952651, 11943.76112477763, 12242.632721018384, 11513.30920144794, 11574.247673901169, 11857.16999810005, 11883.287002779862, 12179.330136842558, 9345.439273643604, 9349.906500934903, 9553.474558899326, 9903.962183239651, 10010.8498372523, 7974.561399123104, 9896.257780666623, 9917.504124793588, 9927.503624818875, 9938.254630902567], "value": 7203.339804167935, "unit": "score"}], "name": "dromaeo_css", "value": 7168.2992878618115, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 110.0, 107.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 138.0, 142.0, 149.0, 117.0, 115.0, 115.0, 114.0, 135.0, 114.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 186.0, 183.0, 182.0, 182.0, 204.0, 252.0, 182.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 85.0, 63.0, 78.0, 84.0, 79.0, 74.0, 57.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 83.0, 69.0, 69.0, 69.0, 82.0, 65.0, 68.0, 83.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 83.0, 86.0, 83.0, 87.0, 84.0, 85.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 93.0, 96.0, 93.0, 92.0, 93.0, 97.0, 126.0, 148.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 88.0, 86.0, 87.0, 89.0, 86.0, 87.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 54.0, 55.0, 56.0, 57.0, 55.0, 55.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 75.0, 71.0, 72.0, 70.0, 69.0, 71.0, 69.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 88.0, 87.0, 75.0, 101.0, 98.0, 108.0, 106.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 153.0, 158.0, 151.0, 146.0, 166.0, 151.0, 216.0, 172.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 172.0, 226.0, 190.0, 181.0, 238.0, 209.0, 174.0, 176.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 71.0, 70.0, 70.0, 67.0, 70.0, 69.0, 69.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1496.6999999999998, "alertThreshold": 2.0}]} 06:37:02 INFO - Return code: 0 06:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:37:02 INFO - # TBPL SUCCESS # 06:37:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:37:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:37:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:37:02 INFO - 2016-11-30 06:37:02.233 screenresolution[1658:11253] starting screenresolution argv=screenresolution get 06:37:02 INFO - 2016-11-30 06:37:02.238 screenresolution[1658:11253] Display 0: 1600x1200x32@60 06:37:02 INFO - 2016-11-30 06:37:02.244 screenresolution[1659:11256] starting screenresolution argv=screenresolution list 06:37:02 INFO - Available Modes on Display 0 06:37:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:37:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:37:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:37:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:37:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:37:02 INFO - Intel Iris: 06:37:02 INFO - Chipset Model: Intel Iris 06:37:02 INFO - Type: GPU 06:37:02 INFO - Bus: Built-In 06:37:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:37:02 INFO - Vendor: Intel (0x8086) 06:37:02 INFO - Device ID: 0x0a2e 06:37:02 INFO - Revision ID: 0x0009 06:37:02 INFO - Displays: 06:37:02 INFO - Display: 06:37:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:37:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:37:02 INFO - Display Serial Number: 1600x1200 60 06:37:02 INFO - Main Display: Yes 06:37:02 INFO - Mirror: Off 06:37:02 INFO - Online: Yes 06:37:02 INFO - Rotation: Supported 06:37:02 INFO - Return code: 0 06:37:02 INFO - Running post-action listener: _resource_record_post_action 06:37:02 INFO - [mozharness: 2016-11-30 14:37:02.495688Z] Finished run-tests step (success) 06:37:02 INFO - Running post-run listener: _resource_record_post_run 06:37:02 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 33610752; Write bytes: 692051456; Read time: 317; Write time: 2183 06:37:02 INFO - TinderboxPrint: CPU usage
53.8% 06:37:02 INFO - TinderboxPrint: I/O read bytes / time
33,610,752 / 317 06:37:02 INFO - TinderboxPrint: I/O write bytes / time
692,051,456 / 2,183 06:37:02 INFO - TinderboxPrint: CPU idle
2,241.8 (46.2%) 06:37:02 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 06:37:02 INFO - TinderboxPrint: CPU user
1,640.7 (33.8%) 06:37:02 INFO - TinderboxPrint: Swap in / out
413,270,016 / 0 06:37:02 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 384573952; Write bytes: 387060736; Read time: 29345; Write time: 731 06:37:02 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 29166592; Write bytes: 303634944; Read time: 279; Write time: 1447 06:37:02 INFO - Running post-run listener: _upload_blobber_files 06:37:02 INFO - Blob upload gear active. 06:37:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:37:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:37:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:37:03 INFO - (blobuploader) - INFO - Open directory for files ... 06:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:05 INFO - (blobuploader) - INFO - Done attempting. 06:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:06 INFO - (blobuploader) - INFO - Done attempting. 06:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:37:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:37:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:37:08 INFO - (blobuploader) - INFO - Done attempting. 06:37:08 INFO - (blobuploader) - INFO - Iteration through files over. 06:37:08 INFO - Return code: 0 06:37:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:37:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:37:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac8b1fcbcad7fe3af6c53a088bdbd4831ef2712bec8e5fb438cd6024d0785dec84a882bb8a7cf4c3ac328c2622b6d4af4e0f572936fd3b9d6d6ef508d5e0767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b93d953bb75ee46e9687efcac12abac0e0aa88416d6db8069909151b43d812f03553223a9eb530a02fbcca91a4e66fddb9c5201d7355314fb5420f76a6a07a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0962efbddb12b38d2b9498fa01bbda57456a9378a9e15c6b25f413f5c530a169e419cc3bd697805fb77dc4946b07cd0955ca7fbed9be667e2a6ed9882d081aa8"} 06:37:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:37:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:37:08 INFO - Contents: 06:37:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac8b1fcbcad7fe3af6c53a088bdbd4831ef2712bec8e5fb438cd6024d0785dec84a882bb8a7cf4c3ac328c2622b6d4af4e0f572936fd3b9d6d6ef508d5e0767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b93d953bb75ee46e9687efcac12abac0e0aa88416d6db8069909151b43d812f03553223a9eb530a02fbcca91a4e66fddb9c5201d7355314fb5420f76a6a07a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0962efbddb12b38d2b9498fa01bbda57456a9378a9e15c6b25f413f5c530a169e419cc3bd697805fb77dc4946b07cd0955ca7fbed9be667e2a6ed9882d081aa8"} 06:37:08 INFO - Running post-run listener: copy_logs_to_upload_dir 06:37:08 INFO - Copying logs to upload dir... 06:37:08 INFO - mkdir: /builds/slave/test/build/upload/logs 06:37:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.897509 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 06:37:08.511923) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 06:37:08.515854) ========= 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.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/eac8b1fcbcad7fe3af6c53a088bdbd4831ef2712bec8e5fb438cd6024d0785dec84a882bb8a7cf4c3ac328c2622b6d4af4e0f572936fd3b9d6d6ef508d5e0767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b93d953bb75ee46e9687efcac12abac0e0aa88416d6db8069909151b43d812f03553223a9eb530a02fbcca91a4e66fddb9c5201d7355314fb5420f76a6a07a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0962efbddb12b38d2b9498fa01bbda57456a9378a9e15c6b25f413f5c530a169e419cc3bd697805fb77dc4946b07cd0955ca7fbed9be667e2a6ed9882d081aa8"} build_url:https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011071 build_url: 'https://queue.taskcluster.net/v1/task/UXSJrwRLRLmpPgB0v3kGag/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/eac8b1fcbcad7fe3af6c53a088bdbd4831ef2712bec8e5fb438cd6024d0785dec84a882bb8a7cf4c3ac328c2622b6d4af4e0f572936fd3b9d6d6ef508d5e0767", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b93d953bb75ee46e9687efcac12abac0e0aa88416d6db8069909151b43d812f03553223a9eb530a02fbcca91a4e66fddb9c5201d7355314fb5420f76a6a07a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0962efbddb12b38d2b9498fa01bbda57456a9378a9e15c6b25f413f5c530a169e419cc3bd697805fb77dc4946b07cd0955ca7fbed9be667e2a6ed9882d081aa8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 06:37:08.549567) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:37:08.549917) ========= 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.6fc3dpGn6G/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.0amjclpHQc/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005221 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 06:37:08.598305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 06:37:08.598627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 06:37:08.598956) ========= ========= Total master_lag: 0.25 =========