builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0295 starttime: 1479315874.81 results: success (0) buildid: 20161116061237 builduid: bcd3cfd89c5d431f81006907ca18823d revision: 50dd0b8e139f152b7c3a7d68b70f139319035fd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:34.805738) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:34.806203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:34.806482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:34.831199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:34.831589) ========= 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.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-16 09:04:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-11-16 09:04:35 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:35.028997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:35.029312) ========= 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.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.019966 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:35.077759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-16 09:04:35.078125) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 50dd0b8e139f152b7c3a7d68b70f139319035fd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 50dd0b8e139f152b7c3a7d68b70f139319035fd8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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-16 09:04:35,165 truncating revision to first 12 chars 2016-11-16 09:04:35,165 Setting DEBUG logging. 2016-11-16 09:04:35,165 attempt 1/10 2016-11-16 09:04:35,165 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/50dd0b8e139f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 09:04:37,473 unpacking tar archive at: graphics-50dd0b8e139f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.586371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-16 09:04:37.687852) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:37.688332) ========= script_repo_revision: 50dd0b8e139f152b7c3a7d68b70f139319035fd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:37.688948) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:37.689333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 09:04:37.709081) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-16 09:04:37.709464) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:04:37 INFO - MultiFileLogger online at 20161116 09:04:37 in /builds/slave/test 09:04:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:04:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:04:37 INFO - {'append_to_log': False, 09:04:37 INFO - 'base_work_dir': '/builds/slave/test', 09:04:37 INFO - 'blob_upload_branch': 'Graphics', 09:04:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:04:37 INFO - 'branch': 'Graphics', 09:04:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:37 INFO - 'config_files': ('talos/mac_config.py',), 09:04:37 INFO - 'default_actions': ('clobber', 09:04:37 INFO - 'read-buildbot-config', 09:04:37 INFO - 'download-and-extract', 09:04:37 INFO - 'populate-webroot', 09:04:37 INFO - 'create-virtualenv', 09:04:37 INFO - 'install', 09:04:37 INFO - 'run-tests'), 09:04:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:37 INFO - 'download_minidump_stackwalk': True, 09:04:37 INFO - 'download_symbols': 'ondemand', 09:04:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:37 INFO - 'installer_path': 'installer.exe', 09:04:37 INFO - 'log_level': 'info', 09:04:37 INFO - 'log_name': 'talos', 09:04:37 INFO - 'log_to_console': True, 09:04:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:04:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:04:37 INFO - 'opt_config_files': (), 09:04:37 INFO - 'pip_index': False, 09:04:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:37 INFO - 'cmd': ('bash', 09:04:37 INFO - '-c', 09:04:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:37 INFO - 'enabled': True, 09:04:37 INFO - 'halt_on_failure': False, 09:04:37 INFO - 'name': 'check_screen_resolution'},), 09:04:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:37 INFO - 'cmd': ('bash', 09:04:37 INFO - '-c', 09:04:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:37 INFO - 'enabled': True, 09:04:37 INFO - 'halt_on_failure': False, 09:04:37 INFO - 'name': 'check_screen_resolution'},), 09:04:37 INFO - 'run_cmd_checks_enabled': True, 09:04:37 INFO - 'sps_profile': False, 09:04:37 INFO - 'sps_profile_interval': 0, 09:04:37 INFO - 'suite': 'dromaeojs-e10s', 09:04:37 INFO - 'system_bits': '32', 09:04:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:37 INFO - 'title': 't-yosemite-r7-0295', 09:04:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:37 INFO - 'use_talos_json': True, 09:04:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:04:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:37 INFO - 'work_dir': 'build'} 09:04:37 INFO - [mozharness: 2016-11-16 17:04:37.913882Z] Running clobber step. 09:04:37 INFO - Running pre-action listener: _resource_record_pre_action 09:04:37 INFO - Running main action method: clobber 09:04:37 INFO - rmtree: /builds/slave/test/build 09:04:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:04:39 INFO - Running post-action listener: _resource_record_post_action 09:04:39 INFO - [mozharness: 2016-11-16 17:04:39.568246Z] Finished clobber step (success) 09:04:39 INFO - [mozharness: 2016-11-16 17:04:39.568408Z] Running read-buildbot-config step. 09:04:39 INFO - Running pre-action listener: _resource_record_pre_action 09:04:39 INFO - Running main action method: read_buildbot_config 09:04:39 INFO - Using buildbot properties: 09:04:39 INFO - { 09:04:39 INFO - "project": "", 09:04:39 INFO - "product": "firefox", 09:04:39 INFO - "script_repo_revision": "default", 09:04:39 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 09:04:39 INFO - "repository": "", 09:04:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 09:04:39 INFO - "buildid": "20161116061237", 09:04:39 INFO - "pgo_build": "False", 09:04:39 INFO - "basedir": "/builds/slave/test", 09:04:39 INFO - "buildnumber": 0, 09:04:39 INFO - "slavename": "t-yosemite-r7-0295", 09:04:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:04:39 INFO - "platform": "yosemite_r7", 09:04:39 INFO - "scheduler": "tests-graphics-macosx64-talos", 09:04:39 INFO - "branch": "graphics", 09:04:39 INFO - "repo_path": "projects/graphics", 09:04:39 INFO - "revision": "50dd0b8e139f152b7c3a7d68b70f139319035fd8", 09:04:39 INFO - "stage_platform": "macosx64", 09:04:39 INFO - "builduid": "bcd3cfd89c5d431f81006907ca18823d", 09:04:39 INFO - "slavebuilddir": "test" 09:04:39 INFO - } 09:04:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:04:39 INFO - Running post-action listener: _resource_record_post_action 09:04:39 INFO - [mozharness: 2016-11-16 17:04:39.571891Z] Finished read-buildbot-config step (success) 09:04:39 INFO - [mozharness: 2016-11-16 17:04:39.572004Z] Running download-and-extract step. 09:04:39 INFO - Running pre-action listener: _resource_record_pre_action 09:04:39 INFO - Running main action method: download_and_extract 09:04:39 INFO - mkdir: /builds/slave/test/build/tests 09:04:39 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')]} 09:04:39 INFO - trying https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:04:39 INFO - Downloading https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/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 09:04:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/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 09:04:41 INFO - Downloaded 1199 bytes. 09:04:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:04:41 INFO - Using the following test package requirements: 09:04:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:04:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:04:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:04:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'jsshell-mac.zip'], 09:04:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:04:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:04:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:04:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:04:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:04:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:04:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:04:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:04:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:04:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:04:41 INFO - Http code: 200 09:04:41 INFO - via: 1.1 88972e3933cc06dd11a6fa704a208631.cloudfront.net (CloudFront) 09:04:41 INFO - x-amz-cf-id: qlsd08Qy9SNHcogS2zg81faTHng3QDu0NeCDZ-zxBdnkagGSsWu-rg== 09:04:41 INFO - x-amz-version-id: d.vKJAmkiXNSbbJbtOwFzgvjCHJxyyGA 09:04:42 INFO - Expected file size: 16268112 09:04:42 INFO - Obtained file size: 16268112 09:04:48 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 09:04:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:04:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:48 INFO - Fetch https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:04:49 INFO - Http code: 200 09:04:49 INFO - via: 1.1 373880a309382d9266a9313233b86d25.cloudfront.net (CloudFront) 09:04:49 INFO - x-amz-cf-id: uc_XJLKpwealAfmTN9c8FXv4Mq0nhYxLKpow2lY_uhsrmsZeuWRBqg== 09:04:49 INFO - x-amz-version-id: d.vKJAmkiXNSbbJbtOwFzgvjCHJxyyGA 09:04:49 INFO - Expected file size: 16268112 09:04:49 INFO - Obtained file size: 16268112 09:04:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:04:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:04:55 INFO - Fetch https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:04:56 INFO - Http code: 200 09:04:56 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 09:04:56 INFO - x-amz-cf-id: DhgeoIhAQYBGOxVzBz-CplDkvCC1ndMffqWJ7YXCNQgrcArEwV4weg== 09:04:56 INFO - x-amz-version-id: Sdl0lKckoHAEPTPYEoj0n_6fnhKRMUW4 09:04:56 INFO - Expected file size: 14409366 09:04:56 INFO - Obtained file size: 14409366 09:04:56 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')]} 09:04:56 INFO - trying https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:04:56 INFO - Downloading https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:04:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/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 09:04:59 INFO - Downloaded 111139082 bytes. 09:04:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:04:59 INFO - mkdir: /builds/slave/test/properties 09:04:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:04:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:04:59 INFO - Contents: 09:04:59 INFO - build_url:https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:05:00 INFO - Running post-action listener: _resource_record_post_action 09:05:00 INFO - Running post-action listener: set_extra_try_arguments 09:05:00 INFO - [mozharness: 2016-11-16 17:05:00.400180Z] Finished download-and-extract step (success) 09:05:00 INFO - [mozharness: 2016-11-16 17:05:00.400293Z] Running populate-webroot step. 09:05:00 INFO - Running pre-action listener: _resource_record_pre_action 09:05:00 INFO - Running main action method: populate_webroot 09:05:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:00 INFO - '--tppagecycles', 09:05:00 INFO - '7'], 09:05:00 INFO - 'tests': ['tsvgm']}}, 09:05:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['damp', 'tps']}, 09:05:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'tests': ['damp', 'tps']}, 09:05:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['dromaeo_dom']}, 09:05:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['basic_compositor_video']}, 09:05:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['a11yr', 09:05:00 INFO - 'ts_paint', 09:05:00 INFO - 'tpaint', 09:05:00 INFO - 'sessionrestore', 09:05:00 INFO - 'sessionrestore_no_auto_restore', 09:05:00 INFO - 'tabpaint']}, 09:05:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['tsvgx', 09:05:00 INFO - 'tsvgr_opacity', 09:05:00 INFO - 'tart', 09:05:00 INFO - 'tscrollx', 09:05:00 INFO - 'cart']}, 09:05:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:00 INFO - 'tsvgr_opacity', 09:05:00 INFO - 'tart', 09:05:00 INFO - 'tscrollx', 09:05:00 INFO - 'cart']}, 09:05:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'talos_options': ['--disable-e10s'], 09:05:00 INFO - 'tests': ['tp5o']}, 09:05:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:05:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'talos_options': ['--disable-e10s', 09:05:00 INFO - '--xperf_path', 09:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:00 INFO - 'tests': ['tp5n']}, 09:05:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:05:00 INFO - 'talos_options': ['--xperf_path', 09:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:00 INFO - 'tests': ['tp5n']}}, 09:05:00 INFO - 'talos.zip': {'path': '', 09:05:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:00 INFO - Running post-action listener: _resource_record_post_action 09:05:00 INFO - [mozharness: 2016-11-16 17:05:00.418292Z] Finished populate-webroot step (success) 09:05:00 INFO - [mozharness: 2016-11-16 17:05:00.418383Z] Running create-virtualenv step. 09:05:00 INFO - Running pre-action listener: _resource_record_pre_action 09:05:00 INFO - Running main action method: create_virtualenv 09:05:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:00 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 09:05:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:00 INFO - Using real prefix '/tools/python27' 09:05:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:04 INFO - Installing pip.................done. 09:05:04 INFO - Return code: 0 09:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:05:04 INFO - Reading from file tmpfile_stdout 09:05:04 INFO - Output received: 09:05:04 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) 09:05:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05: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')]} 09:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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')]} 09:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05: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', 'pip>=1.5'] in /builds/slave/test/build 09:05: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 pip>=1.5 09:05:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:04 INFO - 'HOME': '/Users/cltbld', 09:05:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:04 INFO - 'LOGNAME': 'cltbld', 09:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:04 INFO - 'MOZ_NO_REMOTE': '1', 09:05:04 INFO - 'NO_EM_RESTART': '1', 09:05:04 INFO - 'PAGER': '/bin/cat', 09:05:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:04 INFO - 'PWD': '/builds/slave/test', 09:05:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:04 INFO - 'SHELL': '/bin/bash', 09:05:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:04 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:04 INFO - 'USER': 'cltbld', 09:05:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:04 INFO - 'XPC_FLAGS': '0x0', 09:05:04 INFO - 'XPC_SERVICE_NAME': '0', 09:05:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:04 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 09:05:04 INFO - Cleaning up... 09:05:04 INFO - Return code: 0 09:05:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05: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')]} 09:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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')]} 09:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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', '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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:05: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 psutil>=3.1.1 09:05:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:04 INFO - 'HOME': '/Users/cltbld', 09:05:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:04 INFO - 'LOGNAME': 'cltbld', 09:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:04 INFO - 'MOZ_NO_REMOTE': '1', 09:05:04 INFO - 'NO_EM_RESTART': '1', 09:05:04 INFO - 'PAGER': '/bin/cat', 09:05:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:04 INFO - 'PWD': '/builds/slave/test', 09:05:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:04 INFO - 'SHELL': '/bin/bash', 09:05:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:04 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:04 INFO - 'USER': 'cltbld', 09:05:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:04 INFO - 'XPC_FLAGS': '0x0', 09:05:04 INFO - 'XPC_SERVICE_NAME': '0', 09:05:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:04 INFO - Downloading/unpacking psutil>=3.1.1 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:08 INFO - Installing collected packages: psutil 09:05:08 INFO - Running setup.py install for psutil 09:05:08 INFO - building 'psutil._psutil_osx' extension 09:05:08 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 09:05:08 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 09:05:08 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 09:05:08 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 09:05:08 INFO - building 'psutil._psutil_posix' extension 09:05:08 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 09:05:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:05:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:05:08 INFO - ^ 09:05:09 INFO - 1 warning generated. 09:05:09 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 09:05:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:09 INFO - Successfully installed psutil 09:05:09 INFO - Cleaning up... 09:05:09 INFO - Return code: 0 09:05:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:09 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')]} 09:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:09 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')]} 09:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:09 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:09 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 09:05:09 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 09:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:09 INFO - 'HOME': '/Users/cltbld', 09:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:09 INFO - 'LOGNAME': 'cltbld', 09:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:09 INFO - 'MOZ_NO_REMOTE': '1', 09:05:09 INFO - 'NO_EM_RESTART': '1', 09:05:09 INFO - 'PAGER': '/bin/cat', 09:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:09 INFO - 'PWD': '/builds/slave/test', 09:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:09 INFO - 'SHELL': '/bin/bash', 09:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:09 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:09 INFO - 'USER': 'cltbld', 09:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:09 INFO - 'XPC_FLAGS': '0x0', 09:05:09 INFO - 'XPC_SERVICE_NAME': '0', 09:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:09 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 09:05:09 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 09:05:09 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 09:05:09 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 09:05:09 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 09:05:09 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 09:05:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:12 INFO - Installing collected packages: mozsystemmonitor 09:05:12 INFO - Running setup.py install for mozsystemmonitor 09:05:12 INFO - Successfully installed mozsystemmonitor 09:05:12 INFO - Cleaning up... 09:05:12 INFO - Return code: 0 09:05:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:12 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')]} 09:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:12 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')]} 09:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:12 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:12 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 09:05:12 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 09:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:12 INFO - 'HOME': '/Users/cltbld', 09:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:12 INFO - 'LOGNAME': 'cltbld', 09:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:12 INFO - 'MOZ_NO_REMOTE': '1', 09:05:12 INFO - 'NO_EM_RESTART': '1', 09:05:12 INFO - 'PAGER': '/bin/cat', 09:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:12 INFO - 'PWD': '/builds/slave/test', 09:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:12 INFO - 'SHELL': '/bin/bash', 09:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:12 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:12 INFO - 'USER': 'cltbld', 09:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:12 INFO - 'XPC_FLAGS': '0x0', 09:05:12 INFO - 'XPC_SERVICE_NAME': '0', 09:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:12 INFO - Downloading/unpacking jsonschema==2.5.1 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:15 INFO - warning: no files found matching '*.txt' 09:05:15 INFO - no previously-included directories found matching 'build' 09:05:15 INFO - no previously-included directories found matching 'dist' 09:05:15 INFO - no previously-included directories found matching '.git*' 09:05:15 INFO - Installing collected packages: jsonschema, functools32 09:05:15 INFO - Running setup.py install for functools32 09:05:15 INFO - warning: no files found matching '*.txt' 09:05:15 INFO - no previously-included directories found matching 'build' 09:05:15 INFO - no previously-included directories found matching 'dist' 09:05:15 INFO - no previously-included directories found matching '.git*' 09:05:15 INFO - Successfully installed jsonschema functools32 09:05:15 INFO - Cleaning up... 09:05:15 INFO - Return code: 0 09:05:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:15 INFO - 'HOME': '/Users/cltbld', 09:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:15 INFO - 'LOGNAME': 'cltbld', 09:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:15 INFO - 'MOZ_NO_REMOTE': '1', 09:05:15 INFO - 'NO_EM_RESTART': '1', 09:05:15 INFO - 'PAGER': '/bin/cat', 09:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:15 INFO - 'PWD': '/builds/slave/test', 09:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:15 INFO - 'SHELL': '/bin/bash', 09:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:15 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:15 INFO - 'USER': 'cltbld', 09:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:15 INFO - 'XPC_FLAGS': '0x0', 09:05:15 INFO - 'XPC_SERVICE_NAME': '0', 09:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:16 INFO - Cleaning up... 09:05:16 INFO - Return code: 0 09:05:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05:16 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')]} 09:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:16 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')]} 09:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:16 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:16 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 09:05:16 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 09:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:16 INFO - 'HOME': '/Users/cltbld', 09:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:16 INFO - 'LOGNAME': 'cltbld', 09:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:16 INFO - 'MOZ_NO_REMOTE': '1', 09:05:16 INFO - 'NO_EM_RESTART': '1', 09:05:16 INFO - 'PAGER': '/bin/cat', 09:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:16 INFO - 'PWD': '/builds/slave/test', 09:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:16 INFO - 'SHELL': '/bin/bash', 09:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:16 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:16 INFO - 'USER': 'cltbld', 09:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:16 INFO - 'XPC_FLAGS': '0x0', 09:05:16 INFO - 'XPC_SERVICE_NAME': '0', 09:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:16 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 09:05:16 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 09:05:16 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 09:05:16 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 09:05:16 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 09:05:16 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 09:05:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:05:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:05:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:20 INFO - Downloading docopt-0.6.1.tar.gz 09:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:05:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:05:20 INFO - Running setup.py install for blobuploader 09:05:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:05:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:05:20 INFO - Running setup.py install for requests 09:05:20 INFO - Running setup.py install for docopt 09:05:20 INFO - Successfully installed blobuploader requests docopt 09:05:20 INFO - Cleaning up... 09:05:20 INFO - Return code: 0 09:05:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:20 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:20 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 09:05:20 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 09:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:20 INFO - 'HOME': '/Users/cltbld', 09:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:20 INFO - 'LOGNAME': 'cltbld', 09:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:20 INFO - 'MOZ_NO_REMOTE': '1', 09:05:20 INFO - 'NO_EM_RESTART': '1', 09:05:20 INFO - 'PAGER': '/bin/cat', 09:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:20 INFO - 'PWD': '/builds/slave/test', 09:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:20 INFO - 'SHELL': '/bin/bash', 09:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:20 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:20 INFO - 'USER': 'cltbld', 09:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:20 INFO - 'XPC_FLAGS': '0x0', 09:05:20 INFO - 'XPC_SERVICE_NAME': '0', 09:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-jCDb7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-K7fYUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-kSCQEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-2TKkrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-xDuK_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Kva_ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-CaZSFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:21 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-w0q_Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-5knsOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-_KeuMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-WuhjaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-uoxrxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-IE60Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Ac4zZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-pg0DTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-VXJmP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:22 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-GzwHW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:05:22 INFO - Running setup.py install for manifestparser 09:05:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:05:23 INFO - Running setup.py install for mozcrash 09:05:23 INFO - Running setup.py install for mozdebug 09:05:23 INFO - Running setup.py install for mozdevice 09:05:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:05:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:05:23 INFO - Running setup.py install for mozfile 09:05:23 INFO - Running setup.py install for mozhttpd 09:05:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:05:23 INFO - Running setup.py install for mozinfo 09:05:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Running setup.py install for mozInstall 09:05:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Running setup.py install for mozleak 09:05:24 INFO - Running setup.py install for mozlog 09:05:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Running setup.py install for moznetwork 09:05:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Running setup.py install for mozprocess 09:05:24 INFO - Running setup.py install for mozprofile 09:05:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:05:24 INFO - Running setup.py install for mozrunner 09:05:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:05:25 INFO - Running setup.py install for mozscreenshot 09:05:25 INFO - Running setup.py install for moztest 09:05:25 INFO - Running setup.py install for mozversion 09:05:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:05:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:05:25 INFO - Cleaning up... 09:05:25 INFO - Return code: 0 09:05:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05: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')]} 09:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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')]} 09:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:25 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:25 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 09:05:25 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 09:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:25 INFO - 'HOME': '/Users/cltbld', 09:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:25 INFO - 'LOGNAME': 'cltbld', 09:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:25 INFO - 'MOZ_NO_REMOTE': '1', 09:05:25 INFO - 'NO_EM_RESTART': '1', 09:05:25 INFO - 'PAGER': '/bin/cat', 09:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:25 INFO - 'PWD': '/builds/slave/test', 09:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:25 INFO - 'SHELL': '/bin/bash', 09:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:25 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:25 INFO - 'USER': 'cltbld', 09:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:25 INFO - 'XPC_FLAGS': '0x0', 09:05:25 INFO - 'XPC_SERVICE_NAME': '0', 09:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:25 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-UTLWZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:25 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)) 09:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:25 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-g9Clf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-1CZTZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-487B6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-NfvGe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-X9SyIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-id1rwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-GEZEFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-AXB0Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-aDo2bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:26 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)) 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:26 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-LKHB_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-cFC5au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-xepPSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-MQ2iJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Acw7HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-l5GFz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:27 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)) 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:27 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-4HkC6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:27 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)) 09:05:27 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)) 09:05:27 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)) 09:05:27 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)) 09:05:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:30 INFO - Downloading blessings-1.6.tar.gz 09:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:05:30 INFO - Installing collected packages: blessings 09:05:30 INFO - Running setup.py install for blessings 09:05:30 INFO - Successfully installed blessings 09:05:30 INFO - Cleaning up... 09:05:30 INFO - Return code: 0 09:05:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:05:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:30 INFO - Reading from file tmpfile_stdout 09:05:30 INFO - Current package versions: 09:05:30 INFO - blessings == 1.6 09:05:30 INFO - blobuploader == 1.2.4 09:05:30 INFO - docopt == 0.6.1 09:05:30 INFO - functools32 == 3.2.3-2 09:05:30 INFO - jsonschema == 2.5.1 09:05:30 INFO - manifestparser == 1.1 09:05:30 INFO - mozInstall == 1.12 09:05:30 INFO - mozcrash == 1.0 09:05:30 INFO - mozdebug == 0.1 09:05:30 INFO - mozdevice == 0.48 09:05:30 INFO - mozfile == 1.2 09:05:30 INFO - mozhttpd == 0.7 09:05:30 INFO - mozinfo == 0.9 09:05:30 INFO - mozleak == 0.1 09:05:30 INFO - mozlog == 3.4 09:05:30 INFO - moznetwork == 0.27 09:05:30 INFO - mozprocess == 0.23 09:05:30 INFO - mozprofile == 0.28 09:05:30 INFO - mozrunner == 6.13 09:05:30 INFO - mozscreenshot == 0.1 09:05:30 INFO - mozsystemmonitor == 0.3 09:05:30 INFO - moztest == 0.7 09:05:30 INFO - mozversion == 1.4 09:05:30 INFO - psutil == 3.1.1 09:05:30 INFO - requests == 1.2.3 09:05:30 INFO - wsgiref == 0.1.2 09:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 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 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:30 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 09:05:30 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 09:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:30 INFO - 'HOME': '/Users/cltbld', 09:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:30 INFO - 'LOGNAME': 'cltbld', 09:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:30 INFO - 'MOZ_NO_REMOTE': '1', 09:05:30 INFO - 'NO_EM_RESTART': '1', 09:05:30 INFO - 'PAGER': '/bin/cat', 09:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:30 INFO - 'PWD': '/builds/slave/test', 09:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:30 INFO - 'SHELL': '/bin/bash', 09:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:30 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:30 INFO - 'USER': 'cltbld', 09:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:30 INFO - 'XPC_FLAGS': '0x0', 09:05:30 INFO - 'XPC_SERVICE_NAME': '0', 09:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 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)) 09:05:30 INFO - Cleaning up... 09:05:31 INFO - Return code: 0 09:05:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c63f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ead9db60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, '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.R8PbBkpHc4/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.cEytpkon8e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 09:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:31 INFO - 'HOME': '/Users/cltbld', 09:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:31 INFO - 'LOGNAME': 'cltbld', 09:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:31 INFO - 'MOZ_NO_REMOTE': '1', 09:05:31 INFO - 'NO_EM_RESTART': '1', 09:05:31 INFO - 'PAGER': '/bin/cat', 09:05:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:31 INFO - 'PWD': '/builds/slave/test', 09:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:31 INFO - 'SHELL': '/bin/bash', 09:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:05:31 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:31 INFO - 'USER': 'cltbld', 09:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:31 INFO - 'XPC_FLAGS': '0x0', 09:05:31 INFO - 'XPC_SERVICE_NAME': '0', 09:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:05:32 INFO - Cleaning up... 09:05:32 INFO - Return code: 0 09:05:32 INFO - Running post-action listener: _resource_record_post_action 09:05:32 INFO - Running post-action listener: _start_resource_monitoring 09:05:32 INFO - Starting resource monitoring. 09:05:32 INFO - [mozharness: 2016-11-16 17:05:32.115007Z] Finished create-virtualenv step (success) 09:05:32 INFO - [mozharness: 2016-11-16 17:05:32.116011Z] Running install step. 09:05:32 INFO - Running pre-action listener: _resource_record_pre_action 09:05:32 INFO - Running main action method: install 09:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:32 INFO - Reading from file tmpfile_stdout 09:05:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:05:32 INFO - Reading from file tmpfile_stdout 09:05:32 INFO - Output received: 09:05:32 INFO - Usage: mozinstall [options] installer 09:05:32 INFO - Options: 09:05:32 INFO - -h, --help show this help message and exit 09:05:32 INFO - -d DEST, --destination=DEST 09:05:32 INFO - Directory to install application into. [default: 09:05:32 INFO - "/builds/slave/test"] 09:05:32 INFO - --app=APP Application being installed. [default: firefox] 09:05:32 INFO - mkdir: /builds/slave/test/build/application 09:05:32 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'] 09:05:32 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 09:06:06 INFO - Reading from file tmpfile_stdout 09:06:06 INFO - Output received: 09:06:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:06:06 INFO - Running post-action listener: _resource_record_post_action 09:06:06 INFO - [mozharness: 2016-11-16 17:06:06.837638Z] Finished install step (success) 09:06:06 INFO - [mozharness: 2016-11-16 17:06:06.837760Z] Running run-tests step. 09:06:06 INFO - Running pre-action listener: _resource_record_pre_action 09:06:06 INFO - Running main action method: run_tests 09:06:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:06 INFO - 2016-11-16 09:06:06.850 screenresolution[1897:17884] starting screenresolution argv=screenresolution get 09:06:06 INFO - 2016-11-16 09:06:06.853 screenresolution[1897:17884] Display 0: 1600x1200x32@60 09:06:06 INFO - 2016-11-16 09:06:06.858 screenresolution[1898:17886] starting screenresolution argv=screenresolution list 09:06:06 INFO - Available Modes on Display 0 09:06:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:07 INFO - Intel Iris: 09:06:07 INFO - Chipset Model: Intel Iris 09:06:07 INFO - Type: GPU 09:06:07 INFO - Bus: Built-In 09:06:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:07 INFO - Vendor: Intel (0x8086) 09:06:07 INFO - Device ID: 0x0a2e 09:06:07 INFO - Revision ID: 0x0009 09:06:07 INFO - Displays: 09:06:07 INFO - Display: 09:06:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:07 INFO - Display Serial Number: 1600x1200 60 09:06:07 INFO - Main Display: Yes 09:06:07 INFO - Mirror: Off 09:06:07 INFO - Online: Yes 09:06:07 INFO - Rotation: Supported 09:06:07 INFO - Return code: 0 09:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:07 INFO - Python 2.7.3 09:06:07 INFO - Return code: 0 09:06:07 INFO - grabbing minidump binary from tooltool 09:06:07 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')]} 09:06:07 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 0x10f7dcd30>, '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 0x10f643db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:07 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 09:06:07 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 09:06:07 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 09:06:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:07 INFO - Return code: 0 09:06:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:06:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:06:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0295 --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 09:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8PbBkpHc4/Render', 09:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:07 INFO - 'HOME': '/Users/cltbld', 09:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:07 INFO - 'LOGNAME': 'cltbld', 09:06:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:07 INFO - 'MOZ_NO_REMOTE': '1', 09:06:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:07 INFO - 'NO_EM_RESTART': '1', 09:06:07 INFO - 'PAGER': '/bin/cat', 09:06:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:07 INFO - 'PWD': '/builds/slave/test', 09:06:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:07 INFO - 'SHELL': '/bin/bash', 09:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cEytpkon8e/Listeners', 09:06:07 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 09:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:07 INFO - 'USER': 'cltbld', 09:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:07 INFO - 'XPC_FLAGS': '0x0', 09:06:07 INFO - 'XPC_SERVICE_NAME': '0', 09:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 09:06:07 INFO - mozversion application_buildid: 20161116061237 09:06:07 INFO - mozversion application_changeset: 50dd0b8e139f152b7c3a7d68b70f139319035fd8 09:06:07 INFO - mozversion application_display_name: Nightly 09:06:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:07 INFO - mozversion application_name: Firefox 09:06:07 INFO - mozversion application_remotingname: firefox 09:06:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:06:07 INFO - mozversion application_vendor: Mozilla 09:06:07 INFO - mozversion application_version: 53.0a1 09:06:07 INFO - mozversion platform_buildid: 20161116061237 09:06:07 INFO - mozversion platform_changeset: 50dd0b8e139f152b7c3a7d68b70f139319035fd8 09:06:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:06:07 INFO - mozversion platform_version: 53.0a1 09:06:07 INFO - using testdate: 1479315967 09:06:07 INFO - actual date: 1479315967 09:06:07 INFO - starting webserver on 'localhost:53625' 09:06:07 INFO - SUITE-START | Running 2 tests 09:06:07 INFO - TEST-START | dromaeo_css 09:06:07 INFO - operating with platform_type : mac_ 09:06:07 INFO - Initialising browser for dromaeo_css test... 09:06:07 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpYauTA_/profile http://localhost:53625/getInfo.html) 09:06:10 INFO - PROCESS | 1908 | 2016-11-16 09:06:10.339 plugin-container[1910:18047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 09:06:10 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:10 INFO - PROCESS | 1908 | 2016-11-16 09:06:10.341 plugin-container[1910:18047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 09:06:10 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:10 INFO - PROCESS | 1908 | 2016-11-16 09:06:10.341 plugin-container[1910:18047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:06:10 INFO - PROCESS | 1908 | __metrics Screen width/height:1600/1200 09:06:10 INFO - PROCESS | 1908 | colorDepth:24 09:06:10 INFO - PROCESS | 1908 | Browser inner width/height: 1024/689 09:06:10 INFO - PROCESS | 1908 | __metrics 09:06:10 ERROR - PROCESS | 1908 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 09:06:11 INFO - TEST-INFO | 1908: exit 0 09:06:11 INFO - Browser initialized. 09:06:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:11 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpYauTA_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:13 INFO - PROCESS | 1911 | 2016-11-16 09:06:13.533 plugin-container[1912:18167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:06:13 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:13 INFO - PROCESS | 1911 | 2016-11-16 09:06:13.533 plugin-container[1912:18167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:06:13 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:06:13 INFO - PROCESS | 1911 | 2016-11-16 09:06:13.533 plugin-container[1912:18167] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:06:23 INFO - PROCESS | 1911 | RSS: Main: 237551616 09:06:23 INFO - PROCESS | 1911 | 09:07:12 INFO - PROCESS | 1911 | 1479316032794 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] 09:09:26 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53625/tests/dromaeo/cssquery-ext.html) 09:09:28 INFO - PROCESS | 1911 | RSS: Main: 252006400 09:09:28 INFO - PROCESS | 1911 | 09:12:30 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-ext.html (next: http://localhost:53625/tests/dromaeo/cssquery-jquery.html) 09:12:31 INFO - PROCESS | 1911 | RSS: Main: 264028160 09:12:31 INFO - PROCESS | 1911 | 09:15:33 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53625/tests/dromaeo/cssquery-mootools.html) 09:15:33 INFO - PROCESS | 1911 | RSS: Main: 269635584 09:15:33 INFO - PROCESS | 1911 | 09:18:35 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53625/tests/dromaeo/cssquery-prototype.html) 09:18:36 INFO - PROCESS | 1911 | RSS: Main: 268255232 09:18:36 INFO - PROCESS | 1911 | 09:21:39 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53625/tests/dromaeo/cssquery-yui.html) 09:21:40 INFO - PROCESS | 1911 | RSS: Main: 273403904 09:21:40 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53625/tests/dromaeo/cssquery-yui.html (next: http://localhost:53625/tests/dromaeo/cssquery-dojo.html) 09:24:42 INFO - PROCESS | 1911 | RSS: Main: 275402752 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | __start_tp_report 09:24:42 INFO - PROCESS | 1911 | _x_x_mozilla_page_load 09:24:42 INFO - PROCESS | 1911 | _x_x_mozilla_page_load_details 09:24:42 INFO - PROCESS | 1911 | |i|pagename|runs| 09:24:42 INFO - PROCESS | 1911 | |0;dojo.html;3334.5331653568505;3347.598288205416;3356.848941797619;3360.1431634933087;3388.69501744843;5609.074502707059;5620.241267428896;5634.887302253963;5663.122216056513;5697.6296540724925;5692.601517893749;5732.742026608802;5810.738516766745;5864;5866.120081987704;5174.379074511064;5252.8161514347;5255.763490642911;5257.027449921758;5273.551748101416;5225.817096401626;5332.92000619991;5360.329958755136;5360.544353729938;5363.54409875161;4280.464941882265;5312.5218730314455;5316.494932981361;5326.6005049621235;5383.219433182187;5140.383154021504;5170.896582068338;5180.326557547494;5182.144946083929;5256.526912577886;5112.156494178456;5134.435212126663;5179.715115668637;5191.351081114861;5194.532492075694;5505.807296744595;5692.686902220376;5787.797427090074;5788.479036886701;5810.506106980912;5149.948500515022;6163.075538669191;6201;6222.844428889269;6241.906371404432;6194.194754681863;6200.286966998808;6216.751329946842;6217.5336849735995;6226.40849119333;5885.234919460486;5902.6458412495385;5904.586678932434;5942.673152976541;5982.282126144891;6116.366205733304;6184.319724830265;6193.473554747897;6211.81364559064;6267.122602835607;6197.876042479125;6209.59637623553;6219.906701399483;6221.253449586078;6223.719932603044;6197.380261973857;6229.221347331583;6231.875362492816;6242.219722534684;6270.435660790551;6109.890546868077;6126.609281623701;6156.538259630545;6180.8763824723255;6231.408016238541;5224.712640804716;5915.914418233488;6187.474064704551;6216.906746398808;6247.156633854463;3787.394016957329;4271.889308779678;4304.010077682088;4945.455999840015;5010.49895010496;3645.398509245998;4373.993981384299;4385;4399.296112622029;4432.357308190294;5757.481826635623;5777.768889244468;5788.392218817048;5818.359980402152;5836.241288632451;6208.782692605828;6234.376562343729;6236.594621349598;6251.093591429218;6253.96873015632;5679.602427830012;5732.426757324318;5774.133879917962;5796.217510636095;5801.216835727123;5353.7055461949185;5415.48552887475;5418.756155972991;5419.051665958442;5419.2413062170535;6177.907331390033;6233.657148856856;6250.093736408105;6258.937410625836;6263.091851681481;4949.28235405857;6198.504119670506;6204.5967012144065;6213.130161777445;6225.439710426083;6317.399847014526;6335.873282534231;6352.587081839665;6357.841053973688;6389.52078594098;6092.847678808065;6129.20597562684;6132.141360525156;6249;6252.531060170596;6092.329843717276;6149.139120523041;6153.600015998945;6234.470070044097;6240.719167637558;6113.877722445615;6169.167162433014;6170.598911070766;6184.196054513064;6191.690415479118;6147.84630384244;6159.568830182024;6182.103594978793;6193.566450348432;6202.906956395567;5757.683327416865;5776.133579963039;5904.173415721716;5907.70461476933;5942.524598032233;6108.918862504554;6110.419510146529;6150.446459818638;6150.630962142286;6151.66165860864;5877.382874798255;5957.463828255478;5974.074018527135;6007.459328660441;6063.818085457443 09:24:42 INFO - PROCESS | 1911 | |1;ext.html;456.35653728243176;471.4954998151979;475.54822918227694;483.4150677680007;525.2751203339392;37854.621453785454;39148.519207311;39350.80324598376;39373;39526.99999999996;40146.79926600363;40646;40720.18559628806;40808;40828.38757418641;9263.397879137834;9275.953620231889;9415.81168376632;9427.670031548898;9430.622775089023;9323.30075244356;9337.20633746129;9438.197753190987;9458.905410945872;9461.101195386462;9347.76630584234;9416.199623032016;9460.574274157645;9466.19537339327;9470.52647367629;9327.580258888333;9379.531023448868;9422.246220302393;9425.528723563863;9426.151646351862;9218.723438296794;9287.72136835896;9351.111644393772;9360.672376466791;9413.294002949806;37388;37611.811940940395;37649.0587735306;37753.43369849455;37758.05604859873;3233.886813961524;3653.9634603654154;3912.3544615138467;3946.3291240489184;4055.1909809860435;24524.896379662958;28467.715322846714;29678.109656710334;30130.54804177939;30696.53955190674;2831.178958102148;2865.412590418959;3207.983960080208;3286.9506957395656;3318.96681033189;471920;474664;475588.622056891;477683.61158194335;477876;56702.43297567054;56736.716316418155;56798.43201568014;56977.14534281989;57060.42939570634;46574.76712616415;49305.0138997218;56245.71877140588;56463.15305270424;57912.42087579155;15418.152001639794;15469;15632.765508517381;15639.921800390925;15646.374145034299;11757.529698811952;11983.161178717406;11989.940050299692;12042.458089385998;12070.818937715942;11884.702882427835;12628.494860205488;15211.543653690407;15416.537503874903;15440.066387386361;6618.172728408949;6675.198976122896;6676.86646267072;6776.2546119926765;6804.55770374924;842.7766641839918;883.9513826739591;886.2511178054533;996.2727209137225;1006.5269323418128;28636.570451443244;28790.712092878803;29186.562201566994;29435.85282073619;29892;996.1731762636994;1030.2788048366024;1045.5451878432907;1047.2512153810062;1054.3041592548882;15015.774763378558;15083.321250543751;15186.848131518544;15221.997856056683;15280.541583752505;570853;576947;583637;584193;587026;55438.89122217533;55704.885902282535;55717.72141139268;55720.606984824895;55762.44237557654;1081.659277327655;1326.6683329167709;1328.9136206146763;1350.4260689207047;1354.6207062022452;24660.38349041288;29582.704172957994;29919.80320787231;29942.550861736654;30047.69952300449;41953.16093678171;47919.76040119777;48072;48109.5189048105;48223.27665085097;471494;473688;473989;475128;475923;24738.628920565847;24916.75083249144;25087.74912250854;25173.874130629963;25601.359966001;44078.33882491829;44911;45566.544334557555;45934.86586207258;45957.540424595325;35745.82127089348;36824.81587592045;37320.253594928494;37492.62507374891;38036.42945355877;36602.26795464129;37166.256674865814;37200.81399592985;37887.242255155295;38317.61682383252;26362.604560931984;32084.03747887567;36985.26029479449;37083.814580926926;38181;28591;32297.35405291928;33988;34048.48927266044;34075.65924340725;19899.900500497406;24656.383590410383;25067.623985640595;25630.102946397532;25737.485250295264 09:24:42 INFO - PROCESS | 1911 | |2;jquery.html;298.5417384315074;359.0646366216007;364.2242024487841;373.95699494558124;393.8818354493651;19048.61902761944;19301.517462063457;19329.130189141506;19362.225510979544;19643.410697679094;16008.359665613361;16539.173041347916;17372.826271737278;18801.717974230363;19528.609427811436;5345.839624811262;5746.827595172152;5756.136579513065;5762.193292938971;5763.510101641345;5529.419410961851;5544.91682624761;5849.415058494138;5899.557533185007;5943.64338139713;5957.1957785698805;5964.105384192362;5977.073553599177;5982.910256346158;5989.460948514633;6254.155688981975;6490;6523.315051919528;6552.213734351908;6575.736970521198;5552.444755524456;5698.145278208261;5779.595428320019;5794.768209271624;5799.159121927297;12764.680882977907;13014.934925325313;13038.087333886631;13101.737965240642;21036.579268414545;2036.8879711615855;2220.6446968484966;2236.3961730332717;2340.6372012338115;2898.4782739106954;9447.669331573363;9512.95243523778;9748.366356186896;10106.191504679682;10113.544890479947;1830.1215416599973;1837.9448616541524;1906.8665193436466;1929.0354822588706;1934.7968463311431;111355;117098.41450792777;117134;117581;118327;9061.501617410973;9616.567254473566;9737.221022318197;11124.49939752713;11481.425928703531;12123.272603643632;12142.696432589257;12157.209781364183;12186.2078964867;12199.207051541622;6042.486388656926;6266.717997690115;6269.749210031548;7216.96391518039;7966.800829979181;8116.797080072927;8147.796305092302;8167.550784706898;8214.753557393287;8249.216324449168;3707.5921648618632;3849.9807500962315;3871.864484743077;3898.064464528493;3914.2954268231993;1881.1252767462922;1917.808219178071;1926.5183704073982;1928.6046360496064;1929.218666440094;795.5584650519017;819.9877001844977;831.6465502161557;860.2043110123111;917.6642903047338;19982.000899954943;19988;20027.09878055491;20054.89972550128;20199.8990005049;820.3560205238858;825.2737590919952;877.6050777150181;883.5449743382163;912.6760000199966;8785.736427907173;9967.352122112039;10517.84223236652;10590.417527036087;10611.257211995086;405598;429533;432233;502344.78896898724;518657;116195;119252.40373798076;121869;128248.71751282555;136306;858.9012263589767;964.6720115160879;997.4813097189566;1001.2890847498212;1003.7139415266614;15213.935024548175;17607.710997570233;18419.815801842346;18667.253309867454;18775.90612046931;49516.50483495263;51082.23376649279;52948.735256323474;53785.731071344395;54275;77191;78227.60886195532;79178;79336.73596912735;80005;7466.514676546007;7558.715018446767;7585.582792946441;7721.227877212234;7827.099883513469;8556.57217139153;11212.374205740285;12235.69410764738;12392.380380980734;12480.251184928933;9994.10053095225;10077.748056298651;10231.846522302023;10471;10744.30162039465;10444.947775261075;10607.469626518798;10644.081347124431;10719.410432426292;10946.343219406543;10408.635697750542;10734.248602598056;10843.132549396187;10857.294275872044;10904.727381815517;7574.5833979129475;7633.961830190813;7747.706467711222;7813.374930005472;7996.480228785111;7809.4142939278645;10166.339187952759;10467.319624224401;10748.408837514013;10838.783224335311 09:24:42 INFO - PROCESS | 1911 | |3;mootools.html;1438.8561143885613;1481.525911708253;1623.2776414495547;1767.9558011049714;1769.9115044247792;7629.542227466342;7903.40724445666;8083.393745469091;8493.617787199575;8537.402381833273;7987.680492780281;8271.379646526504;8309.750707478785;8373;8378.287845533137;2231.386368748591;2269.546090781842;2274.1812947338944;2279.247848210095;2279.886005699717;2217.977820221793;2254.8985295661732;2267.7958983691465;2272.2728726807427;2278.270953294946;2285.0517035430275;2294.7819957104134;2298.276043046443;2303.193882141254;2337.2754446121758;2290.385278378739;2294.27730264966;2310.0759696121518;2313.9652905206353;2316.108298305148;2298.97701022991;2318.142287353673;2324.4653729642278;2325.93022209334;2328.7904088632104;8416.2004609562;8421.410501264914;8448.873266901;8489.448185867961;8513.787155321104;5056.988602279559;5192.376914770214;5192.662476938987;5228.738563071831;5229.581633469352;3228.725558327545;3314.320564284316;3557.6620221079215;3564.590072141711;3566.7681600695873;981.8379967305386;991.6083147156833;997.3816233935007;1079.632924805562;1080.2276372393776;10625.990530899553;10903.781924361547;10925.07136893357;10932.016118549369;10962.123030157569;4284.721493102938;4345.869623911288;4410.735355878657;4478.029666946533;4484.417025786692;8633.611487483078;8694.91305086941;8776.385653004229;8797.164269394398;8799.208071273617;5102.795888164432;5106.310648062538;5116.539511443913;5118.155504341817;5125.564327032244;4273.9358909616385;4615.630749540029;4712.151812673752;4740.075685241345;4791.784369703371;3580.6598373154507;3605.2248766515327;3667.156553992597;3694.427363758568;3710.3135919854913;1043.8726175730194;1089.4008295437477;1131.852859128308;1140.1107136433595;1148.7932172208916;717.3328804212133;787.330768846476;812.9675312353278;863.9308855291562;883.3860466975519;5446.782128714886;5453.809116680897;5553.4724201200825;5673.262475878109;5793.246877905845;411.3254263008668;421.11355596469605;430.71787978873886;431.1463302660687;432.88095773662496;3730.552333719971;3745.194783121642;3782.149016471327;3813.9618603813606;3845.3655146901006;9801.656942006995;10229.43738094412;10290.588376465008;10301.639442619473;10313.639022634172;10277.7430564235;10277.845832312521;10305.639302624371;10339.534720937576;10342.896571034193;7604.136986438248;7736.497127686683;7796.766097017098;7912.32745216663;7975;5365.463453654759;5489.617202928353;5531.529819965187;5581.274434323512;5620.606557540933;9342.392744471588;9376.67181648639;9378.5779639915;9387.765305867408;9421.29340299494;9120.635174592942;9442.575084121094;9506.762330941781;9520.095590918852;9530.332876698565;6672.599644021374;6920.653967301514;7094.680739366844;7158.212596614472;7295.52579082358;7983.920160798318;8179.713710020121;8187.099419064018;8189.181081891763;8196.877046844422;7203.639818009184;7308.342249197598;7318.268173182639;7378.335949764547;7415.5179913305465;7080.610566418896;7122.715091396286;7147.749828755968;7274.74538391175;7294.562326260441;6667.333266673294;6775.356341147583;6841.21326047491;6848.520603557703;6850.006749021463;4610.354550362885;4709.710908800467;4826.734529600905;4865.7323847188745;4916.287138364845;4228.767417792051;4818.71087734737;4888.877778055577;4965.155923492943;4991.445509887364 09:24:42 INFO - PROCESS | 1911 | |4;prototype.html;298.6744448551079;303.65535117641497;303.7084398976982;308.6342683919556;311.60581863942105;5317.335333083364;5489.149181876805;5561.5828812839045;5614.438556144389;5655.3779084300695;4935.358403407552;5304.681719096857;5407.351117865862;5487.789643058805;5493.697846618435;4000.3799411091327;4097.037196258877;4178.603032711895;4247.277962746341;4249.893752656193;4083.97958010211;4160.459140311756;4176.749395036292;4251.532331443539;4269.978650106745;3594.8741794037214;4099.754014759124;4136.696940463744;4189.601987811153;4203.978980105096;3615.92084551384;3779.924401511968;3948.6446219840063;4167.729097608676;4173.499180098377;3146.386454641346;3332.283559034819;3528.1708798432346;3634.854605815764;3642.1987162824134;5038.294638750578;5066.328111723123;5206.464093092547;5359.651622644555;5413.53984911283;4700.764961751899;4811.302361157613;4861.465238823726;4863;4872.1473742094995;5539.723013849331;5539.972300138514;5808.59339846211;5844.649321040752;5862.003459411868;3030.4545181867256;3518.384282750534;3751.249750050001;3866.0721426857353;4039.5255731658026;5185.611079169077;6126;6198.473129783929;6200.87598248037;6214;3296.4396052671104;3466.3933811582874;3473.4095203815414;4144.564820693784;4154.833806647761;4031.09300407406;4107.424960505532;4127.876163715094;4164.8125834337525;4198.349255865346;4180.352045432963;4221.116572753074;4227.51383590891;4441.400410944481;4818.397700287464;4686.758009127584;5071.771770270347;5470.972645136749;5719.170720245541;5828.795992140254;1278.603632873812;1319.1227833490814;1331.7203387288585;1349.4737052549317;1354.939027743754;850.0946491986015;853.8345158858237;918.334207699426;941.8540126280437;947.0766003146877;4901.451245352397;4947.802087916443;5155.304033955443;5179.239058719978;5202.684805261698;4820.03567805804;5638.43615638441;5652.07829338027;5670.489655930986;5708.372079071381;4257.446531950826;4924.187509060965;5137;5143.434222235551;5164.535191832753;2797.216779301799;2934.1709069664294;2975.910722678323;2989.832371530623;2998.925026874346;5088.600634825452;5577.84049753976;5894.528437724972;5916.822495325147;5959.970200148971;4354.449600167833;4788.832390866303;5405.675659460445;5634.154876768435;5640;5088.821891233789;5221.503957124143;5255.816046438356;5447;5499.0264117031265;4740.597049250714;4961;5225.432370288822;5562.527185189142;5692.48767610917;4965.975170124127;5503.587775579166;5982.6410415373475;5995.340512543529;6032.276126864805;6226.875462490815;6324.335944725737;6330.588511746721;6343.492520598434;6357.268914074941;3169.46119159748;3759.830807613609;3860.3051450739135;3890.144168282973;3932.488776459042;5319.3084898964435;5505.532029777513;5534.339698090637;5602.170903693609;5807.651540907559;5136.666116702402;5290.455394885359;5395.406505284337;5778.393268706725;5949.876314934694;5677.5457963362005;5886.323072846506;5891.941080589139;5941.970290148694;5943.375945525658;4292.420523229324;4553.4763502197675;5094.662995737797;5220.504052115043;5395.570173903997;5561.8609534760335;5874;5875.941240587539;5877.2947246329;5918.201042859159;5192.558632516128;5725.311033245286;5765.94234057654;5767.250257466502;5800.390958949419 09:24:42 INFO - PROCESS | 1911 | |5;yui.html;683.8598087392082;729.0231090338945;760.9999999999999;777;817.2971244729531;14215.218163001031;14580.270986450689;14679;14838.480653177141;14885.702285954274;10189.694309170718;11725.70685732855;13122.278274694889;13417.396217170226;13457.394417251222;742.888566714993;825.0717942314895;827.7765003449096;828.1759649149105;829.2163905109674;649.4349915573434;666.933306669334;792.0851416613821;812.8374325134968;825.9876101858476;783.4672422752526;848.5828666127582;853.8633818589027;876.4784952953015;878.029777096309;765.9770206893802;863.9049704532497;870.3907264914585;870.6169285514353;871.5990644303596;559.6110703061374;607.1136141233804;641.1152609399046;641.3458272562008;641.95506314558;13732.65668358289;13865.237411942338;14293.927955403282;14335;14353.569392918229;12390.132690711655;13054.2820144892;13385;13996.186920342416;14000.859991400057;11282.717932051764;11427.371494567795;11630.592929247436;11776.941115294454;11938.58214962472;3328.1180487170677;3700.093477098128;3764.9081766287745;3856.7878766667823;3898.4737060496755;90475;93926;94287.52856235743;95105;95533.52233238859;8759.562021898879;10778.353298802096;10789.298695584763;10806.08148307387;10887;21211.15155393798;21513.354599362043;21612.567748645255;21617.243396481048;21688.674669879965;7531.736389226351;7592.392608591299;7752.5348479091435;7793.844123117506;7862.488938218998;4175.91648167035;4191.811368488426;4258.510271318776;4289.635380992589;4356.564343565681;3406.6423025582453;3457.8271086445575;3507.7544571879725;3509.8066657336626;3514.8594056237466;653.5523166630873;690.3062422265691;697.745322957117;703.7466512055759;712.3268511256813;586.9845167859627;616.4698359410903;619.2507066449556;619.4765423217374;646.6475770704977;7732.961335193288;8867.123082304706;8977.685780997634;9105.984925970439;9170.128837760401;505.7774579185147;618.9288231853304;644.7872202173271;677.1197443323659;684.9999001457927;4153.875383738493;4283.31466965284;4408.845690400821;4455.420795296591;4507.864764057018;103434.48282758538;109401;113555;116463;120469.39765301117;55997.72001139968;62832;75963.62018189873;101346.97306053985;106095;66262.33737662659;67708.63019794381;77508.34949902911;84912.57543712242;85525.57237213774;6967.442604591722;8794.604242809217;9075.546222688707;9621.9037809621;9676.95161524216;37142.8142859284;45278;50736.49263507318;53218.66953326198;55534.44465555293;49716.75141624414;68669;69967.30032699811;71686.28313716796;78971.60514197622;12013.399330033288;12032.458539365925;12140.75718485643;12268.141230113806;12352.505899763908;13523.86476135226;13786.517471888435;13825.51610693621;13923.512677056255;14466.132032078109;10729.9463502682;12798.232106073665;12828.422720977765;13181.143225690299;13373.633900122186;12961.606241213423;12991.28547929835;13132.080754347104;13150.934245328712;13421.463141474233;12223.449944752685;12611.558595449114;12674.14304270645;12723.55467558631;12725;10541.262111652111;10639;10808.891911081104;10961;11102.666919992413;10143.746406339606;10895.237333386904;11247.943760281147;11258.831117533407;11352.659420217407 09:24:42 INFO - PROCESS | 1911 | __end_tp_report 09:24:42 INFO - PROCESS | 1911 | __start_cc_report 09:24:42 INFO - PROCESS | 1911 | _x_x_mozilla_cycle_collect,367 09:24:42 INFO - PROCESS | 1911 | __end_cc_report 09:24:42 INFO - PROCESS | 1911 | __startTimestamp1479317082956__endTimestamp 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | ------- Summary: start ------- 09:24:42 INFO - PROCESS | 1911 | Number of tests: 6 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#0] dojo.html Cycles:165 Average:5713.86 Median:5942.52 stddev:683.00 (11.5%) stddev-sans-first:659.10 09:24:42 INFO - PROCESS | 1911 | Values: 3334.5 3347.6 3356.8 3360.1 3388.7 5609.1 5620.2 5634.9 5663.1 5697.6 5692.6 5732.7 5810.7 5864.0 5866.1 5174.4 5252.8 5255.8 5257.0 5273.6 5225.8 5332.9 5360.3 5360.5 5363.5 4280.5 5312.5 5316.5 5326.6 5383.2 5140.4 5170.9 5180.3 5182.1 5256.5 5112.2 5134.4 5179.7 5191.4 5194.5 5505.8 5692.7 5787.8 5788.5 5810.5 5149.9 6163.1 6201.0 6222.8 6241.9 6194.2 6200.3 6216.8 6217.5 6226.4 5885.2 5902.6 5904.6 5942.7 5982.3 6116.4 6184.3 6193.5 6211.8 6267.1 6197.9 6209.6 6219.9 6221.3 6223.7 6197.4 6229.2 6231.9 6242.2 6270.4 6109.9 6126.6 6156.5 6180.9 6231.4 5224.7 5915.9 6187.5 6216.9 6247.2 3787.4 4271.9 4304.0 4945.5 5010.5 3645.4 4374.0 4385.0 4399.3 4432.4 5757.5 5777.8 5788.4 5818.4 5836.2 6208.8 6234.4 6236.6 6251.1 6254.0 5679.6 5732.4 5774.1 5796.2 5801.2 5353.7 5415.5 5418.8 5419.1 5419.2 6177.9 6233.7 6250.1 6258.9 6263.1 4949.3 6198.5 6204.6 6213.1 6225.4 6317.4 6335.9 6352.6 6357.8 6389.5 6092.8 6129.2 6132.1 6249.0 6252.5 6092.3 6149.1 6153.6 6234.5 6240.7 6113.9 6169.2 6170.6 6184.2 6191.7 6147.8 6159.6 6182.1 6193.6 6202.9 5757.7 5776.1 5904.2 5907.7 5942.5 6108.9 6110.4 6150.4 6150.6 6151.7 5877.4 5957.5 5974.1 6007.5 6063.8 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#1] ext.html Cycles:180 Average:63991.93 Median:39361.90 stddev:136663.81 (347.2%) stddev-sans-first:136963.93 09:24:42 INFO - PROCESS | 1911 | Values: 456.4 471.5 475.5 483.4 525.3 37854.6 39148.5 39350.8 39373.0 39527.0 40146.8 40646.0 40720.2 40808.0 40828.4 9263.4 9276.0 9415.8 9427.7 9430.6 9323.3 9337.2 9438.2 9458.9 9461.1 9347.8 9416.2 9460.6 9466.2 9470.5 9327.6 9379.5 9422.2 9425.5 9426.2 9218.7 9287.7 9351.1 9360.7 9413.3 37388.0 37611.8 37649.1 37753.4 37758.1 3233.9 3654.0 3912.4 3946.3 4055.2 24524.9 28467.7 29678.1 30130.5 30696.5 2831.2 2865.4 3208.0 3287.0 3319.0 471920.0 474664.0 475588.6 477683.6 477876.0 56702.4 56736.7 56798.4 56977.1 57060.4 46574.8 49305.0 56245.7 56463.2 57912.4 15418.2 15469.0 15632.8 15639.9 15646.4 11757.5 11983.2 11989.9 12042.5 12070.8 11884.7 12628.5 15211.5 15416.5 15440.1 6618.2 6675.2 6676.9 6776.3 6804.6 842.8 884.0 886.3 996.3 1006.5 28636.6 28790.7 29186.6 29435.9 29892.0 996.2 1030.3 1045.5 1047.3 1054.3 15015.8 15083.3 15186.8 15222.0 15280.5 570853.0 576947.0 583637.0 584193.0 587026.0 55438.9 55704.9 55717.7 55720.6 55762.4 1081.7 1326.7 1328.9 1350.4 1354.6 24660.4 29582.7 29919.8 29942.6 30047.7 41953.2 47919.8 48072.0 48109.5 48223.3 471494.0 473688.0 473989.0 475128.0 475923.0 24738.6 24916.8 25087.7 25173.9 25601.4 44078.3 44911.0 45566.5 45934.9 45957.5 35745.8 36824.8 37320.3 37492.6 38036.4 36602.3 37166.3 37200.8 37887.2 38317.6 26362.6 32084.0 36985.3 37083.8 38181.0 28591.0 32297.4 33988.0 34048.5 34075.7 19899.9 24656.4 25067.6 25630.1 25737.5 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#2] jquery.html Cycles:180 Average:30198.04 Median:2111.97 stddev:78337.49 (3709.2%) stddev-sans-first:78525.08 09:24:42 INFO - PROCESS | 1911 | Values: 298.5 359.1 364.2 374.0 393.9 19048.6 19301.5 19329.1 19362.2 19643.4 16008.4 16539.2 17372.8 18801.7 19528.6 5345.8 5746.8 5756.1 5762.2 5763.5 5529.4 5544.9 5849.4 5899.6 5943.6 5957.2 5964.1 5977.1 5982.9 5989.5 6254.2 6490.0 6523.3 6552.2 6575.7 5552.4 5698.1 5779.6 5794.8 5799.2 12764.7 13014.9 13038.1 13101.7 21036.6 2036.9 2220.6 2236.4 2340.6 2898.5 9447.7 9513.0 9748.4 10106.2 10113.5 1830.1 1837.9 1906.9 1929.0 1934.8 111355.0 117098.4 117134.0 117581.0 118327.0 9061.5 9616.6 9737.2 11124.5 11481.4 12123.3 12142.7 12157.2 12186.2 12199.2 6042.5 6266.7 6269.7 7217.0 7966.8 8116.8 8147.8 8167.6 8214.8 8249.2 3707.6 3850.0 3871.9 3898.1 3914.3 1881.1 1917.8 1926.5 1928.6 1929.2 795.6 820.0 831.6 860.2 917.7 19982.0 19988.0 20027.1 20054.9 20199.9 820.4 825.3 877.6 883.5 912.7 8785.7 9967.4 10517.8 10590.4 10611.3 405598.0 429533.0 432233.0 502344.8 518657.0 116195.0 119252.4 121869.0 128248.7 136306.0 858.9 964.7 997.5 1001.3 1003.7 15213.9 17607.7 18419.8 18667.3 18775.9 49516.5 51082.2 52948.7 53785.7 54275.0 77191.0 78227.6 79178.0 79336.7 80005.0 7466.5 7558.7 7585.6 7721.2 7827.1 8556.6 11212.4 12235.7 12392.4 12480.3 9994.1 10077.7 10231.8 10471.0 10744.3 10444.9 10607.5 10644.1 10719.4 10946.3 10408.6 10734.2 10843.1 10857.3 10904.7 7574.6 7634.0 7747.7 7813.4 7996.5 7809.4 10166.3 10467.3 10748.4 10838.8 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#3] mootools.html Cycles:180 Average:5426.74 Median:5024.22 stddev:3029.01 (60.3%) stddev-sans-first:3022.68 09:24:42 INFO - PROCESS | 1911 | Values: 1438.9 1481.5 1623.3 1768.0 1769.9 7629.5 7903.4 8083.4 8493.6 8537.4 7987.7 8271.4 8309.8 8373.0 8378.3 2231.4 2269.5 2274.2 2279.2 2279.9 2218.0 2254.9 2267.8 2272.3 2278.3 2285.1 2294.8 2298.3 2303.2 2337.3 2290.4 2294.3 2310.1 2314.0 2316.1 2299.0 2318.1 2324.5 2325.9 2328.8 8416.2 8421.4 8448.9 8489.4 8513.8 5057.0 5192.4 5192.7 5228.7 5229.6 3228.7 3314.3 3557.7 3564.6 3566.8 981.8 991.6 997.4 1079.6 1080.2 10626.0 10903.8 10925.1 10932.0 10962.1 4284.7 4345.9 4410.7 4478.0 4484.4 8633.6 8694.9 8776.4 8797.2 8799.2 5102.8 5106.3 5116.5 5118.2 5125.6 4273.9 4615.6 4712.2 4740.1 4791.8 3580.7 3605.2 3667.2 3694.4 3710.3 1043.9 1089.4 1131.9 1140.1 1148.8 717.3 787.3 813.0 863.9 883.4 5446.8 5453.8 5553.5 5673.3 5793.2 411.3 421.1 430.7 431.1 432.9 3730.6 3745.2 3782.1 3814.0 3845.4 9801.7 10229.4 10290.6 10301.6 10313.6 10277.7 10277.8 10305.6 10339.5 10342.9 7604.1 7736.5 7796.8 7912.3 7975.0 5365.5 5489.6 5531.5 5581.3 5620.6 9342.4 9376.7 9378.6 9387.8 9421.3 9120.6 9442.6 9506.8 9520.1 9530.3 6672.6 6920.7 7094.7 7158.2 7295.5 7983.9 8179.7 8187.1 8189.2 8196.9 7203.6 7308.3 7318.3 7378.3 7415.5 7080.6 7122.7 7147.7 7274.7 7294.6 6667.3 6775.4 6841.2 6848.5 6850.0 4610.4 4709.7 4826.7 4865.7 4916.3 4228.8 4818.7 4888.9 4965.2 4991.4 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#4] prototype.html Cycles:180 Average:4583.35 Median:5136.83 stddev:1421.72 (27.7%) stddev-sans-first:1388.86 09:24:42 INFO - PROCESS | 1911 | Values: 298.7 303.7 303.7 308.6 311.6 5317.3 5489.1 5561.6 5614.4 5655.4 4935.4 5304.7 5407.4 5487.8 5493.7 4000.4 4097.0 4178.6 4247.3 4249.9 4084.0 4160.5 4176.7 4251.5 4270.0 3594.9 4099.8 4136.7 4189.6 4204.0 3615.9 3779.9 3948.6 4167.7 4173.5 3146.4 3332.3 3528.2 3634.9 3642.2 5038.3 5066.3 5206.5 5359.7 5413.5 4700.8 4811.3 4861.5 4863.0 4872.1 5539.7 5540.0 5808.6 5844.6 5862.0 3030.5 3518.4 3751.2 3866.1 4039.5 5185.6 6126.0 6198.5 6200.9 6214.0 3296.4 3466.4 3473.4 4144.6 4154.8 4031.1 4107.4 4127.9 4164.8 4198.3 4180.4 4221.1 4227.5 4441.4 4818.4 4686.8 5071.8 5471.0 5719.2 5828.8 1278.6 1319.1 1331.7 1349.5 1354.9 850.1 853.8 918.3 941.9 947.1 4901.5 4947.8 5155.3 5179.2 5202.7 4820.0 5638.4 5652.1 5670.5 5708.4 4257.4 4924.2 5137.0 5143.4 5164.5 2797.2 2934.2 2975.9 2989.8 2998.9 5088.6 5577.8 5894.5 5916.8 5960.0 4354.4 4788.8 5405.7 5634.2 5640.0 5088.8 5221.5 5255.8 5447.0 5499.0 4740.6 4961.0 5225.4 5562.5 5692.5 4966.0 5503.6 5982.6 5995.3 6032.3 6226.9 6324.3 6330.6 6343.5 6357.3 3169.5 3759.8 3860.3 3890.1 3932.5 5319.3 5505.5 5534.3 5602.2 5807.7 5136.7 5290.5 5395.4 5778.4 5949.9 5677.5 5886.3 5891.9 5942.0 5943.4 4292.4 4553.5 5094.7 5220.5 5395.6 5561.9 5874.0 5875.9 5877.3 5918.2 5192.6 5725.3 5765.9 5767.3 5800.4 09:24:42 INFO - PROCESS | 1911 | 09:24:42 INFO - PROCESS | 1911 | [#5] yui.html Cycles:180 Average:19761.91 Median:4270.91 stddev:29017.21 (679.4%) stddev-sans-first:29063.25 09:24:42 INFO - PROCESS | 1911 | Values: 683.9 729.0 761.0 777.0 817.3 14215.2 14580.3 14679.0 14838.5 14885.7 10189.7 11725.7 13122.3 13417.4 13457.4 742.9 825.1 827.8 828.2 829.2 649.4 666.9 792.1 812.8 826.0 783.5 848.6 853.9 876.5 878.0 766.0 863.9 870.4 870.6 871.6 559.6 607.1 641.1 641.3 642.0 13732.7 13865.2 14293.9 14335.0 14353.6 12390.1 13054.3 13385.0 13996.2 14000.9 11282.7 11427.4 11630.6 11776.9 11938.6 3328.1 3700.1 3764.9 3856.8 3898.5 90475.0 93926.0 94287.5 95105.0 95533.5 8759.6 10778.4 10789.3 10806.1 10887.0 21211.2 21513.4 21612.6 21617.2 21688.7 7531.7 7592.4 7752.5 7793.8 7862.5 4175.9 4191.8 4258.5 4289.6 4356.6 3406.6 3457.8 3507.8 3509.8 3514.9 653.6 690.3 697.7 703.7 712.3 587.0 616.5 619.3 619.5 646.6 7733.0 8867.1 8977.7 9106.0 9170.1 505.8 618.9 644.8 677.1 685.0 4153.9 4283.3 4408.8 4455.4 4507.9 103434.5 109401.0 113555.0 116463.0 120469.4 55997.7 62832.0 75963.6 101347.0 106095.0 66262.3 67708.6 77508.3 84912.6 85525.6 6967.4 8794.6 9075.5 9621.9 9677.0 37142.8 45278.0 50736.5 53218.7 55534.4 49716.8 68669.0 69967.3 71686.3 78971.6 12013.4 12032.5 12140.8 12268.1 12352.5 13523.9 13786.5 13825.5 13923.5 14466.1 10729.9 12798.2 12828.4 13181.1 13373.6 12961.6 12991.3 13132.1 13150.9 13421.5 12223.4 12611.6 12674.1 12723.6 12725.0 10541.3 10639.0 10808.9 10961.0 11102.7 10143.7 10895.2 11247.9 11258.8 11352.7 09:24:42 INFO - PROCESS | 1911 | -------- Summary: end -------- 09:24:42 INFO - PROCESS | 1911 | 09:24:43 INFO - TEST-INFO | 1911: exit 0 09:24:43 INFO - TEST-OK | dromaeo_css | took 1116168ms 09:24:43 INFO - TEST-START | kraken 09:24:43 INFO - operating with platform_type : mac_ 09:24:43 INFO - Initialising browser for kraken test... 09:24:43 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpkedjRs/profile http://localhost:53625/getInfo.html) 09:24:46 INFO - PROCESS | 1917 | 2016-11-16 09:24:46.208 plugin-container[1918:20270] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 09:24:46 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:46 INFO - PROCESS | 1917 | 2016-11-16 09:24:46.211 plugin-container[1918:20270] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 09:24:46 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:46 INFO - PROCESS | 1917 | 2016-11-16 09:24:46.211 plugin-container[1918:20270] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:24:46 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 09:24:46 INFO - PROCESS | 1917 | colorDepth:24 09:24:46 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 09:24:46 INFO - PROCESS | 1917 | __metrics 09:24:46 ERROR - PROCESS | 1917 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 09:24:47 INFO - TEST-INFO | 1917: exit 0 09:24:47 INFO - Browser initialized. 09:24:47 INFO - Running cycle 1/1 for kraken test... 09:24:47 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpkedjRs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:49 INFO - PROCESS | 1919 | 2016-11-16 09:24:49.628 plugin-container[1920:20381] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:24:49 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:49 INFO - PROCESS | 1919 | 2016-11-16 09:24:49.629 plugin-container[1920:20381] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:24:49 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:49 INFO - PROCESS | 1919 | 2016-11-16 09:24:49.629 plugin-container[1920:20381] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:24:59 INFO - PROCESS | 1919 | RSS: Main: 237916160 09:24:59 INFO - PROCESS | 1919 | 09:25:01 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53625/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:02 INFO - PROCESS | 1919 | RSS: Main: 250150912 09:25:02 INFO - PROCESS | 1919 | 09:25:05 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53625/tests/kraken/driver.html?testName=audio-dft) 09:25:06 INFO - PROCESS | 1919 | RSS: Main: 257855488 09:25:06 INFO - PROCESS | 1919 | 09:25:09 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53625/tests/kraken/driver.html?testName=audio-fft) 09:25:10 INFO - PROCESS | 1919 | RSS: Main: 260562944 09:25:10 INFO - PROCESS | 1919 | 09:25:12 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53625/tests/kraken/driver.html?testName=audio-oscillator) 09:25:12 INFO - PROCESS | 1919 | RSS: Main: 262836224 09:25:12 INFO - PROCESS | 1919 | 09:25:15 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53625/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:15 INFO - PROCESS | 1919 | RSS: Main: 263098368 09:25:15 INFO - PROCESS | 1919 | 09:25:18 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53625/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:19 INFO - PROCESS | 1919 | RSS: Main: 268627968 09:25:19 INFO - PROCESS | 1919 | 09:25:22 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53625/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:22 INFO - PROCESS | 1919 | RSS: Main: 268345344 09:25:22 INFO - PROCESS | 1919 | 09:25:25 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53625/tests/kraken/driver.html?testName=json-parse-financial) 09:25:26 INFO - PROCESS | 1919 | RSS: Main: 268754944 09:25:26 INFO - PROCESS | 1919 | 09:25:28 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53625/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:28 INFO - PROCESS | 1919 | RSS: Main: 267866112 09:25:28 INFO - PROCESS | 1919 | 09:25:30 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:31 INFO - PROCESS | 1919 | RSS: Main: 267993088 09:25:31 INFO - PROCESS | 1919 | 09:25:33 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:34 INFO - PROCESS | 1919 | RSS: Main: 267866112 09:25:34 INFO - PROCESS | 1919 | 09:25:37 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:38 INFO - PROCESS | 1919 | RSS: Main: 268218368 09:25:38 INFO - PROCESS | 1919 | 09:25:41 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:41 INFO - PROCESS | 1919 | RSS: Main: 267988992 09:25:41 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:53625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53625/tests/kraken/driver.html?testName=ai-astar) 09:25:43 INFO - PROCESS | 1919 | RSS: Main: 269762560 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | __start_tp_report 09:25:43 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 09:25:43 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 09:25:43 INFO - PROCESS | 1919 | |i|pagename|runs| 09:25:43 INFO - PROCESS | 1919 | |0;ai-astar;117;113;119;117;119;118;118;115;117;117 09:25:43 INFO - PROCESS | 1919 | |1;audio-beat-detection;153;99;131;189;146;117;116;147;175;115 09:25:43 INFO - PROCESS | 1919 | |2;audio-dft;226;186;256;267;241;198;197;197;202;195 09:25:43 INFO - PROCESS | 1919 | |3;audio-fft;83;75;60;84;61;64;61;60;78;59 09:25:43 INFO - PROCESS | 1919 | |4;audio-oscillator;92;75;117;102;75;72;115;98;101;87 09:25:43 INFO - PROCESS | 1919 | |5;imaging-gaussian-blur;92;89;92;90;94;100;93;81;136;89 09:25:43 INFO - PROCESS | 1919 | |6;imaging-darkroom;174;123;101;102;113;102;104;113;128;101 09:25:43 INFO - PROCESS | 1919 | |7;imaging-desaturate;135;146;92;92;93;91;90;91;93;94 09:25:43 INFO - PROCESS | 1919 | |8;json-parse-financial;58;57;58;58;59;59;58;58;61;76 09:25:43 INFO - PROCESS | 1919 | |9;json-stringify-tinderbox;68;74;95;80;115;106;106;80;73;73 09:25:43 INFO - PROCESS | 1919 | |10;stanford-crypto-aes;89;89;95;90;90;94;90;89;81;101 09:25:43 INFO - PROCESS | 1919 | |11;stanford-crypto-ccm;158;166;150;145;150;149;156;179;208;269 09:25:43 INFO - PROCESS | 1919 | |12;stanford-crypto-pbkdf2;177;180;178;183;176;173;174;194;186;173 09:25:43 INFO - PROCESS | 1919 | |13;stanford-crypto-sha256-iterative;70;67;70;69;68;69;68;66;70;70 09:25:43 INFO - PROCESS | 1919 | __end_tp_report 09:25:43 INFO - PROCESS | 1919 | __start_cc_report 09:25:43 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,966 09:25:43 INFO - PROCESS | 1919 | __end_cc_report 09:25:43 INFO - PROCESS | 1919 | __startTimestamp1479317143727__endTimestamp 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | ------- Summary: start ------- 09:25:43 INFO - PROCESS | 1919 | Number of tests: 14 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.50 stddev:1.83 (1.6%) stddev-sans-first:1.94 09:25:43 INFO - PROCESS | 1919 | Values: 117.0 113.0 119.0 117.0 119.0 118.0 118.0 115.0 117.0 117.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:150.00 stddev:28.59 (19.1%) stddev-sans-first:29.86 09:25:43 INFO - PROCESS | 1919 | Values: 153.0 99.0 131.0 189.0 146.0 117.0 116.0 147.0 175.0 115.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#2] audio-dft Cycles:10 Average:216.50 Median:214.00 stddev:28.88 (13.5%) stddev-sans-first:30.43 09:25:43 INFO - PROCESS | 1919 | Values: 226.0 186.0 256.0 267.0 241.0 198.0 197.0 197.0 202.0 195.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#3] audio-fft Cycles:10 Average:68.50 Median:69.50 stddev:10.28 (14.8%) stddev-sans-first:9.47 09:25:43 INFO - PROCESS | 1919 | Values: 83.0 75.0 60.0 84.0 61.0 64.0 61.0 60.0 78.0 59.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:75.00 stddev:16.17 (21.6%) stddev-sans-first:17.15 09:25:43 INFO - PROCESS | 1919 | Values: 92.0 75.0 117.0 102.0 75.0 72.0 115.0 98.0 101.0 87.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:91.00 stddev:14.98 (16.5%) stddev-sans-first:15.83 09:25:43 INFO - PROCESS | 1919 | Values: 92.0 89.0 92.0 90.0 94.0 100.0 93.0 81.0 136.0 89.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#6] imaging-darkroom Cycles:10 Average:116.10 Median:113.00 stddev:22.51 (19.9%) stddev-sans-first:10.22 09:25:43 INFO - PROCESS | 1919 | Values: 174.0 123.0 101.0 102.0 113.0 102.0 104.0 113.0 128.0 101.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:92.00 stddev:20.65 (22.4%) stddev-sans-first:18.04 09:25:43 INFO - PROCESS | 1919 | Values: 135.0 146.0 92.0 92.0 93.0 91.0 90.0 91.0 93.0 94.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:58.50 stddev:5.65 (9.7%) stddev-sans-first:5.94 09:25:43 INFO - PROCESS | 1919 | Values: 58.0 57.0 58.0 58.0 59.0 59.0 58.0 58.0 61.0 76.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#9] json-stringify-tinderbox Cycles:10 Average:87.00 Median:73.50 stddev:16.96 (23.1%) stddev-sans-first:16.54 09:25:43 INFO - PROCESS | 1919 | Values: 68.0 74.0 95.0 80.0 115.0 106.0 106.0 80.0 73.0 73.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:90.00 stddev:5.16 (5.7%) stddev-sans-first:5.43 09:25:43 INFO - PROCESS | 1919 | Values: 89.0 89.0 95.0 90.0 90.0 94.0 90.0 89.0 81.0 101.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#11] stanford-crypto-ccm Cycles:10 Average:173.00 Median:162.00 stddev:38.67 (23.9%) stddev-sans-first:40.63 09:25:43 INFO - PROCESS | 1919 | Values: 158.0 166.0 150.0 145.0 150.0 149.0 156.0 179.0 208.0 269.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:179.00 stddev:6.67 (3.7%) stddev-sans-first:7.02 09:25:43 INFO - PROCESS | 1919 | Values: 177.0 180.0 178.0 183.0 176.0 173.0 174.0 194.0 186.0 173.0 09:25:43 INFO - PROCESS | 1919 | 09:25:43 INFO - PROCESS | 1919 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.50 stddev:1.42 (2.0%) stddev-sans-first:1.42 09:25:43 INFO - PROCESS | 1919 | Values: 70.0 67.0 70.0 69.0 68.0 69.0 68.0 66.0 70.0 70.0 09:25:43 INFO - PROCESS | 1919 | -------- Summary: end -------- 09:25:43 INFO - PROCESS | 1919 | 09:25:44 INFO - TEST-INFO | 1919: exit 0 09:25:44 INFO - TEST-OK | kraken | took 60969ms 09:25:44 INFO - SUITE-END | took 1177s 09:25:44 INFO - Completed test suite (00:19:37) 09:25:44 INFO - javascript benchmark 09:25:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7033.539456241625, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.5331653568505, 3347.598288205416, 3356.848941797619, 3360.1431634933087, 3388.69501744843, 5609.074502707059, 5620.241267428896, 5634.887302253963, 5663.122216056513, 5697.6296540724925, 5692.601517893749, 5732.742026608802, 5810.738516766745, 5864.0, 5866.120081987704, 5174.379074511064, 5252.8161514347, 5255.763490642911, 5257.027449921758, 5273.551748101416, 5225.817096401626, 5332.92000619991, 5360.329958755136, 5360.544353729938, 5363.54409875161, 4280.464941882265, 5312.5218730314455, 5316.494932981361, 5326.6005049621235, 5383.219433182187, 5140.383154021504, 5170.896582068338, 5180.326557547494, 5182.144946083929, 5256.526912577886, 5112.156494178456, 5134.435212126663, 5179.715115668637, 5191.351081114861, 5194.532492075694, 5505.807296744595, 5692.686902220376, 5787.797427090074, 5788.479036886701, 5810.506106980912, 5149.948500515022, 6163.075538669191, 6201.0, 6222.844428889269, 6241.906371404432, 6194.194754681863, 6200.286966998808, 6216.751329946842, 6217.5336849735995, 6226.40849119333, 5885.234919460486, 5902.6458412495385, 5904.586678932434, 5942.673152976541, 5982.282126144891, 6116.366205733304, 6184.319724830265, 6193.473554747897, 6211.81364559064, 6267.122602835607, 6197.876042479125, 6209.59637623553, 6219.906701399483, 6221.253449586078, 6223.719932603044, 6197.380261973857, 6229.221347331583, 6231.875362492816, 6242.219722534684, 6270.435660790551, 6109.890546868077, 6126.609281623701, 6156.538259630545, 6180.8763824723255, 6231.408016238541, 5224.712640804716, 5915.914418233488, 6187.474064704551, 6216.906746398808, 6247.156633854463, 3787.394016957329, 4271.889308779678, 4304.010077682088, 4945.455999840015, 5010.49895010496, 3645.398509245998, 4373.993981384299, 4385.0, 4399.296112622029, 4432.357308190294, 5757.481826635623, 5777.768889244468, 5788.392218817048, 5818.359980402152, 5836.241288632451, 6208.782692605828, 6234.376562343729, 6236.594621349598, 6251.093591429218, 6253.96873015632, 5679.602427830012, 5732.426757324318, 5774.133879917962, 5796.217510636095, 5801.216835727123, 5353.7055461949185, 5415.48552887475, 5418.756155972991, 5419.051665958442, 5419.2413062170535, 6177.907331390033, 6233.657148856856, 6250.093736408105, 6258.937410625836, 6263.091851681481, 4949.28235405857, 6198.504119670506, 6204.5967012144065, 6213.130161777445, 6225.439710426083, 6317.399847014526, 6335.873282534231, 6352.587081839665, 6357.841053973688, 6389.52078594098, 6092.847678808065, 6129.20597562684, 6132.141360525156, 6249.0, 6252.531060170596, 6092.329843717276, 6149.139120523041, 6153.600015998945, 6234.470070044097, 6240.719167637558, 6113.877722445615, 6169.167162433014, 6170.598911070766, 6184.196054513064, 6191.690415479118, 6147.84630384244, 6159.568830182024, 6182.103594978793, 6193.566450348432, 6202.906956395567, 5757.683327416865, 5776.133579963039, 5904.173415721716, 5907.70461476933, 5942.524598032233, 6108.918862504554, 6110.419510146529, 6150.446459818638, 6150.630962142286, 6151.66165860864, 5877.382874798255, 5957.463828255478, 5974.074018527135, 6007.459328660441, 6063.818085457443], "value": 5668.814379183209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.35653728243176, 471.4954998151979, 475.54822918227694, 483.4150677680007, 525.2751203339392, 37854.621453785454, 39148.519207311, 39350.80324598376, 39373.0, 39526.99999999996, 40146.79926600363, 40646.0, 40720.18559628806, 40808.0, 40828.38757418641, 9263.397879137834, 9275.953620231889, 9415.81168376632, 9427.670031548898, 9430.622775089023, 9323.30075244356, 9337.20633746129, 9438.197753190987, 9458.905410945872, 9461.101195386462, 9347.76630584234, 9416.199623032016, 9460.574274157645, 9466.19537339327, 9470.52647367629, 9327.580258888333, 9379.531023448868, 9422.246220302393, 9425.528723563863, 9426.151646351862, 9218.723438296794, 9287.72136835896, 9351.111644393772, 9360.672376466791, 9413.294002949806, 37388.0, 37611.811940940395, 37649.0587735306, 37753.43369849455, 37758.05604859873, 3233.886813961524, 3653.9634603654154, 3912.3544615138467, 3946.3291240489184, 4055.1909809860435, 24524.896379662958, 28467.715322846714, 29678.109656710334, 30130.54804177939, 30696.53955190674, 2831.178958102148, 2865.412590418959, 3207.983960080208, 3286.9506957395656, 3318.96681033189, 471920.0, 474664.0, 475588.622056891, 477683.61158194335, 477876.0, 56702.43297567054, 56736.716316418155, 56798.43201568014, 56977.14534281989, 57060.42939570634, 46574.76712616415, 49305.0138997218, 56245.71877140588, 56463.15305270424, 57912.42087579155, 15418.152001639794, 15469.0, 15632.765508517381, 15639.921800390925, 15646.374145034299, 11757.529698811952, 11983.161178717406, 11989.940050299692, 12042.458089385998, 12070.818937715942, 11884.702882427835, 12628.494860205488, 15211.543653690407, 15416.537503874903, 15440.066387386361, 6618.172728408949, 6675.198976122896, 6676.86646267072, 6776.2546119926765, 6804.55770374924, 842.7766641839918, 883.9513826739591, 886.2511178054533, 996.2727209137225, 1006.5269323418128, 28636.570451443244, 28790.712092878803, 29186.562201566994, 29435.85282073619, 29892.0, 996.1731762636994, 1030.2788048366024, 1045.5451878432907, 1047.2512153810062, 1054.3041592548882, 15015.774763378558, 15083.321250543751, 15186.848131518544, 15221.997856056683, 15280.541583752505, 570853.0, 576947.0, 583637.0, 584193.0, 587026.0, 55438.89122217533, 55704.885902282535, 55717.72141139268, 55720.606984824895, 55762.44237557654, 1081.659277327655, 1326.6683329167709, 1328.9136206146763, 1350.4260689207047, 1354.6207062022452, 24660.38349041288, 29582.704172957994, 29919.80320787231, 29942.550861736654, 30047.69952300449, 41953.16093678171, 47919.76040119777, 48072.0, 48109.5189048105, 48223.27665085097, 471494.0, 473688.0, 473989.0, 475128.0, 475923.0, 24738.628920565847, 24916.75083249144, 25087.74912250854, 25173.874130629963, 25601.359966001, 44078.33882491829, 44911.0, 45566.544334557555, 45934.86586207258, 45957.540424595325, 35745.82127089348, 36824.81587592045, 37320.253594928494, 37492.62507374891, 38036.42945355877, 36602.26795464129, 37166.256674865814, 37200.81399592985, 37887.242255155295, 38317.61682383252, 26362.604560931984, 32084.03747887567, 36985.26029479449, 37083.814580926926, 38181.0, 28591.0, 32297.35405291928, 33988.0, 34048.48927266044, 34075.65924340725, 19899.900500497406, 24656.383590410383, 25067.623985640595, 25630.102946397532, 25737.485250295264], "value": 18877.442080943005, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.5417384315074, 359.0646366216007, 364.2242024487841, 373.95699494558124, 393.8818354493651, 19048.61902761944, 19301.517462063457, 19329.130189141506, 19362.225510979544, 19643.410697679094, 16008.359665613361, 16539.173041347916, 17372.826271737278, 18801.717974230363, 19528.609427811436, 5345.839624811262, 5746.827595172152, 5756.136579513065, 5762.193292938971, 5763.510101641345, 5529.419410961851, 5544.91682624761, 5849.415058494138, 5899.557533185007, 5943.64338139713, 5957.1957785698805, 5964.105384192362, 5977.073553599177, 5982.910256346158, 5989.460948514633, 6254.155688981975, 6490.0, 6523.315051919528, 6552.213734351908, 6575.736970521198, 5552.444755524456, 5698.145278208261, 5779.595428320019, 5794.768209271624, 5799.159121927297, 12764.680882977907, 13014.934925325313, 13038.087333886631, 13101.737965240642, 21036.579268414545, 2036.8879711615855, 2220.6446968484966, 2236.3961730332717, 2340.6372012338115, 2898.4782739106954, 9447.669331573363, 9512.95243523778, 9748.366356186896, 10106.191504679682, 10113.544890479947, 1830.1215416599973, 1837.9448616541524, 1906.8665193436466, 1929.0354822588706, 1934.7968463311431, 111355.0, 117098.41450792777, 117134.0, 117581.0, 118327.0, 9061.501617410973, 9616.567254473566, 9737.221022318197, 11124.49939752713, 11481.425928703531, 12123.272603643632, 12142.696432589257, 12157.209781364183, 12186.2078964867, 12199.207051541622, 6042.486388656926, 6266.717997690115, 6269.749210031548, 7216.96391518039, 7966.800829979181, 8116.797080072927, 8147.796305092302, 8167.550784706898, 8214.753557393287, 8249.216324449168, 3707.5921648618632, 3849.9807500962315, 3871.864484743077, 3898.064464528493, 3914.2954268231993, 1881.1252767462922, 1917.808219178071, 1926.5183704073982, 1928.6046360496064, 1929.218666440094, 795.5584650519017, 819.9877001844977, 831.6465502161557, 860.2043110123111, 917.6642903047338, 19982.000899954943, 19988.0, 20027.09878055491, 20054.89972550128, 20199.8990005049, 820.3560205238858, 825.2737590919952, 877.6050777150181, 883.5449743382163, 912.6760000199966, 8785.736427907173, 9967.352122112039, 10517.84223236652, 10590.417527036087, 10611.257211995086, 405598.0, 429533.0, 432233.0, 502344.78896898724, 518657.0, 116195.0, 119252.40373798076, 121869.0, 128248.71751282555, 136306.0, 858.9012263589767, 964.6720115160879, 997.4813097189566, 1001.2890847498212, 1003.7139415266614, 15213.935024548175, 17607.710997570233, 18419.815801842346, 18667.253309867454, 18775.90612046931, 49516.50483495263, 51082.23376649279, 52948.735256323474, 53785.731071344395, 54275.0, 77191.0, 78227.60886195532, 79178.0, 79336.73596912735, 80005.0, 7466.514676546007, 7558.715018446767, 7585.582792946441, 7721.227877212234, 7827.099883513469, 8556.57217139153, 11212.374205740285, 12235.69410764738, 12392.380380980734, 12480.251184928933, 9994.10053095225, 10077.748056298651, 10231.846522302023, 10471.0, 10744.30162039465, 10444.947775261075, 10607.469626518798, 10644.081347124431, 10719.410432426292, 10946.343219406543, 10408.635697750542, 10734.248602598056, 10843.132549396187, 10857.294275872044, 10904.727381815517, 7574.5833979129475, 7633.961830190813, 7747.706467711222, 7813.374930005472, 7996.480228785111, 7809.4142939278645, 10166.339187952759, 10467.319624224401, 10748.408837514013, 10838.783224335311], "value": 8909.11188297719, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.8561143885613, 1481.525911708253, 1623.2776414495547, 1767.9558011049714, 1769.9115044247792, 7629.542227466342, 7903.40724445666, 8083.393745469091, 8493.617787199575, 8537.402381833273, 7987.680492780281, 8271.379646526504, 8309.750707478785, 8373.0, 8378.287845533137, 2231.386368748591, 2269.546090781842, 2274.1812947338944, 2279.247848210095, 2279.886005699717, 2217.977820221793, 2254.8985295661732, 2267.7958983691465, 2272.2728726807427, 2278.270953294946, 2285.0517035430275, 2294.7819957104134, 2298.276043046443, 2303.193882141254, 2337.2754446121758, 2290.385278378739, 2294.27730264966, 2310.0759696121518, 2313.9652905206353, 2316.108298305148, 2298.97701022991, 2318.142287353673, 2324.4653729642278, 2325.93022209334, 2328.7904088632104, 8416.2004609562, 8421.410501264914, 8448.873266901, 8489.448185867961, 8513.787155321104, 5056.988602279559, 5192.376914770214, 5192.662476938987, 5228.738563071831, 5229.581633469352, 3228.725558327545, 3314.320564284316, 3557.6620221079215, 3564.590072141711, 3566.7681600695873, 981.8379967305386, 991.6083147156833, 997.3816233935007, 1079.632924805562, 1080.2276372393776, 10625.990530899553, 10903.781924361547, 10925.07136893357, 10932.016118549369, 10962.123030157569, 4284.721493102938, 4345.869623911288, 4410.735355878657, 4478.029666946533, 4484.417025786692, 8633.611487483078, 8694.91305086941, 8776.385653004229, 8797.164269394398, 8799.208071273617, 5102.795888164432, 5106.310648062538, 5116.539511443913, 5118.155504341817, 5125.564327032244, 4273.9358909616385, 4615.630749540029, 4712.151812673752, 4740.075685241345, 4791.784369703371, 3580.6598373154507, 3605.2248766515327, 3667.156553992597, 3694.427363758568, 3710.3135919854913, 1043.8726175730194, 1089.4008295437477, 1131.852859128308, 1140.1107136433595, 1148.7932172208916, 717.3328804212133, 787.330768846476, 812.9675312353278, 863.9308855291562, 883.3860466975519, 5446.782128714886, 5453.809116680897, 5553.4724201200825, 5673.262475878109, 5793.246877905845, 411.3254263008668, 421.11355596469605, 430.71787978873886, 431.1463302660687, 432.88095773662496, 3730.552333719971, 3745.194783121642, 3782.149016471327, 3813.9618603813606, 3845.3655146901006, 9801.656942006995, 10229.43738094412, 10290.588376465008, 10301.639442619473, 10313.639022634172, 10277.7430564235, 10277.845832312521, 10305.639302624371, 10339.534720937576, 10342.896571034193, 7604.136986438248, 7736.497127686683, 7796.766097017098, 7912.32745216663, 7975.0, 5365.463453654759, 5489.617202928353, 5531.529819965187, 5581.274434323512, 5620.606557540933, 9342.392744471588, 9376.67181648639, 9378.5779639915, 9387.765305867408, 9421.29340299494, 9120.635174592942, 9442.575084121094, 9506.762330941781, 9520.095590918852, 9530.332876698565, 6672.599644021374, 6920.653967301514, 7094.680739366844, 7158.212596614472, 7295.52579082358, 7983.920160798318, 8179.713710020121, 8187.099419064018, 8189.181081891763, 8196.877046844422, 7203.639818009184, 7308.342249197598, 7318.268173182639, 7378.335949764547, 7415.5179913305465, 7080.610566418896, 7122.715091396286, 7147.749828755968, 7274.74538391175, 7294.562326260441, 6667.333266673294, 6775.356341147583, 6841.21326047491, 6848.520603557703, 6850.006749021463, 4610.354550362885, 4709.710908800467, 4826.734529600905, 4865.7323847188745, 4916.287138364845, 4228.767417792051, 4818.71087734737, 4888.877778055577, 4965.155923492943, 4991.445509887364], "value": 4289.601357549363, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.6744448551079, 303.65535117641497, 303.7084398976982, 308.6342683919556, 311.60581863942105, 5317.335333083364, 5489.149181876805, 5561.5828812839045, 5614.438556144389, 5655.3779084300695, 4935.358403407552, 5304.681719096857, 5407.351117865862, 5487.789643058805, 5493.697846618435, 4000.3799411091327, 4097.037196258877, 4178.603032711895, 4247.277962746341, 4249.893752656193, 4083.97958010211, 4160.459140311756, 4176.749395036292, 4251.532331443539, 4269.978650106745, 3594.8741794037214, 4099.754014759124, 4136.696940463744, 4189.601987811153, 4203.978980105096, 3615.92084551384, 3779.924401511968, 3948.6446219840063, 4167.729097608676, 4173.499180098377, 3146.386454641346, 3332.283559034819, 3528.1708798432346, 3634.854605815764, 3642.1987162824134, 5038.294638750578, 5066.328111723123, 5206.464093092547, 5359.651622644555, 5413.53984911283, 4700.764961751899, 4811.302361157613, 4861.465238823726, 4863.0, 4872.1473742094995, 5539.723013849331, 5539.972300138514, 5808.59339846211, 5844.649321040752, 5862.003459411868, 3030.4545181867256, 3518.384282750534, 3751.249750050001, 3866.0721426857353, 4039.5255731658026, 5185.611079169077, 6126.0, 6198.473129783929, 6200.87598248037, 6214.0, 3296.4396052671104, 3466.3933811582874, 3473.4095203815414, 4144.564820693784, 4154.833806647761, 4031.09300407406, 4107.424960505532, 4127.876163715094, 4164.8125834337525, 4198.349255865346, 4180.352045432963, 4221.116572753074, 4227.51383590891, 4441.400410944481, 4818.397700287464, 4686.758009127584, 5071.771770270347, 5470.972645136749, 5719.170720245541, 5828.795992140254, 1278.603632873812, 1319.1227833490814, 1331.7203387288585, 1349.4737052549317, 1354.939027743754, 850.0946491986015, 853.8345158858237, 918.334207699426, 941.8540126280437, 947.0766003146877, 4901.451245352397, 4947.802087916443, 5155.304033955443, 5179.239058719978, 5202.684805261698, 4820.03567805804, 5638.43615638441, 5652.07829338027, 5670.489655930986, 5708.372079071381, 4257.446531950826, 4924.187509060965, 5137.0, 5143.434222235551, 5164.535191832753, 2797.216779301799, 2934.1709069664294, 2975.910722678323, 2989.832371530623, 2998.925026874346, 5088.600634825452, 5577.84049753976, 5894.528437724972, 5916.822495325147, 5959.970200148971, 4354.449600167833, 4788.832390866303, 5405.675659460445, 5634.154876768435, 5640.0, 5088.821891233789, 5221.503957124143, 5255.816046438356, 5447.0, 5499.0264117031265, 4740.597049250714, 4961.0, 5225.432370288822, 5562.527185189142, 5692.48767610917, 4965.975170124127, 5503.587775579166, 5982.6410415373475, 5995.340512543529, 6032.276126864805, 6226.875462490815, 6324.335944725737, 6330.588511746721, 6343.492520598434, 6357.268914074941, 3169.46119159748, 3759.830807613609, 3860.3051450739135, 3890.144168282973, 3932.488776459042, 5319.3084898964435, 5505.532029777513, 5534.339698090637, 5602.170903693609, 5807.651540907559, 5136.666116702402, 5290.455394885359, 5395.406505284337, 5778.393268706725, 5949.876314934694, 5677.5457963362005, 5886.323072846506, 5891.941080589139, 5941.970290148694, 5943.375945525658, 4292.420523229324, 4553.4763502197675, 5094.662995737797, 5220.504052115043, 5395.570173903997, 5561.8609534760335, 5874.0, 5875.941240587539, 5877.2947246329, 5918.201042859159, 5192.558632516128, 5725.311033245286, 5765.94234057654, 5767.250257466502, 5800.390958949419], "value": 4129.620170969481, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.8598087392082, 729.0231090338945, 760.9999999999999, 777.0, 817.2971244729531, 14215.218163001031, 14580.270986450689, 14679.0, 14838.480653177141, 14885.702285954274, 10189.694309170718, 11725.70685732855, 13122.278274694889, 13417.396217170226, 13457.394417251222, 742.888566714993, 825.0717942314895, 827.7765003449096, 828.1759649149105, 829.2163905109674, 649.4349915573434, 666.933306669334, 792.0851416613821, 812.8374325134968, 825.9876101858476, 783.4672422752526, 848.5828666127582, 853.8633818589027, 876.4784952953015, 878.029777096309, 765.9770206893802, 863.9049704532497, 870.3907264914585, 870.6169285514353, 871.5990644303596, 559.6110703061374, 607.1136141233804, 641.1152609399046, 641.3458272562008, 641.95506314558, 13732.65668358289, 13865.237411942338, 14293.927955403282, 14335.0, 14353.569392918229, 12390.132690711655, 13054.2820144892, 13385.0, 13996.186920342416, 14000.859991400057, 11282.717932051764, 11427.371494567795, 11630.592929247436, 11776.941115294454, 11938.58214962472, 3328.1180487170677, 3700.093477098128, 3764.9081766287745, 3856.7878766667823, 3898.4737060496755, 90475.0, 93926.0, 94287.52856235743, 95105.0, 95533.52233238859, 8759.562021898879, 10778.353298802096, 10789.298695584763, 10806.08148307387, 10887.0, 21211.15155393798, 21513.354599362043, 21612.567748645255, 21617.243396481048, 21688.674669879965, 7531.736389226351, 7592.392608591299, 7752.5348479091435, 7793.844123117506, 7862.488938218998, 4175.91648167035, 4191.811368488426, 4258.510271318776, 4289.635380992589, 4356.564343565681, 3406.6423025582453, 3457.8271086445575, 3507.7544571879725, 3509.8066657336626, 3514.8594056237466, 653.5523166630873, 690.3062422265691, 697.745322957117, 703.7466512055759, 712.3268511256813, 586.9845167859627, 616.4698359410903, 619.2507066449556, 619.4765423217374, 646.6475770704977, 7732.961335193288, 8867.123082304706, 8977.685780997634, 9105.984925970439, 9170.128837760401, 505.7774579185147, 618.9288231853304, 644.7872202173271, 677.1197443323659, 684.9999001457927, 4153.875383738493, 4283.31466965284, 4408.845690400821, 4455.420795296591, 4507.864764057018, 103434.48282758538, 109401.0, 113555.0, 116463.0, 120469.39765301117, 55997.72001139968, 62832.0, 75963.62018189873, 101346.97306053985, 106095.0, 66262.33737662659, 67708.63019794381, 77508.34949902911, 84912.57543712242, 85525.57237213774, 6967.442604591722, 8794.604242809217, 9075.546222688707, 9621.9037809621, 9676.95161524216, 37142.8142859284, 45278.0, 50736.49263507318, 53218.66953326198, 55534.44465555293, 49716.75141624414, 68669.0, 69967.30032699811, 71686.28313716796, 78971.60514197622, 12013.399330033288, 12032.458539365925, 12140.75718485643, 12268.141230113806, 12352.505899763908, 13523.86476135226, 13786.517471888435, 13825.51610693621, 13923.512677056255, 14466.132032078109, 10729.9463502682, 12798.232106073665, 12828.422720977765, 13181.143225690299, 13373.633900122186, 12961.606241213423, 12991.28547929835, 13132.080754347104, 13150.934245328712, 13421.463141474233, 12223.449944752685, 12611.558595449114, 12674.14304270645, 12723.55467558631, 12725.0, 10541.262111652111, 10639.0, 10808.891911081104, 10961.0, 11102.666919992413, 10143.746406339606, 10895.237333386904, 11247.943760281147, 11258.831117533407, 11352.659420217407], "value": 7168.063741852434, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 119.0, 117.0, 119.0, 118.0, 118.0, 115.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 99.0, 131.0, 189.0, 146.0, 117.0, 116.0, 147.0, 175.0, 115.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 186.0, 256.0, 267.0, 241.0, 198.0, 197.0, 197.0, 202.0, 195.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 60.0, 84.0, 61.0, 64.0, 61.0, 60.0, 78.0, 59.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 75.0, 117.0, 102.0, 75.0, 72.0, 115.0, 98.0, 101.0, 87.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 90.0, 94.0, 100.0, 93.0, 81.0, 136.0, 89.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 123.0, 101.0, 102.0, 113.0, 102.0, 104.0, 113.0, 128.0, 101.0], "value": 116.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 92.0, 92.0, 93.0, 91.0, 90.0, 91.0, 93.0, 94.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 58.0, 59.0, 59.0, 58.0, 58.0, 61.0, 76.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 95.0, 80.0, 115.0, 106.0, 106.0, 80.0, 73.0, 73.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 95.0, 90.0, 90.0, 94.0, 90.0, 89.0, 81.0, 101.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 166.0, 150.0, 145.0, 150.0, 149.0, 156.0, 179.0, 208.0, 269.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 178.0, 183.0, 176.0, 173.0, 174.0, 194.0, 186.0, 173.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 69.0, 68.0, 69.0, 68.0, 66.0, 70.0, 70.0], "value": 68.7, "unit": "score"}]}]} 09:25:44 INFO - Return code: 0 09:25:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:25:45 ERROR - # TBPL SUCCESS # 09:25:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:45 INFO - 2016-11-16 09:25:45.055 screenresolution[1924:20758] starting screenresolution argv=screenresolution get 09:25:45 INFO - 2016-11-16 09:25:45.058 screenresolution[1924:20758] Display 0: 1600x1200x32@60 09:25:45 INFO - 2016-11-16 09:25:45.064 screenresolution[1925:20761] starting screenresolution argv=screenresolution list 09:25:45 INFO - Available Modes on Display 0 09:25:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:45 INFO - Intel Iris: 09:25:45 INFO - Chipset Model: Intel Iris 09:25:45 INFO - Type: GPU 09:25:45 INFO - Bus: Built-In 09:25:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:45 INFO - Vendor: Intel (0x8086) 09:25:45 INFO - Device ID: 0x0a2e 09:25:45 INFO - Revision ID: 0x0009 09:25:45 INFO - Displays: 09:25:45 INFO - Display: 09:25:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:45 INFO - Display Serial Number: 1600x1200 60 09:25:45 INFO - Main Display: Yes 09:25:45 INFO - Mirror: Off 09:25:45 INFO - Online: Yes 09:25:45 INFO - Rotation: Supported 09:25:45 INFO - Return code: 0 09:25:45 INFO - Running post-action listener: _resource_record_post_action 09:25:45 INFO - [mozharness: 2016-11-16 17:25:45.299964Z] Finished run-tests step (success) 09:25:45 INFO - Running post-run listener: _resource_record_post_run 09:25:45 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 839680; Write bytes: 701457408; Read time: 8; Write time: 1978 09:25:45 INFO - TinderboxPrint: CPU usage
54.6% 09:25:45 INFO - TinderboxPrint: I/O read bytes / time
839,680 / 8 09:25:45 INFO - TinderboxPrint: I/O write bytes / time
701,457,408 / 1,978 09:25:45 INFO - TinderboxPrint: CPU idle
2,201.5 (45.4%) 09:25:45 INFO - TinderboxPrint: CPU system
985.6 (20.3%) 09:25:45 INFO - TinderboxPrint: CPU user
1,662.6 (34.3%) 09:25:45 INFO - TinderboxPrint: Swap in / out
399,507,456 / 0 09:25:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385638912; Write bytes: 380160000; Read time: 29235; Write time: 774 09:25:45 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 839680; Write bytes: 299719680; Read time: 8; Write time: 1151 09:25:45 INFO - Running post-run listener: _upload_blobber_files 09:25:45 INFO - Blob upload gear active. 09:25:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:25:45 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 09:25:45 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:25:45 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:25:46 INFO - (blobuploader) - INFO - Open directory for files ... 09:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:47 INFO - (blobuploader) - INFO - Done attempting. 09:25:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:25:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:25:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:48 INFO - (blobuploader) - INFO - Done attempting. 09:25:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:25:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:50 INFO - (blobuploader) - INFO - Done attempting. 09:25:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:25:50 INFO - Return code: 0 09:25:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:25:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:25:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c2558de37a29e91f9165f30d940985c57e7366fec65dc5f7983bbc02a49e87ac5e31c0752249057c28606626bdb0f7e7eeba8608aedec9c6bf84445b1b9b9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17a60b636e896708d8b6876e73a8be558ffbd4e74a78869c42005faedb93fe1898be7b92a0a6b09231cd73106ab718ce3164df761c4e0cc8d4169ba95bdcbffb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ece1607bec41057bb04875bf3370f8fc7cb1bacb24bde5e97d9f7a19c7d0c8a79bc487ed87b83456c3627b077569c2ef538c363021e9225c9d83f8efa5df71b2"} 09:25:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:25:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:25:50 INFO - Contents: 09:25:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c2558de37a29e91f9165f30d940985c57e7366fec65dc5f7983bbc02a49e87ac5e31c0752249057c28606626bdb0f7e7eeba8608aedec9c6bf84445b1b9b9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17a60b636e896708d8b6876e73a8be558ffbd4e74a78869c42005faedb93fe1898be7b92a0a6b09231cd73106ab718ce3164df761c4e0cc8d4169ba95bdcbffb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ece1607bec41057bb04875bf3370f8fc7cb1bacb24bde5e97d9f7a19c7d0c8a79bc487ed87b83456c3627b077569c2ef538c363021e9225c9d83f8efa5df71b2"} 09:25:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:25:50 INFO - Copying logs to upload dir... 09:25:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:25:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.817818 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-16 09:25:50.579853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 09:25:50.587289) ========= 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.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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/Graphics/sha512/c2558de37a29e91f9165f30d940985c57e7366fec65dc5f7983bbc02a49e87ac5e31c0752249057c28606626bdb0f7e7eeba8608aedec9c6bf84445b1b9b9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17a60b636e896708d8b6876e73a8be558ffbd4e74a78869c42005faedb93fe1898be7b92a0a6b09231cd73106ab718ce3164df761c4e0cc8d4169ba95bdcbffb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ece1607bec41057bb04875bf3370f8fc7cb1bacb24bde5e97d9f7a19c7d0c8a79bc487ed87b83456c3627b077569c2ef538c363021e9225c9d83f8efa5df71b2"} build_url:https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010913 build_url: 'https://queue.taskcluster.net/v1/task/SfTExFTyRGqHkYfJArIcNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c2558de37a29e91f9165f30d940985c57e7366fec65dc5f7983bbc02a49e87ac5e31c0752249057c28606626bdb0f7e7eeba8608aedec9c6bf84445b1b9b9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/17a60b636e896708d8b6876e73a8be558ffbd4e74a78869c42005faedb93fe1898be7b92a0a6b09231cd73106ab718ce3164df761c4e0cc8d4169ba95bdcbffb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ece1607bec41057bb04875bf3370f8fc7cb1bacb24bde5e97d9f7a19c7d0c8a79bc487ed87b83456c3627b077569c2ef538c363021e9225c9d83f8efa5df71b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 09:25:50.622875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:25:50.623233) ========= 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.R8PbBkpHc4/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.cEytpkon8e/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.005235 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:25:50.671624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 09:25:50.672651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 09:25:50.673000) ========= ========= Total master_lag: 0.20 =========