builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0386 starttime: 1486749125.37 results: success (0) buildid: 20170210081222 builduid: 8516d95a978f4a97a86d11123d66232f revision: 4990c20c7326e90dd12a9a98c86ff194d08e8f67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.368414) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.369405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.369810) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.396337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.396680) ========= 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.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 --2017-02-10 09:52:05-- 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% 18.3M=0.001s 2017-02-10 09:52:05 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093930 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.517907) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.518277) ========= 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.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.025526 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:05.566741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 09:52:05.569848) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4990c20c7326e90dd12a9a98c86ff194d08e8f67 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4990c20c7326e90dd12a9a98c86ff194d08e8f67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 2017-02-10 09:52:05,682 truncating revision to first 12 chars 2017-02-10 09:52:05,683 Setting DEBUG logging. 2017-02-10 09:52:05,683 attempt 1/10 2017-02-10 09:52:05,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4990c20c7326?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-10 09:52:06,414 unpacking tar archive at: mozilla-beta-4990c20c7326/testing/mozharness/ program finished with exit code 0 elapsedTime=0.982649 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 09:52:06.579064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:06.579441) ========= script_repo_revision: 4990c20c7326e90dd12a9a98c86ff194d08e8f67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:06.579929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:06.580238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 09:52:06.599964) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-10 09:52:06.600287) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:06 INFO - MultiFileLogger online at 20170210 09:52:06 in /builds/slave/test 09:52:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:52:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:06 INFO - {'append_to_log': False, 09:52:06 INFO - 'base_work_dir': '/builds/slave/test', 09:52:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:52:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:06 INFO - 'branch': 'Mozilla-Beta', 09:52:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:06 INFO - 'config_files': ('talos/mac_config.py',), 09:52:06 INFO - 'default_actions': ('clobber', 09:52:06 INFO - 'read-buildbot-config', 09:52:06 INFO - 'download-and-extract', 09:52:06 INFO - 'populate-webroot', 09:52:06 INFO - 'create-virtualenv', 09:52:06 INFO - 'install', 09:52:06 INFO - 'run-tests'), 09:52:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:06 INFO - 'download_minidump_stackwalk': True, 09:52:06 INFO - 'download_symbols': 'ondemand', 09:52:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:06 INFO - 'installer_path': 'installer.exe', 09:52:06 INFO - 'log_level': 'info', 09:52:06 INFO - 'log_name': 'talos', 09:52:06 INFO - 'log_to_console': True, 09:52:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:52:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:52:06 INFO - 'opt_config_files': (), 09:52:06 INFO - 'pip_index': False, 09:52:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:06 INFO - 'cmd': ('bash', 09:52:06 INFO - '-c', 09:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:06 INFO - 'enabled': True, 09:52:06 INFO - 'halt_on_failure': False, 09:52:06 INFO - 'name': 'check_screen_resolution'},), 09:52:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:06 INFO - 'cmd': ('bash', 09:52:06 INFO - '-c', 09:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:06 INFO - 'enabled': True, 09:52:06 INFO - 'halt_on_failure': False, 09:52:06 INFO - 'name': 'check_screen_resolution'},), 09:52:06 INFO - 'run_cmd_checks_enabled': True, 09:52:06 INFO - 'sps_profile': False, 09:52:06 INFO - 'sps_profile_interval': 0, 09:52:06 INFO - 'suite': 'dromaeojs-e10s', 09:52:06 INFO - 'system_bits': '32', 09:52:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:06 INFO - 'title': 't-yosemite-r7-0386', 09:52:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:06 INFO - 'use_talos_json': True, 09:52:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:06 INFO - 'work_dir': 'build'} 09:52:06 INFO - [mozharness: 2017-02-10 17:52:06.821466Z] Running clobber step. 09:52:06 INFO - Running pre-action listener: _resource_record_pre_action 09:52:06 INFO - Running main action method: clobber 09:52:06 INFO - rmtree: /builds/slave/test/build 09:52:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:09 INFO - Running post-action listener: _resource_record_post_action 09:52:09 INFO - [mozharness: 2017-02-10 17:52:09.255818Z] Finished clobber step (success) 09:52:09 INFO - [mozharness: 2017-02-10 17:52:09.255944Z] Running read-buildbot-config step. 09:52:09 INFO - Running pre-action listener: _resource_record_pre_action 09:52:09 INFO - Running main action method: read_buildbot_config 09:52:09 INFO - Using buildbot properties: 09:52:09 INFO - { 09:52:09 INFO - "project": "", 09:52:09 INFO - "product": "firefox", 09:52:09 INFO - "script_repo_revision": "production", 09:52:09 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:52:09 INFO - "repository": "", 09:52:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:52:09 INFO - "buildid": "20170210081222", 09:52:09 INFO - "pgo_build": "False", 09:52:09 INFO - "basedir": "/builds/slave/test", 09:52:09 INFO - "buildnumber": 15, 09:52:09 INFO - "slavename": "t-yosemite-r7-0386", 09:52:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:52:09 INFO - "platform": "yosemite_r7", 09:52:09 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:52:09 INFO - "branch": "mozilla-beta", 09:52:09 INFO - "repo_path": "releases/mozilla-beta", 09:52:09 INFO - "revision": "4990c20c7326e90dd12a9a98c86ff194d08e8f67", 09:52:09 INFO - "stage_platform": "macosx64", 09:52:09 INFO - "builduid": "8516d95a978f4a97a86d11123d66232f", 09:52:09 INFO - "slavebuilddir": "test" 09:52:09 INFO - } 09:52:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:52:09 INFO - Running post-action listener: _resource_record_post_action 09:52:09 INFO - [mozharness: 2017-02-10 17:52:09.259386Z] Finished read-buildbot-config step (success) 09:52:09 INFO - [mozharness: 2017-02-10 17:52:09.259491Z] Running download-and-extract step. 09:52:09 INFO - Running pre-action listener: _resource_record_pre_action 09:52:09 INFO - Running main action method: download_and_extract 09:52:09 INFO - mkdir: /builds/slave/test/build/tests 09:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:09 INFO - trying https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:52:09 INFO - Downloading https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:52:10 INFO - Downloaded 1165 bytes. 09:52:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:52:10 INFO - Using the following test package requirements: 09:52:10 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:52:10 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:52:10 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:52:10 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:52:10 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:52:10 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:52:10 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:52:10 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:52:10 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:52:10 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:52:10 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:52:10 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:52:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:52:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:52:10 INFO - Fetch https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:52:11 INFO - Http code: 200 09:52:11 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 09:52:11 INFO - x-amz-cf-id: 9xGBLmBVE5V8hTdr1kT6w0WpJu5yecHG-f0cQM0B0eM_kmtK6H7H6A== 09:52:11 INFO - x-amz-version-id: Eu25jk8bhp7nqPlTvWE8rSUqK6khkWVc 09:52:12 INFO - Expected file size: 16093652 09:52:12 INFO - Obtained file size: 16093652 09:52:19 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:52:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:52:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:52:19 INFO - Fetch https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:52:19 INFO - Http code: 200 09:52:19 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 09:52:19 INFO - x-amz-cf-id: iwcUgpNOvwhARFYokzP5YK1a12vTdHPu0sLcRQ4k8QHFpU6HAWljZQ== 09:52:19 INFO - x-amz-version-id: Eu25jk8bhp7nqPlTvWE8rSUqK6khkWVc 09:52:21 INFO - Expected file size: 16093652 09:52:21 INFO - Obtained file size: 16093652 09:52:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:52:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:52:27 INFO - Fetch https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:52:29 INFO - Http code: 200 09:52:29 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:52:29 INFO - x-amz-cf-id: ErqSSoR5bxF3bu-zC_mw-pWxd03wBhsVFwlp0tQunvVPsZzATlvKtQ== 09:52:29 INFO - x-amz-version-id: OkZQJj3onb_tbAf3LytA_UfTrIdagIbx 09:52:32 INFO - Expected file size: 14403005 09:52:32 INFO - Obtained file size: 14403005 09:52:32 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:52:32 INFO - trying https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:52:32 INFO - Downloading https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:52:36 INFO - Downloaded 82152700 bytes. 09:52:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:52:36 INFO - mkdir: /builds/slave/test/properties 09:52:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:52:36 INFO - Writing to file /builds/slave/test/properties/build_url 09:52:36 INFO - Contents: 09:52:36 INFO - build_url:https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:52:38 INFO - Running post-action listener: _resource_record_post_action 09:52:38 INFO - Running post-action listener: set_extra_try_arguments 09:52:38 INFO - [mozharness: 2017-02-10 17:52:38.044167Z] Finished download-and-extract step (success) 09:52:38 INFO - [mozharness: 2017-02-10 17:52:38.044323Z] Running populate-webroot step. 09:52:38 INFO - Running pre-action listener: _resource_record_pre_action 09:52:38 INFO - Running main action method: populate_webroot 09:52:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:52:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:52:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:52:38 INFO - '--tppagecycles', 09:52:38 INFO - '7'], 09:52:38 INFO - 'tests': ['tsvgm']}}, 09:52:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:52:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:52:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:52:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:52:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['damp', 'tps']}, 09:52:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'tests': ['damp', 'tps']}, 09:52:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['dromaeo_dom']}, 09:52:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:52:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['basic_compositor_video']}, 09:52:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:52:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'other-e10s': {'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['a11yr', 09:52:38 INFO - 'ts_paint', 09:52:38 INFO - 'tpaint', 09:52:38 INFO - 'sessionrestore', 09:52:38 INFO - 'sessionrestore_no_auto_restore', 09:52:38 INFO - 'tabpaint']}, 09:52:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['tsvgx', 09:52:38 INFO - 'tsvgr_opacity', 09:52:38 INFO - 'tart', 09:52:38 INFO - 'tscrollx', 09:52:38 INFO - 'cart']}, 09:52:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:52:38 INFO - 'tsvgr_opacity', 09:52:38 INFO - 'tart', 09:52:38 INFO - 'tscrollx', 09:52:38 INFO - 'cart']}, 09:52:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'talos_options': ['--disable-e10s'], 09:52:38 INFO - 'tests': ['tp5o']}, 09:52:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'tests': ['tp5o']}, 09:52:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'talos_options': ['--disable-e10s', 09:52:38 INFO - '--xperf_path', 09:52:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:38 INFO - 'tests': ['tp5n']}, 09:52:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:38 INFO - 'talos_options': ['--xperf_path', 09:52:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:38 INFO - 'tests': ['tp5n']}}, 09:52:38 INFO - 'talos.zip': {'path': '', 09:52:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:52:38 INFO - Running post-action listener: _resource_record_post_action 09:52:38 INFO - [mozharness: 2017-02-10 17:52:38.063154Z] Finished populate-webroot step (success) 09:52:38 INFO - [mozharness: 2017-02-10 17:52:38.063289Z] Running create-virtualenv step. 09:52:38 INFO - Running pre-action listener: _resource_record_pre_action 09:52:38 INFO - Running main action method: create_virtualenv 09:52:38 INFO - Creating virtualenv /builds/slave/test/build/venv 09:52:38 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:52:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:52:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:52:38 INFO - Using real prefix '/tools/python27' 09:52:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:52:39 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:41 INFO - Installing pip.................done. 09:52:41 INFO - Return code: 0 09:52:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:52:42 INFO - Reading from file tmpfile_stdout 09:52:42 INFO - Output received: 09:52:42 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:52:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:52:42 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:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 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:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:42 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:52:42 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:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:42 INFO - 'HOME': '/Users/cltbld', 09:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:42 INFO - 'LOGNAME': 'cltbld', 09:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:42 INFO - 'MOZ_NO_REMOTE': '1', 09:52:42 INFO - 'NO_EM_RESTART': '1', 09:52:42 INFO - 'PAGER': '/bin/cat', 09:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:42 INFO - 'PWD': '/builds/slave/test', 09:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:42 INFO - 'SHELL': '/bin/bash', 09:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:42 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:42 INFO - 'USER': 'cltbld', 09:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:42 INFO - 'XPC_FLAGS': '0x0', 09:52:42 INFO - 'XPC_SERVICE_NAME': '0', 09:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:42 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:52:42 INFO - Cleaning up... 09:52:42 INFO - Return code: 0 09:52:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:52:42 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:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 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:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:42 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:42 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:52:42 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:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:42 INFO - 'HOME': '/Users/cltbld', 09:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:42 INFO - 'LOGNAME': 'cltbld', 09:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:42 INFO - 'MOZ_NO_REMOTE': '1', 09:52:42 INFO - 'NO_EM_RESTART': '1', 09:52:42 INFO - 'PAGER': '/bin/cat', 09:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:42 INFO - 'PWD': '/builds/slave/test', 09:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:42 INFO - 'SHELL': '/bin/bash', 09:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:42 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:42 INFO - 'USER': 'cltbld', 09:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:42 INFO - 'XPC_FLAGS': '0x0', 09:52:42 INFO - 'XPC_SERVICE_NAME': '0', 09:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:42 INFO - Downloading/unpacking psutil>=3.1.1 09:52:42 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:52:42 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:52:42 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:52:42 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:52:42 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:52:42 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:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:46 INFO - Installing collected packages: psutil 09:52:46 INFO - Running setup.py install for psutil 09:52:46 INFO - building 'psutil._psutil_osx' extension 09:52:46 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:52:46 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:52:46 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:52:46 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:52:47 INFO - building 'psutil._psutil_posix' extension 09:52:47 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:52:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:52:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:52:47 INFO - ^ 09:52:47 INFO - 1 warning generated. 09:52:47 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:52:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:47 INFO - Successfully installed psutil 09:52:47 INFO - Cleaning up... 09:52:47 INFO - Return code: 0 09:52:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:47 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:47 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:52:47 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:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:47 INFO - 'HOME': '/Users/cltbld', 09:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:47 INFO - 'LOGNAME': 'cltbld', 09:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:47 INFO - 'MOZ_NO_REMOTE': '1', 09:52:47 INFO - 'NO_EM_RESTART': '1', 09:52:47 INFO - 'PAGER': '/bin/cat', 09:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:47 INFO - 'PWD': '/builds/slave/test', 09:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:47 INFO - 'SHELL': '/bin/bash', 09:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:47 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:47 INFO - 'USER': 'cltbld', 09:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:47 INFO - 'XPC_FLAGS': '0x0', 09:52:47 INFO - 'XPC_SERVICE_NAME': '0', 09:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:52:47 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:52:47 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:52:47 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:52:47 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:52:47 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:52:47 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:52:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:52:50 INFO - Installing collected packages: mozsystemmonitor 09:52:50 INFO - Running setup.py install for mozsystemmonitor 09:52:50 INFO - Successfully installed mozsystemmonitor 09:52:50 INFO - Cleaning up... 09:52:50 INFO - Return code: 0 09:52:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:52:50 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:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:50 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:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:50 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:50 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:52:50 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:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:50 INFO - 'HOME': '/Users/cltbld', 09:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:50 INFO - 'LOGNAME': 'cltbld', 09:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:50 INFO - 'MOZ_NO_REMOTE': '1', 09:52:50 INFO - 'NO_EM_RESTART': '1', 09:52:50 INFO - 'PAGER': '/bin/cat', 09:52:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:50 INFO - 'PWD': '/builds/slave/test', 09:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:50 INFO - 'SHELL': '/bin/bash', 09:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:50 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:50 INFO - 'USER': 'cltbld', 09:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:50 INFO - 'XPC_FLAGS': '0x0', 09:52:50 INFO - 'XPC_SERVICE_NAME': '0', 09:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:52:51 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:52:51 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:52:51 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:52:51 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:52:51 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:52:51 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:52:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:52: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:52: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:52: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:52: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:52: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:52: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:52:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:54 INFO - warning: no files found matching '*.txt' 09:52:54 INFO - no previously-included directories found matching 'build' 09:52:54 INFO - no previously-included directories found matching 'dist' 09:52:54 INFO - no previously-included directories found matching '.git*' 09:52:54 INFO - Installing collected packages: jsonschema, functools32 09:52:54 INFO - Running setup.py install for functools32 09:52:54 INFO - warning: no files found matching '*.txt' 09:52:54 INFO - no previously-included directories found matching 'build' 09:52:54 INFO - no previously-included directories found matching 'dist' 09:52:54 INFO - no previously-included directories found matching '.git*' 09:52:54 INFO - Successfully installed jsonschema functools32 09:52:54 INFO - Cleaning up... 09:52:54 INFO - Return code: 0 09:52:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:54 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:54 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:52:54 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:52:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:54 INFO - 'HOME': '/Users/cltbld', 09:52:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:54 INFO - 'LOGNAME': 'cltbld', 09:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:54 INFO - 'MOZ_NO_REMOTE': '1', 09:52:54 INFO - 'NO_EM_RESTART': '1', 09:52:54 INFO - 'PAGER': '/bin/cat', 09:52:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:54 INFO - 'PWD': '/builds/slave/test', 09:52:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:54 INFO - 'SHELL': '/bin/bash', 09:52:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:54 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:54 INFO - 'USER': 'cltbld', 09:52:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:54 INFO - 'XPC_FLAGS': '0x0', 09:52:54 INFO - 'XPC_SERVICE_NAME': '0', 09:52:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:52:54 INFO - Cleaning up... 09:52:54 INFO - Return code: 0 09:52:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:54 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:52:54 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:52:54 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:52:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:52:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:54 INFO - 'HOME': '/Users/cltbld', 09:52:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:54 INFO - 'LOGNAME': 'cltbld', 09:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:54 INFO - 'MOZ_NO_REMOTE': '1', 09:52:54 INFO - 'NO_EM_RESTART': '1', 09:52:54 INFO - 'PAGER': '/bin/cat', 09:52:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:54 INFO - 'PWD': '/builds/slave/test', 09:52:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:54 INFO - 'SHELL': '/bin/bash', 09:52:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:52:54 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:52:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:54 INFO - 'USER': 'cltbld', 09:52:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:54 INFO - 'XPC_FLAGS': '0x0', 09:52:54 INFO - 'XPC_SERVICE_NAME': '0', 09:52:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:52:55 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:52:55 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:52:55 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:52:55 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:52:55 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:52:55 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:52:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52:57 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:52:57 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:52:57 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:52:57 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:52:57 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:52:57 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:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52: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:52: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:52: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:52: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:52: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:52: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:52:58 INFO - Downloading docopt-0.6.1.tar.gz 09:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:58 INFO - Running setup.py install for blobuploader 09:52:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:59 INFO - Running setup.py install for requests 09:52:59 INFO - Running setup.py install for docopt 09:52:59 INFO - Successfully installed blobuploader requests docopt 09:52:59 INFO - Cleaning up... 09:52:59 INFO - Return code: 0 09:52:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:59 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:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:00 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:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:00 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:53:00 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:53:00 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:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:00 INFO - 'HOME': '/Users/cltbld', 09:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:00 INFO - 'LOGNAME': 'cltbld', 09:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:00 INFO - 'MOZ_NO_REMOTE': '1', 09:53:00 INFO - 'NO_EM_RESTART': '1', 09:53:00 INFO - 'PAGER': '/bin/cat', 09:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:00 INFO - 'PWD': '/builds/slave/test', 09:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:00 INFO - 'SHELL': '/bin/bash', 09:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:53:00 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:00 INFO - 'USER': 'cltbld', 09:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:00 INFO - 'XPC_FLAGS': '0x0', 09:53:00 INFO - 'XPC_SERVICE_NAME': '0', 09:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:00 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Ly3RhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:00 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-s7WO1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-XA40aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ewZRFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pelrsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7a_96n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-m0hx6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7Q7Ego-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-_BW6iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3JtZBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:01 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-oStOAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pXn_dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-IdZLq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-lo8ETx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tfDoSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-VYJsJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:02 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-XwV0eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:53:02 INFO - Running setup.py install for manifestparser 09:53:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Running setup.py install for mozcrash 09:53:02 INFO - Running setup.py install for mozdebug 09:53:03 INFO - Running setup.py install for mozdevice 09:53:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Running setup.py install for mozfile 09:53:03 INFO - Running setup.py install for mozhttpd 09:53:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Running setup.py install for mozinfo 09:53:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Running setup.py install for mozInstall 09:53:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Running setup.py install for mozleak 09:53:03 INFO - Running setup.py install for mozlog 09:53:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Running setup.py install for moznetwork 09:53:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Running setup.py install for mozprocess 09:53:04 INFO - Running setup.py install for mozprofile 09:53:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Running setup.py install for mozrunner 09:53:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:53:04 INFO - Running setup.py install for mozscreenshot 09:53:04 INFO - Running setup.py install for moztest 09:53:05 INFO - Running setup.py install for mozversion 09:53:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:53:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:53:05 INFO - Cleaning up... 09:53:05 INFO - Return code: 0 09:53:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:05 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:53:05 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:53:05 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:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:05 INFO - 'HOME': '/Users/cltbld', 09:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:05 INFO - 'LOGNAME': 'cltbld', 09:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:05 INFO - 'MOZ_NO_REMOTE': '1', 09:53:05 INFO - 'NO_EM_RESTART': '1', 09:53:05 INFO - 'PAGER': '/bin/cat', 09:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:05 INFO - 'PWD': '/builds/slave/test', 09:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:05 INFO - 'SHELL': '/bin/bash', 09:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:53:05 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:05 INFO - 'USER': 'cltbld', 09:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:05 INFO - 'XPC_FLAGS': '0x0', 09:53:05 INFO - 'XPC_SERVICE_NAME': '0', 09:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:05 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-t7cfBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:05 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-NGsRFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-bvU0BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-91knyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-5JhrFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-IXhFN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-IRrDiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-1aHqJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-TOdqQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3mIvQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:06 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-UnG5UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:07 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:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-puzipK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:07 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:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-j6mCUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:07 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:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-AvpY7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-0Dd6wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:07 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:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-lpMaYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:07 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-N5Alc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:07 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:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:07 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:53:07 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:53:07 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:53:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:10 INFO - Downloading blessings-1.6.tar.gz 09:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:10 INFO - Installing collected packages: blessings 09:53:10 INFO - Running setup.py install for blessings 09:53:10 INFO - Successfully installed blessings 09:53:10 INFO - Cleaning up... 09:53:11 INFO - Return code: 0 09:53:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:11 INFO - Reading from file tmpfile_stdout 09:53:11 INFO - Current package versions: 09:53:11 INFO - blessings == 1.6 09:53:11 INFO - blobuploader == 1.2.4 09:53:11 INFO - docopt == 0.6.1 09:53:11 INFO - functools32 == 3.2.3-2 09:53:11 INFO - jsonschema == 2.5.1 09:53:11 INFO - manifestparser == 1.1 09:53:11 INFO - mozInstall == 1.12 09:53:11 INFO - mozcrash == 1.0 09:53:11 INFO - mozdebug == 0.1 09:53:11 INFO - mozdevice == 0.48 09:53:11 INFO - mozfile == 1.2 09:53:11 INFO - mozhttpd == 0.7 09:53:11 INFO - mozinfo == 0.9 09:53:11 INFO - mozleak == 0.1 09:53:11 INFO - mozlog == 3.4 09:53:11 INFO - moznetwork == 0.27 09:53:11 INFO - mozprocess == 0.23 09:53:11 INFO - mozprofile == 0.28 09:53:11 INFO - mozrunner == 6.13 09:53:11 INFO - mozscreenshot == 0.1 09:53:11 INFO - mozsystemmonitor == 0.3 09:53:11 INFO - moztest == 0.8 09:53:11 INFO - mozversion == 1.4 09:53:11 INFO - psutil == 3.1.1 09:53:11 INFO - requests == 1.2.3 09:53:11 INFO - wsgiref == 0.1.2 09:53:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:11 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:53:11 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:53:11 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:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:11 INFO - 'HOME': '/Users/cltbld', 09:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:11 INFO - 'LOGNAME': 'cltbld', 09:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:11 INFO - 'MOZ_NO_REMOTE': '1', 09:53:11 INFO - 'NO_EM_RESTART': '1', 09:53:11 INFO - 'PAGER': '/bin/cat', 09:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:11 INFO - 'PWD': '/builds/slave/test', 09:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:11 INFO - 'SHELL': '/bin/bash', 09:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:53:11 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:11 INFO - 'USER': 'cltbld', 09:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:11 INFO - 'XPC_FLAGS': '0x0', 09:53:11 INFO - 'XPC_SERVICE_NAME': '0', 09:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 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:53:11 INFO - Cleaning up... 09:53:11 INFO - Return code: 0 09:53:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:11 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 0x10b7ed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b763570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8659d9cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, '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.YktiuUuPrb/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.NksFLBpT4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:53:11 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:53:11 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:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:11 INFO - 'HOME': '/Users/cltbld', 09:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:11 INFO - 'LOGNAME': 'cltbld', 09:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:11 INFO - 'MOZ_NO_REMOTE': '1', 09:53:11 INFO - 'NO_EM_RESTART': '1', 09:53:11 INFO - 'PAGER': '/bin/cat', 09:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:11 INFO - 'PWD': '/builds/slave/test', 09:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:11 INFO - 'SHELL': '/bin/bash', 09:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:53:11 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:11 INFO - 'USER': 'cltbld', 09:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:11 INFO - 'XPC_FLAGS': '0x0', 09:53:11 INFO - 'XPC_SERVICE_NAME': '0', 09:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:53:11 INFO - Cleaning up... 09:53:11 INFO - Return code: 0 09:53:11 INFO - Running post-action listener: _resource_record_post_action 09:53:11 INFO - Running post-action listener: _start_resource_monitoring 09:53:11 INFO - Starting resource monitoring. 09:53:11 INFO - [mozharness: 2017-02-10 17:53:11.937251Z] Finished create-virtualenv step (success) 09:53:11 INFO - [mozharness: 2017-02-10 17:53:11.938266Z] Running install step. 09:53:11 INFO - Running pre-action listener: _resource_record_pre_action 09:53:11 INFO - Running main action method: install 09:53:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:12 INFO - Reading from file tmpfile_stdout 09:53:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:12 INFO - Reading from file tmpfile_stdout 09:53:12 INFO - Output received: 09:53:12 INFO - Usage: mozinstall [options] installer 09:53:12 INFO - Options: 09:53:12 INFO - -h, --help show this help message and exit 09:53:12 INFO - -d DEST, --destination=DEST 09:53:12 INFO - Directory to install application into. [default: 09:53:12 INFO - "/builds/slave/test"] 09:53:12 INFO - --app=APP Application being installed. [default: firefox] 09:53:12 INFO - mkdir: /builds/slave/test/build/application 09:53:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:53:33 INFO - Reading from file tmpfile_stdout 09:53:33 INFO - Output received: 09:53:33 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:53:33 INFO - Running post-action listener: _resource_record_post_action 09:53:33 INFO - [mozharness: 2017-02-10 17:53:33.517514Z] Finished install step (success) 09:53:33 INFO - [mozharness: 2017-02-10 17:53:33.517669Z] Running run-tests step. 09:53:33 INFO - Running pre-action listener: _resource_record_pre_action 09:53:33 INFO - Running main action method: run_tests 09:53:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:33 INFO - 2017-02-10 09:53:33.532 screenresolution[1623:7464] starting screenresolution argv=screenresolution get 09:53:33 INFO - 2017-02-10 09:53:33.534 screenresolution[1623:7464] Display 0: 1600x1200x32@60 09:53:33 INFO - 2017-02-10 09:53:33.540 screenresolution[1624:7467] starting screenresolution argv=screenresolution list 09:53:33 INFO - Available Modes on Display 0 09:53:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:33 INFO - Intel Iris: 09:53:33 INFO - Chipset Model: Intel Iris 09:53:33 INFO - Type: GPU 09:53:33 INFO - Bus: Built-In 09:53:33 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:33 INFO - Vendor: Intel (0x8086) 09:53:33 INFO - Device ID: 0x0a2e 09:53:33 INFO - Revision ID: 0x0009 09:53:33 INFO - Displays: 09:53:33 INFO - Display: 09:53:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:33 INFO - Display Serial Number: 1600x1200 60 09:53:33 INFO - Main Display: Yes 09:53:33 INFO - Mirror: Off 09:53:33 INFO - Online: Yes 09:53:33 INFO - Rotation: Supported 09:53:33 INFO - Return code: 0 09:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:33 INFO - Python 2.7.3 09:53:33 INFO - Return code: 0 09:53:33 INFO - grabbing minidump binary from tooltool 09:53:33 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:53:33 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 0x10b601d30>, '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 0x10b467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5f9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:33 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:53:33 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:53:33 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:53:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:33 INFO - Return code: 0 09:53:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:53:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:53:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YktiuUuPrb/Render', 09:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:33 INFO - 'HOME': '/Users/cltbld', 09:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:33 INFO - 'LOGNAME': 'cltbld', 09:53:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:33 INFO - 'MOZ_NO_REMOTE': '1', 09:53:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:33 INFO - 'NO_EM_RESTART': '1', 09:53:33 INFO - 'PAGER': '/bin/cat', 09:53:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:33 INFO - 'PWD': '/builds/slave/test', 09:53:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:33 INFO - 'SHELL': '/bin/bash', 09:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NksFLBpT4S/Listeners', 09:53:33 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:33 INFO - 'USER': 'cltbld', 09:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:33 INFO - 'XPC_FLAGS': '0x0', 09:53:33 INFO - 'XPC_SERVICE_NAME': '0', 09:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:53:34 INFO - mozversion application_buildid: 20170210081222 09:53:34 INFO - mozversion application_changeset: 4990c20c7326e90dd12a9a98c86ff194d08e8f67 09:53:34 INFO - mozversion application_display_name: Firefox 09:53:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:34 INFO - mozversion application_name: Firefox 09:53:34 INFO - mozversion application_remotingname: firefox 09:53:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:53:34 INFO - mozversion application_vendor: Mozilla 09:53:34 INFO - mozversion application_version: 52.0 09:53:34 INFO - mozversion platform_buildid: 20170210081222 09:53:34 INFO - mozversion platform_changeset: 4990c20c7326e90dd12a9a98c86ff194d08e8f67 09:53:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:53:34 INFO - mozversion platform_version: 52.0 09:53:34 INFO - using testdate: 1486749214 09:53:34 INFO - actual date: 1486749214 09:53:34 INFO - starting webserver on 'localhost:49244' 09:53:34 INFO - SUITE-START | Running 2 tests 09:53:34 INFO - TEST-START | dromaeo_css 09:53:34 INFO - operating with platform_type : mac_ 09:53:34 INFO - Initialising browser for dromaeo_css test... 09:53:34 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp4GiACW/profile http://localhost:49244/getInfo.html) 09:53:37 INFO - PROCESS | 1634 | 2017-02-10 09:53:37.015 plugin-container[1637:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 09:53:37 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:37 INFO - PROCESS | 1634 | 2017-02-10 09:53:37.016 plugin-container[1637:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d17, name = 'com.apple.CFPasteboardClient' 09:53:37 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:37 INFO - PROCESS | 1634 | 2017-02-10 09:53:37.016 plugin-container[1637:7653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:37 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:53:37 INFO - PROCESS | 1634 | colorDepth:24 09:53:37 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:53:37 INFO - PROCESS | 1634 | __metrics 09:53:37 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:53:37 INFO - TEST-INFO | 1634: exit 0 09:53:37 INFO - Browser initialized. 09:53:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:37 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp4GiACW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:40 INFO - PROCESS | 1638 | 2017-02-10 09:53:40.243 plugin-container[1639:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:53:40 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:40 INFO - PROCESS | 1638 | 2017-02-10 09:53:40.244 plugin-container[1639:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:53:40 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:40 INFO - PROCESS | 1638 | 2017-02-10 09:53:40.244 plugin-container[1639:7789] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:50 INFO - PROCESS | 1638 | RSS: Main: 177172480 09:53:50 INFO - PROCESS | 1638 | 09:56:51 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 09:56:53 INFO - PROCESS | 1638 | RSS: Main: 182669312 09:56:53 INFO - PROCESS | 1638 | 09:59:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 09:59:55 INFO - PROCESS | 1638 | RSS: Main: 193208320 09:59:55 INFO - PROCESS | 1638 | 10:02:57 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 10:02:58 INFO - PROCESS | 1638 | RSS: Main: 195629056 10:02:58 INFO - PROCESS | 1638 | 10:06:00 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 10:06:00 INFO - PROCESS | 1638 | RSS: Main: 199196672 10:06:00 INFO - PROCESS | 1638 | 10:09:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 10:09:04 INFO - PROCESS | 1638 | RSS: Main: 200617984 10:09:04 INFO - PROCESS | 1638 | 10:12:06 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 10:12:07 INFO - PROCESS | 1638 | RSS: Main: 200847360 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | __start_tp_report 10:12:07 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 10:12:07 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 10:12:07 INFO - PROCESS | 1638 | |i|pagename|runs| 10:12:07 INFO - PROCESS | 1638 | |0;dojo.html;3304.240024794297;3540.0441880692215;3572.0712614720196;3597.3704601694703;3605.2969670914167;5867.207926929863;5895.911561326584;5900.468957793798;5941.287045554534;5948.553858460611;5876.236089308384;5892.64644121353;5959.910601340984;5995.520358371331;5996.760129594822;5241.187615919539;5262.92105618416;5272.604554658416;5292.576593872481;5357.544608708245;5297.788088476475;5335.0663633864115;5341.359036915575;5342.171963345688;5351.7056561889085;5209.114450543417;5232.032074066291;5335.573154147678;5340.973295133519;5374.596905232103;5274.446183150752;5300.522952934235;5326.280952071459;5357.705326207057;5400.459954004607;5174.818881339173;5271.894562108775;5280.947190528084;5307.761150748225;5311.336082989626;5792.8841423171725;5984.401559844025;6037.184980027728;6049.092636104576;6059;6171.444569988723;6176.598521096159;6206.224221972254;6208.2239720034995;6294.7482100715915;6312.968435157794;6328.398802113792;6334.113224148577;6340.270868850095;6351.618902865843;6044.063170208625;6055.182550355734;6057.8485537861025;6060.636361818261;6074;6289.811305660837;6314.557980941382;6385.616862988236;6417.743290268337;6427.2929977702415;6168.1056246843955;6207.503399728011;6236.532260080512;6240.906386404207;6247.562670613013;6331.05034244867;6350.396712312323;6359.618422894641;6365.077063825812;6366.076918846741;6155.045967874987;6156.538259630456;6165.722542485599;6204.844878878064;6210.099535567318;5937.2578427696535;6211.09939058843;6225.533085018642;6240.251169859646;6244.219472565929;5074.3657042869645;5074.985002999341;5078.111330517145;5083.161278389099;5095.955329157514;4341.9131617367475;4466.173757854807;4510.391097201901;4519.276915693474;4553.817847286137;5961.672108034099;6117.296510901303;6180.876382472416;6211.2857021443115;6218.284897236876;6293.685315734195;6334.5565810393755;6353.68231588428;6360.045993100979;6360.7137678804565;6035.215421995113;6110.633361998294;6111.083337499411;6138.081874381997;6155.230596175478;5539.833804985857;5561.332640083216;5607.943920560824;5608.439156084441;5613.550915926716;6549.083128362033;6559.901601475982;6573.474122070127;6582.275949645631;6584.1440612721;6133.79375043651;6217.7823776169425;6219.875602488016;6262.906056409066;6327.240731112296;6579.703913323816;6588.110605068255;6595.769148079794;6605.240397354366;6612.603243805387;6264.59280146789;6271.122042913905;6273.466755325849;6276.1213430118905;6279.686015699105;6121.087175196906;6151.907721384094;6177.505799536116;6283.717232724447;6319.431251187415;6226.62640241587;6245.094461303176;6251.6874156292915;6266.68666566679;6274.74901003973;5342.839714808562;5619.8760247951395;6255.186825712628;6257.1240026395435;6291.339409361951;5973.372795856372;6013.128096426081;6081.594307187812;6095.542834287535;6255.624662520263;6156.230471191101;6173.59871608344;6177.598456100339;6192.907106393318;6200.255969283715;6214.223222097238;6224.813255602339;6227.221597300338;6230;6265.561410701207 10:12:07 INFO - PROCESS | 1638 | |1;ext.html;478.31600810840484;505.77745791851606;530.4191909858705;542.5415523882319;574.7614739882948;37423.625763742355;40119.197616047735;40157.79921100394;40441;40873.79563102184;40366.59633403665;40510;40885.182296354054;40911.59088409115;41112.38331425031;9471.763205919855;9636.951815240915;9682.12860842524;9684.322097453145;9712.514374281258;9581.377210481296;9584.616615335379;9664.985176556465;9671;9682.273829462783;9593.376430532027;9606.615735370577;9655.710328690115;9682.46746428946;9682.806343873119;9610.135087842058;9643.276754243425;9661.855072173923;9716.61133554657;9746;9507.809843803154;9546.570404331822;9550.95224523873;9670;9693.806123877483;39205.80397097996;39316.41025384622;39368.409473857915;39502.81491555258;39524.01189970245;2755.875985580654;2900.390917907221;3436.295559410315;3695.8521659133603;4082.4896887888717;29609;30145.09564713062;30429.391412171633;30493;31387.058388248388;3266.6080070391704;3333.2666813301034;3372.038968893878;3415.5389022481895;3465.9480107798404;504548.4317735237;518474.4076279632;521650;524744.3762781161;529375;60017.39982600205;62728;62958.68520657459;63237;63358.732825343235;65677.67161164164;65762.01356979649;65866.67066664636;65982;66180.66909665517;16441.58896027585;16446.095464749313;16549.91725041367;16625.3349866004;16806.663866722596;12190.939045304716;12205.084618653636;12221.938890305491;12707.046971477177;12707.237565746085;16189.67620647604;17900.271845410265;18846.528836778914;18977.810221897882;19019.33432329862;7785.571793551409;7891.487053341514;7977.60111994398;8001.919980800118;8047.597620118971;839.546644811807;853.568947681417;917.5045475443186;954.4034978138664;1118.5917140243753;29635.703642963294;30064.849675751917;30374.266316031662;30541.847290763402;30877;1061.9734506637242;1095.5124969388546;1110.1673744691482;1132.428123797477;1159.7390587118;12249.877501224873;15839.049657020385;15901.920490397473;15908;15981.041137531785;634490.4826427607;648303;649552;649799.751001242;652061;58095.709521452125;58689.41310586925;58689.706551467825;58757.70621146867;58947.70526147428;1347.2455424962052;1364.6149158604026;1378.1386633354155;1379.3861731529573;1382.0740104130457;29707.702922970493;30343.393132137677;30379.240518986317;30748.231294217825;31031.68968310288;49846;50117.99764004626;50334.49665503298;50468.49531504638;50489.49510504848;619980;622554;623024.7697522966;625024;625441;26491;26526.938922442885;26732.866335668197;26973.055943041898;27221.86389068042;46803.76598117124;46821;46841.85664918028;47114.76442617765;47621;38379.65671201495;38538.80730596329;38603;38834.834954951395;39773.0056748572;38523;38984.220315594095;39351.2129757409;39562.2087558253;39812.80093599513;37219.25561488809;37608.43587346247;38157.427638586;38269.80865095768;38288.80855595704;30540.694593054675;33063.008109756745;33959.32081358408;34140.31719365648;34750;23350.416239594146;25666.743332566435;25668.486630267664;25710.2002859798;26061.957521698918 10:12:07 INFO - PROCESS | 1638 | |2;jquery.html;340.6644455211618;380.5756581411726;382.655609951044;390.2156665103142;392.811450503758;16323.673526529463;19097.14062867171;20400.89799551004;20404.591908161845;20598.97005149744;17833.732494012602;17893;18548.443546693587;20355.796442035575;20450.693239601416;5325.227841962923;5447.782088716446;5507.449255074501;5538.47384498474;5727.34135574408;5921.615095018831;6057.091436284571;6060.090986352038;6066;6073.997790364607;3528.2414280929597;3729.440583912408;5832.562557808182;6063.090536419529;6183.598066125687;5884.440978107115;6084.908726369108;6106.450419462248;6120.663363515005;6121.204243448346;5925.407459254083;5983.820485385445;5987.161797348374;5993.970030149865;6000.789972350947;13248.940084793194;17642.823571764246;19581.314653987043;24349.391265218335;24516.632250516257;2232.1964092926532;2317.3279748872806;2354.693889794333;2475.8762061897014;2786.6795318538425;10131.240156988255;10145.137663298628;10269.589216431408;10632.734181645443;10717.303375280582;1886.311496303853;1968.8621796474254;1972.2998335590946;1976.0021189299455;1986.5331647062928;119909;119955;124680;124929;127814.36092819569;9627.663031793854;11751.353675547756;11836.17146799733;11838.88161118395;11963.940180299043;14640.414383424544;14686.118832870063;15027.699446011018;15037.248137593077;15074.849251507563;8332.208440198172;8591.957040214758;8617.009043959899;8715.21563059315;8754.86867696985;8344.749657510285;8406.075331713506;8443.240108390151;8494.70268540598;8554.743357699279;3995.880123596297;4122.742563518096;4229.217594744982;4544.091181763661;4692.577668009895;1703.3271857616173;2007.6687346587942;2032.9695054574192;2046.6009128219855;2052.8049835265924;344.55552337484863;363.72514337248305;383.90778235918765;408.8139896347155;413.2190160596475;10372.377657340583;11850.466728997217;11890.464929078213;11918.88081119195;12104.455299511543;432.1249469823632;434.5046646822625;435.29047652326506;441.5628527757497;441.67095513842395;4550.340200670884;6192.442680158807;6210.099535567408;6231.065340199007;6321;481952;483833;486292;605968;625663;60777;87033;102600.46099308367;107061;151948;850.0352100366129;915.231205787142;949.1694767078806;1015.21320976244;1038.4755698372273;18407.53981150482;18750.624987500447;18987.62024759525;19012.859228446338;19077.855328680325;55845.16232256438;56376.71811640915;56803.71598142148;57457.13814292873;58153.70923145357;74020;75694;76436.23563764291;79244.20755792368;82850.342993141;8087.191280872101;8224.38317126206;8319.209675080858;8347.040090389683;8428.704995325133;11229.66311010671;12686.302253376152;12964.675883102998;12980.15628984113;13195.944324453829;10324.690259292232;10354.896451035393;10701.946490267499;11111.333320000826;11156;10859.402732849769;11304.773904522028;11345.092392608407;11464;11519.53921843117;11476.655700329004;11481.540738370371;11545.653630391102;11546.653600392001;11626.244294120856;8134.83730325402;8287.461315014505;8458.74623761288;8470.49177049379;8496.830063398575;9300.581473833558;11199.944000279946;11205.831912521482;11510.94244528772;11668.533258669897 10:12:07 INFO - PROCESS | 1638 | |3;mootools.html;1495.4093133212384;1533.1261181126758;1601.8157911840142;1698.057578044185;1835.6695794756938;7700.730474433396;7941.0867750208745;8193.426460147784;8640.783980400493;8665.480071195732;8177.100518942912;8417.4107812453;8433.746987590366;8449.78875528112;8515.105913879032;2010.5275260313815;2380.119355838342;2391;2392.688950436441;2394.437307232799;2399.3521749127726;2415.8308918375633;2424.5635785558593;2439.560879041772;2449.4243852694603;2370.5614461324626;2376.489054853206;2396.7483414241515;2407.422218667516;2409.77107174819;2106.810387065164;2294.2199652118275;2324.523472688095;2346.5658853112227;2384.8688322142275;2104.8000439958178;2317.2468947591933;2389.689340385739;2419.7837703491987;2427.453822889853;8431.367647426407;8472.025717042558;8487.236148746642;8586.22723954847;8645.265152462049;5121.719570107473;5223.869403264949;5294.993951149308;5300.946990530084;5301.443348448396;3098.7211150996295;3551.236484155893;3583.587887392931;3595.1191957970204;3642.1623026704005;1060.3796778884362;1066.632011955874;1107.778444311133;1116.9162312826488;1120.3501968858043;10995.395253261067;11069.889301106967;11091.611793587186;11092.944535277353;11119.388433636146;4518.977405112953;4670.813167473331;4731.195696731564;4736.3369128322065;4800.711957282574;9609.615615375447;9615.759606009906;9620.37467564606;9626.133647971716;9653.90346096544;4572;5168.638195326291;5203.765830537635;5233.712145832016;5245.396779370345;4347.739135651871;4358.498772641186;4643.094596553639;4739.052189562032;4803.543663351976;3840.4815349927962;3856.2094770571966;3964.7819369934355;3968.6428221460205;3990.1421194442755;1274.2290913997083;1280.2510531339085;1297.6885547468728;1298.3638017371609;1306.882380585752;792.9405294602927;831.6964308027526;872.1757938747949;917.064594114;926.7544100813155;5771.394003629643;5853.619514731528;5893.11603259506;5946.970265148647;6073.30157031947;439.18969501270743;444.6820523325773;462.5536357415111;463.46932761987335;467.25239616613146;3882.1265215326325;3893.785841778673;3964.6431821136234;4016.116454380032;4044.272031034442;9872.40765554069;10072.748181295527;10131.594736210616;10216.489175541194;10237.385756854612;8455.70405035833;9897.80204395908;10039.69880903574;10128.088472037552;10149.594016239416;7862.253085956825;7866.252705992922;7898.328642065374;7925.960370198112;7997.560134192675;5506.33923929115;5576.275084239023;5622.971885140548;5735.541156707537;5770.567207459373;9222.44665320083;9286.30352723535;9377.859332109887;9445;9472.905270947202;8153.388495862715;9103.726888193365;9436.811263774824;9520.714378568655;9573.952130239582;7110.32451917067;7129.893051604334;7176.569405835667;7217.206107328085;7242.601656908921;7821.374290056668;7839.960800195962;7922.7227047053075;7927.088384835818;7929.920700792918;6019.909701354571;7070.186928503288;7167.928320716726;7200.315969982843;7339.522931009467;6012.549058820518;6296.835085509196;7054.858902821812;7078.93815927615;7083.787486375417;6584.670766461562;6650.467962562887;6716.3955244028275;6742.831429214309;6763.864722705617;4927.975360123177;4965.45380008206;4971.204607262821;4995.275685025724;5014.648974571745;4794.640401969797;4971.527704868032;4971.676841005323;5060.1650727631;5069.214271787879 10:12:07 INFO - PROCESS | 1638 | |4;prototype.html;278.4667362001767;309.3719748909713;314.1862575928346;320.92297848516347;327.6723276723277;5054.570361519271;5187.922181167276;5190.662606930551;5239.594263728573;5524.1989911462815;5173.560247378968;5478.767277362592;5623.184638227454;5663.207150998864;5665.008623490883;4298.634616057631;4316.870493885172;4318.330658747899;4334.284843000901;4334.523202447728;3781.9810900945595;4176.519700234481;4265.31754919213;4296.806643701041;4319.956800431987;4197.349410841309;4197.664186865043;4201.810918508674;4211.115665710205;4227.4927008759;3384.7969121852766;4227.682923780729;4235.512916014667;4245.426867372896;4249.3413520904305;3581.7105841896896;3603.387424137903;3726.2361215950937;3748.5689145748315;3789.559967212469;4491.640668746525;5355.571554275688;5366.007288651612;5487.780488780444;5599.188117722948;3957.5448823385104;4584.426946631671;5002.824901128443;5052.545270925634;5065.873353166164;5004.549590536869;5691.0365923756235;5712.514436272922;5935.287765468128;5980.910286345708;3837.424386342043;4071.9209409506507;4167.437211045858;4167.978845182919;4194.559571245006;5531.917021244684;6046.637201767864;6303.2325422239055;6378.362163783585;6397.584157029824;4036.3138266494766;4215.810288537024;4217.536071032184;4221.535631080578;4259.361095835649;3321.983390083034;3416.8974930752115;4033.515978082649;4279.16556271524;4304.491839151082;4364.454443194601;4720.984988227548;4885.462599114095;4973.303737476756;4994.725290109006;5506.650870536744;5578.493806672176;5696.715164241771;5999.670018149044;6120.755169793248;1286.8841804237663;1368.3500337339683;1384.9099808512415;1413.8939579531577;1415.6673181802269;782.9217078292126;832.488019867777;893.3032234856822;931.9534023298808;952.5380190607689;4618.745968971672;5045.47022562633;5394.572442400935;5450.511672529584;5477.699366266178;4955.40535135786;5135.512126347991;5652.7173641318595;5894.970525147432;5983.670898100646;5322.361316642027;5398.595105367113;5483.369412517532;5507.449255074541;5687.7156142192725;3044.512877939519;3102.8293443860803;3139.152428844199;3141.6701246368802;3154.479510880679;4938.320970869921;5821.79623713168;5947.672877991752;6286.308506064329;6364.554481186273;5592.608517403824;5994.880102397928;6202.56582039262;6308.558400911892;6416.294207637156;5672.744726487318;6391.42477177047;6397.200349956255;6489.513286503437;6515.478761698958;5537.771129394166;5804.506616937608;6090.847728806638;6174.487250623446;6216.72024758896;5102.4642412546145;5496.395396506301;6084.482818960261;6093.725782339894;6124.969375153096;5682.721387687737;5728.742206600629;6122.877542449037;6131.908021379772;6407.967960160169;4037.5962403760564;4041.1917616476235;4056;4059.127287633173;4075.5924407559005;5897.882042359214;6032.034874420072;6055.397897597769;6223.844403889939;6294.968525157345;4548.135854187693;5769.653820770767;5931.5847890648965;5970.910436343545;6036.456718895321;4857.9028419432125;6003.339632640494;6014.368491308524;6044.75820967174;6084.665343406155;4934.55588996992;5758.510526605285;5852.531797456279;5891.23413956183;5949.8215053548465;5302.083853880427;5643.88712225745;6001.399860013964;6085.69571521431;6138.815835524942;5622.746976385991;5900.321463031806;5925.496332811759;5930.644161350333;5998.920733900989 10:12:07 INFO - PROCESS | 1638 | |5;yui.html;671.664167916042;759.4304271796153;785.8821176823476;801.1547817053007;824.060570949118;11241.325520468778;13921.37353819078;14497.420103195873;15184.088954662715;15219.923900380496;14547.78178327326;14959.326830292635;15175.924120379383;15330.386784528633;15468.149251791147;816.2612835383973;836.5022811427191;845.023997283139;845.4082142500243;848.0840692052568;823.3948048184579;843.9789005274856;851.7742798158492;852.9402941794077;855.1533981358473;883.7525492861977;899.33449247557;900.1043961258557;901.1214066285352;905.1491597897988;885.3315746611299;894.2354287084514;896.7354630384052;899.5906862377602;900.2527901841488;655.7704803318848;658.8912829383146;666.6000399760154;667.0594461808874;667.4160109903834;12075.456604452733;14492.34784434703;14757.77863332051;14877.776833347509;14995.475158369405;13401;13846.723065538632;13958.651033724136;13961.930190348983;13964.441422343094;11622.651320460314;11708.063354931584;11857.347845868473;11891.286522808572;11931.105167112415;3607.8556857725657;3724.3854763963914;3790.431435284702;3807.447920051577;3906.433567132778;78349;95350.52324738332;97836.51081744545;98378.508107459;99254.0074599252;10637.521311540999;10655.467226638792;10764.677059688222;10863.565457381774;10924.126069914388;17843.37548185807;20827.58344833125;20897.686534701992;20959.161633534794;21094.683579746317;7238.27617238272;7678.270564296383;8060.879086813702;8176.713815016565;8199.467034642848;4321.48142222929;4460.174867649431;4549.408576884984;4572.565606267399;4620.561046700558;3462.8961131166106;3538.469229615578;3576.5886923004186;3581.767185132958;3593.2094939113354;650.3593959949446;650.8665723526666;668.6122049211543;695.9408450281783;704.2007321689919;591.7159763313633;618.0142672437455;618.9566730328834;677.9830504237434;691.920429150644;8963.238124759338;9423.811523769486;9447.385919915183;9533.809323813484;9624.133827955378;560.1289994059196;562.445990699161;572.1874937588634;696.8327601375634;714.2500374606747;4317.373980772833;4526.253168227206;4570.451545814523;4633.3500131291385;4776.331313616097;71856;77526.4494710114;100139;103233;106387.40418893723;94203.11593768223;95122;96708;98095;98856.01143988612;79441.99999999885;80373;80727.59636201781;87872.56063719641;89111;8522.36082293818;9835;9921.851172232567;10028.097471227624;10120.645777398051;52680;52839;53707.462925370244;55575.444245557024;56634.15048774354;71141.28858711346;76114.61942690251;79218;80001.79997299929;80252;10767.838482422614;12638.988880889323;12737.55418560346;12900.8064879025;12912.677183070498;13254.469821207043;13345.266010369523;13425.597232083053;13597.252151131783;13688.247146406646;13360.665983350495;13409.463621455423;13431.597052088453;14211.715765684836;14332.211728355041;13262.867371326549;13613.251271179784;13668;14257.928710356382;14280.500182493563;13509.581493943388;13579.660508486972;13963.30183490801;14126.576202713935;14179.64550886236;11145.331280123219;11293.491792869176;11443.713907152054;11513.769724605294;11853.822192667289;11385.602586684443;11518.220435937457;11636.709082273012;11669.183157178915;11943.880561194625 10:12:07 INFO - PROCESS | 1638 | __end_tp_report 10:12:07 INFO - PROCESS | 1638 | __start_cc_report 10:12:07 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,354 10:12:07 INFO - PROCESS | 1638 | __end_cc_report 10:12:07 INFO - PROCESS | 1638 | __startTimestamp1486750327083__endTimestamp 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | ------- Summary: start ------- 10:12:07 INFO - PROCESS | 1638 | Number of tests: 6 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5881.69 Median:6155.23 stddev:638.69 (10.4%) stddev-sans-first:607.80 10:12:07 INFO - PROCESS | 1638 | Values: 3304.2 3540.0 3572.1 3597.4 3605.3 5867.2 5895.9 5900.5 5941.3 5948.6 5876.2 5892.6 5959.9 5995.5 5996.8 5241.2 5262.9 5272.6 5292.6 5357.5 5297.8 5335.1 5341.4 5342.2 5351.7 5209.1 5232.0 5335.6 5341.0 5374.6 5274.4 5300.5 5326.3 5357.7 5400.5 5174.8 5271.9 5280.9 5307.8 5311.3 5792.9 5984.4 6037.2 6049.1 6059.0 6171.4 6176.6 6206.2 6208.2 6294.7 6313.0 6328.4 6334.1 6340.3 6351.6 6044.1 6055.2 6057.8 6060.6 6074.0 6289.8 6314.6 6385.6 6417.7 6427.3 6168.1 6207.5 6236.5 6240.9 6247.6 6331.1 6350.4 6359.6 6365.1 6366.1 6155.0 6156.5 6165.7 6204.8 6210.1 5937.3 6211.1 6225.5 6240.3 6244.2 5074.4 5075.0 5078.1 5083.2 5096.0 4341.9 4466.2 4510.4 4519.3 4553.8 5961.7 6117.3 6180.9 6211.3 6218.3 6293.7 6334.6 6353.7 6360.0 6360.7 6035.2 6110.6 6111.1 6138.1 6155.2 5539.8 5561.3 5607.9 5608.4 5613.6 6549.1 6559.9 6573.5 6582.3 6584.1 6133.8 6217.8 6219.9 6262.9 6327.2 6579.7 6588.1 6595.8 6605.2 6612.6 6264.6 6271.1 6273.5 6276.1 6279.7 6121.1 6151.9 6177.5 6283.7 6319.4 6226.6 6245.1 6251.7 6266.7 6274.7 5342.8 5619.9 6255.2 6257.1 6291.3 5973.4 6013.1 6081.6 6095.5 6255.6 6156.2 6173.6 6177.6 6192.9 6200.3 6214.2 6224.8 6227.2 6230.0 6265.6 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:72658.28 Median:39966.00 stddev:160262.57 (401.0%) stddev-sans-first:160620.52 10:12:07 INFO - PROCESS | 1638 | Values: 478.3 505.8 530.4 542.5 574.8 37423.6 40119.2 40157.8 40441.0 40873.8 40366.6 40510.0 40885.2 40911.6 41112.4 9471.8 9637.0 9682.1 9684.3 9712.5 9581.4 9584.6 9665.0 9671.0 9682.3 9593.4 9606.6 9655.7 9682.5 9682.8 9610.1 9643.3 9661.9 9716.6 9746.0 9507.8 9546.6 9551.0 9670.0 9693.8 39205.8 39316.4 39368.4 39502.8 39524.0 2755.9 2900.4 3436.3 3695.9 4082.5 29609.0 30145.1 30429.4 30493.0 31387.1 3266.6 3333.3 3372.0 3415.5 3465.9 504548.4 518474.4 521650.0 524744.4 529375.0 60017.4 62728.0 62958.7 63237.0 63358.7 65677.7 65762.0 65866.7 65982.0 66180.7 16441.6 16446.1 16549.9 16625.3 16806.7 12190.9 12205.1 12221.9 12707.0 12707.2 16189.7 17900.3 18846.5 18977.8 19019.3 7785.6 7891.5 7977.6 8001.9 8047.6 839.5 853.6 917.5 954.4 1118.6 29635.7 30064.8 30374.3 30541.8 30877.0 1062.0 1095.5 1110.2 1132.4 1159.7 12249.9 15839.0 15901.9 15908.0 15981.0 634490.5 648303.0 649552.0 649799.8 652061.0 58095.7 58689.4 58689.7 58757.7 58947.7 1347.2 1364.6 1378.1 1379.4 1382.1 29707.7 30343.4 30379.2 30748.2 31031.7 49846.0 50118.0 50334.5 50468.5 50489.5 619980.0 622554.0 623024.8 625024.0 625441.0 26491.0 26526.9 26732.9 26973.1 27221.9 46803.8 46821.0 46841.9 47114.8 47621.0 38379.7 38538.8 38603.0 38834.8 39773.0 38523.0 38984.2 39351.2 39562.2 39812.8 37219.3 37608.4 38157.4 38269.8 38288.8 30540.7 33063.0 33959.3 34140.3 34750.0 23350.4 25666.7 25668.5 25710.2 26062.0 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:32230.45 Median:1945.98 stddev:90619.48 (4656.7%) stddev-sans-first:90842.05 10:12:07 INFO - PROCESS | 1638 | Values: 340.7 380.6 382.7 390.2 392.8 16323.7 19097.1 20400.9 20404.6 20599.0 17833.7 17893.0 18548.4 20355.8 20450.7 5325.2 5447.8 5507.4 5538.5 5727.3 5921.6 6057.1 6060.1 6066.0 6074.0 3528.2 3729.4 5832.6 6063.1 6183.6 5884.4 6084.9 6106.5 6120.7 6121.2 5925.4 5983.8 5987.2 5994.0 6000.8 13248.9 17642.8 19581.3 24349.4 24516.6 2232.2 2317.3 2354.7 2475.9 2786.7 10131.2 10145.1 10269.6 10632.7 10717.3 1886.3 1968.9 1972.3 1976.0 1986.5 119909.0 119955.0 124680.0 124929.0 127814.4 9627.7 11751.4 11836.2 11838.9 11963.9 14640.4 14686.1 15027.7 15037.2 15074.8 8332.2 8592.0 8617.0 8715.2 8754.9 8344.7 8406.1 8443.2 8494.7 8554.7 3995.9 4122.7 4229.2 4544.1 4692.6 1703.3 2007.7 2033.0 2046.6 2052.8 344.6 363.7 383.9 408.8 413.2 10372.4 11850.5 11890.5 11918.9 12104.5 432.1 434.5 435.3 441.6 441.7 4550.3 6192.4 6210.1 6231.1 6321.0 481952.0 483833.0 486292.0 605968.0 625663.0 60777.0 87033.0 102600.5 107061.0 151948.0 850.0 915.2 949.2 1015.2 1038.5 18407.5 18750.6 18987.6 19012.9 19077.9 55845.2 56376.7 56803.7 57457.1 58153.7 74020.0 75694.0 76436.2 79244.2 82850.3 8087.2 8224.4 8319.2 8347.0 8428.7 11229.7 12686.3 12964.7 12980.2 13195.9 10324.7 10354.9 10701.9 11111.3 11156.0 10859.4 11304.8 11345.1 11464.0 11519.5 11476.7 11481.5 11545.7 11546.7 11626.2 8134.8 8287.5 8458.7 8470.5 8496.8 9300.6 11199.9 11205.8 11510.9 11668.5 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5475.46 Median:5037.41 stddev:2995.97 (59.5%) stddev-sans-first:2989.44 10:12:07 INFO - PROCESS | 1638 | Values: 1495.4 1533.1 1601.8 1698.1 1835.7 7700.7 7941.1 8193.4 8640.8 8665.5 8177.1 8417.4 8433.7 8449.8 8515.1 2010.5 2380.1 2391.0 2392.7 2394.4 2399.4 2415.8 2424.6 2439.6 2449.4 2370.6 2376.5 2396.7 2407.4 2409.8 2106.8 2294.2 2324.5 2346.6 2384.9 2104.8 2317.2 2389.7 2419.8 2427.5 8431.4 8472.0 8487.2 8586.2 8645.3 5121.7 5223.9 5295.0 5300.9 5301.4 3098.7 3551.2 3583.6 3595.1 3642.2 1060.4 1066.6 1107.8 1116.9 1120.4 10995.4 11069.9 11091.6 11092.9 11119.4 4519.0 4670.8 4731.2 4736.3 4800.7 9609.6 9615.8 9620.4 9626.1 9653.9 4572.0 5168.6 5203.8 5233.7 5245.4 4347.7 4358.5 4643.1 4739.1 4803.5 3840.5 3856.2 3964.8 3968.6 3990.1 1274.2 1280.3 1297.7 1298.4 1306.9 792.9 831.7 872.2 917.1 926.8 5771.4 5853.6 5893.1 5947.0 6073.3 439.2 444.7 462.6 463.5 467.3 3882.1 3893.8 3964.6 4016.1 4044.3 9872.4 10072.7 10131.6 10216.5 10237.4 8455.7 9897.8 10039.7 10128.1 10149.6 7862.3 7866.3 7898.3 7926.0 7997.6 5506.3 5576.3 5623.0 5735.5 5770.6 9222.4 9286.3 9377.9 9445.0 9472.9 8153.4 9103.7 9436.8 9520.7 9574.0 7110.3 7129.9 7176.6 7217.2 7242.6 7821.4 7840.0 7922.7 7927.1 7929.9 6019.9 7070.2 7167.9 7200.3 7339.5 6012.5 6296.8 7054.9 7078.9 7083.8 6584.7 6650.5 6716.4 6742.8 6763.9 4928.0 4965.5 4971.2 4995.3 5014.6 4794.6 4971.5 4971.7 5060.2 5069.2 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4795.12 Median:5396.58 stddev:1485.15 (27.5%) stddev-sans-first:1450.11 10:12:07 INFO - PROCESS | 1638 | Values: 278.5 309.4 314.2 320.9 327.7 5054.6 5187.9 5190.7 5239.6 5524.2 5173.6 5478.8 5623.2 5663.2 5665.0 4298.6 4316.9 4318.3 4334.3 4334.5 3782.0 4176.5 4265.3 4296.8 4320.0 4197.3 4197.7 4201.8 4211.1 4227.5 3384.8 4227.7 4235.5 4245.4 4249.3 3581.7 3603.4 3726.2 3748.6 3789.6 4491.6 5355.6 5366.0 5487.8 5599.2 3957.5 4584.4 5002.8 5052.5 5065.9 5004.5 5691.0 5712.5 5935.3 5980.9 3837.4 4071.9 4167.4 4168.0 4194.6 5531.9 6046.6 6303.2 6378.4 6397.6 4036.3 4215.8 4217.5 4221.5 4259.4 3322.0 3416.9 4033.5 4279.2 4304.5 4364.5 4721.0 4885.5 4973.3 4994.7 5506.7 5578.5 5696.7 5999.7 6120.8 1286.9 1368.4 1384.9 1413.9 1415.7 782.9 832.5 893.3 932.0 952.5 4618.7 5045.5 5394.6 5450.5 5477.7 4955.4 5135.5 5652.7 5895.0 5983.7 5322.4 5398.6 5483.4 5507.4 5687.7 3044.5 3102.8 3139.2 3141.7 3154.5 4938.3 5821.8 5947.7 6286.3 6364.6 5592.6 5994.9 6202.6 6308.6 6416.3 5672.7 6391.4 6397.2 6489.5 6515.5 5537.8 5804.5 6090.8 6174.5 6216.7 5102.5 5496.4 6084.5 6093.7 6125.0 5682.7 5728.7 6122.9 6131.9 6408.0 4037.6 4041.2 4056.0 4059.1 4075.6 5897.9 6032.0 6055.4 6223.8 6295.0 4548.1 5769.7 5931.6 5970.9 6036.5 4857.9 6003.3 6014.4 6044.8 6084.7 4934.6 5758.5 5852.5 5891.2 5949.8 5302.1 5643.9 6001.4 6085.7 6138.8 5622.7 5900.3 5925.5 5930.6 5998.9 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20500.71 Median:4571.51 stddev:29247.39 (639.8%) stddev-sans-first:29291.54 10:12:07 INFO - PROCESS | 1638 | Values: 671.7 759.4 785.9 801.2 824.1 11241.3 13921.4 14497.4 15184.1 15219.9 14547.8 14959.3 15175.9 15330.4 15468.1 816.3 836.5 845.0 845.4 848.1 823.4 844.0 851.8 852.9 855.2 883.8 899.3 900.1 901.1 905.1 885.3 894.2 896.7 899.6 900.3 655.8 658.9 666.6 667.1 667.4 12075.5 14492.3 14757.8 14877.8 14995.5 13401.0 13846.7 13958.7 13961.9 13964.4 11622.7 11708.1 11857.3 11891.3 11931.1 3607.9 3724.4 3790.4 3807.4 3906.4 78349.0 95350.5 97836.5 98378.5 99254.0 10637.5 10655.5 10764.7 10863.6 10924.1 17843.4 20827.6 20897.7 20959.2 21094.7 7238.3 7678.3 8060.9 8176.7 8199.5 4321.5 4460.2 4549.4 4572.6 4620.6 3462.9 3538.5 3576.6 3581.8 3593.2 650.4 650.9 668.6 695.9 704.2 591.7 618.0 619.0 678.0 691.9 8963.2 9423.8 9447.4 9533.8 9624.1 560.1 562.4 572.2 696.8 714.3 4317.4 4526.3 4570.5 4633.4 4776.3 71856.0 77526.4 100139.0 103233.0 106387.4 94203.1 95122.0 96708.0 98095.0 98856.0 79442.0 80373.0 80727.6 87872.6 89111.0 8522.4 9835.0 9921.9 10028.1 10120.6 52680.0 52839.0 53707.5 55575.4 56634.2 71141.3 76114.6 79218.0 80001.8 80252.0 10767.8 12639.0 12737.6 12900.8 12912.7 13254.5 13345.3 13425.6 13597.3 13688.2 13360.7 13409.5 13431.6 14211.7 14332.2 13262.9 13613.3 13668.0 14257.9 14280.5 13509.6 13579.7 13963.3 14126.6 14179.6 11145.3 11293.5 11443.7 11513.8 11853.8 11385.6 11518.2 11636.7 11669.2 11943.9 10:12:07 INFO - PROCESS | 1638 | -------- Summary: end -------- 10:12:07 INFO - PROCESS | 1638 | 10:12:07 INFO - TEST-INFO | 1638: exit 0 10:12:07 INFO - TEST-OK | dromaeo_css | took 1113562ms 10:12:07 INFO - TEST-START | kraken 10:12:07 INFO - operating with platform_type : mac_ 10:12:07 INFO - Initialising browser for kraken test... 10:12:07 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp2cLBUd/profile http://localhost:49244/getInfo.html) 10:12:10 INFO - PROCESS | 1650 | 2017-02-10 10:12:10.241 plugin-container[1652:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 10:12:10 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:10 INFO - PROCESS | 1650 | 2017-02-10 10:12:10.242 plugin-container[1652:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 10:12:10 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:10 INFO - PROCESS | 1650 | 2017-02-10 10:12:10.242 plugin-container[1652:10222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:12:10 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 10:12:10 INFO - PROCESS | 1650 | colorDepth:24 10:12:10 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 10:12:10 INFO - PROCESS | 1650 | __metrics 10:12:10 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 10:12:11 INFO - TEST-INFO | 1650: exit 0 10:12:11 INFO - Browser initialized. 10:12:11 INFO - Running cycle 1/1 for kraken test... 10:12:11 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp2cLBUd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:13 INFO - PROCESS | 1653 | 2017-02-10 10:12:13.700 plugin-container[1654:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 10:12:13 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:13 INFO - PROCESS | 1653 | 2017-02-10 10:12:13.701 plugin-container[1654:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:12:13 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:13 INFO - PROCESS | 1653 | 2017-02-10 10:12:13.701 plugin-container[1654:10340] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:12:23 INFO - PROCESS | 1653 | RSS: Main: 177504256 10:12:23 INFO - PROCESS | 1653 | 10:12:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:27 INFO - PROCESS | 1653 | RSS: Main: 190951424 10:12:27 INFO - PROCESS | 1653 | 10:12:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 10:12:30 INFO - PROCESS | 1653 | RSS: Main: 199634944 10:12:30 INFO - PROCESS | 1653 | 10:12:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 10:12:34 INFO - PROCESS | 1653 | RSS: Main: 202444800 10:12:34 INFO - PROCESS | 1653 | 10:12:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 10:12:36 INFO - PROCESS | 1653 | RSS: Main: 202502144 10:12:36 INFO - PROCESS | 1653 | 10:12:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:39 INFO - PROCESS | 1653 | RSS: Main: 202985472 10:12:39 INFO - PROCESS | 1653 | 10:12:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 10:12:42 INFO - PROCESS | 1653 | RSS: Main: 207388672 10:12:42 INFO - PROCESS | 1653 | 10:12:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 10:12:46 INFO - PROCESS | 1653 | RSS: Main: 207470592 10:12:46 INFO - PROCESS | 1653 | 10:12:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 10:12:49 INFO - PROCESS | 1653 | RSS: Main: 206970880 10:12:49 INFO - PROCESS | 1653 | 10:12:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:12:51 INFO - PROCESS | 1653 | RSS: Main: 205910016 10:12:51 INFO - PROCESS | 1653 | 10:12:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:12:54 INFO - PROCESS | 1653 | RSS: Main: 206082048 10:12:54 INFO - PROCESS | 1653 | 10:12:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:12:57 INFO - PROCESS | 1653 | RSS: Main: 206094336 10:12:57 INFO - PROCESS | 1653 | 10:13:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:00 INFO - PROCESS | 1653 | RSS: Main: 206065664 10:13:00 INFO - PROCESS | 1653 | 10:13:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:13:04 INFO - PROCESS | 1653 | RSS: Main: 206483456 10:13:04 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 10:13:06 INFO - PROCESS | 1653 | RSS: Main: 208306176 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | __start_tp_report 10:13:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 10:13:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 10:13:06 INFO - PROCESS | 1653 | |i|pagename|runs| 10:13:06 INFO - PROCESS | 1653 | |0;ai-astar;105;117;118;119;115;116;118;117;116;114 10:13:06 INFO - PROCESS | 1653 | |1;audio-beat-detection;140;108;110;140;110;109;106;111;158;138 10:13:06 INFO - PROCESS | 1653 | |2;audio-dft;200;201;260;277;194;198;193;202;194;194 10:13:06 INFO - PROCESS | 1653 | |3;audio-fft;79;60;59;97;81;82;81;57;75;53 10:13:06 INFO - PROCESS | 1653 | |4;audio-oscillator;83;70;88;73;69;71;85;69;70;86 10:13:06 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;86;89;86;88;84;88;86;95;86 10:13:06 INFO - PROCESS | 1653 | |6;imaging-darkroom;94;95;97;123;147;122;96;96;94;95 10:13:06 INFO - PROCESS | 1653 | |7;imaging-desaturate;89;89;86;98;124;88;94;94;87;88 10:13:06 INFO - PROCESS | 1653 | |8;json-parse-financial;56;54;57;64;85;84;49;53;75;84 10:13:06 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;67;71;78;76;72;68;68;69;70;70 10:13:06 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;80;83;82;83;84;86;82;84;84;84 10:13:06 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;147;129;159;133;166;186;162;154;187;160 10:13:06 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;177;189;220;169;160;219;214;177;166;163 10:13:06 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;70;87;85;86;64;65;66;67 10:13:06 INFO - PROCESS | 1653 | __end_tp_report 10:13:06 INFO - PROCESS | 1653 | __start_cc_report 10:13:06 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,901 10:13:06 INFO - PROCESS | 1653 | __end_cc_report 10:13:06 INFO - PROCESS | 1653 | __startTimestamp1486750386525__endTimestamp 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | ------- Summary: start ------- 10:13:06 INFO - PROCESS | 1653 | Number of tests: 14 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:115.50 Median:117.00 stddev:3.98 (3.4%) stddev-sans-first:1.58 10:13:06 INFO - PROCESS | 1653 | Values: 105.0 117.0 118.0 119.0 115.0 116.0 118.0 117.0 116.0 114.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:124.50 stddev:18.92 (15.2%) stddev-sans-first:19.04 10:13:06 INFO - PROCESS | 1653 | Values: 140.0 108.0 110.0 140.0 110.0 109.0 106.0 111.0 158.0 138.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:211.30 Median:200.50 stddev:30.58 (15.3%) stddev-sans-first:32.16 10:13:06 INFO - PROCESS | 1653 | Values: 200.0 201.0 260.0 277.0 194.0 198.0 193.0 202.0 194.0 194.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:72.40 Median:80.00 stddev:14.31 (17.9%) stddev-sans-first:14.97 10:13:06 INFO - PROCESS | 1653 | Values: 79.0 60.0 59.0 97.0 81.0 82.0 81.0 57.0 75.0 53.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:78.00 stddev:8.00 (10.3%) stddev-sans-first:8.12 10:13:06 INFO - PROCESS | 1653 | Values: 83.0 70.0 88.0 73.0 69.0 71.0 85.0 69.0 70.0 86.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:2.99 (3.4%) stddev-sans-first:3.17 10:13:06 INFO - PROCESS | 1653 | Values: 88.0 86.0 89.0 86.0 88.0 84.0 88.0 86.0 95.0 86.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:95.00 stddev:18.37 (19.3%) stddev-sans-first:18.97 10:13:06 INFO - PROCESS | 1653 | Values: 94.0 95.0 97.0 123.0 147.0 122.0 96.0 96.0 94.0 95.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:89.00 stddev:11.30 (12.7%) stddev-sans-first:11.86 10:13:06 INFO - PROCESS | 1653 | Values: 89.0 89.0 86.0 98.0 124.0 88.0 94.0 94.0 87.0 88.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:69.50 stddev:14.44 (20.8%) stddev-sans-first:14.85 10:13:06 INFO - PROCESS | 1653 | Values: 56.0 54.0 57.0 64.0 85.0 84.0 49.0 53.0 75.0 84.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:3.57 (5.1%) stddev-sans-first:3.50 10:13:06 INFO - PROCESS | 1653 | Values: 67.0 71.0 78.0 76.0 72.0 68.0 68.0 69.0 70.0 70.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:84.00 stddev:1.62 (1.9%) stddev-sans-first:1.24 10:13:06 INFO - PROCESS | 1653 | Values: 80.0 83.0 82.0 83.0 84.0 86.0 82.0 84.0 84.0 84.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:161.00 stddev:19.18 (11.9%) stddev-sans-first:19.91 10:13:06 INFO - PROCESS | 1653 | Values: 147.0 129.0 159.0 133.0 166.0 186.0 162.0 154.0 187.0 160.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:183.00 stddev:23.78 (13.0%) stddev-sans-first:25.03 10:13:06 INFO - PROCESS | 1653 | Values: 177.0 189.0 220.0 169.0 160.0 219.0 214.0 177.0 166.0 163.0 10:13:06 INFO - PROCESS | 1653 | 10:13:06 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:68.50 stddev:9.59 (14.0%) stddev-sans-first:9.98 10:13:06 INFO - PROCESS | 1653 | Values: 67.0 66.0 70.0 87.0 85.0 86.0 64.0 65.0 66.0 67.0 10:13:06 INFO - PROCESS | 1653 | -------- Summary: end -------- 10:13:06 INFO - PROCESS | 1653 | 10:13:07 INFO - TEST-INFO | 1653: exit 0 10:13:07 INFO - TEST-OK | kraken | took 59828ms 10:13:07 INFO - SUITE-END | took 1173s 10:13:07 INFO - Completed test suite (00:19:33) 10:13:07 INFO - javascript benchmark 10:13:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7248.513456803213, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.240024794297, 3540.0441880692215, 3572.0712614720196, 3597.3704601694703, 3605.2969670914167, 5867.207926929863, 5895.911561326584, 5900.468957793798, 5941.287045554534, 5948.553858460611, 5876.236089308384, 5892.64644121353, 5959.910601340984, 5995.520358371331, 5996.760129594822, 5241.187615919539, 5262.92105618416, 5272.604554658416, 5292.576593872481, 5357.544608708245, 5297.788088476475, 5335.0663633864115, 5341.359036915575, 5342.171963345688, 5351.7056561889085, 5209.114450543417, 5232.032074066291, 5335.573154147678, 5340.973295133519, 5374.596905232103, 5274.446183150752, 5300.522952934235, 5326.280952071459, 5357.705326207057, 5400.459954004607, 5174.818881339173, 5271.894562108775, 5280.947190528084, 5307.761150748225, 5311.336082989626, 5792.8841423171725, 5984.401559844025, 6037.184980027728, 6049.092636104576, 6059.0, 6171.444569988723, 6176.598521096159, 6206.224221972254, 6208.2239720034995, 6294.7482100715915, 6312.968435157794, 6328.398802113792, 6334.113224148577, 6340.270868850095, 6351.618902865843, 6044.063170208625, 6055.182550355734, 6057.8485537861025, 6060.636361818261, 6074.0, 6289.811305660837, 6314.557980941382, 6385.616862988236, 6417.743290268337, 6427.2929977702415, 6168.1056246843955, 6207.503399728011, 6236.532260080512, 6240.906386404207, 6247.562670613013, 6331.05034244867, 6350.396712312323, 6359.618422894641, 6365.077063825812, 6366.076918846741, 6155.045967874987, 6156.538259630456, 6165.722542485599, 6204.844878878064, 6210.099535567318, 5937.2578427696535, 6211.09939058843, 6225.533085018642, 6240.251169859646, 6244.219472565929, 5074.3657042869645, 5074.985002999341, 5078.111330517145, 5083.161278389099, 5095.955329157514, 4341.9131617367475, 4466.173757854807, 4510.391097201901, 4519.276915693474, 4553.817847286137, 5961.672108034099, 6117.296510901303, 6180.876382472416, 6211.2857021443115, 6218.284897236876, 6293.685315734195, 6334.5565810393755, 6353.68231588428, 6360.045993100979, 6360.7137678804565, 6035.215421995113, 6110.633361998294, 6111.083337499411, 6138.081874381997, 6155.230596175478, 5539.833804985857, 5561.332640083216, 5607.943920560824, 5608.439156084441, 5613.550915926716, 6549.083128362033, 6559.901601475982, 6573.474122070127, 6582.275949645631, 6584.1440612721, 6133.79375043651, 6217.7823776169425, 6219.875602488016, 6262.906056409066, 6327.240731112296, 6579.703913323816, 6588.110605068255, 6595.769148079794, 6605.240397354366, 6612.603243805387, 6264.59280146789, 6271.122042913905, 6273.466755325849, 6276.1213430118905, 6279.686015699105, 6121.087175196906, 6151.907721384094, 6177.505799536116, 6283.717232724447, 6319.431251187415, 6226.62640241587, 6245.094461303176, 6251.6874156292915, 6266.68666566679, 6274.74901003973, 5342.839714808562, 5619.8760247951395, 6255.186825712628, 6257.1240026395435, 6291.339409361951, 5973.372795856372, 6013.128096426081, 6081.594307187812, 6095.542834287535, 6255.624662520263, 6156.230471191101, 6173.59871608344, 6177.598456100339, 6192.907106393318, 6200.255969283715, 6214.223222097238, 6224.813255602339, 6227.221597300338, 6230.0, 6265.561410701207], "value": 5841.435700058595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.31600810840484, 505.77745791851606, 530.4191909858705, 542.5415523882319, 574.7614739882948, 37423.625763742355, 40119.197616047735, 40157.79921100394, 40441.0, 40873.79563102184, 40366.59633403665, 40510.0, 40885.182296354054, 40911.59088409115, 41112.38331425031, 9471.763205919855, 9636.951815240915, 9682.12860842524, 9684.322097453145, 9712.514374281258, 9581.377210481296, 9584.616615335379, 9664.985176556465, 9671.0, 9682.273829462783, 9593.376430532027, 9606.615735370577, 9655.710328690115, 9682.46746428946, 9682.806343873119, 9610.135087842058, 9643.276754243425, 9661.855072173923, 9716.61133554657, 9746.0, 9507.809843803154, 9546.570404331822, 9550.95224523873, 9670.0, 9693.806123877483, 39205.80397097996, 39316.41025384622, 39368.409473857915, 39502.81491555258, 39524.01189970245, 2755.875985580654, 2900.390917907221, 3436.295559410315, 3695.8521659133603, 4082.4896887888717, 29609.0, 30145.09564713062, 30429.391412171633, 30493.0, 31387.058388248388, 3266.6080070391704, 3333.2666813301034, 3372.038968893878, 3415.5389022481895, 3465.9480107798404, 504548.4317735237, 518474.4076279632, 521650.0, 524744.3762781161, 529375.0, 60017.39982600205, 62728.0, 62958.68520657459, 63237.0, 63358.732825343235, 65677.67161164164, 65762.01356979649, 65866.67066664636, 65982.0, 66180.66909665517, 16441.58896027585, 16446.095464749313, 16549.91725041367, 16625.3349866004, 16806.663866722596, 12190.939045304716, 12205.084618653636, 12221.938890305491, 12707.046971477177, 12707.237565746085, 16189.67620647604, 17900.271845410265, 18846.528836778914, 18977.810221897882, 19019.33432329862, 7785.571793551409, 7891.487053341514, 7977.60111994398, 8001.919980800118, 8047.597620118971, 839.546644811807, 853.568947681417, 917.5045475443186, 954.4034978138664, 1118.5917140243753, 29635.703642963294, 30064.849675751917, 30374.266316031662, 30541.847290763402, 30877.0, 1061.9734506637242, 1095.5124969388546, 1110.1673744691482, 1132.428123797477, 1159.7390587118, 12249.877501224873, 15839.049657020385, 15901.920490397473, 15908.0, 15981.041137531785, 634490.4826427607, 648303.0, 649552.0, 649799.751001242, 652061.0, 58095.709521452125, 58689.41310586925, 58689.706551467825, 58757.70621146867, 58947.70526147428, 1347.2455424962052, 1364.6149158604026, 1378.1386633354155, 1379.3861731529573, 1382.0740104130457, 29707.702922970493, 30343.393132137677, 30379.240518986317, 30748.231294217825, 31031.68968310288, 49846.0, 50117.99764004626, 50334.49665503298, 50468.49531504638, 50489.49510504848, 619980.0, 622554.0, 623024.7697522966, 625024.0, 625441.0, 26491.0, 26526.938922442885, 26732.866335668197, 26973.055943041898, 27221.86389068042, 46803.76598117124, 46821.0, 46841.85664918028, 47114.76442617765, 47621.0, 38379.65671201495, 38538.80730596329, 38603.0, 38834.834954951395, 39773.0056748572, 38523.0, 38984.220315594095, 39351.2129757409, 39562.2087558253, 39812.80093599513, 37219.25561488809, 37608.43587346247, 38157.427638586, 38269.80865095768, 38288.80855595704, 30540.694593054675, 33063.008109756745, 33959.32081358408, 34140.31719365648, 34750.0, 23350.416239594146, 25666.743332566435, 25668.486630267664, 25710.2002859798, 26061.957521698918], "value": 20149.10583633045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6644455211618, 380.5756581411726, 382.655609951044, 390.2156665103142, 392.811450503758, 16323.673526529463, 19097.14062867171, 20400.89799551004, 20404.591908161845, 20598.97005149744, 17833.732494012602, 17893.0, 18548.443546693587, 20355.796442035575, 20450.693239601416, 5325.227841962923, 5447.782088716446, 5507.449255074501, 5538.47384498474, 5727.34135574408, 5921.615095018831, 6057.091436284571, 6060.090986352038, 6066.0, 6073.997790364607, 3528.2414280929597, 3729.440583912408, 5832.562557808182, 6063.090536419529, 6183.598066125687, 5884.440978107115, 6084.908726369108, 6106.450419462248, 6120.663363515005, 6121.204243448346, 5925.407459254083, 5983.820485385445, 5987.161797348374, 5993.970030149865, 6000.789972350947, 13248.940084793194, 17642.823571764246, 19581.314653987043, 24349.391265218335, 24516.632250516257, 2232.1964092926532, 2317.3279748872806, 2354.693889794333, 2475.8762061897014, 2786.6795318538425, 10131.240156988255, 10145.137663298628, 10269.589216431408, 10632.734181645443, 10717.303375280582, 1886.311496303853, 1968.8621796474254, 1972.2998335590946, 1976.0021189299455, 1986.5331647062928, 119909.0, 119955.0, 124680.0, 124929.0, 127814.36092819569, 9627.663031793854, 11751.353675547756, 11836.17146799733, 11838.88161118395, 11963.940180299043, 14640.414383424544, 14686.118832870063, 15027.699446011018, 15037.248137593077, 15074.849251507563, 8332.208440198172, 8591.957040214758, 8617.009043959899, 8715.21563059315, 8754.86867696985, 8344.749657510285, 8406.075331713506, 8443.240108390151, 8494.70268540598, 8554.743357699279, 3995.880123596297, 4122.742563518096, 4229.217594744982, 4544.091181763661, 4692.577668009895, 1703.3271857616173, 2007.6687346587942, 2032.9695054574192, 2046.6009128219855, 2052.8049835265924, 344.55552337484863, 363.72514337248305, 383.90778235918765, 408.8139896347155, 413.2190160596475, 10372.377657340583, 11850.466728997217, 11890.464929078213, 11918.88081119195, 12104.455299511543, 432.1249469823632, 434.5046646822625, 435.29047652326506, 441.5628527757497, 441.67095513842395, 4550.340200670884, 6192.442680158807, 6210.099535567408, 6231.065340199007, 6321.0, 481952.0, 483833.0, 486292.0, 605968.0, 625663.0, 60777.0, 87033.0, 102600.46099308367, 107061.0, 151948.0, 850.0352100366129, 915.231205787142, 949.1694767078806, 1015.21320976244, 1038.4755698372273, 18407.53981150482, 18750.624987500447, 18987.62024759525, 19012.859228446338, 19077.855328680325, 55845.16232256438, 56376.71811640915, 56803.71598142148, 57457.13814292873, 58153.70923145357, 74020.0, 75694.0, 76436.23563764291, 79244.20755792368, 82850.342993141, 8087.191280872101, 8224.38317126206, 8319.209675080858, 8347.040090389683, 8428.704995325133, 11229.66311010671, 12686.302253376152, 12964.675883102998, 12980.15628984113, 13195.944324453829, 10324.690259292232, 10354.896451035393, 10701.946490267499, 11111.333320000826, 11156.0, 10859.402732849769, 11304.773904522028, 11345.092392608407, 11464.0, 11519.53921843117, 11476.655700329004, 11481.540738370371, 11545.653630391102, 11546.653600392001, 11626.244294120856, 8134.83730325402, 8287.461315014505, 8458.74623761288, 8470.49177049379, 8496.830063398575, 9300.581473833558, 11199.944000279946, 11205.831912521482, 11510.94244528772, 11668.533258669897], "value": 8722.364157776567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.4093133212384, 1533.1261181126758, 1601.8157911840142, 1698.057578044185, 1835.6695794756938, 7700.730474433396, 7941.0867750208745, 8193.426460147784, 8640.783980400493, 8665.480071195732, 8177.100518942912, 8417.4107812453, 8433.746987590366, 8449.78875528112, 8515.105913879032, 2010.5275260313815, 2380.119355838342, 2391.0, 2392.688950436441, 2394.437307232799, 2399.3521749127726, 2415.8308918375633, 2424.5635785558593, 2439.560879041772, 2449.4243852694603, 2370.5614461324626, 2376.489054853206, 2396.7483414241515, 2407.422218667516, 2409.77107174819, 2106.810387065164, 2294.2199652118275, 2324.523472688095, 2346.5658853112227, 2384.8688322142275, 2104.8000439958178, 2317.2468947591933, 2389.689340385739, 2419.7837703491987, 2427.453822889853, 8431.367647426407, 8472.025717042558, 8487.236148746642, 8586.22723954847, 8645.265152462049, 5121.719570107473, 5223.869403264949, 5294.993951149308, 5300.946990530084, 5301.443348448396, 3098.7211150996295, 3551.236484155893, 3583.587887392931, 3595.1191957970204, 3642.1623026704005, 1060.3796778884362, 1066.632011955874, 1107.778444311133, 1116.9162312826488, 1120.3501968858043, 10995.395253261067, 11069.889301106967, 11091.611793587186, 11092.944535277353, 11119.388433636146, 4518.977405112953, 4670.813167473331, 4731.195696731564, 4736.3369128322065, 4800.711957282574, 9609.615615375447, 9615.759606009906, 9620.37467564606, 9626.133647971716, 9653.90346096544, 4572.0, 5168.638195326291, 5203.765830537635, 5233.712145832016, 5245.396779370345, 4347.739135651871, 4358.498772641186, 4643.094596553639, 4739.052189562032, 4803.543663351976, 3840.4815349927962, 3856.2094770571966, 3964.7819369934355, 3968.6428221460205, 3990.1421194442755, 1274.2290913997083, 1280.2510531339085, 1297.6885547468728, 1298.3638017371609, 1306.882380585752, 792.9405294602927, 831.6964308027526, 872.1757938747949, 917.064594114, 926.7544100813155, 5771.394003629643, 5853.619514731528, 5893.11603259506, 5946.970265148647, 6073.30157031947, 439.18969501270743, 444.6820523325773, 462.5536357415111, 463.46932761987335, 467.25239616613146, 3882.1265215326325, 3893.785841778673, 3964.6431821136234, 4016.116454380032, 4044.272031034442, 9872.40765554069, 10072.748181295527, 10131.594736210616, 10216.489175541194, 10237.385756854612, 8455.70405035833, 9897.80204395908, 10039.69880903574, 10128.088472037552, 10149.594016239416, 7862.253085956825, 7866.252705992922, 7898.328642065374, 7925.960370198112, 7997.560134192675, 5506.33923929115, 5576.275084239023, 5622.971885140548, 5735.541156707537, 5770.567207459373, 9222.44665320083, 9286.30352723535, 9377.859332109887, 9445.0, 9472.905270947202, 8153.388495862715, 9103.726888193365, 9436.811263774824, 9520.714378568655, 9573.952130239582, 7110.32451917067, 7129.893051604334, 7176.569405835667, 7217.206107328085, 7242.601656908921, 7821.374290056668, 7839.960800195962, 7922.7227047053075, 7927.088384835818, 7929.920700792918, 6019.909701354571, 7070.186928503288, 7167.928320716726, 7200.315969982843, 7339.522931009467, 6012.549058820518, 6296.835085509196, 7054.858902821812, 7078.93815927615, 7083.787486375417, 6584.670766461562, 6650.467962562887, 6716.3955244028275, 6742.831429214309, 6763.864722705617, 4927.975360123177, 4965.45380008206, 4971.204607262821, 4995.275685025724, 5014.648974571745, 4794.640401969797, 4971.527704868032, 4971.676841005323, 5060.1650727631, 5069.214271787879], "value": 4380.947942338919, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.4667362001767, 309.3719748909713, 314.1862575928346, 320.92297848516347, 327.6723276723277, 5054.570361519271, 5187.922181167276, 5190.662606930551, 5239.594263728573, 5524.1989911462815, 5173.560247378968, 5478.767277362592, 5623.184638227454, 5663.207150998864, 5665.008623490883, 4298.634616057631, 4316.870493885172, 4318.330658747899, 4334.284843000901, 4334.523202447728, 3781.9810900945595, 4176.519700234481, 4265.31754919213, 4296.806643701041, 4319.956800431987, 4197.349410841309, 4197.664186865043, 4201.810918508674, 4211.115665710205, 4227.4927008759, 3384.7969121852766, 4227.682923780729, 4235.512916014667, 4245.426867372896, 4249.3413520904305, 3581.7105841896896, 3603.387424137903, 3726.2361215950937, 3748.5689145748315, 3789.559967212469, 4491.640668746525, 5355.571554275688, 5366.007288651612, 5487.780488780444, 5599.188117722948, 3957.5448823385104, 4584.426946631671, 5002.824901128443, 5052.545270925634, 5065.873353166164, 5004.549590536869, 5691.0365923756235, 5712.514436272922, 5935.287765468128, 5980.910286345708, 3837.424386342043, 4071.9209409506507, 4167.437211045858, 4167.978845182919, 4194.559571245006, 5531.917021244684, 6046.637201767864, 6303.2325422239055, 6378.362163783585, 6397.584157029824, 4036.3138266494766, 4215.810288537024, 4217.536071032184, 4221.535631080578, 4259.361095835649, 3321.983390083034, 3416.8974930752115, 4033.515978082649, 4279.16556271524, 4304.491839151082, 4364.454443194601, 4720.984988227548, 4885.462599114095, 4973.303737476756, 4994.725290109006, 5506.650870536744, 5578.493806672176, 5696.715164241771, 5999.670018149044, 6120.755169793248, 1286.8841804237663, 1368.3500337339683, 1384.9099808512415, 1413.8939579531577, 1415.6673181802269, 782.9217078292126, 832.488019867777, 893.3032234856822, 931.9534023298808, 952.5380190607689, 4618.745968971672, 5045.47022562633, 5394.572442400935, 5450.511672529584, 5477.699366266178, 4955.40535135786, 5135.512126347991, 5652.7173641318595, 5894.970525147432, 5983.670898100646, 5322.361316642027, 5398.595105367113, 5483.369412517532, 5507.449255074541, 5687.7156142192725, 3044.512877939519, 3102.8293443860803, 3139.152428844199, 3141.6701246368802, 3154.479510880679, 4938.320970869921, 5821.79623713168, 5947.672877991752, 6286.308506064329, 6364.554481186273, 5592.608517403824, 5994.880102397928, 6202.56582039262, 6308.558400911892, 6416.294207637156, 5672.744726487318, 6391.42477177047, 6397.200349956255, 6489.513286503437, 6515.478761698958, 5537.771129394166, 5804.506616937608, 6090.847728806638, 6174.487250623446, 6216.72024758896, 5102.4642412546145, 5496.395396506301, 6084.482818960261, 6093.725782339894, 6124.969375153096, 5682.721387687737, 5728.742206600629, 6122.877542449037, 6131.908021379772, 6407.967960160169, 4037.5962403760564, 4041.1917616476235, 4056.0, 4059.127287633173, 4075.5924407559005, 5897.882042359214, 6032.034874420072, 6055.397897597769, 6223.844403889939, 6294.968525157345, 4548.135854187693, 5769.653820770767, 5931.5847890648965, 5970.910436343545, 6036.456718895321, 4857.9028419432125, 6003.339632640494, 6014.368491308524, 6044.75820967174, 6084.665343406155, 4934.55588996992, 5758.510526605285, 5852.531797456279, 5891.23413956183, 5949.8215053548465, 5302.083853880427, 5643.88712225745, 6001.399860013964, 6085.69571521431, 6138.815835524942, 5622.746976385991, 5900.321463031806, 5925.496332811759, 5930.644161350333, 5998.920733900989], "value": 4312.728674841819, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.664167916042, 759.4304271796153, 785.8821176823476, 801.1547817053007, 824.060570949118, 11241.325520468778, 13921.37353819078, 14497.420103195873, 15184.088954662715, 15219.923900380496, 14547.78178327326, 14959.326830292635, 15175.924120379383, 15330.386784528633, 15468.149251791147, 816.2612835383973, 836.5022811427191, 845.023997283139, 845.4082142500243, 848.0840692052568, 823.3948048184579, 843.9789005274856, 851.7742798158492, 852.9402941794077, 855.1533981358473, 883.7525492861977, 899.33449247557, 900.1043961258557, 901.1214066285352, 905.1491597897988, 885.3315746611299, 894.2354287084514, 896.7354630384052, 899.5906862377602, 900.2527901841488, 655.7704803318848, 658.8912829383146, 666.6000399760154, 667.0594461808874, 667.4160109903834, 12075.456604452733, 14492.34784434703, 14757.77863332051, 14877.776833347509, 14995.475158369405, 13401.0, 13846.723065538632, 13958.651033724136, 13961.930190348983, 13964.441422343094, 11622.651320460314, 11708.063354931584, 11857.347845868473, 11891.286522808572, 11931.105167112415, 3607.8556857725657, 3724.3854763963914, 3790.431435284702, 3807.447920051577, 3906.433567132778, 78349.0, 95350.52324738332, 97836.51081744545, 98378.508107459, 99254.0074599252, 10637.521311540999, 10655.467226638792, 10764.677059688222, 10863.565457381774, 10924.126069914388, 17843.37548185807, 20827.58344833125, 20897.686534701992, 20959.161633534794, 21094.683579746317, 7238.27617238272, 7678.270564296383, 8060.879086813702, 8176.713815016565, 8199.467034642848, 4321.48142222929, 4460.174867649431, 4549.408576884984, 4572.565606267399, 4620.561046700558, 3462.8961131166106, 3538.469229615578, 3576.5886923004186, 3581.767185132958, 3593.2094939113354, 650.3593959949446, 650.8665723526666, 668.6122049211543, 695.9408450281783, 704.2007321689919, 591.7159763313633, 618.0142672437455, 618.9566730328834, 677.9830504237434, 691.920429150644, 8963.238124759338, 9423.811523769486, 9447.385919915183, 9533.809323813484, 9624.133827955378, 560.1289994059196, 562.445990699161, 572.1874937588634, 696.8327601375634, 714.2500374606747, 4317.373980772833, 4526.253168227206, 4570.451545814523, 4633.3500131291385, 4776.331313616097, 71856.0, 77526.4494710114, 100139.0, 103233.0, 106387.40418893723, 94203.11593768223, 95122.0, 96708.0, 98095.0, 98856.01143988612, 79441.99999999885, 80373.0, 80727.59636201781, 87872.56063719641, 89111.0, 8522.36082293818, 9835.0, 9921.851172232567, 10028.097471227624, 10120.645777398051, 52680.0, 52839.0, 53707.462925370244, 55575.444245557024, 56634.15048774354, 71141.28858711346, 76114.61942690251, 79218.0, 80001.79997299929, 80252.0, 10767.838482422614, 12638.988880889323, 12737.55418560346, 12900.8064879025, 12912.677183070498, 13254.469821207043, 13345.266010369523, 13425.597232083053, 13597.252151131783, 13688.247146406646, 13360.665983350495, 13409.463621455423, 13431.597052088453, 14211.715765684836, 14332.211728355041, 13262.867371326549, 13613.251271179784, 13668.0, 14257.928710356382, 14280.500182493563, 13509.581493943388, 13579.660508486972, 13963.30183490801, 14126.576202713935, 14179.64550886236, 11145.331280123219, 11293.491792869176, 11443.713907152054, 11513.769724605294, 11853.822192667289, 11385.602586684443, 11518.220435937457, 11636.709082273012, 11669.183157178915, 11943.880561194625], "value": 7476.868908377002, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 119.0, 115.0, 116.0, 118.0, 117.0, 116.0, 114.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 110.0, 140.0, 110.0, 109.0, 106.0, 111.0, 158.0, 138.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 201.0, 260.0, 277.0, 194.0, 198.0, 193.0, 202.0, 194.0, 194.0], "value": 211.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 59.0, 97.0, 81.0, 82.0, 81.0, 57.0, 75.0, 53.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 88.0, 73.0, 69.0, 71.0, 85.0, 69.0, 70.0, 86.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 84.0, 88.0, 86.0, 95.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 97.0, 123.0, 147.0, 122.0, 96.0, 96.0, 94.0, 95.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 86.0, 98.0, 124.0, 88.0, 94.0, 94.0, 87.0, 88.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 57.0, 64.0, 85.0, 84.0, 49.0, 53.0, 75.0, 84.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 78.0, 76.0, 72.0, 68.0, 68.0, 69.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 82.0, 83.0, 84.0, 86.0, 82.0, 84.0, 84.0, 84.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 129.0, 159.0, 133.0, 166.0, 186.0, 162.0, 154.0, 187.0, 160.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 189.0, 220.0, 169.0, 160.0, 219.0, 214.0, 177.0, 166.0, 163.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 87.0, 85.0, 86.0, 64.0, 65.0, 66.0, 67.0], "value": 72.3, "unit": "score"}]}]} 10:13:07 INFO - Return code: 0 10:13:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:13:07 ERROR - # TBPL SUCCESS # 10:13:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:13:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:13:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:13:07 INFO - 2017-02-10 10:13:07.887 screenresolution[1658:10731] starting screenresolution argv=screenresolution get 10:13:07 INFO - 2017-02-10 10:13:07.890 screenresolution[1658:10731] Display 0: 1600x1200x32@60 10:13:07 INFO - 2017-02-10 10:13:07.896 screenresolution[1659:10734] starting screenresolution argv=screenresolution list 10:13:07 INFO - Available Modes on Display 0 10:13:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:13:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:13:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:13:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:08 INFO - Intel Iris: 10:13:08 INFO - Chipset Model: Intel Iris 10:13:08 INFO - Type: GPU 10:13:08 INFO - Bus: Built-In 10:13:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:08 INFO - Vendor: Intel (0x8086) 10:13:08 INFO - Device ID: 0x0a2e 10:13:08 INFO - Revision ID: 0x0009 10:13:08 INFO - Displays: 10:13:08 INFO - Display: 10:13:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:08 INFO - Display Serial Number: 1600x1200 60 10:13:08 INFO - Main Display: Yes 10:13:08 INFO - Mirror: Off 10:13:08 INFO - Online: Yes 10:13:08 INFO - Rotation: Supported 10:13:08 INFO - Return code: 0 10:13:08 INFO - Running post-action listener: _resource_record_post_action 10:13:08 INFO - [mozharness: 2017-02-10 18:13:08.133371Z] Finished run-tests step (success) 10:13:08 INFO - Running post-run listener: _resource_record_post_run 10:13:08 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39340032; Write bytes: 504457728; Read time: 246; Write time: 1616 10:13:08 INFO - TinderboxPrint: CPU usage
54.3% 10:13:08 INFO - TinderboxPrint: I/O read bytes / time
39,340,032 / 246 10:13:08 INFO - TinderboxPrint: I/O write bytes / time
504,457,728 / 1,616 10:13:08 INFO - TinderboxPrint: CPU idle
2,184.6 (45.7%) 10:13:08 INFO - TinderboxPrint: CPU system
970.1 (20.3%) 10:13:08 INFO - TinderboxPrint: CPU user
1,627.0 (34.0%) 10:13:08 INFO - TinderboxPrint: Swap in / out
231,174,144 / 0 10:13:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 186593792; Write bytes: 186360320; Read time: 16817; Write time: 402 10:13:08 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 37435392; Write bytes: 308613632; Read time: 230; Write time: 1176 10:13:08 INFO - Running post-run listener: _upload_blobber_files 10:13:08 INFO - Blob upload gear active. 10:13:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:13:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:13:08 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:13:08 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:13:09 INFO - (blobuploader) - INFO - Open directory for files ... 10:13:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:13:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:13:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:09 INFO - (blobuploader) - INFO - Done attempting. 10:13:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:13:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:13:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:10 INFO - (blobuploader) - INFO - Done attempting. 10:13:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:13:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:12 INFO - (blobuploader) - INFO - Done attempting. 10:13:12 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:12 INFO - Return code: 0 10:13:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b2504a979f4980f7cfc7d6dcfbee351642b652e3c82840d8d310715074367b2996349f797608b64750aab5cc4ea07c09170b27f4a4b71d397cc010467857e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc8f063066a4b1114325e73573551b7a5021ee7e424e90e6e72244c913a53f938336641d88a2a2b7b306e2d7291547a9f87203d93f2cf7dd8c48276bc351e448", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04162d435d637e6fb5e9825ce62e6c2cbb6ecc9ece75cfb55f181bb245bd4f31b093a50bccfeb4a6418d88584de2ca5886d818c5d0e663d9acd78092d6783b05"} 10:13:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:12 INFO - Contents: 10:13:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b2504a979f4980f7cfc7d6dcfbee351642b652e3c82840d8d310715074367b2996349f797608b64750aab5cc4ea07c09170b27f4a4b71d397cc010467857e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc8f063066a4b1114325e73573551b7a5021ee7e424e90e6e72244c913a53f938336641d88a2a2b7b306e2d7291547a9f87203d93f2cf7dd8c48276bc351e448", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04162d435d637e6fb5e9825ce62e6c2cbb6ecc9ece75cfb55f181bb245bd4f31b093a50bccfeb4a6418d88584de2ca5886d818c5d0e663d9acd78092d6783b05"} 10:13:12 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:12 INFO - Copying logs to upload dir... 10:13:12 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.277713 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-10 10:13:12.919619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 10:13:12.923305) ========= 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.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b2504a979f4980f7cfc7d6dcfbee351642b652e3c82840d8d310715074367b2996349f797608b64750aab5cc4ea07c09170b27f4a4b71d397cc010467857e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc8f063066a4b1114325e73573551b7a5021ee7e424e90e6e72244c913a53f938336641d88a2a2b7b306e2d7291547a9f87203d93f2cf7dd8c48276bc351e448", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04162d435d637e6fb5e9825ce62e6c2cbb6ecc9ece75cfb55f181bb245bd4f31b093a50bccfeb4a6418d88584de2ca5886d818c5d0e663d9acd78092d6783b05"} build_url:https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/c8nOjYmnTxKBpx_xISZGvQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b2504a979f4980f7cfc7d6dcfbee351642b652e3c82840d8d310715074367b2996349f797608b64750aab5cc4ea07c09170b27f4a4b71d397cc010467857e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc8f063066a4b1114325e73573551b7a5021ee7e424e90e6e72244c913a53f938336641d88a2a2b7b306e2d7291547a9f87203d93f2cf7dd8c48276bc351e448", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04162d435d637e6fb5e9825ce62e6c2cbb6ecc9ece75cfb55f181bb245bd4f31b093a50bccfeb4a6418d88584de2ca5886d818c5d0e663d9acd78092d6783b05"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 10:13:12.957260) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 10:13:12.957610) ========= 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.YktiuUuPrb/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.NksFLBpT4S/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 10:13:13.006771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 10:13:13.007103) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 10:13:13.007561) ========= ========= Total master_lag: 0.19 =========