builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1480443144.91 results: success (0) buildid: 20161129074627 builduid: 8024a74ebeb4458b9640a6af793c2000 revision: 9694bdce768041e494a875fd6d2d278dfdfe70d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:24.909349) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:24.909819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:24.910115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:24.937990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:24.938373) ========= 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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 10:12:24-- 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% 173M=0s 2016-11-29 10:12:25 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132039 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:25.095902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:25.096268) ========= 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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.025986 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:25.146980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 10:12:25.147326) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9694bdce768041e494a875fd6d2d278dfdfe70d0 --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 9694bdce768041e494a875fd6d2d278dfdfe70d0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 10:12:25,245 truncating revision to first 12 chars 2016-11-29 10:12:25,246 Setting DEBUG logging. 2016-11-29 10:12:25,246 attempt 1/10 2016-11-29 10:12:25,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9694bdce7680?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 10:12:25,974 unpacking tar archive at: mozilla-central-9694bdce7680/testing/mozharness/ program finished with exit code 0 elapsedTime=1.009960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 10:12:26.180796) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:26.181127) ========= script_repo_revision: 9694bdce768041e494a875fd6d2d278dfdfe70d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:26.181524) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:26.181789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 10:12:26.202119) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-29 10:12:26.202505) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:12:26 INFO - MultiFileLogger online at 20161129 10:12:26 in /builds/slave/test 10:12:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:12:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:12:26 INFO - {'append_to_log': False, 10:12:26 INFO - 'base_work_dir': '/builds/slave/test', 10:12:26 INFO - 'blob_upload_branch': 'Firefox', 10:12:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:12:26 INFO - 'branch': 'Firefox', 10:12:26 INFO - 'buildbot_json_path': 'buildprops.json', 10:12:26 INFO - 'config_files': ('talos/mac_config.py',), 10:12:26 INFO - 'default_actions': ('clobber', 10:12:26 INFO - 'read-buildbot-config', 10:12:26 INFO - 'download-and-extract', 10:12:26 INFO - 'populate-webroot', 10:12:26 INFO - 'create-virtualenv', 10:12:26 INFO - 'install', 10:12:26 INFO - 'run-tests'), 10:12:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:12:26 INFO - 'download_minidump_stackwalk': True, 10:12:26 INFO - 'download_symbols': 'ondemand', 10:12:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:12:26 INFO - 'tooltool.py': '/tools/tooltool.py', 10:12:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:12:26 INFO - '/tools/misc-python/virtualenv.py')}, 10:12:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:12:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:12:26 INFO - 'installer_path': 'installer.exe', 10:12:26 INFO - 'log_level': 'info', 10:12:26 INFO - 'log_name': 'talos', 10:12:26 INFO - 'log_to_console': True, 10:12:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:12:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:12:26 INFO - 'opt_config_files': (), 10:12:26 INFO - 'pip_index': False, 10:12:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:12:26 INFO - 'cmd': ('bash', 10:12:26 INFO - '-c', 10:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:12:26 INFO - 'enabled': True, 10:12:26 INFO - 'halt_on_failure': False, 10:12:26 INFO - 'name': 'check_screen_resolution'},), 10:12:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:12:26 INFO - 'cmd': ('bash', 10:12:26 INFO - '-c', 10:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:12:26 INFO - 'enabled': True, 10:12:26 INFO - 'halt_on_failure': False, 10:12:26 INFO - 'name': 'check_screen_resolution'},), 10:12:26 INFO - 'run_cmd_checks_enabled': True, 10:12:26 INFO - 'sps_profile': False, 10:12:26 INFO - 'sps_profile_interval': 0, 10:12:26 INFO - 'suite': 'dromaeojs-e10s', 10:12:26 INFO - 'system_bits': '32', 10:12:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:12:26 INFO - 'title': 't-yosemite-r7-0034', 10:12:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:12:26 INFO - 'use_talos_json': True, 10:12:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:12:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:12:26 INFO - 'work_dir': 'build'} 10:12:26 INFO - [mozharness: 2016-11-29 18:12:26.423335Z] Running clobber step. 10:12:26 INFO - Running pre-action listener: _resource_record_pre_action 10:12:26 INFO - Running main action method: clobber 10:12:26 INFO - rmtree: /builds/slave/test/build 10:12:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:12:28 INFO - Running post-action listener: _resource_record_post_action 10:12:28 INFO - [mozharness: 2016-11-29 18:12:28.984311Z] Finished clobber step (success) 10:12:28 INFO - [mozharness: 2016-11-29 18:12:28.984449Z] Running read-buildbot-config step. 10:12:28 INFO - Running pre-action listener: _resource_record_pre_action 10:12:28 INFO - Running main action method: read_buildbot_config 10:12:28 INFO - Using buildbot properties: 10:12:28 INFO - { 10:12:28 INFO - "project": "", 10:12:28 INFO - "product": "firefox", 10:12:28 INFO - "script_repo_revision": "production", 10:12:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:12:28 INFO - "repository": "", 10:12:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:12:28 INFO - "buildid": "20161129074627", 10:12:28 INFO - "pgo_build": "False", 10:12:28 INFO - "basedir": "/builds/slave/test", 10:12:28 INFO - "buildnumber": 100, 10:12:28 INFO - "slavename": "t-yosemite-r7-0034", 10:12:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:12:28 INFO - "platform": "yosemite_r7", 10:12:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:12:28 INFO - "branch": "mozilla-central", 10:12:28 INFO - "repo_path": "mozilla-central", 10:12:28 INFO - "revision": "9694bdce768041e494a875fd6d2d278dfdfe70d0", 10:12:28 INFO - "stage_platform": "macosx64", 10:12:28 INFO - "builduid": "8024a74ebeb4458b9640a6af793c2000", 10:12:28 INFO - "slavebuilddir": "test" 10:12:28 INFO - } 10:12:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 10:12:28 INFO - Running post-action listener: _resource_record_post_action 10:12:28 INFO - [mozharness: 2016-11-29 18:12:28.987788Z] Finished read-buildbot-config step (success) 10:12:28 INFO - [mozharness: 2016-11-29 18:12:28.987914Z] Running download-and-extract step. 10:12:28 INFO - Running pre-action listener: _resource_record_pre_action 10:12:28 INFO - Running main action method: download_and_extract 10:12:28 INFO - mkdir: /builds/slave/test/build/tests 10:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:28 INFO - trying https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 10:12:28 INFO - Downloading https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/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 10:12:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/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 10:12:30 INFO - Downloaded 1199 bytes. 10:12:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:12:30 INFO - Using the following test package requirements: 10:12:30 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:12:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 10:12:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 10:12:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'jsshell-mac.zip'], 10:12:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 10:12:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:12:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 10:12:30 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 10:12:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 10:12:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:12:30 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 10:12:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:12:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:12:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:12:30 INFO - Fetch https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:12:30 INFO - Http code: 200 10:12:30 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:12:30 INFO - x-amz-cf-id: VSCMuzBj_TBBMgwHUVbNJgIKjst5jnxrC1W9BIzIgXGgQLYcn-DIKA== 10:12:30 INFO - x-amz-version-id: ExbalzoSdseP0w_yEzYtn5Tq2SgXxP_t 10:12:31 INFO - Expected file size: 16285185 10:12:31 INFO - Obtained file size: 16285185 10:12:38 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 10:12:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:12:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:12:38 INFO - Fetch https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:12:39 INFO - Http code: 200 10:12:39 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 10:12:39 INFO - x-amz-cf-id: 59566n6fd7h_47hBj-9N-QQQjpus2Qj-6FoKqeqQJNuu2euLPfwOaw== 10:12:39 INFO - x-amz-version-id: ExbalzoSdseP0w_yEzYtn5Tq2SgXxP_t 10:12:39 INFO - Expected file size: 16285185 10:12:39 INFO - Obtained file size: 16285185 10:12:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 10:12:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:12:45 INFO - Fetch https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 10:12:47 INFO - Http code: 200 10:12:47 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:12:47 INFO - x-amz-cf-id: RXeLKLugzAfLNua4bLAFv0CCKhNsaDyZ19P2LbB7F2Gqc8Dye8Kavg== 10:12:47 INFO - x-amz-version-id: fihnWsoRwHWuQAbUN4UErEwVrFSj_n9c 10:12:47 INFO - Expected file size: 14409702 10:12:47 INFO - Obtained file size: 14409702 10:12:47 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')]} 10:12:47 INFO - trying https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:12:47 INFO - Downloading https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 10:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/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 10:12:50 INFO - Downloaded 107539294 bytes. 10:12:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:12:50 INFO - mkdir: /builds/slave/test/properties 10:12:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:12:50 INFO - Writing to file /builds/slave/test/properties/build_url 10:12:50 INFO - Contents: 10:12:50 INFO - build_url:https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:12:51 INFO - Running post-action listener: _resource_record_post_action 10:12:51 INFO - Running post-action listener: set_extra_try_arguments 10:12:51 INFO - [mozharness: 2016-11-29 18:12:51.516549Z] Finished download-and-extract step (success) 10:12:51 INFO - [mozharness: 2016-11-29 18:12:51.516682Z] Running populate-webroot step. 10:12:51 INFO - Running pre-action listener: _resource_record_pre_action 10:12:51 INFO - Running main action method: populate_webroot 10:12:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:12:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:12:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:12:51 INFO - '--tppagecycles', 10:12:51 INFO - '7'], 10:12:51 INFO - 'tests': ['tsvgm']}}, 10:12:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:12:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:12:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:12:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:12:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:12:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:12:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['damp', 'tps']}, 10:12:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'tests': ['damp', 'tps']}, 10:12:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['dromaeo_dom']}, 10:12:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:12:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['basic_compositor_video']}, 10:12:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:12:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'other-e10s': {'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['a11yr', 10:12:51 INFO - 'ts_paint', 10:12:51 INFO - 'tpaint', 10:12:51 INFO - 'sessionrestore', 10:12:51 INFO - 'sessionrestore_no_auto_restore', 10:12:51 INFO - 'tabpaint']}, 10:12:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['tsvgx', 10:12:51 INFO - 'tsvgr_opacity', 10:12:51 INFO - 'tart', 10:12:51 INFO - 'tscrollx', 10:12:51 INFO - 'cart', 10:12:51 INFO - 'tsvg_static']}, 10:12:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:12:51 INFO - 'tsvgr_opacity', 10:12:51 INFO - 'tart', 10:12:51 INFO - 'tscrollx', 10:12:51 INFO - 'cart', 10:12:51 INFO - 'tsvg_static']}, 10:12:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'talos_options': ['--disable-e10s'], 10:12:51 INFO - 'tests': ['tp5o']}, 10:12:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:12:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'talos_options': ['--disable-e10s', 10:12:51 INFO - '--xperf_path', 10:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:12:51 INFO - 'tests': ['tp5n']}, 10:12:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:12:51 INFO - 'talos_options': ['--xperf_path', 10:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:12:51 INFO - 'tests': ['tp5n']}}, 10:12:51 INFO - 'talos.zip': {'path': '', 10:12:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:12:51 INFO - Running post-action listener: _resource_record_post_action 10:12:51 INFO - [mozharness: 2016-11-29 18:12:51.535563Z] Finished populate-webroot step (success) 10:12:51 INFO - [mozharness: 2016-11-29 18:12:51.535690Z] Running create-virtualenv step. 10:12:51 INFO - Running pre-action listener: _resource_record_pre_action 10:12:51 INFO - Running main action method: create_virtualenv 10:12:51 INFO - Creating virtualenv /builds/slave/test/build/venv 10:12:51 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 10:12:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:12:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:12:51 INFO - Using real prefix '/tools/python27' 10:12:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:12:52 INFO - Installing distribute.............................................................................................................................................................................................done. 10:12:55 INFO - Installing pip.................done. 10:12:55 INFO - Return code: 0 10:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:12:55 INFO - Reading from file tmpfile_stdout 10:12:55 INFO - Output received: 10:12:55 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) 10:12:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:12:55 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')]} 10:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:55 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')]} 10:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:12:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:12:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:12:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:55 INFO - 'HOME': '/Users/cltbld', 10:12:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:55 INFO - 'LOGNAME': 'cltbld', 10:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:55 INFO - 'MOZ_NO_REMOTE': '1', 10:12:55 INFO - 'NO_EM_RESTART': '1', 10:12:55 INFO - 'PAGER': '/bin/cat', 10:12:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:55 INFO - 'PWD': '/builds/slave/test', 10:12:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:55 INFO - 'SHELL': '/bin/bash', 10:12:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:12:55 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:12:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:55 INFO - 'USER': 'cltbld', 10:12:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:55 INFO - 'XPC_FLAGS': '0x0', 10:12:55 INFO - 'XPC_SERVICE_NAME': '0', 10:12:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:55 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 10:12:55 INFO - Cleaning up... 10:12:55 INFO - Return code: 0 10:12:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:12:55 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')]} 10:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:55 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')]} 10:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:12:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:56 INFO - 'HOME': '/Users/cltbld', 10:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:56 INFO - 'LOGNAME': 'cltbld', 10:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:56 INFO - 'MOZ_NO_REMOTE': '1', 10:12:56 INFO - 'NO_EM_RESTART': '1', 10:12:56 INFO - 'PAGER': '/bin/cat', 10:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:56 INFO - 'PWD': '/builds/slave/test', 10:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:56 INFO - 'SHELL': '/bin/bash', 10:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:12:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:56 INFO - 'USER': 'cltbld', 10:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:56 INFO - 'XPC_FLAGS': '0x0', 10:12:56 INFO - 'XPC_SERVICE_NAME': '0', 10:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:56 INFO - Downloading/unpacking psutil>=3.1.1 10:12:56 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 10:12:56 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 10:12:56 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 10:12:56 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 10:12:56 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 10:12:56 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 10:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:12:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:12:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:12:59 INFO - Installing collected packages: psutil 10:12:59 INFO - Running setup.py install for psutil 10:13:00 INFO - building 'psutil._psutil_osx' extension 10:13:00 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 10:13:00 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 10:13:00 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 10:13:00 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 10:13:00 INFO - building 'psutil._psutil_posix' extension 10:13:00 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 10:13:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:13:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:13:00 INFO - ^ 10:13:00 INFO - 1 warning generated. 10:13:00 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 10:13:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:01 INFO - Successfully installed psutil 10:13:01 INFO - Cleaning up... 10:13:01 INFO - Return code: 0 10:13:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:01 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:01 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 10:13:01 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 10:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:01 INFO - 'HOME': '/Users/cltbld', 10:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:01 INFO - 'LOGNAME': 'cltbld', 10:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:01 INFO - 'MOZ_NO_REMOTE': '1', 10:13:01 INFO - 'NO_EM_RESTART': '1', 10:13:01 INFO - 'PAGER': '/bin/cat', 10:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:01 INFO - 'PWD': '/builds/slave/test', 10:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:01 INFO - 'SHELL': '/bin/bash', 10:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:01 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:01 INFO - 'USER': 'cltbld', 10:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:01 INFO - 'XPC_FLAGS': '0x0', 10:13:01 INFO - 'XPC_SERVICE_NAME': '0', 10:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:13:01 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 10:13:01 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 10:13:01 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 10:13:01 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 10:13:01 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 10:13:01 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 10:13:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:13:04 INFO - Installing collected packages: mozsystemmonitor 10:13:04 INFO - Running setup.py install for mozsystemmonitor 10:13:04 INFO - Successfully installed mozsystemmonitor 10:13:04 INFO - Cleaning up... 10:13:04 INFO - Return code: 0 10:13:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:13:04 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')]} 10:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:04 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')]} 10:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:04 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:04 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 10:13:04 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 10:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:04 INFO - 'HOME': '/Users/cltbld', 10:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:04 INFO - 'LOGNAME': 'cltbld', 10:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:04 INFO - 'MOZ_NO_REMOTE': '1', 10:13:04 INFO - 'NO_EM_RESTART': '1', 10:13:04 INFO - 'PAGER': '/bin/cat', 10:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:04 INFO - 'PWD': '/builds/slave/test', 10:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:04 INFO - 'SHELL': '/bin/bash', 10:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:04 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:04 INFO - 'USER': 'cltbld', 10:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:04 INFO - 'XPC_FLAGS': '0x0', 10:13:04 INFO - 'XPC_SERVICE_NAME': '0', 10:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:04 INFO - Downloading/unpacking jsonschema==2.5.1 10:13:04 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 10:13:04 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 10:13:04 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 10:13:04 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 10:13:04 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 10:13:04 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 10:13:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:13:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:07 INFO - Downloading functools32-3.2.3-2.tar.gz 10:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:13:07 INFO - warning: no files found matching '*.txt' 10:13:07 INFO - no previously-included directories found matching 'build' 10:13:07 INFO - no previously-included directories found matching 'dist' 10:13:07 INFO - no previously-included directories found matching '.git*' 10:13:07 INFO - Installing collected packages: jsonschema, functools32 10:13:08 INFO - Running setup.py install for functools32 10:13:08 INFO - warning: no files found matching '*.txt' 10:13:08 INFO - no previously-included directories found matching 'build' 10:13:08 INFO - no previously-included directories found matching 'dist' 10:13:08 INFO - no previously-included directories found matching '.git*' 10:13:08 INFO - Successfully installed jsonschema functools32 10:13:08 INFO - Cleaning up... 10:13:08 INFO - Return code: 0 10:13:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:13:08 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')]} 10:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:08 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')]} 10:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:08 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:08 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 10:13:08 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 10:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:08 INFO - 'HOME': '/Users/cltbld', 10:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:08 INFO - 'LOGNAME': 'cltbld', 10:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:08 INFO - 'MOZ_NO_REMOTE': '1', 10:13:08 INFO - 'NO_EM_RESTART': '1', 10:13:08 INFO - 'PAGER': '/bin/cat', 10:13:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:08 INFO - 'PWD': '/builds/slave/test', 10:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:08 INFO - 'SHELL': '/bin/bash', 10:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:08 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:08 INFO - 'USER': 'cltbld', 10:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:08 INFO - 'XPC_FLAGS': '0x0', 10:13:08 INFO - 'XPC_SERVICE_NAME': '0', 10:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:13:08 INFO - Cleaning up... 10:13:08 INFO - Return code: 0 10:13:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13:08 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')]} 10:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:08 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')]} 10:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:08 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:08 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 10:13:08 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 10:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:08 INFO - 'HOME': '/Users/cltbld', 10:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:08 INFO - 'LOGNAME': 'cltbld', 10:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:08 INFO - 'MOZ_NO_REMOTE': '1', 10:13:08 INFO - 'NO_EM_RESTART': '1', 10:13:08 INFO - 'PAGER': '/bin/cat', 10:13:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:08 INFO - 'PWD': '/builds/slave/test', 10:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:08 INFO - 'SHELL': '/bin/bash', 10:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:08 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:08 INFO - 'USER': 'cltbld', 10:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:08 INFO - 'XPC_FLAGS': '0x0', 10:13:08 INFO - 'XPC_SERVICE_NAME': '0', 10:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:13:08 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 10:13:08 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 10:13:08 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 10:13:08 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 10:13:08 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 10:13:08 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 10:13:11 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13:11 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 10:13:11 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 10:13:11 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 10:13:11 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 10:13:11 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 10:13:11 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 10:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:12 INFO - Downloading docopt-0.6.1.tar.gz 10:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:13:12 INFO - Installing collected packages: blobuploader, requests, docopt 10:13:12 INFO - Running setup.py install for blobuploader 10:13:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:13:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:13:12 INFO - Running setup.py install for requests 10:13:13 INFO - Running setup.py install for docopt 10:13:13 INFO - Successfully installed blobuploader requests docopt 10:13:13 INFO - Cleaning up... 10:13:13 INFO - Return code: 0 10:13:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:13 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:13 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 10:13:13 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 10:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:13 INFO - 'HOME': '/Users/cltbld', 10:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:13 INFO - 'LOGNAME': 'cltbld', 10:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:13 INFO - 'MOZ_NO_REMOTE': '1', 10:13:13 INFO - 'NO_EM_RESTART': '1', 10:13:13 INFO - 'PAGER': '/bin/cat', 10:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:13 INFO - 'PWD': '/builds/slave/test', 10:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:13 INFO - 'SHELL': '/bin/bash', 10:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:13 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:13 INFO - 'USER': 'cltbld', 10:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:13 INFO - 'XPC_FLAGS': '0x0', 10:13:13 INFO - 'XPC_SERVICE_NAME': '0', 10:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hJRqqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-aPgSdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OQWe9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-cMC1ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-HpHnlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-vUZ1q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_vW1Nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-J9TO90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WSVcXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:14 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-wn8MvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-kQWxl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-7cAjQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5ZHsV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QrnfLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-j3pP61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-12o49K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tsoMxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:13:15 INFO - Running setup.py install for manifestparser 10:13:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:13:16 INFO - Running setup.py install for mozcrash 10:13:16 INFO - Running setup.py install for mozdebug 10:13:16 INFO - Running setup.py install for mozdevice 10:13:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:13:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:13:16 INFO - Running setup.py install for mozfile 10:13:16 INFO - Running setup.py install for mozhttpd 10:13:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:13:16 INFO - Running setup.py install for mozinfo 10:13:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:13:16 INFO - Running setup.py install for mozInstall 10:13:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Running setup.py install for mozleak 10:13:17 INFO - Running setup.py install for mozlog 10:13:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Running setup.py install for moznetwork 10:13:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Running setup.py install for mozprocess 10:13:17 INFO - Running setup.py install for mozprofile 10:13:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:13:17 INFO - Running setup.py install for mozrunner 10:13:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:13:18 INFO - Running setup.py install for mozscreenshot 10:13:18 INFO - Running setup.py install for moztest 10:13:18 INFO - Running setup.py install for mozversion 10:13:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:13:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:13:18 INFO - Cleaning up... 10:13:18 INFO - Return code: 0 10:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:18 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:18 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 10:13:18 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 10:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:18 INFO - 'HOME': '/Users/cltbld', 10:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:18 INFO - 'LOGNAME': 'cltbld', 10:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:18 INFO - 'MOZ_NO_REMOTE': '1', 10:13:18 INFO - 'NO_EM_RESTART': '1', 10:13:18 INFO - 'PAGER': '/bin/cat', 10:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:18 INFO - 'PWD': '/builds/slave/test', 10:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:18 INFO - 'SHELL': '/bin/bash', 10:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:18 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:18 INFO - 'USER': 'cltbld', 10:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:18 INFO - 'XPC_FLAGS': '0x0', 10:13:18 INFO - 'XPC_SERVICE_NAME': '0', 10:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:18 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-b6oaBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-1s23hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-BwgkdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-F43Cms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-BRgIJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UfUFSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-eIyw4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JH3KX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Q7EA6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:19 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)) 10:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:19 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DRmUUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-aKNIJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OtboKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DDOArU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-v3jqIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2LCtYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5kC1Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:20 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)) 10:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bZjjnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:20 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)) 10:13:20 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)) 10:13:20 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)) 10:13:20 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)) 10:13:20 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)) 10:13:20 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)) 10:13:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:23 INFO - Downloading blessings-1.6.tar.gz 10:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:13:24 INFO - Installing collected packages: blessings 10:13:24 INFO - Running setup.py install for blessings 10:13:24 INFO - Successfully installed blessings 10:13:24 INFO - Cleaning up... 10:13:24 INFO - Return code: 0 10:13:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:13:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:24 INFO - Reading from file tmpfile_stdout 10:13:24 INFO - Current package versions: 10:13:24 INFO - blessings == 1.6 10:13:24 INFO - blobuploader == 1.2.4 10:13:24 INFO - docopt == 0.6.1 10:13:24 INFO - functools32 == 3.2.3-2 10:13:24 INFO - jsonschema == 2.5.1 10:13:24 INFO - manifestparser == 1.1 10:13:24 INFO - mozInstall == 1.12 10:13:24 INFO - mozcrash == 1.0 10:13:24 INFO - mozdebug == 0.1 10:13:24 INFO - mozdevice == 0.49 10:13:24 INFO - mozfile == 1.2 10:13:24 INFO - mozhttpd == 0.7 10:13:24 INFO - mozinfo == 0.9 10:13:24 INFO - mozleak == 0.1 10:13:24 INFO - mozlog == 3.4 10:13:24 INFO - moznetwork == 0.27 10:13:24 INFO - mozprocess == 0.23 10:13:24 INFO - mozprofile == 0.28 10:13:24 INFO - mozrunner == 6.13 10:13:24 INFO - mozscreenshot == 0.1 10:13:24 INFO - mozsystemmonitor == 0.3 10:13:24 INFO - moztest == 0.7 10:13:24 INFO - mozversion == 1.4 10:13:24 INFO - psutil == 3.1.1 10:13:24 INFO - requests == 1.2.3 10:13:24 INFO - wsgiref == 0.1.2 10:13:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:24 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:24 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 10:13:24 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 10:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:24 INFO - 'HOME': '/Users/cltbld', 10:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:24 INFO - 'LOGNAME': 'cltbld', 10:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:24 INFO - 'MOZ_NO_REMOTE': '1', 10:13:24 INFO - 'NO_EM_RESTART': '1', 10:13:24 INFO - 'PAGER': '/bin/cat', 10:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:24 INFO - 'PWD': '/builds/slave/test', 10:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:24 INFO - 'SHELL': '/bin/bash', 10:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:24 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:24 INFO - 'USER': 'cltbld', 10:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:24 INFO - 'XPC_FLAGS': '0x0', 10:13:24 INFO - 'XPC_SERVICE_NAME': '0', 10:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 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)) 10:13:25 INFO - Cleaning up... 10:13:25 INFO - Return code: 0 10:13:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:13:25 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')]} 10:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:25 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')]} 10:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:25 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 0x104e963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0524cc7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, '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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 10:13:25 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 10:13:25 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 10:13:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:13:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:25 INFO - 'HOME': '/Users/cltbld', 10:13:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:25 INFO - 'LOGNAME': 'cltbld', 10:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:25 INFO - 'MOZ_NO_REMOTE': '1', 10:13:25 INFO - 'NO_EM_RESTART': '1', 10:13:25 INFO - 'PAGER': '/bin/cat', 10:13:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:25 INFO - 'PWD': '/builds/slave/test', 10:13:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:25 INFO - 'SHELL': '/bin/bash', 10:13:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:13:25 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:13:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:25 INFO - 'USER': 'cltbld', 10:13:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:25 INFO - 'XPC_FLAGS': '0x0', 10:13:25 INFO - 'XPC_SERVICE_NAME': '0', 10:13:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:13:25 INFO - Cleaning up... 10:13:25 INFO - Return code: 0 10:13:25 INFO - Running post-action listener: _resource_record_post_action 10:13:25 INFO - Running post-action listener: _start_resource_monitoring 10:13:25 INFO - Starting resource monitoring. 10:13:25 INFO - [mozharness: 2016-11-29 18:13:25.517228Z] Finished create-virtualenv step (success) 10:13:25 INFO - [mozharness: 2016-11-29 18:13:25.518352Z] Running install step. 10:13:25 INFO - Running pre-action listener: _resource_record_pre_action 10:13:25 INFO - Running main action method: install 10:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:25 INFO - Reading from file tmpfile_stdout 10:13:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:13:25 INFO - Reading from file tmpfile_stdout 10:13:25 INFO - Output received: 10:13:25 INFO - Usage: mozinstall [options] installer 10:13:25 INFO - Options: 10:13:25 INFO - -h, --help show this help message and exit 10:13:25 INFO - -d DEST, --destination=DEST 10:13:25 INFO - Directory to install application into. [default: 10:13:25 INFO - "/builds/slave/test"] 10:13:25 INFO - --app=APP Application being installed. [default: firefox] 10:13:25 INFO - mkdir: /builds/slave/test/build/application 10:13:25 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'] 10:13:25 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 10:14:00 INFO - Reading from file tmpfile_stdout 10:14:00 INFO - Output received: 10:14:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:14:00 INFO - Running post-action listener: _resource_record_post_action 10:14:00 INFO - [mozharness: 2016-11-29 18:14:00.862136Z] Finished install step (success) 10:14:00 INFO - [mozharness: 2016-11-29 18:14:00.862333Z] Running run-tests step. 10:14:00 INFO - Running pre-action listener: _resource_record_pre_action 10:14:00 INFO - Running main action method: run_tests 10:14:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:00 INFO - 2016-11-29 10:14:00.877 screenresolution[1623:7418] starting screenresolution argv=screenresolution get 10:14:00 INFO - 2016-11-29 10:14:00.880 screenresolution[1623:7418] Display 0: 1600x1200x32@60 10:14:00 INFO - 2016-11-29 10:14:00.886 screenresolution[1624:7420] starting screenresolution argv=screenresolution list 10:14:00 INFO - Available Modes on Display 0 10:14:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:01 INFO - Intel Iris: 10:14:01 INFO - Chipset Model: Intel Iris 10:14:01 INFO - Type: GPU 10:14:01 INFO - Bus: Built-In 10:14:01 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:01 INFO - Vendor: Intel (0x8086) 10:14:01 INFO - Device ID: 0x0a2e 10:14:01 INFO - Revision ID: 0x0009 10:14:01 INFO - Displays: 10:14:01 INFO - Display: 10:14:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:01 INFO - Display Serial Number: 1600x1200 60 10:14:01 INFO - Main Display: Yes 10:14:01 INFO - Mirror: Off 10:14:01 INFO - Online: Yes 10:14:01 INFO - Rotation: Supported 10:14:01 INFO - Return code: 0 10:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:14:01 INFO - Python 2.7.3 10:14:01 INFO - Return code: 0 10:14:01 INFO - grabbing minidump binary from tooltool 10:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:01 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 0x104cacd30>, '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 0x104b13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ca4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:14:01 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 10:14:01 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 10:14:01 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 10:14:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:14:01 INFO - Return code: 0 10:14:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:14:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:14:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:14:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:14:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxm8hDSO4j/Render', 10:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:01 INFO - 'HOME': '/Users/cltbld', 10:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:01 INFO - 'LOGNAME': 'cltbld', 10:14:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:14:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:01 INFO - 'MOZ_NO_REMOTE': '1', 10:14:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:14:01 INFO - 'NO_EM_RESTART': '1', 10:14:01 INFO - 'PAGER': '/bin/cat', 10:14:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:01 INFO - 'PWD': '/builds/slave/test', 10:14:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:01 INFO - 'SHELL': '/bin/bash', 10:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5N9JQKEFE/Listeners', 10:14:01 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 10:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:01 INFO - 'USER': 'cltbld', 10:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:01 INFO - 'XPC_FLAGS': '0x0', 10:14:01 INFO - 'XPC_SERVICE_NAME': '0', 10:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:14:01 INFO - mozversion application_buildid: 20161129074627 10:14:01 INFO - mozversion application_changeset: 9694bdce768041e494a875fd6d2d278dfdfe70d0 10:14:01 INFO - mozversion application_display_name: Nightly 10:14:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:14:01 INFO - mozversion application_name: Firefox 10:14:01 INFO - mozversion application_remotingname: firefox 10:14:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:14:01 INFO - mozversion application_vendor: Mozilla 10:14:01 INFO - mozversion application_version: 53.0a1 10:14:01 INFO - mozversion platform_buildid: 20161129074627 10:14:01 INFO - mozversion platform_changeset: 9694bdce768041e494a875fd6d2d278dfdfe70d0 10:14:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:14:01 INFO - mozversion platform_version: 53.0a1 10:14:01 INFO - using testdate: 1480443241 10:14:01 INFO - actual date: 1480443241 10:14:01 INFO - starting webserver on 'localhost:49241' 10:14:01 INFO - SUITE-START | Running 2 tests 10:14:01 INFO - TEST-START | dromaeo_css 10:14:01 INFO - operating with platform_type : mac_ 10:14:01 INFO - Initialising browser for dromaeo_css test... 10:14:01 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpC0yFwb/profile http://localhost:49241/getInfo.html) 10:14:04 INFO - PROCESS | 1634 | 2016-11-29 10:14:04.689 plugin-container[1636:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 10:14:04 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:04 INFO - PROCESS | 1634 | 2016-11-29 10:14:04.692 plugin-container[1636:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c13, name = 'com.apple.CFPasteboardClient' 10:14:04 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:04 INFO - PROCESS | 1634 | 2016-11-29 10:14:04.692 plugin-container[1636:7582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:04 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 10:14:04 INFO - PROCESS | 1634 | colorDepth:24 10:14:04 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 10:14:04 INFO - PROCESS | 1634 | __metrics 10:14:04 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 10:14:05 INFO - TEST-INFO | 1634: exit 0 10:14:05 INFO - Browser initialized. 10:14:05 INFO - Running cycle 1/1 for dromaeo_css test... 10:14:05 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpC0yFwb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:08 INFO - PROCESS | 1637 | 2016-11-29 10:14:08.498 plugin-container[1638:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 10:14:08 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:08 INFO - PROCESS | 1637 | 2016-11-29 10:14:08.499 plugin-container[1638:7712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.CFPasteboardClient' 10:14:08 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:08 INFO - PROCESS | 1637 | 2016-11-29 10:14:08.499 plugin-container[1638:7712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:18 INFO - PROCESS | 1637 | RSS: Main: 242880512 10:14:18 INFO - PROCESS | 1637 | 10:15:07 INFO - PROCESS | 1637 | 1480443307809 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] 10:17:19 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:17:20 INFO - PROCESS | 1637 | RSS: Main: 253571072 10:17:20 INFO - PROCESS | 1637 | 10:20:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:20:23 INFO - PROCESS | 1637 | RSS: Main: 261730304 10:20:23 INFO - PROCESS | 1637 | 10:23:25 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:23:26 INFO - PROCESS | 1637 | RSS: Main: 266969088 10:23:26 INFO - PROCESS | 1637 | 10:26:28 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:26:29 INFO - PROCESS | 1637 | RSS: Main: 266391552 10:26:29 INFO - PROCESS | 1637 | 10:29:31 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:29:32 INFO - PROCESS | 1637 | RSS: Main: 271978496 10:29:32 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:32:35 INFO - PROCESS | 1637 | RSS: Main: 274026496 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | __start_tp_report 10:32:35 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 10:32:35 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 10:32:35 INFO - PROCESS | 1637 | |i|pagename|runs| 10:32:35 INFO - PROCESS | 1637 | |0;dojo.html;2702.1623296778002;2764.281286865417;2764.764994975427;2825.562037884127;2832.107886015904;3994.1212933154698;4200.768957707325;4426.424564806579;4453.554644535545;4579.992401671635;4434.756088415136;4505.256632655616;4528.18492671319;4536.886577835556;4546.56807603278;4100;4151.896202594944;4186.623203911648;4326.459192600925;4352.847650332239;4180.080382315886;4234.1320029394055;4238.173556156551;4240.215560121372;4330.328799036154;4068.8982775430554;4148.211839750437;4206.72656277341;4208.494980602317;4314.395984562164;4089.7137200395982;4117.238310912461;4125.525564560054;4155.542890282067;4182.9790851045855;4191.811368488426;4199.496060472732;4230.915381692349;4266.786660666985;4304.418903448026;3302.058913209724;4357.085012147453;4405.493368262659;4434.733915965053;4482.417285752864;3968.861089861841;4730.361401210827;4813.350197723297;4836.395450568679;4982.252662100714;4849.199882019498;4909.509049095098;4911.533404326583;4938.407391113089;4996.100701873661;4613.677042607019;4619.907601847978;4674.836380726658;4712.363830882856;4718.079974404958;5017.67385119966;5038.722870242099;5041.495850414929;5068.847934561969;5148.897022059613;4695.460022097434;4741.57325840676;4763.642726795435;4812.663113582085;4826.686265392738;4910.901781964412;4921.941782516776;4923.310736496894;4950.702957822542;4988.950110498921;4709.882252943636;4770.594499467584;4787.042591481718;4790.041991601694;4838.129136755348;4375.759333236639;4426.690131690751;4842.661013729005;4909.459959404463;4933.777979990909;3452.6202117767025;4010.538788039413;4032.2741906457118;4033.495813023372;4050.1494686116243;3409.9488507672404;3522.788632682047;3534.0988048047584;3543.681068703829;3574.463830425405;3800.638939300762;4068.613481719232;4551.044280701024;4560.977195114003;4631.791569379386;4107.482939215984;4973.825916092919;4989.600831933437;5010.574101201366;5032.0690672225755;4456.108778244364;4497.77511124449;4517.954820451819;4583.335416364608;4592.127495775859;3740.6446387593137;4339.826406943687;4430.20256353853;4432.202203603382;4451.287793952952;5069.873253168626;5082.263071854634;5099.566536844335;5106.948930510647;5111.821086261963;4908.484609115992;4909.558139767438;4933.77797999098;4977.552020318189;4990.80036798524;3634.3639863024396;4605.861824145281;4936.531029552187;5087.720175390241;5097.464766199496;4863.148948933993;4867.953390021163;4868.780904859361;4873.585745211555;4882.7558622068045;4691.882702932454;4847.103285892122;4866.78099485531;4974.328465657235;5005.249212618136;4826.7827947743135;4861.12839092078;4891.559759621508;4907.067657145131;4912.214045752662;4850.102730994778;4875.780589873393;4880.80476780939;4897.240927656077;4905.117078925685;4505.819767209275;4577.6795624307315;4643.628509719147;4650.883727906829;4681.578657920803;4810.134175848241;4853.830115945925;4879.316895634543;4891.046245981999;4941.604671626189;4783.784729687103;4785.856424307276;4812.590929770869;4819.6867203631655;4824 10:32:35 INFO - PROCESS | 1637 | |1;ext.html;432.74251820167007;453.757239876666;464.4125181645217;467.2337366718582;493.71364608527057;25518.744812551868;37876.62123378766;37915.81042094793;37931.81034094826;37954.43068353974;38293.80853095731;38818.4177237341;38872.41691374632;38899.02752431184;38915.22169556607;8110.432269741121;8892.866607000884;9118.589663465127;9125;9127.22418594417;8026.6789328427085;10680.305780124267;10935.671929842118;10944.890551094506;11070.39112848793;10911.563537458493;10915;10972;10974.506147223394;10998.835017474745;10795.622153224638;10822.51298691553;10868.67393978182;10869.67390978268;10917.126629869592;8467.449615774955;8923.15230053144;9317.534123293808;10776.191785616033;10873.803881573021;28144.577831332546;30156.09531714052;36981.630183698086;37073;37740.62259377399;2672.759451649361;2792.287966568512;3434.742394320436;3498.265364273506;3506.211102501942;27502.312442188904;28122.156335309974;28691.28271793201;28775.136745897446;28857.42285154285;2636.8549729764854;2880.4095160492047;3129.749620030392;3183.570218020561;3204.214967332995;377133;382266;386263;386506;390327;53665.1950220747;53943.730281349126;54034.7298263514;54296.45703542914;54439.72780136074;55046.72476637591;55416.445835541934;55443;55446.72276638591;55472;14374.640633984025;14757.262136893327;14773.113613183243;14813.777793333109;14822.110673359633;10325.74185645365;10612.893871061346;11223.607173749044;11461.4269286537;11530.654080377602;12492.312922789333;14518.419263229564;14802.407903683732;14908.701825963637;15059.774103388458;5900.64596124234;6851.595422938286;6973.930260697328;7014.929850701427;7030.613316267654;711.4029004884069;721.5814827400095;782.8473447677762;800.9359251259885;805.3436449293807;27522.587161192598;27856.303592410353;28019.719802801712;28412.857935710603;28486.71513284882;860.1871231686122;919;964.2719746591279;985.8422652375729;1020.4387586827215;14377.849772018213;14533.563993080224;14574.85425145735;14693.23487351199;14726.410943562352;475591;485981;486018;486625;493247.3440819897;50927.74536127296;51029.74485127551;51100.48899511032;51131.488685113414;52329.47670523246;1218.6587755428495;1221.883921027501;1263.7346157306886;1264.4373253901924;1268.3594784633885;26724.46551068929;27218.72781272162;27744.167674969784;27861.860690696416;27912.58131127994;45413.772931135136;45732.54267457416;45770;45876.31185532286;45903;435968;436596;438537;438571.8071409623;442861;23713.40716482102;24573.754262457147;24595.877020614782;25326.986920522973;36358;42212.577874222094;42806.78596606997;43463.348049778644;43652;44087;30320.090397288117;30967.535486968165;35422;35622.28755424929;36454.8177259112;29951.251218719706;30253.848730756206;35845;36107;36459.08852278608;35325.76359827394;35326;35354;35462.82268588641;35472.46791298081;27298.590521142596;29673.312179449007;30191.282784548617;30878.4560771965;32313.51529727009;23702.28893133209;23870.761292386855;23934.922928468608;24462;24705.7647117647 10:32:35 INFO - PROCESS | 1637 | |2;jquery.html;312.65451675898134;326.38150704415136;326.6521154969958;343.78169862137537;350.24871650310087;15721;17125.82874171258;18019;18793.308602225796;18975.71536426955;18184.54538636535;18263.81736182638;18771;18772.71840922384;18992;5303.071962406573;5534.75093620788;5588.4411558844195;5592.6085174037835;5631.211630371751;4135.855245066423;5547.750351234184;5573.804916827912;5647.463490968352;5659.547236221113;5715.771369145229;5760.654360738369;5811.6803575803315;5814.866101110285;5835.620684655505;4869.464358920533;5570.693611851347;5761.308642962829;5790.478856902899;5804.361520232771;5546.805861794818;5567.777288908439;5583.776648934078;5589.608727389084;5611.466910643482;12211.206271592408;12469.563565275263;12499.875001250053;12734;12756.553520626827;2024.230792298917;2110.240313487159;2253.245162870424;2427.271818454474;2760.0339881041677;9232;9253.814923701555;9373.390729602623;9741;9753.902460975441;1693.5342780735273;1723.5518765121158;1736.6700326937842;1765.276236742942;1792.6145878636155;85252.57373713153;102655.48672256665;109207.90792092137;109280.4535977323;109475;10826.89173108259;10840.728981775519;11035;11053.613123540637;11120.555177792798;13700.520481783089;13709.383077761524;13713.794293085612;13725.588232353046;13729.656758581115;6501.47988160946;7794.688212471551;7949.125596184415;8025.839483210303;8091;6475.190601174853;7709.113451952985;7711.190325015905;7742.380609551223;7908.52548847071;4248.638865696388;4250.404943307939;4261.232978063917;4270.466191726035;4306.2894622387585;1788.3115000724815;1865.8880467172014;1875.6530041942285;1904.352520143158;1922.5193701574606;652.9249136349381;681.3663293137331;749.6102026945957;816.3673153306116;834.6202477872554;18725.157367918477;19626.313079042164;19716.309929152412;19768.70346944797;20124.698129528068;764.5603777827771;793.6071644535991;821.8685010398308;840.5461051032496;882.960266787996;8368.661014237692;10151.847722284172;10239;10252.333598316234;10285.7428564285;481692;492562;504165;527654;529648;84880.57559712161;115970;116851.41574292074;120470.3976480112;124619.13071303756;820.8973878265217;881.5415983688446;912.9637861027782;916.1662886773005;919.5723988345452;12561.748765024831;14489.347979341166;16982.745258821375;17815.910920445316;18004.189811458255;30098.24754381158;39282.80358598303;40204.59795402126;45725.77137114293;54141.45858541522;48505.5149448501;63159;69767.95348069885;70707.64646176736;71700.28299717145;7294.234105418855;7411.851163069733;7582.848343033219;7749.108852482037;7958;9950.751231218988;11668;11827.408629568316;11908.166428349932;11954.043676506033;3864.787436691009;9531.52342382892;10092.646757363456;10478.79042419132;10513.684589462327;9670.274729395183;9860.309778315735;10053.69838904834;10470.685879423629;10495.737606559896;5420.647657902224;9769.951150244204;10362.585496580052;10528.894711052792;10946.705644254427;7438.73964411243;7579.851179115493;7800.765977020698;7900.802479938047;7987.800304992189;9898.307118501636;10039.949800250952;10409.063184313449;10457.215708822021;10487.79024419492 10:32:35 INFO - PROCESS | 1637 | |3;mootools.html;1307.4441414716487;1370.0341259412112;1460.3574427252008;1567.5845900836287;1646.5883529117727;6747.359000894913;7291.307325804053;7548.622568871562;8197.467164634301;8209.958950205255;7780.610969451519;8086.070101938264;8096;8176.754697359089;8198.262156405923;2137.1558234497325;2180.1824315881545;2182.7053347798083;2185.2242453928866;2195.3633446300555;2162.5891080694696;2199.285232299501;2213.3691897809126;2217.5786600545844;2219.178903805594;1854.2953677602493;2182.4871155278497;2201.2515744646817;2202.570498752744;2210.6352451845505;2155.8814265215406;2170.1536400803698;2176.814970727482;2186.5189658275153;2189.8905054747197;1696.703076961527;1820.7982731397346;2110.2930518276394;2162.470194802268;2174.07601769249;7165.498415110945;7282.526635768658;7733.4973226740085;8138.94193754814;8201.507909525388;4155.8961025974595;4188.623023927861;4200.243956087934;4801.207800712878;5000.399952005746;2984.850757462118;3026.44010857992;3425.6403077676982;3429.8628054877777;3458.4985177149424;924.8242833861613;948.7770373962113;995.2585323933644;995.576879826071;1052.642091701704;10547.525361358757;10578.52396642153;10595.152387808957;10596.576136954513;10636.787264254748;4374.934375984363;4418.138462999685;4488.461384633865;4524.298733696282;4551.658625603093;7910.52536847791;7955.403344749051;7979.800504987422;7998.640061197144;8016.679332826739;4722.787474563653;4729.95270047302;4736.810527578928;4744.122337367598;4750.95249047512;4123.175364927027;4138.79306034697;4357.760323182255;4478.216312145361;4494.797734101973;3331.666833316649;3467.531883195736;3477.7739446935866;3551.893443196708;3602.3155600435834;985.1380042462845;1090.8200146975819;1090.9509072091776;1104.6520346090956;1107.972300692473;690.440742998173;725.575538310084;768.5043147170156;809.817790997021;813.8982627171604;4903.950960490421;5171.689698618095;5213.817516386908;5442.755076021588;5627.127795191752;330.2766940400515;390.47871092091805;393.9901502462461;394.58371418153547;407.33197556008173;3586.7668601540818;3636.6908812751212;3670.3943849265106;3688.870889518854;3734.8132593370765;9458.716238512856;9912.900870991341;9918.553665085088;9923;9953.353032052893;9160.450372977642;9537.713868583953;9877.111060004634;9878.110970012733;9893.554790034466;7345.449091318173;7570.16728159902;7576.886346704804;7584.317411432998;7612.200718924544;5272.235525848807;5272.920906186334;5288.312519372457;5552.416996215339;5553;8855.203031727175;8942.955285223792;8975.640974360953;9034.638614455349;9073.13805188506;7724.227577242231;9080.727578172666;9114.088591140833;9115.088491150831;9211.67759128454;5773.16289138081;6171.475424588781;6570.310117437792;6937.445004399535;7065.728168929642;7710;7749.380049596131;7789.5715735635085;7798.37612990948;7844.176361481962;6870.862582748417;6920.792376228721;6996.685149168198;7003.369696727319;7140.428765698835;6805.931940680728;6808.38724514796;6869.896951545831;6885.896711549431;6922.8615427692175;6407.359264073555;6492.837679057872;6553.803385898431;6555.967220163869;6571.112899758471;4425.690201685979;4624.421947256593;4673.556012178838;4693.24908014716;5001.524855138756;4630.884227894329;4732.787024583833;4741.122892264942;4802.135615589227;4861.781219845187 10:32:35 INFO - PROCESS | 1637 | |4;prototype.html;274.1843017024353;275.40649899466644;280.62256265323145;283.5166041898562;285.3508268688732;4157.189348077126;4279.677525699043;4284.485861696597;4293.3989241506215;4380.795281297641;3485.6165821759587;4020.366659837811;4111.650509706671;4209.515905670849;4239.342901850218;3168.3980043791607;3312.933741325172;3377.527146199534;3379.7972121672715;3393.541871847306;2913.863593198654;3229.4348489014446;3350.430426827433;3363.445031569788;3373.662633736619;3323.3187196624635;3337.5327454156436;3346.916327091818;3350.5644266245354;3364.9158771030675;2649.5893136563864;2794.9580756288674;2824.8728807203725;3342.9665703343144;3413.7269018478464;2878.237267124193;2976.9851150744325;2983.5077212259953;3004.6394432668003;3006.4137493188837;3464.441001549313;3685.7468460723485;3779.2063666630042;4032.475778148851;4037.7981100944835;3729.4336378721;3805.82019573933;3806.9428958565645;3811.9123509957026;3841.3853783394525;4494.943688233263;4502.864914052584;4518.712167032413;4542.863714088549;4544.454665440136;2861.885524579014;3099.2251937015744;3273.950890736617;3278.4098862205033;3286.6713328667183;4712.246040633517;4725.291206319045;4734.455537613149;4739.265413860822;4739.620830333565;2854.743073123387;3304.9339013220083;3315.4529502632276;3320.4023275810105;3333.283344081034;2736;3302.1744563859033;3358.009387230773;3366.8552692084318;3372.409828280041;3548.214651498561;3585.408407612767;3794.317022935898;3795.5065841440987;3814.779270633426;3620.3845346291196;4111.09555897702;4635.837745678885;4647.442306923191;4696.084263568571;1040.9798397570423;1074.9086327662237;1098.961536346224;1109.2124591539934;1110.4780753046055;671.2984930747381;735.9622931666431;770.3259647808168;775.8021704465325;776.5767656627152;3500.299940012008;3563;3992.762243704461;4051.7771522565954;4054.574269701639;4396.824127034883;4524.298733696217;4534;4536.56902594253;4544.182047231464;3397.7451691123265;3869.7097717671286;4041.333180025322;4050.9189816204102;4052.63526282636;2001.8380149438572;2022.6157030164222;2091.9477013074793;2326.651002349661;2327.428985434825;3793.639604237593;3855.4216867470104;3964.7819369934355;4340.327249276367;4680.906381872412;4464.709793863389;4604.286335617984;4609.700369475973;4617.976910115428;4637.25803871379;4097.651699605567;4419.11617676466;4578.473475550354;4662.976685116552;4707.529247075265;4463.843765468193;4641.837535686234;4646.721196728207;4784.521547845187;4819.638527110411;4085.693572981979;4390.560943905584;4574.771261436848;4707.739495756346;4768.928466072943;4625.491195968373;5001.974990125026;5033.597312215087;5043.435690699923;5043.9747801260755;3258.364618899292;3299.109240505098;3343.4316166252283;3345.8327083645236;3345.882894098695;4548.658850586153;4752.809887604595;4823.807047718193;4831.371921650163;4852.986010805476;3463.9653603463644;4430.2468580342065;4593.081383723202;4632.235681112578;4658.114958157939;3441.105312618787;4174.935391475155;4510.030343476168;4623.93064104032;4648.953510464988;3813.3517302058162;3965.4621042533377;4157.875263742093;4567.771611419482;4588.655850811136;4154.3976123461325;4320.978395108004;4501.482329532146;4596.701214421052;4633.86098417048;3986.8836725715687;4343.5656434356315;4531.478879928851;4550.704204226715;4561 10:32:35 INFO - PROCESS | 1637 | |5;yui.html;666.3503084492618;695.5548448992648;700.4291502425523;702.0837806662306;739.4564994728875;13721.862781372183;14398.568042958701;14434.927825360859;14455.277236138203;14512.927435362823;11289.209755317132;13326.933365333183;13367.131136476146;13472.932635336834;13497.662558436044;612.2928018139056;631.8167731357923;771.4175797273051;781.5545139270617;791.5923299500762;807.1565214351017;807.4146243973131;808.3613944983456;809.3889113719134;809.6073404398862;844.0968164064453;844.3582877013453;844.5523872347666;845.623697454633;847.9152084791502;839.9202075802789;841.5203334099566;843.5992903370911;843.7173546861809;844.7423535821572;622.6730966242714;622.8224955887596;622.8349487385847;623.2520974830177;623.4856243599004;12265.38673066352;13631.863681363158;13700.45198192071;13776.24230667323;13829.170249784944;13259.602211933658;13304.268265245408;13349.799753003712;13418.798718019238;13463.663408414868;11334.943325283402;11339.37633430161;11350.148738844537;11475.483603237873;11495.88504114965;3524.436090225577;3528.2237907660274;3535.9646403535894;3554.0759402555523;3661.7253705972157;102496;102853;103987;105025;106806.99999999923;10214;10250.128739057114;10293.330933489298;10373;10411.843822342671;20417.387478375673;20512.79487205139;20521.692174617394;20530.486737831674;20574.58850822975;7095.50331476802;7210.31502007319;7222.422206223489;7357.484976051732;7436.628168591657;4045.554988951213;4074.2869997750277;4189.099343641132;4195.370694395865;4196.349565817303;3282.4748040313434;3375.5443015193127;3417.333619944137;3430.6740859618294;3431.8627254909525;507.6091409614576;537.2746791830997;591.1398914579277;599.9710496498526;607.5352355448085;510.6323447118069;559.1640497456328;563.8280324500985;570.9714514274269;571.0948147186756;7431.51695139825;7830.373570114377;8598.52708101048;8680.826383472295;8845.867311990325;521.3925776470445;550.4806733415801;571.8163401758403;577.3302968556454;585.9912101318483;3478.3043391321835;3553.34263161316;4109.732867363612;4236.533981262059;4268.445102136702;89935.55032224797;90194;92180;93957;93986.06013939773;89154.55422722975;89688.5515572418;89718;92395;93808;63543;70407.64796175987;72074;82064.58967705123;84615.576922115;7456.627168641655;7675.577843218676;8533.914660853312;9069.18377346042;9344.859827102735;32147.67852321447;46186.769066154455;47288;47963.84162712617;48100.27849582189;60147.097793534005;63563.046554302644;63686.68156659187;65155;67196.6640166796;11621.593244236412;11869.703257418634;11943;12321.93839030799;12644.683882903;12407.44166512491;13048.564657887435;13670.17978921268;13706.588802335946;13723.176609403068;11029.889701102886;12631.494740210288;12765.744685106432;12937.935310323388;13144.079716317843;11545.88454115448;12916.41876115596;12960.675983100498;13323.13399629021;13391.86169175631;11651.30092194471;12674.112812103433;12678.175918565265;12913.196445964033;12945.741085178432;10349;10478.318909270873;10571.36571805694;10651.840222396515;10700.411477368509;8975.787154753589;11063.612773552886;11064.424382364346;11078.169137314573;11107.888921110685 10:32:35 INFO - PROCESS | 1637 | __end_tp_report 10:32:35 INFO - PROCESS | 1637 | __start_cc_report 10:32:35 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,391 10:32:35 INFO - PROCESS | 1637 | __end_cc_report 10:32:35 INFO - PROCESS | 1637 | __startTimestamp1480444355679__endTimestamp 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | ------- Summary: start ------- 10:32:35 INFO - PROCESS | 1637 | Number of tests: 6 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:4522.64 Median:4674.84 stddev:508.85 (10.9%) stddev-sans-first:489.96 10:32:35 INFO - PROCESS | 1637 | Values: 2702.2 2764.3 2764.8 2825.6 2832.1 3994.1 4200.8 4426.4 4453.6 4580.0 4434.8 4505.3 4528.2 4536.9 4546.6 4100.0 4151.9 4186.6 4326.5 4352.8 4180.1 4234.1 4238.2 4240.2 4330.3 4068.9 4148.2 4206.7 4208.5 4314.4 4089.7 4117.2 4125.5 4155.5 4183.0 4191.8 4199.5 4230.9 4266.8 4304.4 3302.1 4357.1 4405.5 4434.7 4482.4 3968.9 4730.4 4813.4 4836.4 4982.3 4849.2 4909.5 4911.5 4938.4 4996.1 4613.7 4619.9 4674.8 4712.4 4718.1 5017.7 5038.7 5041.5 5068.8 5148.9 4695.5 4741.6 4763.6 4812.7 4826.7 4910.9 4921.9 4923.3 4950.7 4989.0 4709.9 4770.6 4787.0 4790.0 4838.1 4375.8 4426.7 4842.7 4909.5 4933.8 3452.6 4010.5 4032.3 4033.5 4050.1 3409.9 3522.8 3534.1 3543.7 3574.5 3800.6 4068.6 4551.0 4561.0 4631.8 4107.5 4973.8 4989.6 5010.6 5032.1 4456.1 4497.8 4518.0 4583.3 4592.1 3740.6 4339.8 4430.2 4432.2 4451.3 5069.9 5082.3 5099.6 5106.9 5111.8 4908.5 4909.6 4933.8 4977.6 4990.8 3634.4 4605.9 4936.5 5087.7 5097.5 4863.1 4868.0 4868.8 4873.6 4882.8 4691.9 4847.1 4866.8 4974.3 5005.2 4826.8 4861.1 4891.6 4907.1 4912.2 4850.1 4875.8 4880.8 4897.2 4905.1 4505.8 4577.7 4643.6 4650.9 4681.6 4810.1 4853.8 4879.3 4891.0 4941.6 4783.8 4785.9 4812.6 4819.7 4824.0 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:57114.03 Median:35388.00 stddev:116330.48 (328.7%) stddev-sans-first:116578.98 10:32:35 INFO - PROCESS | 1637 | Values: 432.7 453.8 464.4 467.2 493.7 25518.7 37876.6 37915.8 37931.8 37954.4 38293.8 38818.4 38872.4 38899.0 38915.2 8110.4 8892.9 9118.6 9125.0 9127.2 8026.7 10680.3 10935.7 10944.9 11070.4 10911.6 10915.0 10972.0 10974.5 10998.8 10795.6 10822.5 10868.7 10869.7 10917.1 8467.4 8923.2 9317.5 10776.2 10873.8 28144.6 30156.1 36981.6 37073.0 37740.6 2672.8 2792.3 3434.7 3498.3 3506.2 27502.3 28122.2 28691.3 28775.1 28857.4 2636.9 2880.4 3129.7 3183.6 3204.2 377133.0 382266.0 386263.0 386506.0 390327.0 53665.2 53943.7 54034.7 54296.5 54439.7 55046.7 55416.4 55443.0 55446.7 55472.0 14374.6 14757.3 14773.1 14813.8 14822.1 10325.7 10612.9 11223.6 11461.4 11530.7 12492.3 14518.4 14802.4 14908.7 15059.8 5900.6 6851.6 6973.9 7014.9 7030.6 711.4 721.6 782.8 800.9 805.3 27522.6 27856.3 28019.7 28412.9 28486.7 860.2 919.0 964.3 985.8 1020.4 14377.8 14533.6 14574.9 14693.2 14726.4 475591.0 485981.0 486018.0 486625.0 493247.3 50927.7 51029.7 51100.5 51131.5 52329.5 1218.7 1221.9 1263.7 1264.4 1268.4 26724.5 27218.7 27744.2 27861.9 27912.6 45413.8 45732.5 45770.0 45876.3 45903.0 435968.0 436596.0 438537.0 438571.8 442861.0 23713.4 24573.8 24595.9 25327.0 36358.0 42212.6 42806.8 43463.3 43652.0 44087.0 30320.1 30967.5 35422.0 35622.3 36454.8 29951.3 30253.8 35845.0 36107.0 36459.1 35325.8 35326.0 35354.0 35462.8 35472.5 27298.6 29673.3 30191.3 30878.5 32313.5 23702.3 23870.8 23934.9 24462.0 24705.8 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:29983.01 Median:4255.82 stddev:84855.76 (1993.9%) stddev-sans-first:85064.55 10:32:35 INFO - PROCESS | 1637 | Values: 312.7 326.4 326.7 343.8 350.2 15721.0 17125.8 18019.0 18793.3 18975.7 18184.5 18263.8 18771.0 18772.7 18992.0 5303.1 5534.8 5588.4 5592.6 5631.2 4135.9 5547.8 5573.8 5647.5 5659.5 5715.8 5760.7 5811.7 5814.9 5835.6 4869.5 5570.7 5761.3 5790.5 5804.4 5546.8 5567.8 5583.8 5589.6 5611.5 12211.2 12469.6 12499.9 12734.0 12756.6 2024.2 2110.2 2253.2 2427.3 2760.0 9232.0 9253.8 9373.4 9741.0 9753.9 1693.5 1723.6 1736.7 1765.3 1792.6 85252.6 102655.5 109207.9 109280.5 109475.0 10826.9 10840.7 11035.0 11053.6 11120.6 13700.5 13709.4 13713.8 13725.6 13729.7 6501.5 7794.7 7949.1 8025.8 8091.0 6475.2 7709.1 7711.2 7742.4 7908.5 4248.6 4250.4 4261.2 4270.5 4306.3 1788.3 1865.9 1875.7 1904.4 1922.5 652.9 681.4 749.6 816.4 834.6 18725.2 19626.3 19716.3 19768.7 20124.7 764.6 793.6 821.9 840.5 883.0 8368.7 10151.8 10239.0 10252.3 10285.7 481692.0 492562.0 504165.0 527654.0 529648.0 84880.6 115970.0 116851.4 120470.4 124619.1 820.9 881.5 913.0 916.2 919.6 12561.7 14489.3 16982.7 17815.9 18004.2 30098.2 39282.8 40204.6 45725.8 54141.5 48505.5 63159.0 69768.0 70707.6 71700.3 7294.2 7411.9 7582.8 7749.1 7958.0 9950.8 11668.0 11827.4 11908.2 11954.0 3864.8 9531.5 10092.6 10478.8 10513.7 9670.3 9860.3 10053.7 10470.7 10495.7 5420.6 9770.0 10362.6 10528.9 10946.7 7438.7 7579.9 7800.8 7900.8 7987.8 9898.3 10039.9 10409.1 10457.2 10487.8 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5147.32 Median:5086.61 stddev:2891.03 (56.8%) stddev-sans-first:2884.74 10:32:35 INFO - PROCESS | 1637 | Values: 1307.4 1370.0 1460.4 1567.6 1646.6 6747.4 7291.3 7548.6 8197.5 8210.0 7780.6 8086.1 8096.0 8176.8 8198.3 2137.2 2180.2 2182.7 2185.2 2195.4 2162.6 2199.3 2213.4 2217.6 2219.2 1854.3 2182.5 2201.3 2202.6 2210.6 2155.9 2170.2 2176.8 2186.5 2189.9 1696.7 1820.8 2110.3 2162.5 2174.1 7165.5 7282.5 7733.5 8138.9 8201.5 4155.9 4188.6 4200.2 4801.2 5000.4 2984.9 3026.4 3425.6 3429.9 3458.5 924.8 948.8 995.3 995.6 1052.6 10547.5 10578.5 10595.2 10596.6 10636.8 4374.9 4418.1 4488.5 4524.3 4551.7 7910.5 7955.4 7979.8 7998.6 8016.7 4722.8 4730.0 4736.8 4744.1 4751.0 4123.2 4138.8 4357.8 4478.2 4494.8 3331.7 3467.5 3477.8 3551.9 3602.3 985.1 1090.8 1091.0 1104.7 1108.0 690.4 725.6 768.5 809.8 813.9 4904.0 5171.7 5213.8 5442.8 5627.1 330.3 390.5 394.0 394.6 407.3 3586.8 3636.7 3670.4 3688.9 3734.8 9458.7 9912.9 9918.6 9923.0 9953.4 9160.5 9537.7 9877.1 9878.1 9893.6 7345.4 7570.2 7576.9 7584.3 7612.2 5272.2 5272.9 5288.3 5552.4 5553.0 8855.2 8943.0 8975.6 9034.6 9073.1 7724.2 9080.7 9114.1 9115.1 9211.7 5773.2 6171.5 6570.3 6937.4 7065.7 7710.0 7749.4 7789.6 7798.4 7844.2 6870.9 6920.8 6996.7 7003.4 7140.4 6805.9 6808.4 6869.9 6885.9 6922.9 6407.4 6492.8 6553.8 6556.0 6571.1 4425.7 4624.4 4673.6 4693.2 5001.5 4630.9 4732.8 4741.1 4802.1 4861.8 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:3674.74 Median:4039.57 stddev:1119.18 (27.7%) stddev-sans-first:1092.82 10:32:35 INFO - PROCESS | 1637 | Values: 274.2 275.4 280.6 283.5 285.4 4157.2 4279.7 4284.5 4293.4 4380.8 3485.6 4020.4 4111.7 4209.5 4239.3 3168.4 3312.9 3377.5 3379.8 3393.5 2913.9 3229.4 3350.4 3363.4 3373.7 3323.3 3337.5 3346.9 3350.6 3364.9 2649.6 2795.0 2824.9 3343.0 3413.7 2878.2 2977.0 2983.5 3004.6 3006.4 3464.4 3685.7 3779.2 4032.5 4037.8 3729.4 3805.8 3806.9 3811.9 3841.4 4494.9 4502.9 4518.7 4542.9 4544.5 2861.9 3099.2 3274.0 3278.4 3286.7 4712.2 4725.3 4734.5 4739.3 4739.6 2854.7 3304.9 3315.5 3320.4 3333.3 2736.0 3302.2 3358.0 3366.9 3372.4 3548.2 3585.4 3794.3 3795.5 3814.8 3620.4 4111.1 4635.8 4647.4 4696.1 1041.0 1074.9 1099.0 1109.2 1110.5 671.3 736.0 770.3 775.8 776.6 3500.3 3563.0 3992.8 4051.8 4054.6 4396.8 4524.3 4534.0 4536.6 4544.2 3397.7 3869.7 4041.3 4050.9 4052.6 2001.8 2022.6 2091.9 2326.7 2327.4 3793.6 3855.4 3964.8 4340.3 4680.9 4464.7 4604.3 4609.7 4618.0 4637.3 4097.7 4419.1 4578.5 4663.0 4707.5 4463.8 4641.8 4646.7 4784.5 4819.6 4085.7 4390.6 4574.8 4707.7 4768.9 4625.5 5002.0 5033.6 5043.4 5044.0 3258.4 3299.1 3343.4 3345.8 3345.9 4548.7 4752.8 4823.8 4831.4 4853.0 3464.0 4430.2 4593.1 4632.2 4658.1 3441.1 4174.9 4510.0 4623.9 4649.0 3813.4 3965.5 4157.9 4567.8 4588.7 4154.4 4321.0 4501.5 4596.7 4633.9 3986.9 4343.6 4531.5 4550.7 4561.0 10:32:35 INFO - PROCESS | 1637 | 10:32:35 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19335.55 Median:4192.24 stddev:28239.49 (673.6%) stddev-sans-first:28283.92 10:32:35 INFO - PROCESS | 1637 | Values: 666.4 695.6 700.4 702.1 739.5 13721.9 14398.6 14434.9 14455.3 14512.9 11289.2 13326.9 13367.1 13472.9 13497.7 612.3 631.8 771.4 781.6 791.6 807.2 807.4 808.4 809.4 809.6 844.1 844.4 844.6 845.6 847.9 839.9 841.5 843.6 843.7 844.7 622.7 622.8 622.8 623.3 623.5 12265.4 13631.9 13700.5 13776.2 13829.2 13259.6 13304.3 13349.8 13418.8 13463.7 11334.9 11339.4 11350.1 11475.5 11495.9 3524.4 3528.2 3536.0 3554.1 3661.7 102496.0 102853.0 103987.0 105025.0 106807.0 10214.0 10250.1 10293.3 10373.0 10411.8 20417.4 20512.8 20521.7 20530.5 20574.6 7095.5 7210.3 7222.4 7357.5 7436.6 4045.6 4074.3 4189.1 4195.4 4196.3 3282.5 3375.5 3417.3 3430.7 3431.9 507.6 537.3 591.1 600.0 607.5 510.6 559.2 563.8 571.0 571.1 7431.5 7830.4 8598.5 8680.8 8845.9 521.4 550.5 571.8 577.3 586.0 3478.3 3553.3 4109.7 4236.5 4268.4 89935.6 90194.0 92180.0 93957.0 93986.1 89154.6 89688.6 89718.0 92395.0 93808.0 63543.0 70407.6 72074.0 82064.6 84615.6 7456.6 7675.6 8533.9 9069.2 9344.9 32147.7 46186.8 47288.0 47963.8 48100.3 60147.1 63563.0 63686.7 65155.0 67196.7 11621.6 11869.7 11943.0 12321.9 12644.7 12407.4 13048.6 13670.2 13706.6 13723.2 11029.9 12631.5 12765.7 12937.9 13144.1 11545.9 12916.4 12960.7 13323.1 13391.9 11651.3 12674.1 12678.2 12913.2 12945.7 10349.0 10478.3 10571.4 10651.8 10700.4 8975.8 11063.6 11064.4 11078.2 11107.9 10:32:35 INFO - PROCESS | 1637 | -------- Summary: end -------- 10:32:35 INFO - PROCESS | 1637 | 10:32:36 INFO - TEST-INFO | 1637: exit 0 10:32:36 INFO - TEST-OK | dromaeo_css | took 1114873ms 10:32:36 INFO - TEST-START | kraken 10:32:36 INFO - operating with platform_type : mac_ 10:32:36 INFO - Initialising browser for kraken test... 10:32:36 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpjyEXDM/profile http://localhost:49241/getInfo.html) 10:32:39 INFO - PROCESS | 1649 | 2016-11-29 10:32:39.599 plugin-container[1650:9890] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 10:32:39 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:39 INFO - PROCESS | 1649 | 2016-11-29 10:32:39.602 plugin-container[1650:9890] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 10:32:39 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:39 INFO - PROCESS | 1649 | 2016-11-29 10:32:39.602 plugin-container[1650:9890] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:32:39 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 10:32:39 INFO - PROCESS | 1649 | colorDepth:24 10:32:39 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 10:32:39 INFO - PROCESS | 1649 | __metrics 10:32:39 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 10:32:40 INFO - TEST-INFO | 1649: exit 0 10:32:40 INFO - Browser initialized. 10:32:40 INFO - Running cycle 1/1 for kraken test... 10:32:40 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpjyEXDM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:43 INFO - PROCESS | 1651 | 2016-11-29 10:32:43.546 plugin-container[1652:10005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872b, name = 'com.apple.tsm.portname' 10:32:43 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:43 INFO - PROCESS | 1651 | 2016-11-29 10:32:43.547 plugin-container[1652:10005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 10:32:43 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:32:43 INFO - PROCESS | 1651 | 2016-11-29 10:32:43.547 plugin-container[1652:10005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:32:53 INFO - PROCESS | 1651 | RSS: Main: 243081216 10:32:53 INFO - PROCESS | 1651 | 10:32:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:32:57 INFO - PROCESS | 1651 | RSS: Main: 255016960 10:32:57 INFO - PROCESS | 1651 | 10:33:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:33:01 INFO - PROCESS | 1651 | RSS: Main: 262868992 10:33:01 INFO - PROCESS | 1651 | 10:33:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:33:05 INFO - PROCESS | 1651 | RSS: Main: 265371648 10:33:05 INFO - PROCESS | 1651 | 10:33:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:33:07 INFO - PROCESS | 1651 | RSS: Main: 267296768 10:33:07 INFO - PROCESS | 1651 | 10:33:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:33:10 INFO - PROCESS | 1651 | RSS: Main: 268435456 10:33:10 INFO - PROCESS | 1651 | 10:33:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:33:14 INFO - PROCESS | 1651 | RSS: Main: 273235968 10:33:14 INFO - PROCESS | 1651 | 10:33:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:33:18 INFO - PROCESS | 1651 | RSS: Main: 272650240 10:33:18 INFO - PROCESS | 1651 | 10:33:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:33:22 INFO - PROCESS | 1651 | RSS: Main: 272490496 10:33:22 INFO - PROCESS | 1651 | 10:33:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:33:24 INFO - PROCESS | 1651 | RSS: Main: 272257024 10:33:24 INFO - PROCESS | 1651 | 10:33:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:33:27 INFO - PROCESS | 1651 | RSS: Main: 272289792 10:33:27 INFO - PROCESS | 1651 | 10:33:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:33:30 INFO - PROCESS | 1651 | RSS: Main: 272367616 10:33:30 INFO - PROCESS | 1651 | 10:33:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:33:34 INFO - PROCESS | 1651 | RSS: Main: 272576512 10:33:34 INFO - PROCESS | 1651 | 10:33:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:33:38 INFO - PROCESS | 1651 | RSS: Main: 272396288 10:33:38 INFO - PROCESS | 1651 | 10:33:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:33:40 INFO - PROCESS | 1651 | RSS: Main: 275640320 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | __start_tp_report 10:33:40 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 10:33:40 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 10:33:40 INFO - PROCESS | 1651 | |i|pagename|runs| 10:33:40 INFO - PROCESS | 1651 | |0;ai-astar;114;124;137;183;157;124;123;126;146;179 10:33:40 INFO - PROCESS | 1651 | |1;audio-beat-detection;156;133;151;192;126;124;132;152;174;122 10:33:40 INFO - PROCESS | 1651 | |2;audio-dft;228;227;221;224;229;226;224;223;227;226 10:33:40 INFO - PROCESS | 1651 | |3;audio-fft;84;66;63;88;63;65;64;62;77;61 10:33:40 INFO - PROCESS | 1651 | |4;audio-oscillator;84;75;89;77;71;75;89;74;69;106 10:33:40 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;95;94;115;92;95;92;95;103;123;92 10:33:40 INFO - PROCESS | 1651 | |6;imaging-darkroom;100;100;119;156;107;118;155;106;122;117 10:33:40 INFO - PROCESS | 1651 | |7;imaging-desaturate;106;169;142;112;122;99;97;169;163;116 10:33:40 INFO - PROCESS | 1651 | |8;json-parse-financial;60;61;62;63;61;75;77;57;59;60 10:33:40 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;71;89;75;79;81;74;74;74;76;77 10:33:40 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;93;92;95;114;106;117;115;106;91;94 10:33:40 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;185;190;176;182;179;180;174;159;220;192 10:33:40 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;186;186;188;217;247;195;187;189;216;188 10:33:40 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;75;73;75;75;73;74;74;72;74;75 10:33:40 INFO - PROCESS | 1651 | __end_tp_report 10:33:40 INFO - PROCESS | 1651 | __start_cc_report 10:33:40 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1012 10:33:40 INFO - PROCESS | 1651 | __end_cc_report 10:33:40 INFO - PROCESS | 1651 | __startTimestamp1480444420113__endTimestamp 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | ------- Summary: start ------- 10:33:40 INFO - PROCESS | 1651 | Number of tests: 14 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:141.30 Median:141.50 stddev:24.40 (17.2%) stddev-sans-first:23.80 10:33:40 INFO - PROCESS | 1651 | Values: 114.0 124.0 137.0 183.0 157.0 124.0 123.0 126.0 146.0 179.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:151.50 stddev:23.30 (15.4%) stddev-sans-first:24.44 10:33:40 INFO - PROCESS | 1651 | Values: 156.0 133.0 151.0 192.0 126.0 124.0 132.0 152.0 174.0 122.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:225.50 Median:226.50 stddev:2.46 (1.1%) stddev-sans-first:2.44 10:33:40 INFO - PROCESS | 1651 | Values: 228.0 227.0 221.0 224.0 229.0 226.0 224.0 223.0 227.0 226.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.30 Median:65.50 stddev:9.91 (15.1%) stddev-sans-first:8.97 10:33:40 INFO - PROCESS | 1651 | Values: 84.0 66.0 63.0 88.0 63.0 65.0 64.0 62.0 77.0 61.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:76.00 stddev:11.27 (14.8%) stddev-sans-first:11.90 10:33:40 INFO - PROCESS | 1651 | Values: 84.0 75.0 89.0 77.0 71.0 75.0 89.0 74.0 69.0 106.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:93.00 stddev:10.88 (11.7%) stddev-sans-first:11.41 10:33:40 INFO - PROCESS | 1651 | Values: 95.0 94.0 115.0 92.0 95.0 92.0 95.0 103.0 123.0 92.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:120.00 Median:118.50 stddev:20.29 (17.1%) stddev-sans-first:20.19 10:33:40 INFO - PROCESS | 1651 | Values: 100.0 100.0 119.0 156.0 107.0 118.0 155.0 106.0 122.0 117.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:129.50 Median:166.00 stddev:28.83 (17.4%) stddev-sans-first:29.30 10:33:40 INFO - PROCESS | 1651 | Values: 106.0 169.0 142.0 112.0 122.0 99.0 97.0 169.0 163.0 116.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:61.50 stddev:6.80 (11.1%) stddev-sans-first:7.10 10:33:40 INFO - PROCESS | 1651 | Values: 60.0 61.0 62.0 63.0 61.0 75.0 77.0 57.0 59.0 60.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:77.00 Median:76.50 stddev:5.08 (6.6%) stddev-sans-first:4.90 10:33:40 INFO - PROCESS | 1651 | Values: 71.0 89.0 75.0 79.0 81.0 74.0 74.0 74.0 76.0 77.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:102.30 Median:91.50 stddev:10.46 (11.4%) stddev-sans-first:10.54 10:33:40 INFO - PROCESS | 1651 | Values: 93.0 92.0 95.0 114.0 106.0 117.0 115.0 106.0 91.0 94.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:183.70 Median:183.50 stddev:15.74 (8.6%) stddev-sans-first:16.69 10:33:40 INFO - PROCESS | 1651 | Values: 185.0 190.0 176.0 182.0 179.0 180.0 174.0 159.0 220.0 192.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.90 Median:192.00 stddev:20.41 (10.6%) stddev-sans-first:21.02 10:33:40 INFO - PROCESS | 1651 | Values: 186.0 186.0 188.0 217.0 247.0 195.0 187.0 189.0 216.0 188.0 10:33:40 INFO - PROCESS | 1651 | 10:33:40 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:74.50 stddev:1.05 (1.4%) stddev-sans-first:1.05 10:33:40 INFO - PROCESS | 1651 | Values: 75.0 73.0 75.0 75.0 73.0 74.0 74.0 72.0 74.0 75.0 10:33:40 INFO - PROCESS | 1651 | -------- Summary: end -------- 10:33:40 INFO - PROCESS | 1651 | 10:33:41 INFO - TEST-INFO | 1651: exit 0 10:33:41 INFO - TEST-OK | kraken | took 64828ms 10:33:41 INFO - SUITE-END | took 1179s 10:33:41 INFO - Completed test suite (00:19:39) 10:33:41 INFO - javascript benchmark 10:33:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6337.054154949882, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2702.1623296778002, 2764.281286865417, 2764.764994975427, 2825.562037884127, 2832.107886015904, 3994.1212933154698, 4200.768957707325, 4426.424564806579, 4453.554644535545, 4579.992401671635, 4434.756088415136, 4505.256632655616, 4528.18492671319, 4536.886577835556, 4546.56807603278, 4100.0, 4151.896202594944, 4186.623203911648, 4326.459192600925, 4352.847650332239, 4180.080382315886, 4234.1320029394055, 4238.173556156551, 4240.215560121372, 4330.328799036154, 4068.8982775430554, 4148.211839750437, 4206.72656277341, 4208.494980602317, 4314.395984562164, 4089.7137200395982, 4117.238310912461, 4125.525564560054, 4155.542890282067, 4182.9790851045855, 4191.811368488426, 4199.496060472732, 4230.915381692349, 4266.786660666985, 4304.418903448026, 3302.058913209724, 4357.085012147453, 4405.493368262659, 4434.733915965053, 4482.417285752864, 3968.861089861841, 4730.361401210827, 4813.350197723297, 4836.395450568679, 4982.252662100714, 4849.199882019498, 4909.509049095098, 4911.533404326583, 4938.407391113089, 4996.100701873661, 4613.677042607019, 4619.907601847978, 4674.836380726658, 4712.363830882856, 4718.079974404958, 5017.67385119966, 5038.722870242099, 5041.495850414929, 5068.847934561969, 5148.897022059613, 4695.460022097434, 4741.57325840676, 4763.642726795435, 4812.663113582085, 4826.686265392738, 4910.901781964412, 4921.941782516776, 4923.310736496894, 4950.702957822542, 4988.950110498921, 4709.882252943636, 4770.594499467584, 4787.042591481718, 4790.041991601694, 4838.129136755348, 4375.759333236639, 4426.690131690751, 4842.661013729005, 4909.459959404463, 4933.777979990909, 3452.6202117767025, 4010.538788039413, 4032.2741906457118, 4033.495813023372, 4050.1494686116243, 3409.9488507672404, 3522.788632682047, 3534.0988048047584, 3543.681068703829, 3574.463830425405, 3800.638939300762, 4068.613481719232, 4551.044280701024, 4560.977195114003, 4631.791569379386, 4107.482939215984, 4973.825916092919, 4989.600831933437, 5010.574101201366, 5032.0690672225755, 4456.108778244364, 4497.77511124449, 4517.954820451819, 4583.335416364608, 4592.127495775859, 3740.6446387593137, 4339.826406943687, 4430.20256353853, 4432.202203603382, 4451.287793952952, 5069.873253168626, 5082.263071854634, 5099.566536844335, 5106.948930510647, 5111.821086261963, 4908.484609115992, 4909.558139767438, 4933.77797999098, 4977.552020318189, 4990.80036798524, 3634.3639863024396, 4605.861824145281, 4936.531029552187, 5087.720175390241, 5097.464766199496, 4863.148948933993, 4867.953390021163, 4868.780904859361, 4873.585745211555, 4882.7558622068045, 4691.882702932454, 4847.103285892122, 4866.78099485531, 4974.328465657235, 5005.249212618136, 4826.7827947743135, 4861.12839092078, 4891.559759621508, 4907.067657145131, 4912.214045752662, 4850.102730994778, 4875.780589873393, 4880.80476780939, 4897.240927656077, 4905.117078925685, 4505.819767209275, 4577.6795624307315, 4643.628509719147, 4650.883727906829, 4681.578657920803, 4810.134175848241, 4853.830115945925, 4879.316895634543, 4891.046245981999, 4941.604671626189, 4783.784729687103, 4785.856424307276, 4812.590929770869, 4819.6867203631655, 4824.0], "value": 4493.9993365309065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.74251820167007, 453.757239876666, 464.4125181645217, 467.2337366718582, 493.71364608527057, 25518.744812551868, 37876.62123378766, 37915.81042094793, 37931.81034094826, 37954.43068353974, 38293.80853095731, 38818.4177237341, 38872.41691374632, 38899.02752431184, 38915.22169556607, 8110.432269741121, 8892.866607000884, 9118.589663465127, 9125.0, 9127.22418594417, 8026.6789328427085, 10680.305780124267, 10935.671929842118, 10944.890551094506, 11070.39112848793, 10911.563537458493, 10915.0, 10972.0, 10974.506147223394, 10998.835017474745, 10795.622153224638, 10822.51298691553, 10868.67393978182, 10869.67390978268, 10917.126629869592, 8467.449615774955, 8923.15230053144, 9317.534123293808, 10776.191785616033, 10873.803881573021, 28144.577831332546, 30156.09531714052, 36981.630183698086, 37073.0, 37740.62259377399, 2672.759451649361, 2792.287966568512, 3434.742394320436, 3498.265364273506, 3506.211102501942, 27502.312442188904, 28122.156335309974, 28691.28271793201, 28775.136745897446, 28857.42285154285, 2636.8549729764854, 2880.4095160492047, 3129.749620030392, 3183.570218020561, 3204.214967332995, 377133.0, 382266.0, 386263.0, 386506.0, 390327.0, 53665.1950220747, 53943.730281349126, 54034.7298263514, 54296.45703542914, 54439.72780136074, 55046.72476637591, 55416.445835541934, 55443.0, 55446.72276638591, 55472.0, 14374.640633984025, 14757.262136893327, 14773.113613183243, 14813.777793333109, 14822.110673359633, 10325.74185645365, 10612.893871061346, 11223.607173749044, 11461.4269286537, 11530.654080377602, 12492.312922789333, 14518.419263229564, 14802.407903683732, 14908.701825963637, 15059.774103388458, 5900.64596124234, 6851.595422938286, 6973.930260697328, 7014.929850701427, 7030.613316267654, 711.4029004884069, 721.5814827400095, 782.8473447677762, 800.9359251259885, 805.3436449293807, 27522.587161192598, 27856.303592410353, 28019.719802801712, 28412.857935710603, 28486.71513284882, 860.1871231686122, 919.0, 964.2719746591279, 985.8422652375729, 1020.4387586827215, 14377.849772018213, 14533.563993080224, 14574.85425145735, 14693.23487351199, 14726.410943562352, 475591.0, 485981.0, 486018.0, 486625.0, 493247.3440819897, 50927.74536127296, 51029.74485127551, 51100.48899511032, 51131.488685113414, 52329.47670523246, 1218.6587755428495, 1221.883921027501, 1263.7346157306886, 1264.4373253901924, 1268.3594784633885, 26724.46551068929, 27218.72781272162, 27744.167674969784, 27861.860690696416, 27912.58131127994, 45413.772931135136, 45732.54267457416, 45770.0, 45876.31185532286, 45903.0, 435968.0, 436596.0, 438537.0, 438571.8071409623, 442861.0, 23713.40716482102, 24573.754262457147, 24595.877020614782, 25326.986920522973, 36358.0, 42212.577874222094, 42806.78596606997, 43463.348049778644, 43652.0, 44087.0, 30320.090397288117, 30967.535486968165, 35422.0, 35622.28755424929, 36454.8177259112, 29951.251218719706, 30253.848730756206, 35845.0, 36107.0, 36459.08852278608, 35325.76359827394, 35326.0, 35354.0, 35462.82268588641, 35472.46791298081, 27298.590521142596, 29673.312179449007, 30191.282784548617, 30878.4560771965, 32313.51529727009, 23702.28893133209, 23870.761292386855, 23934.922928468608, 24462.0, 24705.7647117647], "value": 18116.08242073633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.65451675898134, 326.38150704415136, 326.6521154969958, 343.78169862137537, 350.24871650310087, 15721.0, 17125.82874171258, 18019.0, 18793.308602225796, 18975.71536426955, 18184.54538636535, 18263.81736182638, 18771.0, 18772.71840922384, 18992.0, 5303.071962406573, 5534.75093620788, 5588.4411558844195, 5592.6085174037835, 5631.211630371751, 4135.855245066423, 5547.750351234184, 5573.804916827912, 5647.463490968352, 5659.547236221113, 5715.771369145229, 5760.654360738369, 5811.6803575803315, 5814.866101110285, 5835.620684655505, 4869.464358920533, 5570.693611851347, 5761.308642962829, 5790.478856902899, 5804.361520232771, 5546.805861794818, 5567.777288908439, 5583.776648934078, 5589.608727389084, 5611.466910643482, 12211.206271592408, 12469.563565275263, 12499.875001250053, 12734.0, 12756.553520626827, 2024.230792298917, 2110.240313487159, 2253.245162870424, 2427.271818454474, 2760.0339881041677, 9232.0, 9253.814923701555, 9373.390729602623, 9741.0, 9753.902460975441, 1693.5342780735273, 1723.5518765121158, 1736.6700326937842, 1765.276236742942, 1792.6145878636155, 85252.57373713153, 102655.48672256665, 109207.90792092137, 109280.4535977323, 109475.0, 10826.89173108259, 10840.728981775519, 11035.0, 11053.613123540637, 11120.555177792798, 13700.520481783089, 13709.383077761524, 13713.794293085612, 13725.588232353046, 13729.656758581115, 6501.47988160946, 7794.688212471551, 7949.125596184415, 8025.839483210303, 8091.0, 6475.190601174853, 7709.113451952985, 7711.190325015905, 7742.380609551223, 7908.52548847071, 4248.638865696388, 4250.404943307939, 4261.232978063917, 4270.466191726035, 4306.2894622387585, 1788.3115000724815, 1865.8880467172014, 1875.6530041942285, 1904.352520143158, 1922.5193701574606, 652.9249136349381, 681.3663293137331, 749.6102026945957, 816.3673153306116, 834.6202477872554, 18725.157367918477, 19626.313079042164, 19716.309929152412, 19768.70346944797, 20124.698129528068, 764.5603777827771, 793.6071644535991, 821.8685010398308, 840.5461051032496, 882.960266787996, 8368.661014237692, 10151.847722284172, 10239.0, 10252.333598316234, 10285.7428564285, 481692.0, 492562.0, 504165.0, 527654.0, 529648.0, 84880.57559712161, 115970.0, 116851.41574292074, 120470.3976480112, 124619.13071303756, 820.8973878265217, 881.5415983688446, 912.9637861027782, 916.1662886773005, 919.5723988345452, 12561.748765024831, 14489.347979341166, 16982.745258821375, 17815.910920445316, 18004.189811458255, 30098.24754381158, 39282.80358598303, 40204.59795402126, 45725.77137114293, 54141.45858541522, 48505.5149448501, 63159.0, 69767.95348069885, 70707.64646176736, 71700.28299717145, 7294.234105418855, 7411.851163069733, 7582.848343033219, 7749.108852482037, 7958.0, 9950.751231218988, 11668.0, 11827.408629568316, 11908.166428349932, 11954.043676506033, 3864.787436691009, 9531.52342382892, 10092.646757363456, 10478.79042419132, 10513.684589462327, 9670.274729395183, 9860.309778315735, 10053.69838904834, 10470.685879423629, 10495.737606559896, 5420.647657902224, 9769.951150244204, 10362.585496580052, 10528.894711052792, 10946.705644254427, 7438.73964411243, 7579.851179115493, 7800.765977020698, 7900.802479938047, 7987.800304992189, 9898.307118501636, 10039.949800250952, 10409.063184313449, 10457.215708822021, 10487.79024419492], "value": 8512.526341260751, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.4441414716487, 1370.0341259412112, 1460.3574427252008, 1567.5845900836287, 1646.5883529117727, 6747.359000894913, 7291.307325804053, 7548.622568871562, 8197.467164634301, 8209.958950205255, 7780.610969451519, 8086.070101938264, 8096.0, 8176.754697359089, 8198.262156405923, 2137.1558234497325, 2180.1824315881545, 2182.7053347798083, 2185.2242453928866, 2195.3633446300555, 2162.5891080694696, 2199.285232299501, 2213.3691897809126, 2217.5786600545844, 2219.178903805594, 1854.2953677602493, 2182.4871155278497, 2201.2515744646817, 2202.570498752744, 2210.6352451845505, 2155.8814265215406, 2170.1536400803698, 2176.814970727482, 2186.5189658275153, 2189.8905054747197, 1696.703076961527, 1820.7982731397346, 2110.2930518276394, 2162.470194802268, 2174.07601769249, 7165.498415110945, 7282.526635768658, 7733.4973226740085, 8138.94193754814, 8201.507909525388, 4155.8961025974595, 4188.623023927861, 4200.243956087934, 4801.207800712878, 5000.399952005746, 2984.850757462118, 3026.44010857992, 3425.6403077676982, 3429.8628054877777, 3458.4985177149424, 924.8242833861613, 948.7770373962113, 995.2585323933644, 995.576879826071, 1052.642091701704, 10547.525361358757, 10578.52396642153, 10595.152387808957, 10596.576136954513, 10636.787264254748, 4374.934375984363, 4418.138462999685, 4488.461384633865, 4524.298733696282, 4551.658625603093, 7910.52536847791, 7955.403344749051, 7979.800504987422, 7998.640061197144, 8016.679332826739, 4722.787474563653, 4729.95270047302, 4736.810527578928, 4744.122337367598, 4750.95249047512, 4123.175364927027, 4138.79306034697, 4357.760323182255, 4478.216312145361, 4494.797734101973, 3331.666833316649, 3467.531883195736, 3477.7739446935866, 3551.893443196708, 3602.3155600435834, 985.1380042462845, 1090.8200146975819, 1090.9509072091776, 1104.6520346090956, 1107.972300692473, 690.440742998173, 725.575538310084, 768.5043147170156, 809.817790997021, 813.8982627171604, 4903.950960490421, 5171.689698618095, 5213.817516386908, 5442.755076021588, 5627.127795191752, 330.2766940400515, 390.47871092091805, 393.9901502462461, 394.58371418153547, 407.33197556008173, 3586.7668601540818, 3636.6908812751212, 3670.3943849265106, 3688.870889518854, 3734.8132593370765, 9458.716238512856, 9912.900870991341, 9918.553665085088, 9923.0, 9953.353032052893, 9160.450372977642, 9537.713868583953, 9877.111060004634, 9878.110970012733, 9893.554790034466, 7345.449091318173, 7570.16728159902, 7576.886346704804, 7584.317411432998, 7612.200718924544, 5272.235525848807, 5272.920906186334, 5288.312519372457, 5552.416996215339, 5553.0, 8855.203031727175, 8942.955285223792, 8975.640974360953, 9034.638614455349, 9073.13805188506, 7724.227577242231, 9080.727578172666, 9114.088591140833, 9115.088491150831, 9211.67759128454, 5773.16289138081, 6171.475424588781, 6570.310117437792, 6937.445004399535, 7065.728168929642, 7710.0, 7749.380049596131, 7789.5715735635085, 7798.37612990948, 7844.176361481962, 6870.862582748417, 6920.792376228721, 6996.685149168198, 7003.369696727319, 7140.428765698835, 6805.931940680728, 6808.38724514796, 6869.896951545831, 6885.896711549431, 6922.8615427692175, 6407.359264073555, 6492.837679057872, 6553.803385898431, 6555.967220163869, 6571.112899758471, 4425.690201685979, 4624.421947256593, 4673.556012178838, 4693.24908014716, 5001.524855138756, 4630.884227894329, 4732.787024583833, 4741.122892264942, 4802.135615589227, 4861.781219845187], "value": 4056.282151241301, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.1843017024353, 275.40649899466644, 280.62256265323145, 283.5166041898562, 285.3508268688732, 4157.189348077126, 4279.677525699043, 4284.485861696597, 4293.3989241506215, 4380.795281297641, 3485.6165821759587, 4020.366659837811, 4111.650509706671, 4209.515905670849, 4239.342901850218, 3168.3980043791607, 3312.933741325172, 3377.527146199534, 3379.7972121672715, 3393.541871847306, 2913.863593198654, 3229.4348489014446, 3350.430426827433, 3363.445031569788, 3373.662633736619, 3323.3187196624635, 3337.5327454156436, 3346.916327091818, 3350.5644266245354, 3364.9158771030675, 2649.5893136563864, 2794.9580756288674, 2824.8728807203725, 3342.9665703343144, 3413.7269018478464, 2878.237267124193, 2976.9851150744325, 2983.5077212259953, 3004.6394432668003, 3006.4137493188837, 3464.441001549313, 3685.7468460723485, 3779.2063666630042, 4032.475778148851, 4037.7981100944835, 3729.4336378721, 3805.82019573933, 3806.9428958565645, 3811.9123509957026, 3841.3853783394525, 4494.943688233263, 4502.864914052584, 4518.712167032413, 4542.863714088549, 4544.454665440136, 2861.885524579014, 3099.2251937015744, 3273.950890736617, 3278.4098862205033, 3286.6713328667183, 4712.246040633517, 4725.291206319045, 4734.455537613149, 4739.265413860822, 4739.620830333565, 2854.743073123387, 3304.9339013220083, 3315.4529502632276, 3320.4023275810105, 3333.283344081034, 2736.0, 3302.1744563859033, 3358.009387230773, 3366.8552692084318, 3372.409828280041, 3548.214651498561, 3585.408407612767, 3794.317022935898, 3795.5065841440987, 3814.779270633426, 3620.3845346291196, 4111.09555897702, 4635.837745678885, 4647.442306923191, 4696.084263568571, 1040.9798397570423, 1074.9086327662237, 1098.961536346224, 1109.2124591539934, 1110.4780753046055, 671.2984930747381, 735.9622931666431, 770.3259647808168, 775.8021704465325, 776.5767656627152, 3500.299940012008, 3563.0, 3992.762243704461, 4051.7771522565954, 4054.574269701639, 4396.824127034883, 4524.298733696217, 4534.0, 4536.56902594253, 4544.182047231464, 3397.7451691123265, 3869.7097717671286, 4041.333180025322, 4050.9189816204102, 4052.63526282636, 2001.8380149438572, 2022.6157030164222, 2091.9477013074793, 2326.651002349661, 2327.428985434825, 3793.639604237593, 3855.4216867470104, 3964.7819369934355, 4340.327249276367, 4680.906381872412, 4464.709793863389, 4604.286335617984, 4609.700369475973, 4617.976910115428, 4637.25803871379, 4097.651699605567, 4419.11617676466, 4578.473475550354, 4662.976685116552, 4707.529247075265, 4463.843765468193, 4641.837535686234, 4646.721196728207, 4784.521547845187, 4819.638527110411, 4085.693572981979, 4390.560943905584, 4574.771261436848, 4707.739495756346, 4768.928466072943, 4625.491195968373, 5001.974990125026, 5033.597312215087, 5043.435690699923, 5043.9747801260755, 3258.364618899292, 3299.109240505098, 3343.4316166252283, 3345.8327083645236, 3345.882894098695, 4548.658850586153, 4752.809887604595, 4823.807047718193, 4831.371921650163, 4852.986010805476, 3463.9653603463644, 4430.2468580342065, 4593.081383723202, 4632.235681112578, 4658.114958157939, 3441.105312618787, 4174.935391475155, 4510.030343476168, 4623.93064104032, 4648.953510464988, 3813.3517302058162, 3965.4621042533377, 4157.875263742093, 4567.771611419482, 4588.655850811136, 4154.3976123461325, 4320.978395108004, 4501.482329532146, 4596.701214421052, 4633.86098417048, 3986.8836725715687, 4343.5656434356315, 4531.478879928851, 4550.704204226715, 4561.0], "value": 3329.5964056474936, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.3503084492618, 695.5548448992648, 700.4291502425523, 702.0837806662306, 739.4564994728875, 13721.862781372183, 14398.568042958701, 14434.927825360859, 14455.277236138203, 14512.927435362823, 11289.209755317132, 13326.933365333183, 13367.131136476146, 13472.932635336834, 13497.662558436044, 612.2928018139056, 631.8167731357923, 771.4175797273051, 781.5545139270617, 791.5923299500762, 807.1565214351017, 807.4146243973131, 808.3613944983456, 809.3889113719134, 809.6073404398862, 844.0968164064453, 844.3582877013453, 844.5523872347666, 845.623697454633, 847.9152084791502, 839.9202075802789, 841.5203334099566, 843.5992903370911, 843.7173546861809, 844.7423535821572, 622.6730966242714, 622.8224955887596, 622.8349487385847, 623.2520974830177, 623.4856243599004, 12265.38673066352, 13631.863681363158, 13700.45198192071, 13776.24230667323, 13829.170249784944, 13259.602211933658, 13304.268265245408, 13349.799753003712, 13418.798718019238, 13463.663408414868, 11334.943325283402, 11339.37633430161, 11350.148738844537, 11475.483603237873, 11495.88504114965, 3524.436090225577, 3528.2237907660274, 3535.9646403535894, 3554.0759402555523, 3661.7253705972157, 102496.0, 102853.0, 103987.0, 105025.0, 106806.99999999923, 10214.0, 10250.128739057114, 10293.330933489298, 10373.0, 10411.843822342671, 20417.387478375673, 20512.79487205139, 20521.692174617394, 20530.486737831674, 20574.58850822975, 7095.50331476802, 7210.31502007319, 7222.422206223489, 7357.484976051732, 7436.628168591657, 4045.554988951213, 4074.2869997750277, 4189.099343641132, 4195.370694395865, 4196.349565817303, 3282.4748040313434, 3375.5443015193127, 3417.333619944137, 3430.6740859618294, 3431.8627254909525, 507.6091409614576, 537.2746791830997, 591.1398914579277, 599.9710496498526, 607.5352355448085, 510.6323447118069, 559.1640497456328, 563.8280324500985, 570.9714514274269, 571.0948147186756, 7431.51695139825, 7830.373570114377, 8598.52708101048, 8680.826383472295, 8845.867311990325, 521.3925776470445, 550.4806733415801, 571.8163401758403, 577.3302968556454, 585.9912101318483, 3478.3043391321835, 3553.34263161316, 4109.732867363612, 4236.533981262059, 4268.445102136702, 89935.55032224797, 90194.0, 92180.0, 93957.0, 93986.06013939773, 89154.55422722975, 89688.5515572418, 89718.0, 92395.0, 93808.0, 63543.0, 70407.64796175987, 72074.0, 82064.58967705123, 84615.576922115, 7456.627168641655, 7675.577843218676, 8533.914660853312, 9069.18377346042, 9344.859827102735, 32147.67852321447, 46186.769066154455, 47288.0, 47963.84162712617, 48100.27849582189, 60147.097793534005, 63563.046554302644, 63686.68156659187, 65155.0, 67196.6640166796, 11621.593244236412, 11869.703257418634, 11943.0, 12321.93839030799, 12644.683882903, 12407.44166512491, 13048.564657887435, 13670.17978921268, 13706.588802335946, 13723.176609403068, 11029.889701102886, 12631.494740210288, 12765.744685106432, 12937.935310323388, 13144.079716317843, 11545.88454115448, 12916.41876115596, 12960.675983100498, 13323.13399629021, 13391.86169175631, 11651.30092194471, 12674.112812103433, 12678.175918565265, 12913.196445964033, 12945.741085178432, 10349.0, 10478.318909270873, 10571.36571805694, 10651.840222396515, 10700.411477368509, 8975.787154753589, 11063.612773552886, 11064.424382364346, 11078.169137314573, 11107.888921110685], "value": 6918.12343515272, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1712.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 124.0, 137.0, 183.0, 157.0, 124.0, 123.0, 126.0, 146.0, 179.0], "value": 141.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 133.0, 151.0, 192.0, 126.0, 124.0, 132.0, 152.0, 174.0, 122.0], "value": 146.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 227.0, 221.0, 224.0, 229.0, 226.0, 224.0, 223.0, 227.0, 226.0], "value": 225.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 66.0, 63.0, 88.0, 63.0, 65.0, 64.0, 62.0, 77.0, 61.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 89.0, 77.0, 71.0, 75.0, 89.0, 74.0, 69.0, 106.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 115.0, 92.0, 95.0, 92.0, 95.0, 103.0, 123.0, 92.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 119.0, 156.0, 107.0, 118.0, 155.0, 106.0, 122.0, 117.0], "value": 120.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 169.0, 142.0, 112.0, 122.0, 99.0, 97.0, 169.0, 163.0, 116.0], "value": 129.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 63.0, 61.0, 75.0, 77.0, 57.0, 59.0, 60.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 89.0, 75.0, 79.0, 81.0, 74.0, 74.0, 74.0, 76.0, 77.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 95.0, 114.0, 106.0, 117.0, 115.0, 106.0, 91.0, 94.0], "value": 102.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 190.0, 176.0, 182.0, 179.0, 180.0, 174.0, 159.0, 220.0, 192.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 188.0, 217.0, 247.0, 195.0, 187.0, 189.0, 216.0, 188.0], "value": 199.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 75.0, 73.0, 74.0, 74.0, 72.0, 74.0, 75.0], "value": 74.0, "unit": "score"}]}]} 10:33:41 INFO - Return code: 0 10:33:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:33:41 ERROR - # TBPL SUCCESS # 10:33:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:33:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:33:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:33:41 INFO - 2016-11-29 10:33:41.466 screenresolution[1656:10387] starting screenresolution argv=screenresolution get 10:33:41 INFO - 2016-11-29 10:33:41.469 screenresolution[1656:10387] Display 0: 1600x1200x32@60 10:33:41 INFO - 2016-11-29 10:33:41.475 screenresolution[1657:10390] starting screenresolution argv=screenresolution list 10:33:41 INFO - Available Modes on Display 0 10:33:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:33:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:33:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:33:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:33:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:33:41 INFO - Intel Iris: 10:33:41 INFO - Chipset Model: Intel Iris 10:33:41 INFO - Type: GPU 10:33:41 INFO - Bus: Built-In 10:33:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:33:41 INFO - Vendor: Intel (0x8086) 10:33:41 INFO - Device ID: 0x0a2e 10:33:41 INFO - Revision ID: 0x0009 10:33:41 INFO - Displays: 10:33:41 INFO - Display: 10:33:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:33:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:33:41 INFO - Display Serial Number: 1600x1200 60 10:33:41 INFO - Main Display: Yes 10:33:41 INFO - Mirror: Off 10:33:41 INFO - Online: Yes 10:33:41 INFO - Rotation: Supported 10:33:41 INFO - Return code: 0 10:33:41 INFO - Running post-action listener: _resource_record_post_action 10:33:41 INFO - [mozharness: 2016-11-29 18:33:41.728602Z] Finished run-tests step (success) 10:33:41 INFO - Running post-run listener: _resource_record_post_run 10:33:41 INFO - Total resource usage - Wall time: 1215s; CPU: 55.0%; Read bytes: 38816256; Write bytes: 691311104; Read time: 385; Write time: 1833 10:33:41 INFO - TinderboxPrint: CPU usage
54.8% 10:33:41 INFO - TinderboxPrint: I/O read bytes / time
38,816,256 / 385 10:33:41 INFO - TinderboxPrint: I/O write bytes / time
691,311,104 / 1,833 10:33:41 INFO - TinderboxPrint: CPU idle
2,198.2 (45.2%) 10:33:41 INFO - TinderboxPrint: CPU system
987.6 (20.3%) 10:33:41 INFO - TinderboxPrint: CPU user
1,675.9 (34.5%) 10:33:41 INFO - TinderboxPrint: Swap in / out
421,961,728 / 0 10:33:41 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 378765824; Write bytes: 373061632; Read time: 30700; Write time: 655 10:33:41 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 36690432; Write bytes: 303798784; Read time: 362; Write time: 1137 10:33:42 INFO - Running post-run listener: _upload_blobber_files 10:33:42 INFO - Blob upload gear active. 10:33:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:33:42 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 10:33:42 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'] 10:33:42 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 10:33:42 INFO - (blobuploader) - INFO - Open directory for files ... 10:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:43 INFO - (blobuploader) - INFO - Done attempting. 10:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:33:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:44 INFO - (blobuploader) - INFO - Done attempting. 10:33:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:33:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:33:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:46 INFO - (blobuploader) - INFO - Done attempting. 10:33:46 INFO - (blobuploader) - INFO - Iteration through files over. 10:33:46 INFO - Return code: 0 10:33:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:33:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:33:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66dbae92af0057bc26a1a58ca488a2c9358efccec99e8b53c39b49f5043b8c30249e1802f849eea1018e81743f970537488534ad0ad81017f22b3908a014cae8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54bcfdcf4989d7419fe5c024785cb39f4604781a056c7d75255347715b5e262af374504957cfbd08b6e66f1303012b3b96ccf9974d8f7e49c32b0e00cd4c6aec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac9f54ba8829bc5907ef6ba308db10ce98d67c06471aa294d3d34d4b10c66c625b13a8d0faf0c1f611c702edf63f1ba6de76248e7154ab4566a07f9f177b58c"} 10:33:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:33:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:33:46 INFO - Contents: 10:33:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66dbae92af0057bc26a1a58ca488a2c9358efccec99e8b53c39b49f5043b8c30249e1802f849eea1018e81743f970537488534ad0ad81017f22b3908a014cae8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54bcfdcf4989d7419fe5c024785cb39f4604781a056c7d75255347715b5e262af374504957cfbd08b6e66f1303012b3b96ccf9974d8f7e49c32b0e00cd4c6aec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac9f54ba8829bc5907ef6ba308db10ce98d67c06471aa294d3d34d4b10c66c625b13a8d0faf0c1f611c702edf63f1ba6de76248e7154ab4566a07f9f177b58c"} 10:33:46 INFO - Running post-run listener: copy_logs_to_upload_dir 10:33:46 INFO - Copying logs to upload dir... 10:33:46 INFO - mkdir: /builds/slave/test/build/upload/logs 10:33:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.568302 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-29 10:33:46.817547) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:46.821652) ========= 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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66dbae92af0057bc26a1a58ca488a2c9358efccec99e8b53c39b49f5043b8c30249e1802f849eea1018e81743f970537488534ad0ad81017f22b3908a014cae8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54bcfdcf4989d7419fe5c024785cb39f4604781a056c7d75255347715b5e262af374504957cfbd08b6e66f1303012b3b96ccf9974d8f7e49c32b0e00cd4c6aec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac9f54ba8829bc5907ef6ba308db10ce98d67c06471aa294d3d34d4b10c66c625b13a8d0faf0c1f611c702edf63f1ba6de76248e7154ab4566a07f9f177b58c"} build_url:https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011895 build_url: 'https://queue.taskcluster.net/v1/task/BNSsRUSlS7y0NA8tDb91DA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66dbae92af0057bc26a1a58ca488a2c9358efccec99e8b53c39b49f5043b8c30249e1802f849eea1018e81743f970537488534ad0ad81017f22b3908a014cae8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54bcfdcf4989d7419fe5c024785cb39f4604781a056c7d75255347715b5e262af374504957cfbd08b6e66f1303012b3b96ccf9974d8f7e49c32b0e00cd4c6aec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac9f54ba8829bc5907ef6ba308db10ce98d67c06471aa294d3d34d4b10c66c625b13a8d0faf0c1f611c702edf63f1ba6de76248e7154ab4566a07f9f177b58c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:46.859435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:46.859840) ========= 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.bxm8hDSO4j/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.f5N9JQKEFE/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005411 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 10:33:46.909480) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 10:33:46.909820) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 10:33:46.910197) ========= ========= Total master_lag: 0.19 =========