builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0121 starttime: 1478016083.26 results: success (0) buildid: 20161101063618 builduid: 55d5d7bb87824ab381947e26fc0efdd1 revision: 3e73fd638e687a4d7f46613586e5156b8e2af846 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.262140) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.262645) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.262963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.288361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.288671) ========= 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.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-01 09:01:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.8M=0s 2016-11-01 09:01:23 (24.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.443320) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.443733) ========= 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.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.033229 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:23.499606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 09:01:23.500147) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3e73fd638e687a4d7f46613586e5156b8e2af846 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3e73fd638e687a4d7f46613586e5156b8e2af846 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-01 09:01:23,578 truncating revision to first 12 chars 2016-11-01 09:01:23,579 Setting DEBUG logging. 2016-11-01 09:01:23,579 attempt 1/10 2016-11-01 09:01:23,579 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3e73fd638e68?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-01 09:01:24,959 unpacking tar archive at: mozilla-central-3e73fd638e68/testing/mozharness/ program finished with exit code 0 elapsedTime=1.635193 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 09:01:25.456322) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:25.456678) ========= script_repo_revision: 3e73fd638e687a4d7f46613586e5156b8e2af846 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:25.457151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:25.457458) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 09:01:25.477746) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-01 09:01:25.478145) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:01:25 INFO - MultiFileLogger online at 20161101 09:01:25 in /builds/slave/test 09:01:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:01:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:25 INFO - {'append_to_log': False, 09:01:25 INFO - 'base_work_dir': '/builds/slave/test', 09:01:25 INFO - 'blob_upload_branch': 'Firefox', 09:01:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:25 INFO - 'branch': 'Firefox', 09:01:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:25 INFO - 'config_files': ('talos/mac_config.py',), 09:01:25 INFO - 'default_actions': ('clobber', 09:01:25 INFO - 'read-buildbot-config', 09:01:25 INFO - 'download-and-extract', 09:01:25 INFO - 'populate-webroot', 09:01:25 INFO - 'create-virtualenv', 09:01:25 INFO - 'install', 09:01:25 INFO - 'run-tests'), 09:01:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:25 INFO - 'download_minidump_stackwalk': True, 09:01:25 INFO - 'download_symbols': 'ondemand', 09:01:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:25 INFO - 'installer_path': 'installer.exe', 09:01:25 INFO - 'log_level': 'info', 09:01:25 INFO - 'log_name': 'talos', 09:01:25 INFO - 'log_to_console': True, 09:01:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:25 INFO - 'opt_config_files': (), 09:01:25 INFO - 'pip_index': False, 09:01:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:25 INFO - 'cmd': ('bash', 09:01:25 INFO - '-c', 09:01:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:25 INFO - 'enabled': True, 09:01:25 INFO - 'halt_on_failure': False, 09:01:25 INFO - 'name': 'check_screen_resolution'},), 09:01:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:25 INFO - 'cmd': ('bash', 09:01:25 INFO - '-c', 09:01:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:25 INFO - 'enabled': True, 09:01:25 INFO - 'halt_on_failure': False, 09:01:25 INFO - 'name': 'check_screen_resolution'},), 09:01:25 INFO - 'run_cmd_checks_enabled': True, 09:01:25 INFO - 'sps_profile': False, 09:01:25 INFO - 'sps_profile_interval': 0, 09:01:25 INFO - 'suite': 'dromaeojs', 09:01:25 INFO - 'system_bits': '32', 09:01:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:25 INFO - 'title': 't-yosemite-r7-0121', 09:01:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:25 INFO - 'use_talos_json': True, 09:01:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:25 INFO - 'work_dir': 'build'} 09:01:25 INFO - [mozharness: 2016-11-01 16:01:25.688573Z] Running clobber step. 09:01:25 INFO - Running pre-action listener: _resource_record_pre_action 09:01:25 INFO - Running main action method: clobber 09:01:25 INFO - rmtree: /builds/slave/test/build 09:01:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:28 INFO - Running post-action listener: _resource_record_post_action 09:01:28 INFO - [mozharness: 2016-11-01 16:01:28.141092Z] Finished clobber step (success) 09:01:28 INFO - [mozharness: 2016-11-01 16:01:28.141216Z] Running read-buildbot-config step. 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: read_buildbot_config 09:01:28 INFO - Using buildbot properties: 09:01:28 INFO - { 09:01:28 INFO - "project": "", 09:01:28 INFO - "product": "firefox", 09:01:28 INFO - "script_repo_revision": "production", 09:01:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:01:28 INFO - "repository": "", 09:01:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:01:28 INFO - "buildid": "20161101063618", 09:01:28 INFO - "pgo_build": "False", 09:01:28 INFO - "basedir": "/builds/slave/test", 09:01:28 INFO - "buildnumber": 70, 09:01:28 INFO - "slavename": "t-yosemite-r7-0121", 09:01:28 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:01:28 INFO - "platform": "yosemite_r7", 09:01:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:01:28 INFO - "branch": "mozilla-central", 09:01:28 INFO - "repo_path": "mozilla-central", 09:01:28 INFO - "revision": "3e73fd638e687a4d7f46613586e5156b8e2af846", 09:01:28 INFO - "stage_platform": "macosx64", 09:01:28 INFO - "builduid": "55d5d7bb87824ab381947e26fc0efdd1", 09:01:28 INFO - "slavebuilddir": "test" 09:01:28 INFO - } 09:01:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:01:28 INFO - Running post-action listener: _resource_record_post_action 09:01:28 INFO - [mozharness: 2016-11-01 16:01:28.144582Z] Finished read-buildbot-config step (success) 09:01:28 INFO - [mozharness: 2016-11-01 16:01:28.144692Z] Running download-and-extract step. 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: download_and_extract 09:01:28 INFO - mkdir: /builds/slave/test/build/tests 09:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:28 INFO - trying https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:28 INFO - Downloading https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 09:01:29 INFO - Downloaded 1199 bytes. 09:01:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:29 INFO - Using the following test package requirements: 09:01:29 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:29 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:29 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:01:29 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'jsshell-mac.zip'], 09:01:29 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:01:29 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:29 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:01:29 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:01:29 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:01:29 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:29 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:29 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:01:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:29 INFO - Fetch https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:01:29 INFO - Http code: 200 09:01:29 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:01:29 INFO - x-amz-cf-id: QNcKWop71iTUY2czkkXPvo5URIEgFXw1HVaUJktXNEtwbS4m1c3S3Q== 09:01:29 INFO - x-amz-version-id: VK95.Tu4ABZG0Y4KDasp1OoP6JLvS59Q 09:01:30 INFO - Expected file size: 16404962 09:01:30 INFO - Obtained file size: 16404962 09:01:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:01:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:01:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:36 INFO - Fetch https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:01:37 INFO - Http code: 200 09:01:37 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 09:01:37 INFO - x-amz-cf-id: F23qBXieYie7uKligLL9Q4eKVdRp4vc3tC62UznFh1nWJ24frp9YLQ== 09:01:37 INFO - x-amz-version-id: VK95.Tu4ABZG0Y4KDasp1OoP6JLvS59Q 09:01:37 INFO - Expected file size: 16404962 09:01:37 INFO - Obtained file size: 16404962 09:01:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:01:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:01:43 INFO - Fetch https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:01:44 INFO - Http code: 200 09:01:44 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 09:01:44 INFO - x-amz-cf-id: nqsY8PPnwtIHXD8aZ2QwVVUV6HJAHy4N8M8d7rV4RbQc0J2IRsSGHA== 09:01:44 INFO - x-amz-version-id: ykfWY1N7feq.ZZSeP0.nVfCyl_37tUbq 09:01:44 INFO - Expected file size: 14409038 09:01:44 INFO - Obtained file size: 14409038 09:01:45 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:01:45 INFO - trying https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:01:45 INFO - Downloading https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 09:01:48 INFO - Downloaded 112096063 bytes. 09:01:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:01:48 INFO - mkdir: /builds/slave/test/properties 09:01:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:48 INFO - Contents: 09:01:48 INFO - build_url:https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - Running post-action listener: set_extra_try_arguments 09:01:49 INFO - [mozharness: 2016-11-01 16:01:49.130010Z] Finished download-and-extract step (success) 09:01:49 INFO - [mozharness: 2016-11-01 16:01:49.130130Z] Running populate-webroot step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: populate_webroot 09:01:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:49 INFO - '--tppagecycles', 09:01:49 INFO - '7'], 09:01:49 INFO - 'tests': ['tsvgm']}}, 09:01:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['damp', 'tps']}, 09:01:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'tests': ['damp', 'tps']}, 09:01:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['dromaeo_dom']}, 09:01:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['basic_compositor_video']}, 09:01:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:01:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['a11yr', 09:01:49 INFO - 'ts_paint', 09:01:49 INFO - 'tpaint', 09:01:49 INFO - 'sessionrestore', 09:01:49 INFO - 'sessionrestore_no_auto_restore', 09:01:49 INFO - 'tabpaint']}, 09:01:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['tsvgx', 09:01:49 INFO - 'tsvgr_opacity', 09:01:49 INFO - 'tart', 09:01:49 INFO - 'tscrollx', 09:01:49 INFO - 'cart']}, 09:01:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:49 INFO - 'tsvgr_opacity', 09:01:49 INFO - 'tart', 09:01:49 INFO - 'tscrollx', 09:01:49 INFO - 'cart']}, 09:01:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'talos_options': ['--disable-e10s'], 09:01:49 INFO - 'tests': ['tp5o']}, 09:01:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'tests': ['tp5o']}, 09:01:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'talos_options': ['--disable-e10s', 09:01:49 INFO - '--xperf_path', 09:01:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:49 INFO - 'tests': ['tp5n']}, 09:01:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:49 INFO - 'talos_options': ['--xperf_path', 09:01:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:49 INFO - 'tests': ['tp5n']}}, 09:01:49 INFO - 'talos.zip': {'path': '', 09:01:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - [mozharness: 2016-11-01 16:01:49.148411Z] Finished populate-webroot step (success) 09:01:49 INFO - [mozharness: 2016-11-01 16:01:49.148547Z] Running create-virtualenv step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: create_virtualenv 09:01:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:49 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:01:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:49 INFO - Using real prefix '/tools/python27' 09:01:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:52 INFO - Installing pip.................done. 09:01:52 INFO - Return code: 0 09:01:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:01:53 INFO - Reading from file tmpfile_stdout 09:01:53 INFO - Output received: 09:01:53 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:01:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:01:53 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:01:53 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:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:53 INFO - 'HOME': '/Users/cltbld', 09:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PAGER': '/bin/cat', 09:01:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:01:53 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - 'XPC_FLAGS': '0x0', 09:01:53 INFO - 'XPC_SERVICE_NAME': '0', 09:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:53 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:01:53 INFO - Cleaning up... 09:01:53 INFO - Return code: 0 09:01:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:01:53 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:01:53 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:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:53 INFO - 'HOME': '/Users/cltbld', 09:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PAGER': '/bin/cat', 09:01:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:01:53 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - 'XPC_FLAGS': '0x0', 09:01:53 INFO - 'XPC_SERVICE_NAME': '0', 09:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:53 INFO - Downloading/unpacking psutil>=3.1.1 09:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:57 INFO - Installing collected packages: psutil 09:01:57 INFO - Running setup.py install for psutil 09:01:57 INFO - building 'psutil._psutil_osx' extension 09:01:57 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:01:57 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:01:57 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:01:57 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:01:57 INFO - building 'psutil._psutil_posix' extension 09:01:57 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:01:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:58 INFO - ^ 09:01:58 INFO - 1 warning generated. 09:01:58 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:01:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:58 INFO - Successfully installed psutil 09:01:58 INFO - Cleaning up... 09:01:58 INFO - Return code: 0 09:01:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01:58 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:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 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:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:01:58 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:01:58 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:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:58 INFO - 'HOME': '/Users/cltbld', 09:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:58 INFO - 'LOGNAME': 'cltbld', 09:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:58 INFO - 'MOZ_NO_REMOTE': '1', 09:01:58 INFO - 'NO_EM_RESTART': '1', 09:01:58 INFO - 'PAGER': '/bin/cat', 09:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:58 INFO - 'PWD': '/builds/slave/test', 09:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:58 INFO - 'SHELL': '/bin/bash', 09:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:01:58 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:58 INFO - 'USER': 'cltbld', 09:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:58 INFO - 'XPC_FLAGS': '0x0', 09:01:58 INFO - 'XPC_SERVICE_NAME': '0', 09:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:02:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:01 INFO - Installing collected packages: mozsystemmonitor 09:02:01 INFO - Running setup.py install for mozsystemmonitor 09:02:01 INFO - Successfully installed mozsystemmonitor 09:02:01 INFO - Cleaning up... 09:02:01 INFO - Return code: 0 09:02:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:01 INFO - 'HOME': '/Users/cltbld', 09:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:01 INFO - 'LOGNAME': 'cltbld', 09:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:01 INFO - 'MOZ_NO_REMOTE': '1', 09:02:01 INFO - 'NO_EM_RESTART': '1', 09:02:01 INFO - 'PAGER': '/bin/cat', 09:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:01 INFO - 'PWD': '/builds/slave/test', 09:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:01 INFO - 'SHELL': '/bin/bash', 09:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:01 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:01 INFO - 'USER': 'cltbld', 09:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:01 INFO - 'XPC_FLAGS': '0x0', 09:02:01 INFO - 'XPC_SERVICE_NAME': '0', 09:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:01 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:04 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:04 INFO - warning: no files found matching '*.txt' 09:02:04 INFO - no previously-included directories found matching 'build' 09:02:04 INFO - no previously-included directories found matching 'dist' 09:02:04 INFO - no previously-included directories found matching '.git*' 09:02:04 INFO - Installing collected packages: jsonschema, functools32 09:02:04 INFO - Running setup.py install for functools32 09:02:04 INFO - warning: no files found matching '*.txt' 09:02:04 INFO - no previously-included directories found matching 'build' 09:02:04 INFO - no previously-included directories found matching 'dist' 09:02:04 INFO - no previously-included directories found matching '.git*' 09:02:04 INFO - Successfully installed jsonschema functools32 09:02:04 INFO - Cleaning up... 09:02:04 INFO - Return code: 0 09:02:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02: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:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', '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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02: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 functools32==3.2.3-2 09:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:04 INFO - 'HOME': '/Users/cltbld', 09:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:04 INFO - 'LOGNAME': 'cltbld', 09:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:04 INFO - 'MOZ_NO_REMOTE': '1', 09:02:04 INFO - 'NO_EM_RESTART': '1', 09:02:04 INFO - 'PAGER': '/bin/cat', 09:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:04 INFO - 'PWD': '/builds/slave/test', 09:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:04 INFO - 'SHELL': '/bin/bash', 09:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:04 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:04 INFO - 'USER': 'cltbld', 09:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:04 INFO - 'XPC_FLAGS': '0x0', 09:02:04 INFO - 'XPC_SERVICE_NAME': '0', 09:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:05 INFO - Cleaning up... 09:02:05 INFO - Return code: 0 09:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:05 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:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:05 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:02:05 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:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:05 INFO - 'HOME': '/Users/cltbld', 09:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:05 INFO - 'LOGNAME': 'cltbld', 09:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:05 INFO - 'MOZ_NO_REMOTE': '1', 09:02:05 INFO - 'NO_EM_RESTART': '1', 09:02:05 INFO - 'PAGER': '/bin/cat', 09:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:05 INFO - 'PWD': '/builds/slave/test', 09:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:05 INFO - 'SHELL': '/bin/bash', 09:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:05 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:05 INFO - 'USER': 'cltbld', 09:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:05 INFO - 'XPC_FLAGS': '0x0', 09:02:05 INFO - 'XPC_SERVICE_NAME': '0', 09:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:05 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:05 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:02:05 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:02:05 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:02:05 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:02:05 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:02:05 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:02:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - Downloading docopt-0.6.1.tar.gz 09:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:09 INFO - Running setup.py install for blobuploader 09:02:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:09 INFO - Running setup.py install for requests 09:02:09 INFO - Running setup.py install for docopt 09:02:09 INFO - Successfully installed blobuploader requests docopt 09:02:09 INFO - Cleaning up... 09:02:10 INFO - Return code: 0 09:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:10 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:10 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:02:10 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:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:10 INFO - 'HOME': '/Users/cltbld', 09:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:10 INFO - 'LOGNAME': 'cltbld', 09:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:10 INFO - 'MOZ_NO_REMOTE': '1', 09:02:10 INFO - 'NO_EM_RESTART': '1', 09:02:10 INFO - 'PAGER': '/bin/cat', 09:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:10 INFO - 'PWD': '/builds/slave/test', 09:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:10 INFO - 'SHELL': '/bin/bash', 09:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:10 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:10 INFO - 'USER': 'cltbld', 09:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:10 INFO - 'XPC_FLAGS': '0x0', 09:02:10 INFO - 'XPC_SERVICE_NAME': '0', 09:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-u72I2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-kMGF_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-0fO12M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-CDIiBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-MGxsdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-n5dfjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-lwqSuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:10 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-lx7SUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-htTkCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-fUTffM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-7jOxQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-vHzVNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-_5Jjmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-lgWw4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ChzdAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-P1wZdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:11 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-aKYR0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:12 INFO - Running setup.py install for manifestparser 09:02:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozcrash 09:02:12 INFO - Running setup.py install for mozdebug 09:02:12 INFO - Running setup.py install for mozdevice 09:02:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozfile 09:02:12 INFO - Running setup.py install for mozhttpd 09:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:12 INFO - Running setup.py install for mozinfo 09:02:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for mozInstall 09:02:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for mozleak 09:02:13 INFO - Running setup.py install for mozlog 09:02:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for moznetwork 09:02:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for mozprocess 09:02:13 INFO - Running setup.py install for mozprofile 09:02:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for mozrunner 09:02:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozscreenshot 09:02:14 INFO - Running setup.py install for moztest 09:02:14 INFO - Running setup.py install for mozversion 09:02:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:14 INFO - Cleaning up... 09:02:14 INFO - Return code: 0 09:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:14 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:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:14 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:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:14 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:14 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:02:14 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:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:14 INFO - 'HOME': '/Users/cltbld', 09:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:14 INFO - 'LOGNAME': 'cltbld', 09:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:14 INFO - 'MOZ_NO_REMOTE': '1', 09:02:14 INFO - 'NO_EM_RESTART': '1', 09:02:14 INFO - 'PAGER': '/bin/cat', 09:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:14 INFO - 'PWD': '/builds/slave/test', 09:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:14 INFO - 'SHELL': '/bin/bash', 09:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:14 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:14 INFO - 'USER': 'cltbld', 09:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:14 INFO - 'XPC_FLAGS': '0x0', 09:02:14 INFO - 'XPC_SERVICE_NAME': '0', 09:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-QpR8Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-E8yrYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-szP759-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-WNZwAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-sq5QRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-iPzPRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-fcDGN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-2LtgkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-5qE2nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ktfXsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-QtH65g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-1mlPC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-F7yTnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-po7wTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-vgNail-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Kn5ZUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:16 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-P4TVuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:16 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:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:16 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:02:16 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:02:16 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:02:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:19 INFO - Downloading blessings-1.6.tar.gz 09:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:19 INFO - Installing collected packages: blessings 09:02:19 INFO - Running setup.py install for blessings 09:02:19 INFO - Successfully installed blessings 09:02:19 INFO - Cleaning up... 09:02:19 INFO - Return code: 0 09:02:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:19 INFO - Reading from file tmpfile_stdout 09:02:19 INFO - Current package versions: 09:02:19 INFO - blessings == 1.6 09:02:19 INFO - blobuploader == 1.2.4 09:02:19 INFO - docopt == 0.6.1 09:02:19 INFO - functools32 == 3.2.3-2 09:02:19 INFO - jsonschema == 2.5.1 09:02:19 INFO - manifestparser == 1.1 09:02:19 INFO - mozInstall == 1.12 09:02:19 INFO - mozcrash == 0.17 09:02:19 INFO - mozdebug == 0.1 09:02:19 INFO - mozdevice == 0.48 09:02:19 INFO - mozfile == 1.2 09:02:19 INFO - mozhttpd == 0.7 09:02:19 INFO - mozinfo == 0.9 09:02:19 INFO - mozleak == 0.1 09:02:19 INFO - mozlog == 3.4 09:02:19 INFO - moznetwork == 0.27 09:02:19 INFO - mozprocess == 0.23 09:02:19 INFO - mozprofile == 0.28 09:02:19 INFO - mozrunner == 6.12 09:02:19 INFO - mozscreenshot == 0.1 09:02:19 INFO - mozsystemmonitor == 0.3 09:02:19 INFO - moztest == 0.7 09:02:19 INFO - mozversion == 1.4 09:02:19 INFO - psutil == 3.1.1 09:02:19 INFO - requests == 1.2.3 09:02:19 INFO - wsgiref == 0.1.2 09:02:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:20 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 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:20 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:02:20 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:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:20 INFO - 'HOME': '/Users/cltbld', 09:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:20 INFO - 'LOGNAME': 'cltbld', 09:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:20 INFO - 'MOZ_NO_REMOTE': '1', 09:02:20 INFO - 'NO_EM_RESTART': '1', 09:02:20 INFO - 'PAGER': '/bin/cat', 09:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:20 INFO - 'PWD': '/builds/slave/test', 09:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:20 INFO - 'SHELL': '/bin/bash', 09:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:20 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:20 INFO - 'USER': 'cltbld', 09:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:20 INFO - 'XPC_FLAGS': '0x0', 09:02:20 INFO - 'XPC_SERVICE_NAME': '0', 09:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 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:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e56490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104dcc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a0ca3c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, '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.SGi2wVVjEC/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.HBAeyAcUut/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:21 INFO - 'HOME': '/Users/cltbld', 09:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:21 INFO - 'LOGNAME': 'cltbld', 09:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:21 INFO - 'MOZ_NO_REMOTE': '1', 09:02:21 INFO - 'NO_EM_RESTART': '1', 09:02:21 INFO - 'PAGER': '/bin/cat', 09:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:21 INFO - 'PWD': '/builds/slave/test', 09:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:21 INFO - 'SHELL': '/bin/bash', 09:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:21 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:21 INFO - 'USER': 'cltbld', 09:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:21 INFO - 'XPC_FLAGS': '0x0', 09:02:21 INFO - 'XPC_SERVICE_NAME': '0', 09:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Running post-action listener: _resource_record_post_action 09:02:21 INFO - Running post-action listener: _start_resource_monitoring 09:02:21 INFO - Starting resource monitoring. 09:02:21 INFO - [mozharness: 2016-11-01 16:02:21.919301Z] Finished create-virtualenv step (success) 09:02:21 INFO - [mozharness: 2016-11-01 16:02:21.920299Z] Running install step. 09:02:21 INFO - Running pre-action listener: _resource_record_pre_action 09:02:21 INFO - Running main action method: install 09:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:22 INFO - Reading from file tmpfile_stdout 09:02:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:22 INFO - Reading from file tmpfile_stdout 09:02:22 INFO - Output received: 09:02:22 INFO - Usage: mozinstall [options] installer 09:02:22 INFO - Options: 09:02:22 INFO - -h, --help show this help message and exit 09:02:22 INFO - -d DEST, --destination=DEST 09:02:22 INFO - Directory to install application into. [default: 09:02:22 INFO - "/builds/slave/test"] 09:02:22 INFO - --app=APP Application being installed. [default: firefox] 09:02:22 INFO - mkdir: /builds/slave/test/build/application 09:02:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:02:56 INFO - Reading from file tmpfile_stdout 09:02:56 INFO - Output received: 09:02:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:02:56 INFO - Running post-action listener: _resource_record_post_action 09:02:56 INFO - [mozharness: 2016-11-01 16:02:56.400513Z] Finished install step (success) 09:02:56 INFO - [mozharness: 2016-11-01 16:02:56.400676Z] Running run-tests step. 09:02:56 INFO - Running pre-action listener: _resource_record_pre_action 09:02:56 INFO - Running main action method: run_tests 09:02:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:56 INFO - 2016-11-01 09:02:56.415 screenresolution[1644:7596] starting screenresolution argv=screenresolution get 09:02:56 INFO - 2016-11-01 09:02:56.418 screenresolution[1644:7596] Display 0: 1600x1200x32@60 09:02:56 INFO - 2016-11-01 09:02:56.424 screenresolution[1645:7598] starting screenresolution argv=screenresolution list 09:02:56 INFO - Available Modes on Display 0 09:02:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:56 INFO - Intel Iris: 09:02:56 INFO - Chipset Model: Intel Iris 09:02:56 INFO - Type: GPU 09:02:56 INFO - Bus: Built-In 09:02:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:56 INFO - Vendor: Intel (0x8086) 09:02:56 INFO - Device ID: 0x0a2e 09:02:56 INFO - Revision ID: 0x0009 09:02:56 INFO - Displays: 09:02:56 INFO - Display: 09:02:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:56 INFO - Display Serial Number: 1600x1200 60 09:02:56 INFO - Main Display: Yes 09:02:56 INFO - Mirror: Off 09:02:56 INFO - Online: Yes 09:02:56 INFO - Rotation: Supported 09:02:56 INFO - Return code: 0 09:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:56 INFO - Python 2.7.3 09:02:56 INFO - Return code: 0 09:02:56 INFO - grabbing minidump binary from tooltool 09:02: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:02:56 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 0x104c6ad30>, '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 0x104a90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c62b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:56 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:02:56 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:02:56 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:02:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:56 INFO - Return code: 0 09:02:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:02:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:02:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0121 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGi2wVVjEC/Render', 09:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:56 INFO - 'HOME': '/Users/cltbld', 09:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:56 INFO - 'LOGNAME': 'cltbld', 09:02:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:56 INFO - 'MOZ_NO_REMOTE': '1', 09:02:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:56 INFO - 'NO_EM_RESTART': '1', 09:02:56 INFO - 'PAGER': '/bin/cat', 09:02:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:56 INFO - 'PWD': '/builds/slave/test', 09:02:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:56 INFO - 'SHELL': '/bin/bash', 09:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HBAeyAcUut/Listeners', 09:02:56 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:56 INFO - 'USER': 'cltbld', 09:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:56 INFO - 'XPC_FLAGS': '0x0', 09:02:56 INFO - 'XPC_SERVICE_NAME': '0', 09:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:02:57 INFO - mozversion application_buildid: 20161101063618 09:02:57 INFO - mozversion application_changeset: 3e73fd638e687a4d7f46613586e5156b8e2af846 09:02:57 INFO - mozversion application_display_name: Nightly 09:02:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:57 INFO - mozversion application_name: Firefox 09:02:57 INFO - mozversion application_remotingname: firefox 09:02:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:57 INFO - mozversion application_vendor: Mozilla 09:02:57 INFO - mozversion application_version: 52.0a1 09:02:57 INFO - mozversion platform_buildid: 20161101063618 09:02:57 INFO - mozversion platform_changeset: 3e73fd638e687a4d7f46613586e5156b8e2af846 09:02:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:57 INFO - mozversion platform_version: 52.0a1 09:02:57 INFO - using testdate: 1478016177 09:02:57 INFO - actual date: 1478016177 09:02:57 INFO - starting webserver on 'localhost:49278' 09:02:57 INFO - SUITE-START | Running 2 tests 09:02:57 INFO - TEST-START | dromaeo_css 09:02:57 INFO - operating with platform_type : mac_ 09:02:57 INFO - Initialising browser for dromaeo_css test... 09:02:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmp851WKf/profile http://localhost:49278/getInfo.html) 09:02:59 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:02:59 INFO - PROCESS | 1655 | colorDepth:24 09:02:59 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:02:59 INFO - PROCESS | 1655 | __metrics 09:02:59 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:02:59 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:00 INFO - TEST-INFO | 1655: exit 0 09:03:00 INFO - Browser initialized. 09:03:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:00 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmp851WKf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:12 INFO - PROCESS | 1660 | RSS: Main: 240119808 09:03:12 INFO - PROCESS | 1660 | 09:04:04 INFO - PROCESS | 1660 | 1478016244300 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:06:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 09:06:19 INFO - PROCESS | 1660 | RSS: Main: 478797824 09:06:19 INFO - PROCESS | 1660 | 09:09:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 09:09:23 INFO - PROCESS | 1660 | RSS: Main: 371777536 09:09:23 INFO - PROCESS | 1660 | 09:12:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 09:12:26 INFO - PROCESS | 1660 | RSS: Main: 352636928 09:12:26 INFO - PROCESS | 1660 | 09:15:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 09:15:29 INFO - PROCESS | 1660 | RSS: Main: 341655552 09:15:29 INFO - PROCESS | 1660 | 09:18:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 09:18:33 INFO - PROCESS | 1660 | RSS: Main: 321339392 09:18:33 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 09:21:36 INFO - PROCESS | 1660 | RSS: Main: 381116416 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | __start_tp_report 09:21:36 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 09:21:36 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 09:21:36 INFO - PROCESS | 1660 | |i|pagename|runs| 09:21:36 INFO - PROCESS | 1660 | |0;dojo.html;2714.3621249006483;3282.2450836307635;3299.752518561107;3312.569365982422;3339.3321335732844;5488.286522752047;5576.470235327637;5645.5483561315095;5658.660480371175;5660.235868157797;5533.584981126422;5571.582131340146;5615.80344687936;5621.887562248763;5624.775008999635;5092.185250359943;5124.154514505102;5137.049645815536;5172.069027575035;5200.401953775327;4266.2534056540135;5162.251473536335;5228.529432351088;5254.185601231815;5266.368035835705;5179.430262671122;5186.247994040862;5196.324477817897;5224.92162617561;5262.02652509287;5248.790048398059;5254.369475662907;5257.263983042358;5266.8683282917855;5310.6813591184655;5078.542931136216;5082.23766435034;5117.5650069744115;5191.013707395583;5195.012947539955;5528.723563821793;5571.916421253684;5587.078132108198;5612.635178713371;5613.46672066153;6086.847828804271;6142.26292844857;6164.907526387108;6166.321704612534;6172.47533959614;5382.946170538323;6230.2523697156175;6241.157443745127;6243.843903902439;6256.092866534373;5603.691796951125;5656.717164141776;5665.206871038058;5672.404397538282;5789.88420231593;6303.716332765037;6351.301356850743;6361.777337793155;6365.108884756138;6368.681565921685;6040.456358927718;6050.30421501533;6058.515318774487;6064.241969753781;6070.3929607038945;5989.850253743692;6043.395660433922;6097.585173720644;6105.175801266772;6152.230971128609;5103.2855400244;6109.779029160984;6115.3578874217565;6149.569530132937;6150.7232174552255;6128.509719222451;6157.599756015934;6163.1679723237685;6183.567150299526;6202.844928876814;4726.243800991893;4832.758362081952;4872.220444728827;4884.0476107158765;4916.336294600254;4392.846250381222;4412.69111162222;4431.268840641323;4439.4006809080365;4459.063596644741;5792.304923409218;5801.680907550125;5814.796482123105;5824.388439213822;5844.152597873284;6189;6209.223847019122;6237.251529816451;6239.220097487814;6241.843953901098;5672.064109421982;5715.257016587817;5718.25662663851;5761.107028410603;5817.50551203144;5319.521243088141;5378.91931621026;5384.326959130109;5401.378841433207;5401.7029063401105;6329.7151628175925;6345.365463453618;6347.206599175195;6353.8729225416155;6359.554831161774;6097.4512293891985;6233.501319894307;6235.127082208585;6249.843753906189;6262.248530176408;6205.379462053758;6233.2208473940755;6286.18279623646;6288.811335659938;6337.302896681454;6047.516198704181;6084.39156084388;6091.116788065617;6094.603850749687;6102.664353460602;5919.526437885045;6106.15732346443;6117.248930028793;6142.375998160927;6148.661823599566;5979.641221526548;5981.0729336952845;5992.940070599238;5997.760089596367;5998.760049597967;5963.821085367446;5971.4327138921735;5973.880522389615;5982.49148822355;5983.551233657406;5801.622894511843;5846.123081537803;5851.1515830205235;5863.00328944072;5868.383819699041;5864.853378665397;5982.611130276518;6003.849903752441;6009.519238461;6017.428344307284;5835.591508594358;5851.882962340815;5859;5865.765369385177;5915.970420147872 09:21:36 INFO - PROCESS | 1660 | |1;ext.html;449.2318135987461;493.46952026571444;504.4577079639386;511.8541215753511;512.3953734593181;37347;39521.20957580847;39613.603863961354;39649.8017509912;39868.202635947266;32386.19034524138;33239.00282991514;33390.66609333906;34222.65777342226;40220.59227927016;9391.295652826031;9566.139047485725;9581.37721048133;9588.952055239713;9593.088656577615;8530;9405.858912116324;9511.714648560554;9530.952345238264;9576.186024187953;8592.957035214815;8657.69698060568;8657.783555411102;8767.561621918878;11193.384363860025;11264.4367781611;11447.484863181093;11462.770744585061;11473.655790326304;11477.311361318265;11374.886251137465;11460.656180314603;11464.19750617457;11465.484053217626;11504.194706370557;38776.418353724715;39067.82796516081;39201.60798392037;39209.21581568381;39271.803640981896;3391.101358140095;3428.3486137633768;3616.7468277220605;3677.8528858845616;3827.808609569538;29718.405631887243;30425.543616845764;30544.69455305463;30866.382672346426;31275.84362078198;3204.535342375366;3218.356328734239;3222.065600975714;3262.771605987582;3322.4517954537473;488425;493117;494613;494703.5264823689;500123;56287.43712562904;56718;56731;56743.71628141833;56758.43241567614;59485;59597;59630;59660;59707;15256.847431525543;15794;15805.36778528869;15808.889688680796;15814.288357023961;12259.57091501811;12312.445939932724;12317.260964342167;12318.384080795924;12340.568080117153;15079.623009424633;15117.470888518848;15162.924185379003;15302.923485382502;15307.387704491695;4994.70031798086;5307.301663467731;5679.620625727685;5862.736176872051;6008.909866352008;850;923.385948344357;969.3699095587924;973.9481360131041;1107.4583830109916;27654;28914.566281505795;29308;29383;29542.261443464085;1003.5835128421623;1064.7391389109719;1074.9408782517037;1091.5033659684825;1097.5609756097624;13151.60545183646;14664.926675366554;15038.32327545263;15098.943073984716;15463.381464741284;628352;632778;634449;636044;637046;47647.28529072067;48611.75694121507;55912;55923;55999.720001399735;1221.987780122205;1302.1145620977827;1317.8945684345229;1353.7157196988742;1357.260293140241;22518.437039074153;29888.103356899326;30367.848160759055;30796.92210772612;30940.22649433782;46564.76717616526;47249;47380.763096184295;47456.76271618758;47660;411648;414268;415127;620173;624868.2539523821;24393.63409548894;24585.754142459064;24916.626250605892;24916.875415622806;25004.37489062788;43844;45203.321950171434;45355.54644453513;45948.540514594424;47536;31577.526337104504;31865.36269274555;36704.449433259055;38232.617673822904;38732.6126738729;36617;37350;37625.87122386333;38290;39195.80402097971;37326.813365933;37764.24471510609;37970.240595188494;37979.24041519099;38137;31369.13778364357;33444.66555334415;33712.49431258484;34098.659013409546;34160.31679366448;24017.519649606562;24643.87678061598;24743.50512989766;24874.631895245937;25023.12419065324 09:21:36 INFO - PROCESS | 1660 | |2;jquery.html;353.95929468111177;372.8415423445036;373.1343283582089;380.5148435744425;400.4353861055912;14836.40654373825;14963.850361496381;15467.922660386695;19892;20515.794842051575;19140.61718765624;19602.70595941062;20177.991100444993;20335.28826491073;20536.38390848273;5711.8000869969455;6021.819345419644;6023.126646636235;6032.396760323976;6032.547558933075;4207.768572728499;5933.584649074566;5962.73167707452;5988.940110598904;5998.430149135847;5571.217210492644;5941.168236446901;5952.672603006833;5953.464188223059;5953.613015154023;5787.102999035156;5936.851578710545;5937.287525496925;5938.257717785277;5938.8218353449465;5511.448855114497;5680.147977803321;5727.684977326246;5730.971345143247;5733.799317023884;12643.431045602878;12757.881909936754;12883.549075782397;12884.098113132084;12895.419706113247;2179.8664694359004;2184.541246338271;2278.270953294946;2396.664466974625;2457.471643596635;9732.805343893153;10272.075513203772;10289.9485502572;10883.292585981886;14913.328900199516;1589.7059044076882;1850.9814901850943;1886.2832123793007;1911.9904400477908;1917.6260629177316;81637;88434;113257;114474.42762786215;122164.77835221624;11050.557977680803;11114.888851111386;11295.322280663186;11324.773504530029;11506.88493115075;14180.078294910798;14184.432622695183;14196.929015354857;14198.787018194735;14216.147031178163;8040.919590804134;8171.877421838677;8269.586520674062;8288;8311.709090181936;8019.518828870287;8021.959890200628;8040.758777236692;8191.0580283267;8192.83614327722;3715.4241092630687;4327.7619730914475;4486.881617275962;4491.483479399845;4493.618042466362;1762.753214549964;1793.957554030317;1800.4958611588777;1847.2518629954889;1932.2560814086396;843.7215718812777;857.2970164465079;921.1663444582664;935.3873213045466;1023.2172388123092;19686.212551498065;20532.536095668318;20549.486262843548;20603.381898543066;20940;876.8421684096924;879.8152387998592;879.8680197970356;908.4594666173616;912.9041450647719;8850.73447796567;9785.168260697777;10144.28989970695;10678.73303167427;10777.030067293981;488178;506218;514235;533197;533378.333108332;120507.79492205141;123442;128255;129730;130484.3475782615;905.1129892705054;919.4069824962861;942.5240253671852;947.6541062512133;964.8745663063617;14324.57026289213;17223.397181099102;17821.910890445466;17917.552061198574;18147.81852181514;50798;51093.74453127711;51443;51535.48464515307;52346;71604;72433;74668.87996680163;77457;81563.18436815556;7003.229644738973;7670.504251670871;7723.266289702469;7920.207979202034;8104.959475202586;10694.946525267324;12836.807447888476;13237.80143297832;13276;13394.531191408254;11023.283486573348;11083.501242443948;11349.716257093638;11464.025557827677;11542.711432213926;10453.163746900078;11152.219344645797;11538.07695384384;11543.076553875837;11566.94216528912;9220;11204.719882003015;11211.55153793839;11222.494987725735;11471.139664525028;7202.243764404662;7446.515976461513;7486.588237646982;7545.471816972979;7607.23927607257;10862.131029517777;10874;11072.501737421997;11304.660860173879;11406.258593191416 09:21:36 INFO - PROCESS | 1660 | |3;mootools.html;1341.3695563085346;1376.1880490510596;1487.8140232470942;1645.876559258056;1843.769528808899;7514.962425187873;7872.29149376556;7877.842443151134;7877.842443151134;7882.7635170944895;7690.3078722914925;7770.805729856742;8004.799880003003;8170.79573010675;8226.629801658924;2281.190177486993;2310.249169020067;2310.861348319098;2312.283192210412;2317.1079134533156;2268.7731226877345;2319.2810228829035;2322.222055611372;2322.501986218888;2324.372419446757;2264.479169790941;2268.421552504109;2291.679164916913;2298.4713515891353;2298.5173113646156;2251.5271792923504;2255.548890221962;2277.373722226401;2281.258590957937;2285.5086156476273;2275.533515629292;2299.7470278269375;2300.068472268734;2300.6779050932882;2303.5277768057676;8239.299659528888;8285.8342833143;8316.958415207946;8319.209675080858;8385.119562445916;4997.800087996476;5032.47159048302;5196.480351964811;5199.376074871039;5204.115300398941;3436.4501679731125;3535.08087897146;3548.3967725486473;3548.6983606393487;3550.928981420357;911.5077857956695;1044.780596074825;1059.4596755654673;1078.3529882070773;1090.2531765740493;10638.148948084212;10736.838947415796;10758.838617420744;10759.19306052057;10800.837987430195;4235.53409124996;4401.317795741666;4475.844529606619;4651.744154071558;4689.882752931204;8650.091740367297;8710.172533609424;8711.47731136134;8715.956420217859;8720.12798720123;5102.285680004802;5113.74431278442;5115.360579927509;5116.104681680691;5126.154184559506;4285.05728739683;4288.335308027261;4318.805653745589;4804.039192161582;4808.182608956486;2977.478941185284;3544.3442963051916;3580.4987301777774;3601.7838929664304;3669;1122.5734220995969;1126.8366086917515;1127.503898284752;1133.9206255562196;1139.5384869127852;734.2877408913346;819.6557445872747;878.3500209844669;882.1795729971188;895.9462432254086;5367.677939323653;5409.26974858397;5441;5510.6969116698965;5679.488846003879;410.51969196040704;414.23573506879757;419.3583816760362;423.8999795530593;440.5902510665112;3623.4021386471463;3721.832517536717;3736.4021756518828;3834.405667121545;3844.519435070616;9641.27690423221;10176.491175441199;10180.083792458714;10191.643292484876;10198.286119971532;10151.543180556744;10155.339902906288;10155.949220253851;10163.695089147337;10169.389836609827;6524.151860258231;7689.846203075907;7718.228177182237;7725.381969442431;7864.331531819745;5443.183522471502;5511.22842802016;5536.557075434036;5604.355499117653;5874.970625147018;8693.695720649746;9066.274698024274;9230.861537077084;9312.860307095534;9465.716028519155;7747.845043098993;9492.050794920453;9519.381240219363;9564.234861211226;9569.952150239204;6614;6855.451563874978;6857.245702972569;7089.539179953286;7319.975203471625;7612.200718924433;7872.291493765589;7903.644336005009;7974.601269936596;7994;7033.296670332926;7071.36357727807;7131.072960515307;7178.3898368637165;7236.095488063992;6931.584104953719;6936.20233673134;7021.824454388477;7111.608861512666;7144.285571442814;6219.689015549114;6226.813195604139;6292.590981586182;6303.527235457451;6344.841378965562;4705.106029854317;4725.929111063406;4783.1390349737385;4803;4879.634027448026;4672.392588963413;4725.740084295397;4812.663113582155;4897.167481528078;5000.64995450315 09:21:36 INFO - PROCESS | 1660 | |4;prototype.html;295.22503428500187;298.6774283773524;307.386763407003;308.6450581830894;312.03269863423407;5377.580088959969;5452.372977107634;5453.727313634322;5456.508914197721;5481.725913704308;5026.547610715035;5223.440514148068;5232.7122008289525;5240.039377751567;5419.590906364344;3501.9844245168997;3663.6336366363284;4007.639312461878;4013.55850856407;4022.5776293489203;3553.95158428263;4083.4079058536495;4083.8361067095884;4098.057446787226;4166.375043743433;3804.9809750951345;4034.4553485279553;4035.515738111431;4066.7966601669946;4103.133074451132;3606.999999999987;3659.9386178008344;3816.713746469012;3905.297046531623;4027.3958906164016;3494.968984149682;3495.755297129202;3497.475378693191;3501.562304711911;3509.6490350964955;3958.7228893977435;4218.388333691628;5144;5247.929960972835;5269.762860671279;3937.6652984496354;4006.4991876015497;4523.525029871882;4565.56627120423;4578.885527861797;4449.065696203801;5361.758720857571;5650.0959846424785;5650.124230744241;5685.219329146113;3546.3261980223933;3833.712471564616;3834.69322454203;3853.190829925719;3853.325668008087;5365.953639040389;5858.355580886099;5885.5585831062845;5953.404659534012;5976.223090998229;3685.244524872395;3883.941740873889;3885.8445662173763;3885.902852428712;3904.121572646132;3321.667833216659;3984.083660758042;4001.519817621904;4002.059001384326;4019.979900100481;4424.650481603077;4606.788741404145;4634.95365046352;4647.651426143053;4674.7896344663895;5195.9740201298755;5429.158480435539;5731.226284451629;5761.711914404263;5776.768929242868;1111.3998440841856;1264.3488603369271;1341.557286095586;1344.4890941442188;1344.717609302038;803.5098589860181;830.3523251863556;851.7019043334783;876.7895705030746;914.291424146292;4876.073546026244;5325.281087053276;5353.86615334612;5378.91931621026;5387.649802762808;5039.596832253411;5351.44808005674;5801.05664602363;5827.017899695784;5840.532757379399;4219.388188712681;4607.677462577654;4665.883352916204;5233.188855727369;5286.3920649125075;2995.4608170529077;3008.5709288087983;3024.803387779831;3025.4705426550267;3059.4492991261786;5000.674956127913;5597.832065038056;5915.112733090071;5974.566104134977;5983.311919129356;5112.3865136183895;5842.503387212135;5843.415658434207;5864.709763851945;5951.464368206882;5058.721770302669;5625.803096891589;5873.3245676746865;5940.079287710412;5964.373740757158;4303.849365272201;5124.718820294926;5345.198220266836;5358.812441564526;5377.670974865115;4961;5017.269042180442;5671.546276297804;5675.921574900755;5718.4846015805015;5010.924836127534;5699.943000569941;5718;5904.822855314347;5932.436418540232;3177.8093314400285;3261.1521004538517;3773.358529050013;3816.923661526698;3826.9425958610086;4879.975600121977;5751.769929202785;5784.82645520635;5800.042992906292;5820.272465941757;4492.887677808081;5187.144121220107;5583.553315734813;5614.129809879475;5614.719264036864;3974.880753577397;5159;5715.771369145187;5721.485066344049;5783.929972955017;4796.35249241348;5340.130954166011;5510.338759348743;5633.746481408429;5658.321001479849;5495.527012844316;5639.182318563868;5797.463547236098;5819.799110489129;5820.73806678692;5187.221916712523;5562.610617256753;5574.662081100588;5592.3009623797025;5644.379118297066 09:21:36 INFO - PROCESS | 1660 | |5;yui.html;659.9010148477731;669.3975422120092;677.9525433219675;687.5565260407037;725.4414101142121;14324.570262892117;14452.349644266007;14802.77795833062;14974.475893343722;15053;13279.269640169787;13501.59495215145;13508.18950862949;13510.527131550398;13543.322833858318;812.5327936436543;830.1366578758084;830.6303694855792;830.8296799156188;831.1563762780752;661.8974059020859;681.6421378776132;684.5105749389186;803.2810634482134;829.3945419843518;877.2368039805378;883.9823203535925;885.535094075609;889.6708217959364;893.2719833335843;878.433410450256;879.454738062399;879.9384043116985;880.4145243413122;881.6737807011383;511.04434707097965;528.3791544934686;529.4414392815578;529.549882599791;651.7067319706103;11909.285442873455;13716;14223.21772302523;14247.501337453139;14580.635484112981;13505.594832155051;13863.920411938188;13967.231802250872;13973.441062357493;14077.366518506691;11420.828687569694;11420.94289528547;11550.34342101313;11577.247478913843;11659.00898423635;3653.3789255826573;3685.1892583631557;3756.0422092366543;3765.1151979284846;3823.50294461721;87200.25599488038;87513;88725.11274887233;89406;90229.09770902338;10612.628558000433;10686.625968091079;10741.40922249268;10791.676249712522;11043.116550675926;17861.374851880122;21445.57108857814;21580.46048848791;21606.027728752244;21608.13567457284;7467.962660186773;7640.388768898475;7664.9616751917;7815.491993020549;8040.115587285394;4224.598663126998;4260.105377870682;4303.225419424471;4338.370936214231;4477.1493416250805;3430.2281986553326;3469.3359481939065;3511.940998592847;3514.0863375522035;3542.557180352456;651.2933467188059;655.2824657000576;663.6582160187507;668.8327918020495;672.3780503034674;580.3847921203537;590.5511811023653;599.7031469422664;608.4949491921694;643.7103303513437;8755.518446485505;9032.405203871209;9095.454272743656;9218.308626853013;9435.76410589727;641.8138739765509;649.1982401733854;654.3162395296927;666.8999650052434;670.25266827487;4075.7962101894786;4362.389265502832;4396.054848207651;4460.643148548108;4487.147441986012;62845.371546283954;75224.24775752172;93953;97065.51467242619;123907;55210.17184742232;69112.65443672749;73754.26245737475;89084;91625;55024.72487637536;78624.60687696525;79062;82033;85135.99999999876;9249.953750231205;9492.525373731423;9499.192568631745;9499.857502137611;9541.188998935168;50922.74538627283;51775.74112129415;52274;52337.47662523326;53028.73485632547;69111.3088869125;73441;74419;75105;77718;11628.302301861915;11992.76014479723;12022.458989345676;12058.336791476553;12145.14983951115;13882.791758123434;13941.58175254744;14052.297385130907;14086.36611352512;14248.145111293356;9732.951335243279;10619.628313009007;12450.813237801622;12873;13024.739505209653;11834.644960651194;12338.629841104781;12430.937845311077;12594.181378210387;12615;12001.459934302802;12412.317322547347;12692.809607856074;12862.421191046238;12956.28740419286;7488.438367122597;11221.270617409842;11261.549538018715;11274;11491.54033838637;10767.923207679169;10802.297850639683;10842.747175847371;11089.722756931142;11130.332180069221 09:21:36 INFO - PROCESS | 1660 | __end_tp_report 09:21:36 INFO - PROCESS | 1660 | __start_cc_report 09:21:36 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,3235 09:21:36 INFO - PROCESS | 1660 | __end_cc_report 09:21:36 INFO - PROCESS | 1660 | __startTimestamp1478017296687__endTimestamp 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | ------- Summary: start ------- 09:21:36 INFO - PROCESS | 1660 | Number of tests: 6 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5676.29 Median:5859.00 stddev:653.09 (11.1%) stddev-sans-first:612.37 09:21:36 INFO - PROCESS | 1660 | Values: 2714.4 3282.2 3299.8 3312.6 3339.3 5488.3 5576.5 5645.5 5658.7 5660.2 5533.6 5571.6 5615.8 5621.9 5624.8 5092.2 5124.2 5137.0 5172.1 5200.4 4266.3 5162.3 5228.5 5254.2 5266.4 5179.4 5186.2 5196.3 5224.9 5262.0 5248.8 5254.4 5257.3 5266.9 5310.7 5078.5 5082.2 5117.6 5191.0 5195.0 5528.7 5571.9 5587.1 5612.6 5613.5 6086.8 6142.3 6164.9 6166.3 6172.5 5382.9 6230.3 6241.2 6243.8 6256.1 5603.7 5656.7 5665.2 5672.4 5789.9 6303.7 6351.3 6361.8 6365.1 6368.7 6040.5 6050.3 6058.5 6064.2 6070.4 5989.9 6043.4 6097.6 6105.2 6152.2 5103.3 6109.8 6115.4 6149.6 6150.7 6128.5 6157.6 6163.2 6183.6 6202.8 4726.2 4832.8 4872.2 4884.0 4916.3 4392.8 4412.7 4431.3 4439.4 4459.1 5792.3 5801.7 5814.8 5824.4 5844.2 6189.0 6209.2 6237.3 6239.2 6241.8 5672.1 5715.3 5718.3 5761.1 5817.5 5319.5 5378.9 5384.3 5401.4 5401.7 6329.7 6345.4 6347.2 6353.9 6359.6 6097.5 6233.5 6235.1 6249.8 6262.2 6205.4 6233.2 6286.2 6288.8 6337.3 6047.5 6084.4 6091.1 6094.6 6102.7 5919.5 6106.2 6117.2 6142.4 6148.7 5979.6 5981.1 5992.9 5997.8 5998.8 5963.8 5971.4 5973.9 5982.5 5983.6 5801.6 5846.1 5851.2 5863.0 5868.4 5864.9 5982.6 6003.8 6009.5 6017.4 5835.6 5851.9 5859.0 5865.8 5916.0 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:66871.72 Median:37348.50 stddev:146776.35 (393.0%) stddev-sans-first:147103.37 09:21:36 INFO - PROCESS | 1660 | Values: 449.2 493.5 504.5 511.9 512.4 37347.0 39521.2 39613.6 39649.8 39868.2 32386.2 33239.0 33390.7 34222.7 40220.6 9391.3 9566.1 9581.4 9589.0 9593.1 8530.0 9405.9 9511.7 9531.0 9576.2 8593.0 8657.7 8657.8 8767.6 11193.4 11264.4 11447.5 11462.8 11473.7 11477.3 11374.9 11460.7 11464.2 11465.5 11504.2 38776.4 39067.8 39201.6 39209.2 39271.8 3391.1 3428.3 3616.7 3677.9 3827.8 29718.4 30425.5 30544.7 30866.4 31275.8 3204.5 3218.4 3222.1 3262.8 3322.5 488425.0 493117.0 494613.0 494703.5 500123.0 56287.4 56718.0 56731.0 56743.7 56758.4 59485.0 59597.0 59630.0 59660.0 59707.0 15256.8 15794.0 15805.4 15808.9 15814.3 12259.6 12312.4 12317.3 12318.4 12340.6 15079.6 15117.5 15162.9 15302.9 15307.4 4994.7 5307.3 5679.6 5862.7 6008.9 850.0 923.4 969.4 973.9 1107.5 27654.0 28914.6 29308.0 29383.0 29542.3 1003.6 1064.7 1074.9 1091.5 1097.6 13151.6 14664.9 15038.3 15098.9 15463.4 628352.0 632778.0 634449.0 636044.0 637046.0 47647.3 48611.8 55912.0 55923.0 55999.7 1222.0 1302.1 1317.9 1353.7 1357.3 22518.4 29888.1 30367.8 30796.9 30940.2 46564.8 47249.0 47380.8 47456.8 47660.0 411648.0 414268.0 415127.0 620173.0 624868.3 24393.6 24585.8 24916.6 24916.9 25004.4 43844.0 45203.3 45355.5 45948.5 47536.0 31577.5 31865.4 36704.4 38232.6 38732.6 36617.0 37350.0 37625.9 38290.0 39195.8 37326.8 37764.2 37970.2 37979.2 38137.0 31369.1 33444.7 33712.5 34098.7 34160.3 24017.5 24643.9 24743.5 24874.6 25023.1 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:31604.38 Median:2034.69 stddev:86569.38 (4254.7%) stddev-sans-first:86780.43 09:21:36 INFO - PROCESS | 1660 | Values: 354.0 372.8 373.1 380.5 400.4 14836.4 14963.9 15467.9 19892.0 20515.8 19140.6 19602.7 20178.0 20335.3 20536.4 5711.8 6021.8 6023.1 6032.4 6032.5 4207.8 5933.6 5962.7 5988.9 5998.4 5571.2 5941.2 5952.7 5953.5 5953.6 5787.1 5936.9 5937.3 5938.3 5938.8 5511.4 5680.1 5727.7 5731.0 5733.8 12643.4 12757.9 12883.5 12884.1 12895.4 2179.9 2184.5 2278.3 2396.7 2457.5 9732.8 10272.1 10289.9 10883.3 14913.3 1589.7 1851.0 1886.3 1912.0 1917.6 81637.0 88434.0 113257.0 114474.4 122164.8 11050.6 11114.9 11295.3 11324.8 11506.9 14180.1 14184.4 14196.9 14198.8 14216.1 8040.9 8171.9 8269.6 8288.0 8311.7 8019.5 8022.0 8040.8 8191.1 8192.8 3715.4 4327.8 4486.9 4491.5 4493.6 1762.8 1794.0 1800.5 1847.3 1932.3 843.7 857.3 921.2 935.4 1023.2 19686.2 20532.5 20549.5 20603.4 20940.0 876.8 879.8 879.9 908.5 912.9 8850.7 9785.2 10144.3 10678.7 10777.0 488178.0 506218.0 514235.0 533197.0 533378.3 120507.8 123442.0 128255.0 129730.0 130484.3 905.1 919.4 942.5 947.7 964.9 14324.6 17223.4 17821.9 17917.6 18147.8 50798.0 51093.7 51443.0 51535.5 52346.0 71604.0 72433.0 74668.9 77457.0 81563.2 7003.2 7670.5 7723.3 7920.2 8105.0 10694.9 12836.8 13237.8 13276.0 13394.5 11023.3 11083.5 11349.7 11464.0 11542.7 10453.2 11152.2 11538.1 11543.1 11566.9 9220.0 11204.7 11211.6 11222.5 11471.1 7202.2 7446.5 7486.6 7545.5 7607.2 10862.1 10874.0 11072.5 11304.7 11406.3 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5332.06 Median:4888.40 stddev:2952.76 (60.4%) stddev-sans-first:2945.82 09:21:36 INFO - PROCESS | 1660 | Values: 1341.4 1376.2 1487.8 1645.9 1843.8 7515.0 7872.3 7877.8 7877.8 7882.8 7690.3 7770.8 8004.8 8170.8 8226.6 2281.2 2310.2 2310.9 2312.3 2317.1 2268.8 2319.3 2322.2 2322.5 2324.4 2264.5 2268.4 2291.7 2298.5 2298.5 2251.5 2255.5 2277.4 2281.3 2285.5 2275.5 2299.7 2300.1 2300.7 2303.5 8239.3 8285.8 8317.0 8319.2 8385.1 4997.8 5032.5 5196.5 5199.4 5204.1 3436.5 3535.1 3548.4 3548.7 3550.9 911.5 1044.8 1059.5 1078.4 1090.3 10638.1 10736.8 10758.8 10759.2 10800.8 4235.5 4401.3 4475.8 4651.7 4689.9 8650.1 8710.2 8711.5 8716.0 8720.1 5102.3 5113.7 5115.4 5116.1 5126.2 4285.1 4288.3 4318.8 4804.0 4808.2 2977.5 3544.3 3580.5 3601.8 3669.0 1122.6 1126.8 1127.5 1133.9 1139.5 734.3 819.7 878.4 882.2 895.9 5367.7 5409.3 5441.0 5510.7 5679.5 410.5 414.2 419.4 423.9 440.6 3623.4 3721.8 3736.4 3834.4 3844.5 9641.3 10176.5 10180.1 10191.6 10198.3 10151.5 10155.3 10155.9 10163.7 10169.4 6524.2 7689.8 7718.2 7725.4 7864.3 5443.2 5511.2 5536.6 5604.4 5875.0 8693.7 9066.3 9230.9 9312.9 9465.7 7747.8 9492.1 9519.4 9564.2 9570.0 6614.0 6855.5 6857.2 7089.5 7320.0 7612.2 7872.3 7903.6 7974.6 7994.0 7033.3 7071.4 7131.1 7178.4 7236.1 6931.6 6936.2 7021.8 7111.6 7144.3 6219.7 6226.8 6292.6 6303.5 6344.8 4705.1 4725.9 4783.1 4803.0 4879.6 4672.4 4725.7 4812.7 4897.2 5000.6 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4513.78 Median:5134.36 stddev:1405.06 (27.4%) stddev-sans-first:1372.86 09:21:36 INFO - PROCESS | 1660 | Values: 295.2 298.7 307.4 308.6 312.0 5377.6 5452.4 5453.7 5456.5 5481.7 5026.5 5223.4 5232.7 5240.0 5419.6 3502.0 3663.6 4007.6 4013.6 4022.6 3554.0 4083.4 4083.8 4098.1 4166.4 3805.0 4034.5 4035.5 4066.8 4103.1 3607.0 3659.9 3816.7 3905.3 4027.4 3495.0 3495.8 3497.5 3501.6 3509.6 3958.7 4218.4 5144.0 5247.9 5269.8 3937.7 4006.5 4523.5 4565.6 4578.9 4449.1 5361.8 5650.1 5650.1 5685.2 3546.3 3833.7 3834.7 3853.2 3853.3 5366.0 5858.4 5885.6 5953.4 5976.2 3685.2 3883.9 3885.8 3885.9 3904.1 3321.7 3984.1 4001.5 4002.1 4020.0 4424.7 4606.8 4635.0 4647.7 4674.8 5196.0 5429.2 5731.2 5761.7 5776.8 1111.4 1264.3 1341.6 1344.5 1344.7 803.5 830.4 851.7 876.8 914.3 4876.1 5325.3 5353.9 5378.9 5387.6 5039.6 5351.4 5801.1 5827.0 5840.5 4219.4 4607.7 4665.9 5233.2 5286.4 2995.5 3008.6 3024.8 3025.5 3059.4 5000.7 5597.8 5915.1 5974.6 5983.3 5112.4 5842.5 5843.4 5864.7 5951.5 5058.7 5625.8 5873.3 5940.1 5964.4 4303.8 5124.7 5345.2 5358.8 5377.7 4961.0 5017.3 5671.5 5675.9 5718.5 5010.9 5699.9 5718.0 5904.8 5932.4 3177.8 3261.2 3773.4 3816.9 3826.9 4880.0 5751.8 5784.8 5800.0 5820.3 4492.9 5187.1 5583.6 5614.1 5614.7 3974.9 5159.0 5715.8 5721.5 5783.9 4796.4 5340.1 5510.3 5633.7 5658.3 5495.5 5639.2 5797.5 5819.8 5820.7 5187.2 5562.6 5574.7 5592.3 5644.4 09:21:36 INFO - PROCESS | 1660 | 09:21:36 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:19131.97 Median:26143.39 stddev:27003.56 (103.3%) stddev-sans-first:27043.69 09:21:36 INFO - PROCESS | 1660 | Values: 659.9 669.4 678.0 687.6 725.4 14324.6 14452.3 14802.8 14974.5 15053.0 13279.3 13501.6 13508.2 13510.5 13543.3 812.5 830.1 830.6 830.8 831.2 661.9 681.6 684.5 803.3 829.4 877.2 884.0 885.5 889.7 893.3 878.4 879.5 879.9 880.4 881.7 511.0 528.4 529.4 529.5 651.7 11909.3 13716.0 14223.2 14247.5 14580.6 13505.6 13863.9 13967.2 13973.4 14077.4 11420.8 11420.9 11550.3 11577.2 11659.0 3653.4 3685.2 3756.0 3765.1 3823.5 87200.3 87513.0 88725.1 89406.0 90229.1 10612.6 10686.6 10741.4 10791.7 11043.1 17861.4 21445.6 21580.5 21606.0 21608.1 7468.0 7640.4 7665.0 7815.5 8040.1 4224.6 4260.1 4303.2 4338.4 4477.1 3430.2 3469.3 3511.9 3514.1 3542.6 651.3 655.3 663.7 668.8 672.4 580.4 590.6 599.7 608.5 643.7 8755.5 9032.4 9095.5 9218.3 9435.8 641.8 649.2 654.3 666.9 670.3 4075.8 4362.4 4396.1 4460.6 4487.1 62845.4 75224.2 93953.0 97065.5 123907.0 55210.2 69112.7 73754.3 89084.0 91625.0 55024.7 78624.6 79062.0 82033.0 85136.0 9250.0 9492.5 9499.2 9499.9 9541.2 50922.7 51775.7 52274.0 52337.5 53028.7 69111.3 73441.0 74419.0 75105.0 77718.0 11628.3 11992.8 12022.5 12058.3 12145.1 13882.8 13941.6 14052.3 14086.4 14248.1 9733.0 10619.6 12450.8 12873.0 13024.7 11834.6 12338.6 12430.9 12594.2 12615.0 12001.5 12412.3 12692.8 12862.4 12956.3 7488.4 11221.3 11261.5 11274.0 11491.5 10767.9 10802.3 10842.7 11089.7 11130.3 09:21:36 INFO - PROCESS | 1660 | -------- Summary: end -------- 09:21:36 INFO - PROCESS | 1660 | 09:21:37 INFO - TEST-INFO | 1660: exit 0 09:21:37 INFO - TEST-OK | dromaeo_css | took 1120312ms 09:21:37 INFO - TEST-START | kraken 09:21:37 INFO - operating with platform_type : mac_ 09:21:37 INFO - Initialising browser for kraken test... 09:21:37 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpnZ_fKB/profile http://localhost:49278/getInfo.html) 09:21:39 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 09:21:39 INFO - PROCESS | 1667 | colorDepth:24 09:21:39 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 09:21:39 INFO - PROCESS | 1667 | __metrics 09:21:39 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:21:39 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:40 INFO - TEST-INFO | 1667: exit 0 09:21:40 INFO - Browser initialized. 09:21:40 INFO - Running cycle 1/1 for kraken test... 09:21:40 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpnZ_fKB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:52 INFO - PROCESS | 1668 | RSS: Main: 240771072 09:21:52 INFO - PROCESS | 1668 | 09:21:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:56 INFO - PROCESS | 1668 | RSS: Main: 290406400 09:21:56 INFO - PROCESS | 1668 | 09:21:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 09:21:59 INFO - PROCESS | 1668 | RSS: Main: 289345536 09:21:59 INFO - PROCESS | 1668 | 09:22:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 09:22:03 INFO - PROCESS | 1668 | RSS: Main: 383680512 09:22:03 INFO - PROCESS | 1668 | 09:22:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 09:22:06 INFO - PROCESS | 1668 | RSS: Main: 282546176 09:22:06 INFO - PROCESS | 1668 | 09:22:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:08 INFO - PROCESS | 1668 | RSS: Main: 288079872 09:22:08 INFO - PROCESS | 1668 | 09:22:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:12 INFO - PROCESS | 1668 | RSS: Main: 323616768 09:22:12 INFO - PROCESS | 1668 | 09:22:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:15 INFO - PROCESS | 1668 | RSS: Main: 325787648 09:22:15 INFO - PROCESS | 1668 | 09:22:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 09:22:19 INFO - PROCESS | 1668 | RSS: Main: 322912256 09:22:19 INFO - PROCESS | 1668 | 09:22:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:21 INFO - PROCESS | 1668 | RSS: Main: 302952448 09:22:21 INFO - PROCESS | 1668 | 09:22:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:23 INFO - PROCESS | 1668 | RSS: Main: 302874624 09:22:23 INFO - PROCESS | 1668 | 09:22:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:26 INFO - PROCESS | 1668 | RSS: Main: 305692672 09:22:26 INFO - PROCESS | 1668 | 09:22:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:30 INFO - PROCESS | 1668 | RSS: Main: 317083648 09:22:30 INFO - PROCESS | 1668 | 09:22:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:34 INFO - PROCESS | 1668 | RSS: Main: 317345792 09:22:34 INFO - PROCESS | 1668 | 09:22:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 09:22:36 INFO - PROCESS | 1668 | RSS: Main: 360992768 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | __start_tp_report 09:22:36 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 09:22:36 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 09:22:36 INFO - PROCESS | 1668 | |i|pagename|runs| 09:22:36 INFO - PROCESS | 1668 | |0;ai-astar;113;114;115;114;115;114;113;113;114;113 09:22:36 INFO - PROCESS | 1668 | |1;audio-beat-detection;146;111;112;145;111;112;114;111;140;111 09:22:36 INFO - PROCESS | 1668 | |2;audio-dft;182;184;184;184;185;198;259;181;261;183 09:22:36 INFO - PROCESS | 1668 | |3;audio-fft;81;59;59;88;63;60;59;58;84;58 09:22:36 INFO - PROCESS | 1668 | |4;audio-oscillator;86;70;92;69;69;70;91;69;69;92 09:22:36 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;88;86;88;85;88;85;88;86;88;85 09:22:36 INFO - PROCESS | 1668 | |6;imaging-darkroom;97;137;129;93;93;94;94;94;93;97 09:22:36 INFO - PROCESS | 1668 | |7;imaging-desaturate;83;85;121;79;82;83;86;86;84;83 09:22:36 INFO - PROCESS | 1668 | |8;json-parse-financial;56;56;55;54;55;58;55;56;55;55 09:22:36 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;76;74;75;75;71;69;70;71;75 09:22:36 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;86;88;87;87;88;85;86;87;91 09:22:36 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;147;145;147;147;146;169;156;158;195;172 09:22:36 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;175;175;171;174;174;174;176;172;175;225 09:22:36 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;68;68;67;68;66;68;67;67;69 09:22:36 INFO - PROCESS | 1668 | __end_tp_report 09:22:36 INFO - PROCESS | 1668 | __start_cc_report 09:22:36 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1951 09:22:36 INFO - PROCESS | 1668 | __end_cc_report 09:22:36 INFO - PROCESS | 1668 | __startTimestamp1478017356035__endTimestamp 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | ------- Summary: start ------- 09:22:36 INFO - PROCESS | 1668 | Number of tests: 14 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 09:22:36 INFO - PROCESS | 1668 | Values: 113.0 114.0 115.0 114.0 115.0 114.0 113.0 113.0 114.0 113.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:113.00 stddev:15.54 (13.7%) stddev-sans-first:13.67 09:22:36 INFO - PROCESS | 1668 | Values: 146.0 111.0 112.0 145.0 111.0 112.0 114.0 111.0 140.0 111.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:200.10 Median:184.50 stddev:31.93 (17.3%) stddev-sans-first:33.18 09:22:36 INFO - PROCESS | 1668 | Values: 182.0 184.0 184.0 184.0 185.0 198.0 259.0 181.0 261.0 183.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.90 Median:61.50 stddev:12.22 (19.9%) stddev-sans-first:11.85 09:22:36 INFO - PROCESS | 1668 | Values: 81.0 59.0 59.0 88.0 63.0 60.0 59.0 58.0 84.0 58.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.00 stddev:10.93 (14.0%) stddev-sans-first:11.18 09:22:36 INFO - PROCESS | 1668 | Values: 86.0 70.0 92.0 69.0 69.0 70.0 91.0 69.0 69.0 92.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:1.42 (1.6%) stddev-sans-first:1.42 09:22:36 INFO - PROCESS | 1668 | Values: 88.0 86.0 88.0 85.0 88.0 85.0 88.0 86.0 88.0 85.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:94.00 stddev:16.46 (17.5%) stddev-sans-first:17.36 09:22:36 INFO - PROCESS | 1668 | Values: 97.0 137.0 129.0 93.0 93.0 94.0 94.0 94.0 93.0 97.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:87.20 Median:83.50 stddev:12.05 (14.4%) stddev-sans-first:12.69 09:22:36 INFO - PROCESS | 1668 | Values: 83.0 85.0 121.0 79.0 82.0 83.0 86.0 86.0 84.0 83.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:1.08 (1.9%) stddev-sans-first:1.13 09:22:36 INFO - PROCESS | 1668 | Values: 56.0 56.0 55.0 54.0 55.0 58.0 55.0 56.0 55.0 55.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:74.50 stddev:3.74 (5.0%) stddev-sans-first:2.62 09:22:36 INFO - PROCESS | 1668 | Values: 64.0 76.0 74.0 75.0 75.0 71.0 69.0 70.0 71.0 75.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.50 stddev:1.64 (1.9%) stddev-sans-first:1.72 09:22:36 INFO - PROCESS | 1668 | Values: 88.0 86.0 88.0 87.0 87.0 88.0 85.0 86.0 87.0 91.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:157.00 stddev:16.21 (10.3%) stddev-sans-first:16.68 09:22:36 INFO - PROCESS | 1668 | Values: 147.0 145.0 147.0 147.0 146.0 169.0 156.0 158.0 195.0 172.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:175.00 stddev:16.20 (9.3%) stddev-sans-first:17.11 09:22:36 INFO - PROCESS | 1668 | Values: 175.0 175.0 171.0 174.0 174.0 174.0 176.0 172.0 175.0 225.0 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.84 (1.2%) stddev-sans-first:0.88 09:22:36 INFO - PROCESS | 1668 | Values: 68.0 68.0 68.0 67.0 68.0 66.0 68.0 67.0 67.0 69.0 09:22:36 INFO - PROCESS | 1668 | -------- Summary: end -------- 09:22:36 INFO - PROCESS | 1668 | 09:22:36 INFO - TEST-INFO | 1668: exit 0 09:22:36 INFO - TEST-OK | kraken | took 59680ms 09:22:36 INFO - SUITE-END | took 1179s 09:22:37 INFO - Completed test suite (00:19:40) 09:22:37 INFO - javascript benchmark 09:22:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2714.3621249006483, 3282.2450836307635, 3299.752518561107, 3312.569365982422, 3339.3321335732844, 5488.286522752047, 5576.470235327637, 5645.5483561315095, 5658.660480371175, 5660.235868157797, 5533.584981126422, 5571.582131340146, 5615.80344687936, 5621.887562248763, 5624.775008999635, 5092.185250359943, 5124.154514505102, 5137.049645815536, 5172.069027575035, 5200.401953775327, 4266.2534056540135, 5162.251473536335, 5228.529432351088, 5254.185601231815, 5266.368035835705, 5179.430262671122, 5186.247994040862, 5196.324477817897, 5224.92162617561, 5262.02652509287, 5248.790048398059, 5254.369475662907, 5257.263983042358, 5266.8683282917855, 5310.6813591184655, 5078.542931136216, 5082.23766435034, 5117.5650069744115, 5191.013707395583, 5195.012947539955, 5528.723563821793, 5571.916421253684, 5587.078132108198, 5612.635178713371, 5613.46672066153, 6086.847828804271, 6142.26292844857, 6164.907526387108, 6166.321704612534, 6172.47533959614, 5382.946170538323, 6230.2523697156175, 6241.157443745127, 6243.843903902439, 6256.092866534373, 5603.691796951125, 5656.717164141776, 5665.206871038058, 5672.404397538282, 5789.88420231593, 6303.716332765037, 6351.301356850743, 6361.777337793155, 6365.108884756138, 6368.681565921685, 6040.456358927718, 6050.30421501533, 6058.515318774487, 6064.241969753781, 6070.3929607038945, 5989.850253743692, 6043.395660433922, 6097.585173720644, 6105.175801266772, 6152.230971128609, 5103.2855400244, 6109.779029160984, 6115.3578874217565, 6149.569530132937, 6150.7232174552255, 6128.509719222451, 6157.599756015934, 6163.1679723237685, 6183.567150299526, 6202.844928876814, 4726.243800991893, 4832.758362081952, 4872.220444728827, 4884.0476107158765, 4916.336294600254, 4392.846250381222, 4412.69111162222, 4431.268840641323, 4439.4006809080365, 4459.063596644741, 5792.304923409218, 5801.680907550125, 5814.796482123105, 5824.388439213822, 5844.152597873284, 6189.0, 6209.223847019122, 6237.251529816451, 6239.220097487814, 6241.843953901098, 5672.064109421982, 5715.257016587817, 5718.25662663851, 5761.107028410603, 5817.50551203144, 5319.521243088141, 5378.91931621026, 5384.326959130109, 5401.378841433207, 5401.7029063401105, 6329.7151628175925, 6345.365463453618, 6347.206599175195, 6353.8729225416155, 6359.554831161774, 6097.4512293891985, 6233.501319894307, 6235.127082208585, 6249.843753906189, 6262.248530176408, 6205.379462053758, 6233.2208473940755, 6286.18279623646, 6288.811335659938, 6337.302896681454, 6047.516198704181, 6084.39156084388, 6091.116788065617, 6094.603850749687, 6102.664353460602, 5919.526437885045, 6106.15732346443, 6117.248930028793, 6142.375998160927, 6148.661823599566, 5979.641221526548, 5981.0729336952845, 5992.940070599238, 5997.760089596367, 5998.760049597967, 5963.821085367446, 5971.4327138921735, 5973.880522389615, 5982.49148822355, 5983.551233657406, 5801.622894511843, 5846.123081537803, 5851.1515830205235, 5863.00328944072, 5868.383819699041, 5864.853378665397, 5982.611130276518, 6003.849903752441, 6009.519238461, 6017.428344307284, 5835.591508594358, 5851.882962340815, 5859.0, 5865.765369385177, 5915.970420147872], "value": 5632.6145538548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.2318135987461, 493.46952026571444, 504.4577079639386, 511.8541215753511, 512.3953734593181, 37347.0, 39521.20957580847, 39613.603863961354, 39649.8017509912, 39868.202635947266, 32386.19034524138, 33239.00282991514, 33390.66609333906, 34222.65777342226, 40220.59227927016, 9391.295652826031, 9566.139047485725, 9581.37721048133, 9588.952055239713, 9593.088656577615, 8530.0, 9405.858912116324, 9511.714648560554, 9530.952345238264, 9576.186024187953, 8592.957035214815, 8657.69698060568, 8657.783555411102, 8767.561621918878, 11193.384363860025, 11264.4367781611, 11447.484863181093, 11462.770744585061, 11473.655790326304, 11477.311361318265, 11374.886251137465, 11460.656180314603, 11464.19750617457, 11465.484053217626, 11504.194706370557, 38776.418353724715, 39067.82796516081, 39201.60798392037, 39209.21581568381, 39271.803640981896, 3391.101358140095, 3428.3486137633768, 3616.7468277220605, 3677.8528858845616, 3827.808609569538, 29718.405631887243, 30425.543616845764, 30544.69455305463, 30866.382672346426, 31275.84362078198, 3204.535342375366, 3218.356328734239, 3222.065600975714, 3262.771605987582, 3322.4517954537473, 488425.0, 493117.0, 494613.0, 494703.5264823689, 500123.0, 56287.43712562904, 56718.0, 56731.0, 56743.71628141833, 56758.43241567614, 59485.0, 59597.0, 59630.0, 59660.0, 59707.0, 15256.847431525543, 15794.0, 15805.36778528869, 15808.889688680796, 15814.288357023961, 12259.57091501811, 12312.445939932724, 12317.260964342167, 12318.384080795924, 12340.568080117153, 15079.623009424633, 15117.470888518848, 15162.924185379003, 15302.923485382502, 15307.387704491695, 4994.70031798086, 5307.301663467731, 5679.620625727685, 5862.736176872051, 6008.909866352008, 850.0, 923.385948344357, 969.3699095587924, 973.9481360131041, 1107.4583830109916, 27654.0, 28914.566281505795, 29308.0, 29383.0, 29542.261443464085, 1003.5835128421623, 1064.7391389109719, 1074.9408782517037, 1091.5033659684825, 1097.5609756097624, 13151.60545183646, 14664.926675366554, 15038.32327545263, 15098.943073984716, 15463.381464741284, 628352.0, 632778.0, 634449.0, 636044.0, 637046.0, 47647.28529072067, 48611.75694121507, 55912.0, 55923.0, 55999.720001399735, 1221.987780122205, 1302.1145620977827, 1317.8945684345229, 1353.7157196988742, 1357.260293140241, 22518.437039074153, 29888.103356899326, 30367.848160759055, 30796.92210772612, 30940.22649433782, 46564.76717616526, 47249.0, 47380.763096184295, 47456.76271618758, 47660.0, 411648.0, 414268.0, 415127.0, 620173.0, 624868.2539523821, 24393.63409548894, 24585.754142459064, 24916.626250605892, 24916.875415622806, 25004.37489062788, 43844.0, 45203.321950171434, 45355.54644453513, 45948.540514594424, 47536.0, 31577.526337104504, 31865.36269274555, 36704.449433259055, 38232.617673822904, 38732.6126738729, 36617.0, 37350.0, 37625.87122386333, 38290.0, 39195.80402097971, 37326.813365933, 37764.24471510609, 37970.240595188494, 37979.24041519099, 38137.0, 31369.13778364357, 33444.66555334415, 33712.49431258484, 34098.659013409546, 34160.31679366448, 24017.519649606562, 24643.87678061598, 24743.50512989766, 24874.631895245937, 25023.12419065324], "value": 19232.501308046973, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.95929468111177, 372.8415423445036, 373.1343283582089, 380.5148435744425, 400.4353861055912, 14836.40654373825, 14963.850361496381, 15467.922660386695, 19892.0, 20515.794842051575, 19140.61718765624, 19602.70595941062, 20177.991100444993, 20335.28826491073, 20536.38390848273, 5711.8000869969455, 6021.819345419644, 6023.126646636235, 6032.396760323976, 6032.547558933075, 4207.768572728499, 5933.584649074566, 5962.73167707452, 5988.940110598904, 5998.430149135847, 5571.217210492644, 5941.168236446901, 5952.672603006833, 5953.464188223059, 5953.613015154023, 5787.102999035156, 5936.851578710545, 5937.287525496925, 5938.257717785277, 5938.8218353449465, 5511.448855114497, 5680.147977803321, 5727.684977326246, 5730.971345143247, 5733.799317023884, 12643.431045602878, 12757.881909936754, 12883.549075782397, 12884.098113132084, 12895.419706113247, 2179.8664694359004, 2184.541246338271, 2278.270953294946, 2396.664466974625, 2457.471643596635, 9732.805343893153, 10272.075513203772, 10289.9485502572, 10883.292585981886, 14913.328900199516, 1589.7059044076882, 1850.9814901850943, 1886.2832123793007, 1911.9904400477908, 1917.6260629177316, 81637.0, 88434.0, 113257.0, 114474.42762786215, 122164.77835221624, 11050.557977680803, 11114.888851111386, 11295.322280663186, 11324.773504530029, 11506.88493115075, 14180.078294910798, 14184.432622695183, 14196.929015354857, 14198.787018194735, 14216.147031178163, 8040.919590804134, 8171.877421838677, 8269.586520674062, 8288.0, 8311.709090181936, 8019.518828870287, 8021.959890200628, 8040.758777236692, 8191.0580283267, 8192.83614327722, 3715.4241092630687, 4327.7619730914475, 4486.881617275962, 4491.483479399845, 4493.618042466362, 1762.753214549964, 1793.957554030317, 1800.4958611588777, 1847.2518629954889, 1932.2560814086396, 843.7215718812777, 857.2970164465079, 921.1663444582664, 935.3873213045466, 1023.2172388123092, 19686.212551498065, 20532.536095668318, 20549.486262843548, 20603.381898543066, 20940.0, 876.8421684096924, 879.8152387998592, 879.8680197970356, 908.4594666173616, 912.9041450647719, 8850.73447796567, 9785.168260697777, 10144.28989970695, 10678.73303167427, 10777.030067293981, 488178.0, 506218.0, 514235.0, 533197.0, 533378.333108332, 120507.79492205141, 123442.0, 128255.0, 129730.0, 130484.3475782615, 905.1129892705054, 919.4069824962861, 942.5240253671852, 947.6541062512133, 964.8745663063617, 14324.57026289213, 17223.397181099102, 17821.910890445466, 17917.552061198574, 18147.81852181514, 50798.0, 51093.74453127711, 51443.0, 51535.48464515307, 52346.0, 71604.0, 72433.0, 74668.87996680163, 77457.0, 81563.18436815556, 7003.229644738973, 7670.504251670871, 7723.266289702469, 7920.207979202034, 8104.959475202586, 10694.946525267324, 12836.807447888476, 13237.80143297832, 13276.0, 13394.531191408254, 11023.283486573348, 11083.501242443948, 11349.716257093638, 11464.025557827677, 11542.711432213926, 10453.163746900078, 11152.219344645797, 11538.07695384384, 11543.076553875837, 11566.94216528912, 9220.0, 11204.719882003015, 11211.55153793839, 11222.494987725735, 11471.139664525028, 7202.243764404662, 7446.515976461513, 7486.588237646982, 7545.471816972979, 7607.23927607257, 10862.131029517777, 10874.0, 11072.501737421997, 11304.660860173879, 11406.258593191416], "value": 9103.975370995724, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.3695563085346, 1376.1880490510596, 1487.8140232470942, 1645.876559258056, 1843.769528808899, 7514.962425187873, 7872.29149376556, 7877.842443151134, 7877.842443151134, 7882.7635170944895, 7690.3078722914925, 7770.805729856742, 8004.799880003003, 8170.79573010675, 8226.629801658924, 2281.190177486993, 2310.249169020067, 2310.861348319098, 2312.283192210412, 2317.1079134533156, 2268.7731226877345, 2319.2810228829035, 2322.222055611372, 2322.501986218888, 2324.372419446757, 2264.479169790941, 2268.421552504109, 2291.679164916913, 2298.4713515891353, 2298.5173113646156, 2251.5271792923504, 2255.548890221962, 2277.373722226401, 2281.258590957937, 2285.5086156476273, 2275.533515629292, 2299.7470278269375, 2300.068472268734, 2300.6779050932882, 2303.5277768057676, 8239.299659528888, 8285.8342833143, 8316.958415207946, 8319.209675080858, 8385.119562445916, 4997.800087996476, 5032.47159048302, 5196.480351964811, 5199.376074871039, 5204.115300398941, 3436.4501679731125, 3535.08087897146, 3548.3967725486473, 3548.6983606393487, 3550.928981420357, 911.5077857956695, 1044.780596074825, 1059.4596755654673, 1078.3529882070773, 1090.2531765740493, 10638.148948084212, 10736.838947415796, 10758.838617420744, 10759.19306052057, 10800.837987430195, 4235.53409124996, 4401.317795741666, 4475.844529606619, 4651.744154071558, 4689.882752931204, 8650.091740367297, 8710.172533609424, 8711.47731136134, 8715.956420217859, 8720.12798720123, 5102.285680004802, 5113.74431278442, 5115.360579927509, 5116.104681680691, 5126.154184559506, 4285.05728739683, 4288.335308027261, 4318.805653745589, 4804.039192161582, 4808.182608956486, 2977.478941185284, 3544.3442963051916, 3580.4987301777774, 3601.7838929664304, 3669.0, 1122.5734220995969, 1126.8366086917515, 1127.503898284752, 1133.9206255562196, 1139.5384869127852, 734.2877408913346, 819.6557445872747, 878.3500209844669, 882.1795729971188, 895.9462432254086, 5367.677939323653, 5409.26974858397, 5441.0, 5510.6969116698965, 5679.488846003879, 410.51969196040704, 414.23573506879757, 419.3583816760362, 423.8999795530593, 440.5902510665112, 3623.4021386471463, 3721.832517536717, 3736.4021756518828, 3834.405667121545, 3844.519435070616, 9641.27690423221, 10176.491175441199, 10180.083792458714, 10191.643292484876, 10198.286119971532, 10151.543180556744, 10155.339902906288, 10155.949220253851, 10163.695089147337, 10169.389836609827, 6524.151860258231, 7689.846203075907, 7718.228177182237, 7725.381969442431, 7864.331531819745, 5443.183522471502, 5511.22842802016, 5536.557075434036, 5604.355499117653, 5874.970625147018, 8693.695720649746, 9066.274698024274, 9230.861537077084, 9312.860307095534, 9465.716028519155, 7747.845043098993, 9492.050794920453, 9519.381240219363, 9564.234861211226, 9569.952150239204, 6614.0, 6855.451563874978, 6857.245702972569, 7089.539179953286, 7319.975203471625, 7612.200718924433, 7872.291493765589, 7903.644336005009, 7974.601269936596, 7994.0, 7033.296670332926, 7071.36357727807, 7131.072960515307, 7178.3898368637165, 7236.095488063992, 6931.584104953719, 6936.20233673134, 7021.824454388477, 7111.608861512666, 7144.285571442814, 6219.689015549114, 6226.813195604139, 6292.590981586182, 6303.527235457451, 6344.841378965562, 4705.106029854317, 4725.929111063406, 4783.1390349737385, 4803.0, 4879.634027448026, 4672.392588963413, 4725.740084295397, 4812.663113582155, 4897.167481528078, 5000.64995450315], "value": 4237.539561703648, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.22503428500187, 298.6774283773524, 307.386763407003, 308.6450581830894, 312.03269863423407, 5377.580088959969, 5452.372977107634, 5453.727313634322, 5456.508914197721, 5481.725913704308, 5026.547610715035, 5223.440514148068, 5232.7122008289525, 5240.039377751567, 5419.590906364344, 3501.9844245168997, 3663.6336366363284, 4007.639312461878, 4013.55850856407, 4022.5776293489203, 3553.95158428263, 4083.4079058536495, 4083.8361067095884, 4098.057446787226, 4166.375043743433, 3804.9809750951345, 4034.4553485279553, 4035.515738111431, 4066.7966601669946, 4103.133074451132, 3606.999999999987, 3659.9386178008344, 3816.713746469012, 3905.297046531623, 4027.3958906164016, 3494.968984149682, 3495.755297129202, 3497.475378693191, 3501.562304711911, 3509.6490350964955, 3958.7228893977435, 4218.388333691628, 5144.0, 5247.929960972835, 5269.762860671279, 3937.6652984496354, 4006.4991876015497, 4523.525029871882, 4565.56627120423, 4578.885527861797, 4449.065696203801, 5361.758720857571, 5650.0959846424785, 5650.124230744241, 5685.219329146113, 3546.3261980223933, 3833.712471564616, 3834.69322454203, 3853.190829925719, 3853.325668008087, 5365.953639040389, 5858.355580886099, 5885.5585831062845, 5953.404659534012, 5976.223090998229, 3685.244524872395, 3883.941740873889, 3885.8445662173763, 3885.902852428712, 3904.121572646132, 3321.667833216659, 3984.083660758042, 4001.519817621904, 4002.059001384326, 4019.979900100481, 4424.650481603077, 4606.788741404145, 4634.95365046352, 4647.651426143053, 4674.7896344663895, 5195.9740201298755, 5429.158480435539, 5731.226284451629, 5761.711914404263, 5776.768929242868, 1111.3998440841856, 1264.3488603369271, 1341.557286095586, 1344.4890941442188, 1344.717609302038, 803.5098589860181, 830.3523251863556, 851.7019043334783, 876.7895705030746, 914.291424146292, 4876.073546026244, 5325.281087053276, 5353.86615334612, 5378.91931621026, 5387.649802762808, 5039.596832253411, 5351.44808005674, 5801.05664602363, 5827.017899695784, 5840.532757379399, 4219.388188712681, 4607.677462577654, 4665.883352916204, 5233.188855727369, 5286.3920649125075, 2995.4608170529077, 3008.5709288087983, 3024.803387779831, 3025.4705426550267, 3059.4492991261786, 5000.674956127913, 5597.832065038056, 5915.112733090071, 5974.566104134977, 5983.311919129356, 5112.3865136183895, 5842.503387212135, 5843.415658434207, 5864.709763851945, 5951.464368206882, 5058.721770302669, 5625.803096891589, 5873.3245676746865, 5940.079287710412, 5964.373740757158, 4303.849365272201, 5124.718820294926, 5345.198220266836, 5358.812441564526, 5377.670974865115, 4961.0, 5017.269042180442, 5671.546276297804, 5675.921574900755, 5718.4846015805015, 5010.924836127534, 5699.943000569941, 5718.0, 5904.822855314347, 5932.436418540232, 3177.8093314400285, 3261.1521004538517, 3773.358529050013, 3816.923661526698, 3826.9425958610086, 4879.975600121977, 5751.769929202785, 5784.82645520635, 5800.042992906292, 5820.272465941757, 4492.887677808081, 5187.144121220107, 5583.553315734813, 5614.129809879475, 5614.719264036864, 3974.880753577397, 5159.0, 5715.771369145187, 5721.485066344049, 5783.929972955017, 4796.35249241348, 5340.130954166011, 5510.338759348743, 5633.746481408429, 5658.321001479849, 5495.527012844316, 5639.182318563868, 5797.463547236098, 5819.799110489129, 5820.73806678692, 5187.221916712523, 5562.610617256753, 5574.662081100588, 5592.3009623797025, 5644.379118297066], "value": 4065.334511324589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.9010148477731, 669.3975422120092, 677.9525433219675, 687.5565260407037, 725.4414101142121, 14324.570262892117, 14452.349644266007, 14802.77795833062, 14974.475893343722, 15053.0, 13279.269640169787, 13501.59495215145, 13508.18950862949, 13510.527131550398, 13543.322833858318, 812.5327936436543, 830.1366578758084, 830.6303694855792, 830.8296799156188, 831.1563762780752, 661.8974059020859, 681.6421378776132, 684.5105749389186, 803.2810634482134, 829.3945419843518, 877.2368039805378, 883.9823203535925, 885.535094075609, 889.6708217959364, 893.2719833335843, 878.433410450256, 879.454738062399, 879.9384043116985, 880.4145243413122, 881.6737807011383, 511.04434707097965, 528.3791544934686, 529.4414392815578, 529.549882599791, 651.7067319706103, 11909.285442873455, 13716.0, 14223.21772302523, 14247.501337453139, 14580.635484112981, 13505.594832155051, 13863.920411938188, 13967.231802250872, 13973.441062357493, 14077.366518506691, 11420.828687569694, 11420.94289528547, 11550.34342101313, 11577.247478913843, 11659.00898423635, 3653.3789255826573, 3685.1892583631557, 3756.0422092366543, 3765.1151979284846, 3823.50294461721, 87200.25599488038, 87513.0, 88725.11274887233, 89406.0, 90229.09770902338, 10612.628558000433, 10686.625968091079, 10741.40922249268, 10791.676249712522, 11043.116550675926, 17861.374851880122, 21445.57108857814, 21580.46048848791, 21606.027728752244, 21608.13567457284, 7467.962660186773, 7640.388768898475, 7664.9616751917, 7815.491993020549, 8040.115587285394, 4224.598663126998, 4260.105377870682, 4303.225419424471, 4338.370936214231, 4477.1493416250805, 3430.2281986553326, 3469.3359481939065, 3511.940998592847, 3514.0863375522035, 3542.557180352456, 651.2933467188059, 655.2824657000576, 663.6582160187507, 668.8327918020495, 672.3780503034674, 580.3847921203537, 590.5511811023653, 599.7031469422664, 608.4949491921694, 643.7103303513437, 8755.518446485505, 9032.405203871209, 9095.454272743656, 9218.308626853013, 9435.76410589727, 641.8138739765509, 649.1982401733854, 654.3162395296927, 666.8999650052434, 670.25266827487, 4075.7962101894786, 4362.389265502832, 4396.054848207651, 4460.643148548108, 4487.147441986012, 62845.371546283954, 75224.24775752172, 93953.0, 97065.51467242619, 123907.0, 55210.17184742232, 69112.65443672749, 73754.26245737475, 89084.0, 91625.0, 55024.72487637536, 78624.60687696525, 79062.0, 82033.0, 85135.99999999876, 9249.953750231205, 9492.525373731423, 9499.192568631745, 9499.857502137611, 9541.188998935168, 50922.74538627283, 51775.74112129415, 52274.0, 52337.47662523326, 53028.73485632547, 69111.3088869125, 73441.0, 74419.0, 75105.0, 77718.0, 11628.302301861915, 11992.76014479723, 12022.458989345676, 12058.336791476553, 12145.14983951115, 13882.791758123434, 13941.58175254744, 14052.297385130907, 14086.36611352512, 14248.145111293356, 9732.951335243279, 10619.628313009007, 12450.813237801622, 12873.0, 13024.739505209653, 11834.644960651194, 12338.629841104781, 12430.937845311077, 12594.181378210387, 12615.0, 12001.459934302802, 12412.317322547347, 12692.809607856074, 12862.421191046238, 12956.28740419286, 7488.438367122597, 11221.270617409842, 11261.549538018715, 11274.0, 11491.54033838637, 10767.923207679169, 10802.297850639683, 10842.747175847371, 11089.722756931142, 11130.332180069221], "value": 7114.070075073876, "unit": "score"}], "name": "dromaeo_css", "value": 7031.673635218895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 114.0, 115.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 112.0, 145.0, 111.0, 112.0, 114.0, 111.0, 140.0, 111.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 184.0, 185.0, 198.0, 259.0, 181.0, 261.0, 183.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 88.0, 63.0, 60.0, 59.0, 58.0, 84.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 92.0, 69.0, 69.0, 70.0, 91.0, 69.0, 69.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 137.0, 129.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 97.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 121.0, 79.0, 82.0, 83.0, 86.0, 86.0, 84.0, 83.0], "value": 87.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 55.0, 58.0, 55.0, 56.0, 55.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 74.0, 75.0, 75.0, 71.0, 69.0, 70.0, 71.0, 75.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 87.0, 87.0, 88.0, 85.0, 86.0, 87.0, 91.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 147.0, 147.0, 146.0, 169.0, 156.0, 158.0, 195.0, 172.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 171.0, 174.0, 174.0, 174.0, 176.0, 172.0, 175.0, 225.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 68.0, 66.0, 68.0, 67.0, 67.0, 69.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1475.5, "alertThreshold": 2.0}]} 09:22:37 INFO - Return code: 0 09:22:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:37 INFO - # TBPL SUCCESS # 09:22:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:37 INFO - 2016-11-01 09:22:37.361 screenresolution[1672:9968] starting screenresolution argv=screenresolution get 09:22:37 INFO - 2016-11-01 09:22:37.364 screenresolution[1672:9968] Display 0: 1600x1200x32@60 09:22:37 INFO - 2016-11-01 09:22:37.371 screenresolution[1673:9971] starting screenresolution argv=screenresolution list 09:22:37 INFO - Available Modes on Display 0 09:22:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:37 INFO - Intel Iris: 09:22:37 INFO - Chipset Model: Intel Iris 09:22:37 INFO - Type: GPU 09:22:37 INFO - Bus: Built-In 09:22:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:37 INFO - Vendor: Intel (0x8086) 09:22:37 INFO - Device ID: 0x0a2e 09:22:37 INFO - Revision ID: 0x0009 09:22:37 INFO - Displays: 09:22:37 INFO - Display: 09:22:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:37 INFO - Display Serial Number: 1600x1200 60 09:22:37 INFO - Main Display: Yes 09:22:37 INFO - Mirror: Off 09:22:37 INFO - Online: Yes 09:22:37 INFO - Rotation: Supported 09:22:37 INFO - Return code: 0 09:22:37 INFO - Running post-action listener: _resource_record_post_action 09:22:37 INFO - [mozharness: 2016-11-01 16:22:37.616492Z] Finished run-tests step (success) 09:22:37 INFO - Running post-run listener: _resource_record_post_run 09:22:37 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 38556672; Write bytes: 709136384; Read time: 380; Write time: 2109 09:22:37 INFO - TinderboxPrint: CPU usage
53.5% 09:22:37 INFO - TinderboxPrint: I/O read bytes / time
38,556,672 / 380 09:22:37 INFO - TinderboxPrint: I/O write bytes / time
709,136,384 / 2,109 09:22:37 INFO - TinderboxPrint: CPU idle
2,261.8 (46.5%) 09:22:37 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 09:22:37 INFO - TinderboxPrint: CPU user
1,630.0 (33.5%) 09:22:37 INFO - TinderboxPrint: Swap in / out
445,579,264 / 0 09:22:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400079360; Write bytes: 394057728; Read time: 29458; Write time: 747 09:22:37 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 36537344; Write bytes: 298715136; Read time: 356; Write time: 1321 09:22:38 INFO - Running post-run listener: _upload_blobber_files 09:22:38 INFO - Blob upload gear active. 09:22:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:38 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:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:22:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:22:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:39 INFO - (blobuploader) - INFO - Done attempting. 09:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:22:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:40 INFO - (blobuploader) - INFO - Done attempting. 09:22:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:42 INFO - (blobuploader) - INFO - Done attempting. 09:22:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:42 INFO - Return code: 0 09:22:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15e582fa227f8b5f0a85891dec0762502a75ce15505646d30c64eb17467801a8ad2d8825f0f6d9d62095968766100c32267f8f465e830ae0e5651d75a8e5df0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892b30cc47358accf48679d7c170fdb3ff98aaa7fdfe15b2134accb433caef243a2f1d7d568a25a4e519a598cba2953f7c36a662a5daa3124905049dec0be7fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef92b3835a66bff78ad9a23f6c59619eef447a4fa5ffb78408c54a7989f5f7dde98d5d7932e00dcee44608b9e6f39c188acfc6d9df47401b287b5533bd4066dd"} 09:22:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:42 INFO - Contents: 09:22:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15e582fa227f8b5f0a85891dec0762502a75ce15505646d30c64eb17467801a8ad2d8825f0f6d9d62095968766100c32267f8f465e830ae0e5651d75a8e5df0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892b30cc47358accf48679d7c170fdb3ff98aaa7fdfe15b2134accb433caef243a2f1d7d568a25a4e519a598cba2953f7c36a662a5daa3124905049dec0be7fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef92b3835a66bff78ad9a23f6c59619eef447a4fa5ffb78408c54a7989f5f7dde98d5d7932e00dcee44608b9e6f39c188acfc6d9df47401b287b5533bd4066dd"} 09:22:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:42 INFO - Copying logs to upload dir... 09:22:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.974122 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-01 09:22:42.493013) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 09:22:42.496554) ========= 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.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15e582fa227f8b5f0a85891dec0762502a75ce15505646d30c64eb17467801a8ad2d8825f0f6d9d62095968766100c32267f8f465e830ae0e5651d75a8e5df0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892b30cc47358accf48679d7c170fdb3ff98aaa7fdfe15b2134accb433caef243a2f1d7d568a25a4e519a598cba2953f7c36a662a5daa3124905049dec0be7fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef92b3835a66bff78ad9a23f6c59619eef447a4fa5ffb78408c54a7989f5f7dde98d5d7932e00dcee44608b9e6f39c188acfc6d9df47401b287b5533bd4066dd"} build_url:https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/AAKotXqRRmCP7zb7sm7mSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15e582fa227f8b5f0a85891dec0762502a75ce15505646d30c64eb17467801a8ad2d8825f0f6d9d62095968766100c32267f8f465e830ae0e5651d75a8e5df0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892b30cc47358accf48679d7c170fdb3ff98aaa7fdfe15b2134accb433caef243a2f1d7d568a25a4e519a598cba2953f7c36a662a5daa3124905049dec0be7fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef92b3835a66bff78ad9a23f6c59619eef447a4fa5ffb78408c54a7989f5f7dde98d5d7932e00dcee44608b9e6f39c188acfc6d9df47401b287b5533bd4066dd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 09:22:42.530682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:22:42.531063) ========= 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.SGi2wVVjEC/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.HBAeyAcUut/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.004932 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 09:22:42.580667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-01 09:22:42.581025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-01 09:22:42.581459) ========= ========= Total master_lag: 0.48 =========