builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1476789492.19 results: success (0) buildid: 20161018013948 builduid: b23bebc0b8db44b5b4ff2aea4e592213 revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.195119) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.195588) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.196328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.226758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.227179) ========= 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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-18 04:18:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2016-10-18 04:18:12 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.414962) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.415284) ========= 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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.026043 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.464464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:12.467983) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-18 04:18:12,541 truncating revision to first 12 chars 2016-10-18 04:18:12,541 Setting DEBUG logging. 2016-10-18 04:18:12,541 attempt 1/10 2016-10-18 04:18:12,541 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 04:18:13,238 unpacking tar archive at: mozilla-central-dc89484d4b45/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:13.442135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:13.442569) ========= script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:13.443081) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:13.443398) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 04:18:13.465531) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-18 04:18:13.465918) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:13 INFO - MultiFileLogger online at 20161018 04:18:13 in /builds/slave/test 04:18:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:18:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:18:13 INFO - {'append_to_log': False, 04:18:13 INFO - 'base_work_dir': '/builds/slave/test', 04:18:13 INFO - 'blob_upload_branch': 'Firefox', 04:18:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:18:13 INFO - 'branch': 'Firefox', 04:18:13 INFO - 'buildbot_json_path': 'buildprops.json', 04:18:13 INFO - 'config_files': ('talos/mac_config.py',), 04:18:13 INFO - 'default_actions': ('clobber', 04:18:13 INFO - 'read-buildbot-config', 04:18:13 INFO - 'download-and-extract', 04:18:13 INFO - 'populate-webroot', 04:18:13 INFO - 'create-virtualenv', 04:18:13 INFO - 'install', 04:18:13 INFO - 'run-tests'), 04:18:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:18:13 INFO - 'download_minidump_stackwalk': True, 04:18:13 INFO - 'download_symbols': 'ondemand', 04:18:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:18:13 INFO - 'tooltool.py': '/tools/tooltool.py', 04:18:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:18:13 INFO - '/tools/misc-python/virtualenv.py')}, 04:18:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:18:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:18:13 INFO - 'installer_path': 'installer.exe', 04:18:13 INFO - 'log_level': 'info', 04:18:13 INFO - 'log_name': 'talos', 04:18:13 INFO - 'log_to_console': True, 04:18:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:18:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:18:13 INFO - 'opt_config_files': (), 04:18:13 INFO - 'pip_index': False, 04:18:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:13 INFO - 'cmd': ('bash', 04:18:13 INFO - '-c', 04:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:13 INFO - 'enabled': True, 04:18:13 INFO - 'halt_on_failure': False, 04:18:13 INFO - 'name': 'check_screen_resolution'},), 04:18:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:13 INFO - 'cmd': ('bash', 04:18:13 INFO - '-c', 04:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:13 INFO - 'enabled': True, 04:18:13 INFO - 'halt_on_failure': False, 04:18:13 INFO - 'name': 'check_screen_resolution'},), 04:18:13 INFO - 'run_cmd_checks_enabled': True, 04:18:13 INFO - 'sps_profile': False, 04:18:13 INFO - 'sps_profile_interval': 0, 04:18:13 INFO - 'suite': 'dromaeojs-e10s', 04:18:13 INFO - 'system_bits': '32', 04:18:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:18:13 INFO - 'title': 't-yosemite-r7-0086', 04:18:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:18:13 INFO - 'use_talos_json': True, 04:18:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:18:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:18:13 INFO - 'work_dir': 'build'} 04:18:13 INFO - [mozharness: 2016-10-18 11:18:13.666550Z] Running clobber step. 04:18:13 INFO - Running pre-action listener: _resource_record_pre_action 04:18:13 INFO - Running main action method: clobber 04:18:13 INFO - rmtree: /builds/slave/test/build 04:18:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:18:15 INFO - Running post-action listener: _resource_record_post_action 04:18:15 INFO - [mozharness: 2016-10-18 11:18:15.826080Z] Finished clobber step (success) 04:18:15 INFO - [mozharness: 2016-10-18 11:18:15.826202Z] Running read-buildbot-config step. 04:18:15 INFO - Running pre-action listener: _resource_record_pre_action 04:18:15 INFO - Running main action method: read_buildbot_config 04:18:15 INFO - Using buildbot properties: 04:18:15 INFO - { 04:18:15 INFO - "project": "", 04:18:15 INFO - "product": "firefox", 04:18:15 INFO - "script_repo_revision": "production", 04:18:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:18:15 INFO - "repository": "", 04:18:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:18:15 INFO - "buildid": "20161018013948", 04:18:15 INFO - "pgo_build": "False", 04:18:15 INFO - "basedir": "/builds/slave/test", 04:18:15 INFO - "buildnumber": 12, 04:18:15 INFO - "slavename": "t-yosemite-r7-0086", 04:18:15 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:18:15 INFO - "platform": "yosemite_r7", 04:18:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:18:15 INFO - "branch": "mozilla-central", 04:18:15 INFO - "repo_path": "mozilla-central", 04:18:15 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d", 04:18:15 INFO - "stage_platform": "macosx64", 04:18:15 INFO - "builduid": "b23bebc0b8db44b5b4ff2aea4e592213", 04:18:15 INFO - "slavebuilddir": "test" 04:18:15 INFO - } 04:18:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:18:15 INFO - Running post-action listener: _resource_record_post_action 04:18:15 INFO - [mozharness: 2016-10-18 11:18:15.829617Z] Finished read-buildbot-config step (success) 04:18:15 INFO - [mozharness: 2016-10-18 11:18:15.829742Z] Running download-and-extract step. 04:18:15 INFO - Running pre-action listener: _resource_record_pre_action 04:18:15 INFO - Running main action method: download_and_extract 04:18:15 INFO - mkdir: /builds/slave/test/build/tests 04:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:15 INFO - trying https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:18:16 INFO - Downloaded 1199 bytes. 04:18:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:18:16 INFO - Using the following test package requirements: 04:18:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:18:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:18:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:18:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'jsshell-mac.zip'], 04:18:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:18:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:18:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:18:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:18:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:18:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:18:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:18:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:18:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:18:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:18:16 INFO - Fetch https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:18:17 INFO - Http code: 200 04:18:17 INFO - via: 1.1 aa89533ad2ec5e0edba466c9920bd000.cloudfront.net (CloudFront) 04:18:17 INFO - x-amz-cf-id: FRA1I_4bv__QtX-VXQq0zwzQHnRMeUz6e4of_WpQglEWgRr34eCK_w== 04:18:17 INFO - x-amz-version-id: r9trN7B8ZF7vURSzSQBZ3CPZ_rth4Hhn 04:18:17 INFO - Expected file size: 16483715 04:18:17 INFO - Obtained file size: 16483715 04:18:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:18:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:18:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:18:24 INFO - Fetch https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:18:24 INFO - Http code: 200 04:18:24 INFO - via: 1.1 6eaa7f856e226a0db7cef6201d3b8393.cloudfront.net (CloudFront) 04:18:24 INFO - x-amz-cf-id: dqFYn1mdfyFwkWU-AxX42m-bw785VoaRnnK3gq0CgyeJiFci_Kclrw== 04:18:24 INFO - x-amz-version-id: r9trN7B8ZF7vURSzSQBZ3CPZ_rth4Hhn 04:18:25 INFO - Expected file size: 16483715 04:18:25 INFO - Obtained file size: 16483715 04:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:18:32 INFO - Http code: 200 04:18:32 INFO - via: 1.1 8e86372eaaa62fc64b70e7bcde725dd9.cloudfront.net (CloudFront) 04:18:32 INFO - x-amz-cf-id: TpuceKLB0tpChdsxXEX7kZ60DR2I96LjCJ3tJy9GXWNN6s_pCTjFyg== 04:18:32 INFO - x-amz-version-id: uklBvolccHBogk7Bz_f4h8LXV50t__pe 04:18:32 INFO - Expected file size: 14409028 04:18:32 INFO - Obtained file size: 14409028 04:18: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')]} 04:18:33 INFO - trying https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:18:36 INFO - Downloaded 111624046 bytes. 04:18:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:18:36 INFO - mkdir: /builds/slave/test/properties 04:18:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:18:36 INFO - Writing to file /builds/slave/test/properties/build_url 04:18:36 INFO - Contents: 04:18:36 INFO - build_url:https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:18:36 INFO - Running post-action listener: _resource_record_post_action 04:18:36 INFO - Running post-action listener: set_extra_try_arguments 04:18:36 INFO - [mozharness: 2016-10-18 11:18:36.714588Z] Finished download-and-extract step (success) 04:18:36 INFO - [mozharness: 2016-10-18 11:18:36.714732Z] Running populate-webroot step. 04:18:36 INFO - Running pre-action listener: _resource_record_pre_action 04:18:36 INFO - Running main action method: populate_webroot 04:18:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:18:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:18:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:18:36 INFO - '--tppagecycles', 04:18:36 INFO - '7'], 04:18:36 INFO - 'tests': ['tsvgm']}}, 04:18:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:18:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:18:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:18:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:18:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['damp', 'tps']}, 04:18:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'tests': ['damp', 'tps']}, 04:18:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['dromaeo_dom']}, 04:18:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:18:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['basic_compositor_video']}, 04:18:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:18:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['a11yr', 04:18:36 INFO - 'ts_paint', 04:18:36 INFO - 'tpaint', 04:18:36 INFO - 'sessionrestore', 04:18:36 INFO - 'sessionrestore_no_auto_restore', 04:18:36 INFO - 'tabpaint']}, 04:18:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['tsvgx', 04:18:36 INFO - 'tsvgr_opacity', 04:18:36 INFO - 'tart', 04:18:36 INFO - 'tscrollx', 04:18:36 INFO - 'cart']}, 04:18:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:18:36 INFO - 'tsvgr_opacity', 04:18:36 INFO - 'tart', 04:18:36 INFO - 'tscrollx', 04:18:36 INFO - 'cart']}, 04:18:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'talos_options': ['--disable-e10s'], 04:18:36 INFO - 'tests': ['tp5o']}, 04:18:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'tests': ['tp5o']}, 04:18:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'talos_options': ['--disable-e10s', 04:18:36 INFO - '--xperf_path', 04:18:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:36 INFO - 'tests': ['tp5n']}, 04:18:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:36 INFO - 'talos_options': ['--xperf_path', 04:18:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:36 INFO - 'tests': ['tp5n']}}, 04:18:36 INFO - 'talos.zip': {'path': '', 04:18:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:18:36 INFO - Running post-action listener: _resource_record_post_action 04:18:36 INFO - [mozharness: 2016-10-18 11:18:36.733383Z] Finished populate-webroot step (success) 04:18:36 INFO - [mozharness: 2016-10-18 11:18:36.733514Z] Running create-virtualenv step. 04:18:36 INFO - Running pre-action listener: _resource_record_pre_action 04:18:36 INFO - Running main action method: create_virtualenv 04:18:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:18:36 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 04:18:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:18:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:18:36 INFO - Using real prefix '/tools/python27' 04:18:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:18:37 INFO - Installing distribute.............................................................................................................................................................................................done. 04:18:40 INFO - Installing pip.................done. 04:18:40 INFO - Return code: 0 04:18:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:18:40 INFO - Reading from file tmpfile_stdout 04:18:40 INFO - Output received: 04:18:40 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) 04:18:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:18:40 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')]} 04:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:40 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')]} 04:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:40 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:40 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 04:18:40 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 04:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:40 INFO - 'HOME': '/Users/cltbld', 04:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:40 INFO - 'LOGNAME': 'cltbld', 04:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:40 INFO - 'MOZ_NO_REMOTE': '1', 04:18:40 INFO - 'NO_EM_RESTART': '1', 04:18:40 INFO - 'PAGER': '/bin/cat', 04:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:40 INFO - 'PWD': '/builds/slave/test', 04:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:40 INFO - 'SHELL': '/bin/bash', 04:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:40 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:40 INFO - 'USER': 'cltbld', 04:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:40 INFO - 'XPC_FLAGS': '0x0', 04:18:40 INFO - 'XPC_SERVICE_NAME': '0', 04:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:40 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 04:18:40 INFO - Cleaning up... 04:18:40 INFO - Return code: 0 04:18:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:18:40 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')]} 04:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:40 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')]} 04:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:40 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:40 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 04:18:40 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 04:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:40 INFO - 'HOME': '/Users/cltbld', 04:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:40 INFO - 'LOGNAME': 'cltbld', 04:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:40 INFO - 'MOZ_NO_REMOTE': '1', 04:18:40 INFO - 'NO_EM_RESTART': '1', 04:18:40 INFO - 'PAGER': '/bin/cat', 04:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:40 INFO - 'PWD': '/builds/slave/test', 04:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:40 INFO - 'SHELL': '/bin/bash', 04:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:40 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:40 INFO - 'USER': 'cltbld', 04:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:40 INFO - 'XPC_FLAGS': '0x0', 04:18:40 INFO - 'XPC_SERVICE_NAME': '0', 04:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:41 INFO - Downloading/unpacking psutil>=3.1.1 04:18:41 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 04:18:41 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 04:18:41 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 04:18:41 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 04:18:41 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 04:18:41 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 04:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:18:44 INFO - Installing collected packages: psutil 04:18:44 INFO - Running setup.py install for psutil 04:18:44 INFO - building 'psutil._psutil_osx' extension 04:18:44 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 04:18:45 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 04:18:45 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 04:18:45 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 04:18:45 INFO - building 'psutil._psutil_posix' extension 04:18:45 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 04:18:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:18:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:18:45 INFO - ^ 04:18:45 INFO - 1 warning generated. 04:18:45 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 04:18:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:18:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:18:45 INFO - Successfully installed psutil 04:18:45 INFO - Cleaning up... 04:18:45 INFO - Return code: 0 04:18:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:45 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:45 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 04:18:45 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 04:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:45 INFO - 'HOME': '/Users/cltbld', 04:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:45 INFO - 'LOGNAME': 'cltbld', 04:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:45 INFO - 'MOZ_NO_REMOTE': '1', 04:18:45 INFO - 'NO_EM_RESTART': '1', 04:18:45 INFO - 'PAGER': '/bin/cat', 04:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:45 INFO - 'PWD': '/builds/slave/test', 04:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:45 INFO - 'SHELL': '/bin/bash', 04:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:45 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:45 INFO - 'USER': 'cltbld', 04:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:45 INFO - 'XPC_FLAGS': '0x0', 04:18:45 INFO - 'XPC_SERVICE_NAME': '0', 04:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:18:46 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 04:18:46 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 04:18:46 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 04:18:46 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 04:18:46 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 04:18:46 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 04:18:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:18:48 INFO - Installing collected packages: mozsystemmonitor 04:18:48 INFO - Running setup.py install for mozsystemmonitor 04:18:48 INFO - Successfully installed mozsystemmonitor 04:18:48 INFO - Cleaning up... 04:18:48 INFO - Return code: 0 04:18:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:18:48 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')]} 04:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:48 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')]} 04:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:48 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:48 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 04:18:48 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 04:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:48 INFO - 'HOME': '/Users/cltbld', 04:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:48 INFO - 'LOGNAME': 'cltbld', 04:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:48 INFO - 'MOZ_NO_REMOTE': '1', 04:18:48 INFO - 'NO_EM_RESTART': '1', 04:18:48 INFO - 'PAGER': '/bin/cat', 04:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:48 INFO - 'PWD': '/builds/slave/test', 04:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:48 INFO - 'SHELL': '/bin/bash', 04:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:48 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:48 INFO - 'USER': 'cltbld', 04:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:48 INFO - 'XPC_FLAGS': '0x0', 04:18:48 INFO - 'XPC_SERVICE_NAME': '0', 04:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:49 INFO - Downloading/unpacking jsonschema==2.5.1 04:18:49 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 04:18:49 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 04:18:49 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 04:18:49 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 04:18:49 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 04:18:49 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 04:18:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:18:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18:52 INFO - Downloading functools32-3.2.3-2.tar.gz 04:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:18:52 INFO - warning: no files found matching '*.txt' 04:18:52 INFO - no previously-included directories found matching 'build' 04:18:52 INFO - no previously-included directories found matching 'dist' 04:18:52 INFO - no previously-included directories found matching '.git*' 04:18:52 INFO - Installing collected packages: jsonschema, functools32 04:18:52 INFO - Running setup.py install for functools32 04:18:52 INFO - warning: no files found matching '*.txt' 04:18:52 INFO - no previously-included directories found matching 'build' 04:18:52 INFO - no previously-included directories found matching 'dist' 04:18:52 INFO - no previously-included directories found matching '.git*' 04:18:52 INFO - Successfully installed jsonschema functools32 04:18:52 INFO - Cleaning up... 04:18:52 INFO - Return code: 0 04:18:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:18:52 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')]} 04:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:52 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')]} 04:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:52 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:52 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 04:18:52 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 04:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:52 INFO - 'HOME': '/Users/cltbld', 04:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:52 INFO - 'LOGNAME': 'cltbld', 04:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:52 INFO - 'MOZ_NO_REMOTE': '1', 04:18:52 INFO - 'NO_EM_RESTART': '1', 04:18:52 INFO - 'PAGER': '/bin/cat', 04:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:52 INFO - 'PWD': '/builds/slave/test', 04:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:52 INFO - 'SHELL': '/bin/bash', 04:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:52 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:52 INFO - 'USER': 'cltbld', 04:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:52 INFO - 'XPC_FLAGS': '0x0', 04:18:52 INFO - 'XPC_SERVICE_NAME': '0', 04:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:18:52 INFO - Cleaning up... 04:18:52 INFO - Return code: 0 04:18:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:18:52 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')]} 04:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:52 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')]} 04:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:52 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:52 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 04:18:52 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 04:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:52 INFO - 'HOME': '/Users/cltbld', 04:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:52 INFO - 'LOGNAME': 'cltbld', 04:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:52 INFO - 'MOZ_NO_REMOTE': '1', 04:18:52 INFO - 'NO_EM_RESTART': '1', 04:18:52 INFO - 'PAGER': '/bin/cat', 04:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:52 INFO - 'PWD': '/builds/slave/test', 04:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:52 INFO - 'SHELL': '/bin/bash', 04:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:52 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:52 INFO - 'USER': 'cltbld', 04:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:52 INFO - 'XPC_FLAGS': '0x0', 04:18:52 INFO - 'XPC_SERVICE_NAME': '0', 04:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:53 INFO - Downloading/unpacking blobuploader==1.2.4 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18:55 INFO - Downloading blobuploader-1.2.4.tar.gz 04:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:18:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18: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 04:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:18:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:56 INFO - Downloading docopt-0.6.1.tar.gz 04:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:18:56 INFO - Installing collected packages: blobuploader, requests, docopt 04:18:56 INFO - Running setup.py install for blobuploader 04:18:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:18:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:18:56 INFO - Running setup.py install for requests 04:18:57 INFO - Running setup.py install for docopt 04:18:57 INFO - Successfully installed blobuploader requests docopt 04:18:57 INFO - Cleaning up... 04:18:57 INFO - Return code: 0 04:18:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:57 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')]} 04:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 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')]} 04:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:18:57 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 04:18:57 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 04:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:57 INFO - 'HOME': '/Users/cltbld', 04:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:57 INFO - 'LOGNAME': 'cltbld', 04:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:57 INFO - 'MOZ_NO_REMOTE': '1', 04:18:57 INFO - 'NO_EM_RESTART': '1', 04:18:57 INFO - 'PAGER': '/bin/cat', 04:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:57 INFO - 'PWD': '/builds/slave/test', 04:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:57 INFO - 'SHELL': '/bin/bash', 04:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:18:57 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:57 INFO - 'USER': 'cltbld', 04:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:57 INFO - 'XPC_FLAGS': '0x0', 04:18:57 INFO - 'XPC_SERVICE_NAME': '0', 04:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_PU21D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Mle6E_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SM49Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MjVYn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-r8Zy2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3iBB7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-EgfWim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-0nFWNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-I4cmvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-8ZoVBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-eXVYBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PVKiC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-txIdag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-tPB5iK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sbjX7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3AfnXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:59 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-QzfW04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:18:59 INFO - Running setup.py install for manifestparser 04:18:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:18:59 INFO - Running setup.py install for mozcrash 04:18:59 INFO - Running setup.py install for mozdebug 04:19:00 INFO - Running setup.py install for mozdevice 04:19:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Running setup.py install for mozfile 04:19:00 INFO - Running setup.py install for mozhttpd 04:19:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Running setup.py install for mozinfo 04:19:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Running setup.py install for mozInstall 04:19:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:19:00 INFO - Running setup.py install for mozleak 04:19:00 INFO - Running setup.py install for mozlog 04:19:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Running setup.py install for moznetwork 04:19:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Running setup.py install for mozprocess 04:19:01 INFO - Running setup.py install for mozprofile 04:19:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Running setup.py install for mozrunner 04:19:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:19:01 INFO - Running setup.py install for mozscreenshot 04:19:01 INFO - Running setup.py install for moztest 04:19:02 INFO - Running setup.py install for mozversion 04:19:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:19:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:19:02 INFO - Cleaning up... 04:19:02 INFO - Return code: 0 04:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:02 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')]} 04:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:02 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')]} 04:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:02 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:19:02 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 04:19:02 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 04:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:02 INFO - 'HOME': '/Users/cltbld', 04:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:02 INFO - 'LOGNAME': 'cltbld', 04:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:02 INFO - 'MOZ_NO_REMOTE': '1', 04:19:02 INFO - 'NO_EM_RESTART': '1', 04:19:02 INFO - 'PAGER': '/bin/cat', 04:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:02 INFO - 'PWD': '/builds/slave/test', 04:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:02 INFO - 'SHELL': '/bin/bash', 04:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:19:02 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:02 INFO - 'USER': 'cltbld', 04:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:02 INFO - 'XPC_FLAGS': '0x0', 04:19:02 INFO - 'XPC_SERVICE_NAME': '0', 04:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-aANsKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:02 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)) 04:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-yt5NVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-iTQTi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:02 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)) 04:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Z_WQBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:02 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)) 04:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Byhq8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-57kXnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MijNYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pP81KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-CQH3xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pWxL9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-k1scbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-QBqKlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-8NGrrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:03 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)) 04:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:03 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-V4RGOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:04 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-NTyYZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:04 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)) 04:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:04 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pJLo2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:04 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3Wk7bK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:04 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)) 04:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:19:04 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)) 04:19:04 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)) 04:19:04 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)) 04:19:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:06 INFO - Downloading blessings-1.6.tar.gz 04:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:19:06 INFO - Installing collected packages: blessings 04:19:06 INFO - Running setup.py install for blessings 04:19:07 INFO - Successfully installed blessings 04:19:07 INFO - Cleaning up... 04:19:07 INFO - Return code: 0 04:19:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:07 INFO - Reading from file tmpfile_stdout 04:19:07 INFO - Current package versions: 04:19:07 INFO - blessings == 1.6 04:19:07 INFO - blobuploader == 1.2.4 04:19:07 INFO - docopt == 0.6.1 04:19:07 INFO - functools32 == 3.2.3-2 04:19:07 INFO - jsonschema == 2.5.1 04:19:07 INFO - manifestparser == 1.1 04:19:07 INFO - mozInstall == 1.12 04:19:07 INFO - mozcrash == 0.17 04:19:07 INFO - mozdebug == 0.1 04:19:07 INFO - mozdevice == 0.48 04:19:07 INFO - mozfile == 1.2 04:19:07 INFO - mozhttpd == 0.7 04:19:07 INFO - mozinfo == 0.9 04:19:07 INFO - mozleak == 0.1 04:19:07 INFO - mozlog == 3.4 04:19:07 INFO - moznetwork == 0.27 04:19:07 INFO - mozprocess == 0.23 04:19:07 INFO - mozprofile == 0.28 04:19:07 INFO - mozrunner == 6.12 04:19:07 INFO - mozscreenshot == 0.1 04:19:07 INFO - mozsystemmonitor == 0.3 04:19:07 INFO - moztest == 0.7 04:19:07 INFO - mozversion == 1.4 04:19:07 INFO - psutil == 3.1.1 04:19:07 INFO - requests == 1.2.3 04:19:07 INFO - wsgiref == 0.1.2 04:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:08 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 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:19:08 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 04:19:08 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 04:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:08 INFO - 'HOME': '/Users/cltbld', 04:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:08 INFO - 'LOGNAME': 'cltbld', 04:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:08 INFO - 'MOZ_NO_REMOTE': '1', 04:19:08 INFO - 'NO_EM_RESTART': '1', 04:19:08 INFO - 'PAGER': '/bin/cat', 04:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:08 INFO - 'PWD': '/builds/slave/test', 04:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:08 INFO - 'SHELL': '/bin/bash', 04:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:19:08 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:08 INFO - 'USER': 'cltbld', 04:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:08 INFO - 'XPC_FLAGS': '0x0', 04:19:08 INFO - 'XPC_SERVICE_NAME': '0', 04:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 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)) 04:19:08 INFO - Cleaning up... 04:19:08 INFO - Return code: 0 04:19:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10086a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e604ba400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, '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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 04:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:08 INFO - 'HOME': '/Users/cltbld', 04:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:08 INFO - 'LOGNAME': 'cltbld', 04:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:08 INFO - 'MOZ_NO_REMOTE': '1', 04:19:08 INFO - 'NO_EM_RESTART': '1', 04:19:08 INFO - 'PAGER': '/bin/cat', 04:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:08 INFO - 'PWD': '/builds/slave/test', 04:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:08 INFO - 'SHELL': '/bin/bash', 04:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:19:08 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:08 INFO - 'USER': 'cltbld', 04:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:08 INFO - 'XPC_FLAGS': '0x0', 04:19:08 INFO - 'XPC_SERVICE_NAME': '0', 04:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:19:09 INFO - Cleaning up... 04:19:09 INFO - Return code: 0 04:19:09 INFO - Running post-action listener: _resource_record_post_action 04:19:09 INFO - Running post-action listener: _start_resource_monitoring 04:19:09 INFO - Starting resource monitoring. 04:19:09 INFO - [mozharness: 2016-10-18 11:19:09.161529Z] Finished create-virtualenv step (success) 04:19:09 INFO - [mozharness: 2016-10-18 11:19:09.162506Z] Running install step. 04:19:09 INFO - Running pre-action listener: _resource_record_pre_action 04:19:09 INFO - Running main action method: install 04:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:09 INFO - Reading from file tmpfile_stdout 04:19:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:19:09 INFO - Reading from file tmpfile_stdout 04:19:09 INFO - Output received: 04:19:09 INFO - Usage: mozinstall [options] installer 04:19:09 INFO - Options: 04:19:09 INFO - -h, --help show this help message and exit 04:19:09 INFO - -d DEST, --destination=DEST 04:19:09 INFO - Directory to install application into. [default: 04:19:09 INFO - "/builds/slave/test"] 04:19:09 INFO - --app=APP Application being installed. [default: firefox] 04:19:09 INFO - mkdir: /builds/slave/test/build/application 04:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:19:43 INFO - Reading from file tmpfile_stdout 04:19:43 INFO - Output received: 04:19:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:19:43 INFO - Running post-action listener: _resource_record_post_action 04:19:43 INFO - [mozharness: 2016-10-18 11:19:43.557762Z] Finished install step (success) 04:19:43 INFO - [mozharness: 2016-10-18 11:19:43.557883Z] Running run-tests step. 04:19:43 INFO - Running pre-action listener: _resource_record_pre_action 04:19:43 INFO - Running main action method: run_tests 04:19:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:43 INFO - 2016-10-18 04:19:43.572 screenresolution[1634:7759] starting screenresolution argv=screenresolution get 04:19:43 INFO - 2016-10-18 04:19:43.575 screenresolution[1634:7759] Display 0: 1600x1200x32@60 04:19:43 INFO - 2016-10-18 04:19:43.580 screenresolution[1635:7761] starting screenresolution argv=screenresolution list 04:19:43 INFO - Available Modes on Display 0 04:19:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:43 INFO - Intel Iris: 04:19:43 INFO - Chipset Model: Intel Iris 04:19:43 INFO - Type: GPU 04:19:43 INFO - Bus: Built-In 04:19:43 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:43 INFO - Vendor: Intel (0x8086) 04:19:43 INFO - Device ID: 0x0a2e 04:19:43 INFO - Revision ID: 0x0009 04:19:43 INFO - Displays: 04:19:43 INFO - Display: 04:19:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:43 INFO - Display Serial Number: 1600x1200 60 04:19:43 INFO - Main Display: Yes 04:19:43 INFO - Mirror: Off 04:19:43 INFO - Online: Yes 04:19:43 INFO - Rotation: Supported 04:19:43 INFO - Return code: 0 04:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:19:43 INFO - Python 2.7.3 04:19:43 INFO - Return code: 0 04:19:43 INFO - grabbing minidump binary from tooltool 04:19:43 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')]} 04:19:43 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 0x10067ed30>, '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 0x1004a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100676b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:19:43 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 04:19:43 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 04:19:43 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 04:19:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:19:43 INFO - Return code: 0 04:19:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:19:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:19:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:19:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:19:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 04:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 04:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pbAmvOKLfy/Render', 04:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:43 INFO - 'HOME': '/Users/cltbld', 04:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:43 INFO - 'LOGNAME': 'cltbld', 04:19:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:19:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:43 INFO - 'MOZ_NO_REMOTE': '1', 04:19:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:19:43 INFO - 'NO_EM_RESTART': '1', 04:19:43 INFO - 'PAGER': '/bin/cat', 04:19:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:43 INFO - 'PWD': '/builds/slave/test', 04:19:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:43 INFO - 'SHELL': '/bin/bash', 04:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LkMl76hvNJ/Listeners', 04:19:43 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 04:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:43 INFO - 'USER': 'cltbld', 04:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:43 INFO - 'XPC_FLAGS': '0x0', 04:19:43 INFO - 'XPC_SERVICE_NAME': '0', 04:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 04:19:44 INFO - mozversion application_buildid: 20161018013948 04:19:44 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 04:19:44 INFO - mozversion application_display_name: Nightly 04:19:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:19:44 INFO - mozversion application_name: Firefox 04:19:44 INFO - mozversion application_remotingname: firefox 04:19:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:19:44 INFO - mozversion application_vendor: Mozilla 04:19:44 INFO - mozversion application_version: 52.0a1 04:19:44 INFO - mozversion platform_buildid: 20161018013948 04:19:44 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 04:19:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:19:44 INFO - mozversion platform_version: 52.0a1 04:19:44 INFO - using testdate: 1476789584 04:19:44 INFO - actual date: 1476789584 04:19:44 INFO - starting webserver on 'localhost:49272' 04:19:44 INFO - SUITE-START | Running 2 tests 04:19:44 INFO - TEST-START | dromaeo_css 04:19:44 INFO - operating with platform_type : mac_ 04:19:44 INFO - Initialising browser for dromaeo_css test... 04:19:44 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpp7kRmH/profile http://localhost:49272/getInfo.html) 04:19:47 INFO - PROCESS | 1645 | 2016-10-18 04:19:47.099 plugin-container[1647:7930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 04:19:47 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:19:47 INFO - PROCESS | 1645 | 2016-10-18 04:19:47.101 plugin-container[1647:7930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 04:19:47 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:19:47 INFO - PROCESS | 1645 | 2016-10-18 04:19:47.101 plugin-container[1647:7930] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:19:47 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 04:19:47 INFO - PROCESS | 1645 | colorDepth:24 04:19:47 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 04:19:47 INFO - PROCESS | 1645 | __metrics 04:19:47 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:19:48 INFO - TEST-INFO | 1645: exit 0 04:19:48 INFO - Browser initialized. 04:19:48 INFO - Running cycle 1/1 for dromaeo_css test... 04:19:48 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpp7kRmH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:19:50 INFO - PROCESS | 1648 | 2016-10-18 04:19:50.523 plugin-container[1649:8053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 04:19:50 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:19:50 INFO - PROCESS | 1648 | 2016-10-18 04:19:50.523 plugin-container[1649:8053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8817, name = 'com.apple.CFPasteboardClient' 04:19:50 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:19:50 INFO - PROCESS | 1648 | 2016-10-18 04:19:50.523 plugin-container[1649:8053] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:20:00 INFO - PROCESS | 1648 | RSS: Main: 236720128 04:20:00 INFO - PROCESS | 1648 | 04:20:49 INFO - PROCESS | 1648 | 1476789649869 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:23:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 04:23:05 INFO - PROCESS | 1648 | RSS: Main: 250408960 04:23:05 INFO - PROCESS | 1648 | 04:26:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 04:26:08 INFO - PROCESS | 1648 | RSS: Main: 262864896 04:26:08 INFO - PROCESS | 1648 | 04:29:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 04:29:10 INFO - PROCESS | 1648 | RSS: Main: 268435456 04:29:10 INFO - PROCESS | 1648 | 04:32:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 04:32:12 INFO - PROCESS | 1648 | RSS: Main: 268075008 04:32:12 INFO - PROCESS | 1648 | 04:35:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 04:35:16 INFO - PROCESS | 1648 | RSS: Main: 274026496 04:35:16 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 04:38:19 INFO - PROCESS | 1648 | RSS: Main: 276860928 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | __start_tp_report 04:38:19 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 04:38:19 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 04:38:19 INFO - PROCESS | 1648 | |i|pagename|runs| 04:38:19 INFO - PROCESS | 1648 | |0;dojo.html;3386.3735208986323;3395.864165433383;3420.2646431017342;3434.072800343906;3440.896773096807;4655.9058621223985;4741.739204343759;5633.436656334365;5690.118031705082;5739.942600573993;5672.716364181795;5732.426757324265;5762.164486149506;5789.305283366002;5864.530837532998;3824.7896365699876;4588.839390621312;4712.599429048535;5105;5260.789568417259;5137.691738495683;5366.953444078406;5384.542313903304;5421.21392398104;5453.345598528183;5013.573846223076;5186.222066689989;5207.713575753332;5214.921776173361;5369.355677318707;4793.592544633675;5045.814233655088;5266.631335806495;5325.201219817059;5361.678299302054;5192.247124166975;5212.4266330703595;5248.790048398059;5249.1338929076655;5292.761825717852;4438.955610443886;5687.459691329317;5687.544996400319;5843.795467158629;5926.377730338296;6129.389354848367;6165.599236049642;6243.781467648656;6289.811305660792;6321.462675672573;6264.934961056586;6265.404786545271;6266.592671476339;6291.245050593912;6333.8416539586415;5747.683877386784;5950.672713000827;5963.522918166536;5968.37332080134;5979.491743201833;6166.352532984062;6174.783882564178;6239.220097487814;6264.2796078451565;6334.809955701337;6219.689015549205;6240.875182496325;6242.344553821783;6244.781432649836;6271;6074.301455332693;6267.561270711098;6296.779612713533;6299.433051025429;6318.810435686937;6150.907736383958;6168.629882207082;6206.534509911775;6241.750329986749;6261.123442718023;6228.813135605939;6278.246610406781;6278.6860656966965;6278.905816412757;6299.496040316763;4982.377202849644;4984.950150498521;4989.476105008995;5005.1491246488185;5032.924506132411;4349.695521313478;4356.106998065389;4441.933370999438;4470.709403888737;4532.5694059065;5837.416258374129;5858.970705146532;5901.675407852609;5907.645541267538;5937.109433584997;6209.658468784261;6267.811965641038;6269.373062693694;6275.623462592259;6287.7799277026;5731.684757338387;5830.7084645767545;5856.882862342729;5859.5351162209445;5909.763609455574;5474.781008759605;5485.945140548623;5495.450454954553;5504.614676972654;5542.695151766611;6400.167978162809;6400.775972840928;6404.1674582304;6418.550701450947;6435.099086127946;6144.771045790771;6181.443670069715;6210.937890621036;6273.843153921189;6286.74853005893;6232.314445410911;6366;6368.554201205871;6380.234371875219;6382.776602818879;6252.843678908063;6258.499320054476;6271.592346497463;6273.059041143865;6286.779962701284;6096.2847974765855;6172.382761723792;6227.190465239671;6232;6265.248170219603;6107.492261292593;6117.279373342116;6130.398136742091;6202.317745047951;6221.222347206599;5232.319798426332;6161.876762464636;6217.347178546188;6239.407256310643;6244.500439964701;4982.526659967298;4993.950060499348;5894.145348924296;6015.72929218195;6101.237345331833;5946.751182251775;6175.073738939195;6188.659623720739;6244.937550624617;6254.530910181846;5058.34241548611;6050.727717252822;6081.969590152198;6093.451589356801;6117.174181485442 04:38:19 INFO - PROCESS | 1648 | |1;ext.html;463.6568938985151;488.8948875991662;513.861257460486;520.5003196930946;559.8404454730405;30394.54408183879;38061.429078563844;39397.40903886444;39412.80293598532;39455;40386.192276154536;41151;41156.58843411565;41267.58732412675;41378.58621413785;9346.39248448852;9630.3258771886;9644.469554174517;9646.807063858718;9669.806603867919;9456.29077819163;9577.138057574817;9577.281703872202;9580.760480987961;9601.519924003807;9523.904760952406;9584.233261339112;9595.904040959605;9606.231501479864;9613.182879455255;9486.146246837783;9523.57143928525;9551.331406801526;9553;9575.281853860988;9471.90528094717;9591.952040239825;9608.759781005461;9617.615295388176;9629;38475.61524384748;38845;38914.61085389138;39029.60970390288;39078.80460597707;3245.8377081146086;3402.336544373848;3427.057559171223;3511.719062474996;4084.550699423091;30318.84840575783;30368.696313037028;30841.228969275722;30852.53721194184;30963.535546966814;2894.160693398912;3124.453220686371;3220.8872689455757;3301;3372.5278461015478;349519;405856;407316;408398;409159;55705;55730;56009.15986260209;56045.87908241812;56148;58405.831883362946;58878;58914.821703565685;59012.70493647504;59369;14418.639534011523;15380.769288460682;15635.296411661502;15719.214039298218;15764.369425223093;9224.492652904155;10505.369677819355;10807.567697292021;12212.633620991384;12234.265944043385;12206.877931220573;15172.78536899285;15188.088714677155;15230.238488075553;15344.769828452581;5698.230738850285;6458.22501299847;6539.771108011197;6633.701483433257;6634.23706273775;775.716863344875;891.5408564589242;967.4630580028152;975.2831668723483;1026.3072426112344;22560.66159007616;29412.852935735184;29471.557926631118;29639.407211856073;30267.545986810215;930.5905401623264;981.3768257156756;1014.2342531388754;1067.3115840283128;1120.4789772755707;13992.930035349758;14816.481423150353;14855.92572037133;15134.940554161323;15173.24133793328;563752.6311316;569080;570894;573351;573893;52772.47227527753;52791.73604132031;53051.73474132604;53217.467825322026;53238;1348.8988325875598;1351.6012776231034;1406.9226192559304;1408.084744915813;1419.2690764256622;23751.881240593688;29846.55230171506;30234.84882575661;30309.848450757607;31464.741410344246;47762.28356574585;47899.76050119727;48268.27597585969;48354.51645483641;48382.790430238114;566215;569849;570040;571153;575014;24791;24868;25010.74989250084;25312.98748050131;25406.237812865642;43008.354874677534;43690;44484.33273500836;44673;44752.328715069954;37025.70410035636;37159.442608360354;38060.85817425482;38163.236735265695;38204.04489887775;36714;36834;37102.814485927396;37931.62068379281;38001.80999094987;36637.45043824398;37726.62273377341;38808;38844.75595794644;39765;30409;34264.828675856465;34318.14204644904;34406;34484.65515344815;25167.496650067264;25469.87265063663;25596.976120955696;25627.231183064538;26057 04:38:19 INFO - PROCESS | 1648 | |2;jquery.html;336.60477321502367;375.4480913057804;378.594903453305;387.078752568886;401.78460158022;18982.90508547457;19970.400887973366;20395.592088158228;20675.276365327216;20865.68701469479;20216;20269.493262668442;20555.280565180223;20564.280250191245;20870.89564552179;3598.85604575818;4441.666874984381;4854.320395144682;4907.11671899058;5374.247605335257;5135.512126347991;6058.485028772559;6101.694915254242;6104.572679912408;6122.969385153068;5721.513671337942;5944.791932282371;6003.759849606011;6045.697715114248;6066.848328791772;5835.153902684087;5921.467067963882;5945;5983.132445795335;5998.670073145975;4288.506821715511;5062.519060689266;5200.5059519345605;5279.208118782175;5279.366476022863;11287.37919414432;13279.933600331937;13402;18656.78730882488;24767;1742.6950283700303;1981.4055783264962;2237.7985981261763;2256.751757306695;2314.4676724353326;9996.250281228937;10268.589256429734;10528.684139475828;11715.589954351643;14711.485098021518;1597.456864666019;1610.5892997285616;1862.9441116766518;1883.915223814918;1907.83783378413;116429.52134307868;122439;122838;123661.38169309185;124314.37842810819;10171.4914254287;10657.520411581498;11635.476403561861;11636.476358563885;11816.586419475278;11961.40192990347;14643.707125857423;14702.63243418923;14715.852841471447;14726.484573039892;8205.466644668197;8487.787805304917;8543.444676096035;8585.356098292654;8597.699080532151;8374;8399.748007559783;8468.039305574948;8547.572621368907;8618.870716939251;4499.167653984024;4588.862334129982;4615.14619795339;4676.7661616919695;4681.438227412732;1668.307652324296;1736.2967997960845;1882.2847318019064;1933.1880610143774;1960.2551030608277;795.3915254830059;883.337496877342;899.9145081217274;938.1134827587864;963.9180669643018;19553.413397598095;20926.686099708517;21042.530552986893;21179.83510906884;21388.679169812465;922.2253307221965;933.3793027636546;948.6347756113807;958.126861984818;980.2158273381267;10802.783944321069;11075.72310692223;11137.276077054965;11208.719782005515;11232.943835280934;562468;594765;604185;607530;608209;71251;117067;122330;124576;140448;899.2221728205186;932.7714709896119;955.3885513271512;958.2382006304865;959.2421986630624;18412;18542.38423507403;18572.421344185354;18638.440846774618;18696;41923;48971.02057958892;51559.96880062303;53476.46523534715;54020.45979540154;71467.64266178636;77710;78057.82913256192;78785;85290;7435.628218589157;7791.026121734783;7934.350198908567;8200.712975045844;8215.917840821754;12106.576269830513;12124;12311.076669250022;12477.376131193223;12550.372481375713;10480.318779279627;10874.67375978722;10885.183611229348;11016.504257308601;11148.665540033811;10596.258261921592;10802.72993175177;10837.729056773329;10924.890751092706;10975.780484390109;10177.643782467578;10606.840897386392;10834.67495975122;10973.177011723992;11309.151813613847;7120.928790712027;7838.960805195938;8085.878711819445;8091.190880911861;8224.958875205824;9941;10848.132149427869;11125.944370278097;11158;11216.214864959375 04:38:19 INFO - PROCESS | 1648 | |3;mootools.html;1533.3133553091598;1571.2929181868158;1813.9479102120772;1881.896495692738;1926.5665225324294;6449.8710025799455;6672.566283191589;8264.132266112061;9024.593893274794;9054.456732596049;7353.48525603208;8770.342224333168;8777.824443511143;8859.24696400805;8889.59996800144;2027.2093883385419;2054.1680619349186;2346.2843832631083;2397.6883005209297;2399.7360290368133;2368.076450184421;2407.374082738492;2416.1664225842055;2418.7581241875846;2425.551273014498;2397.1969390254285;2406.097713357491;2417.347316224618;2421.5762241607736;2429.2105065853666;2386.2125498585424;2391.246757271454;2401.5677178107935;2414;2414.7585241475977;2002.2391491233238;2336.4392545788887;2363.019346971022;2363.409147713072;2364.9645255321184;8757.343199260082;8844.11558844117;8854.557272136368;8880.8667869982;8912.24245939096;5253.921191182136;5451.863703407407;5454.127339625641;5455.645383050089;5461.344638643349;3582.6954708849516;3605.6214097519646;3644.052546337945;3681.7054635629083;3693.9445908311395;1061.946902654864;1067.8291473364225;1077;1084.6474895658941;1085.2294870641854;11355.489002994802;11498.42507874603;11535.538578456852;11629.47667354971;11671.124665650026;4223.852165174204;4594.862154135314;4784.976075119602;4853.306414939742;4900.240462728283;8493.702720404879;9878.506074696237;9932.851007234898;9943.955884632154;9985.400875947467;5282.283257941184;5288.788448462095;5297.973510132425;5302.8939421212135;5308.203769434615;3988.980055099764;4561.794719237642;4849.9030019399415;4893.314935908976;4909.558139767438;3944.684425245973;3990;4078.9184216316103;4093.7543747375253;4128.814203360856;1299.844018717741;1301.8828305452366;1307.1634154141357;1314.2443095220287;1319.769040417923;826.4297634632085;914.8444764390069;921.0789210789211;930.265090578449;976.9609215631295;5769.8557536061935;6072.605280656743;6084.117802918552;6142.723577439026;6191.380861913773;920.5167287174153;982.1455333859587;986.363795351994;994.7910938702953;1008.5159123620557;3863.9806800965816;3986.4418981342637;4066.0851308455954;4086.652634526039;4166.64583510405;10618;10890.346579205274;10907.781844363068;10985.450727463754;10992.120630349553;10912.96326848948;10922.399268040175;10924.672259832218;10927.3989930553;10940.179486538542;7589.810254743566;7751.806204844924;7881.605919704107;8320.04319503265;8321.41750077501;5853.502452291603;5885.882282354414;6000.549958753199;6018.6689732063405;6021.698915054142;8258.298044666271;8739.519326437106;9196.574530947713;10093;10109.848352274576;8591.914080859362;9685.6610018649;10062.849057264293;10066.496675166067;10196.286259961731;6928.757493487704;7009.544379615309;7152.749653762094;7176.7846964591145;7306.488545801955;7534.171241163577;8042.115367309708;8231.753047408587;8242.29940455068;8276.627551760064;7383.704651813868;7489.625518724151;7553.886691699519;7581.393488520795;7627.199144090013;7268.41852651776;7403.296686814744;7440.925590744023;7525.059367579052;7532.322091011835;5961.940380596138;5963.701814909324;6894.965525172342;7026.297370262932;7051;4800.423949125988;4844.539768721966;4912.435069966999;5025.7989680412375;5044.991001799582;4996.502448286142;5098.311727916684;5145.555860966011;5251.474852514718;5295.629305948547 04:38:19 INFO - PROCESS | 1648 | |4;prototype.html;302.1238408615016;303.5371059134819;309.8837935774085;314.0797463432143;317.8204314562272;5481.287432633757;5617.578681598881;5617.831465056045;5654.349749778771;5674;5576.665400075998;5583.35791383991;5586.765293882449;5590.217369568264;5657.264555607776;3548.8757893473735;3984.9278375244785;4052.878413647595;4098.815553300109;4099.631033207011;3846.5767665963494;4148.5851414858425;4182.79086045698;4185.769782661952;4205.306124489455;3265.853036613358;4093.672506199497;4174.791260436981;4192.140611174702;4219.974412282107;3892.455056292093;4159.147374788161;4195.769232692201;4198.047907722924;4209.116085622039;3166.2401023754364;3487.3897068013;3539.8407071681577;3561.5370001899587;3602.4329416703818;4643.931895664016;5417.756200970965;5486.753096110685;5516.613837031409;5520.806771762969;4514.8194072237075;4619.399478067863;4796.664233503656;4834;4850;5622.847316300149;5855.707214639251;5914.674692891889;5942.108683697436;5966.970165149147;3754.6858599490233;3767.6436482866147;3949.8420063197436;3988.282109220339;4013.9397909031386;5950.109391702227;6112.51099912006;6149.969250153766;6217.844553886189;6222.284437289679;3583.2475180212446;3961.9104746194453;3972.662323702518;3984.30274701926;3991;3475.6444986455303;3880.243352546283;4028.213479329421;4081.3673880548563;4104.8152833122585;4528.864134075983;4783.258594917794;4789.856304310876;4806.2310030394965;4813.3983252093485;4897.902041959141;5840.970795145997;5897.203877476486;5929.644221346733;5952.553558483131;1212.8969037631723;1346.6364081697886;1361.1288775183891;1377.5040985245303;1387.9306034698225;718.586812582767;721.7942886277385;780.5238804329355;814.4339683919737;841.87792770048;4792.329073929653;5088.949110508922;5340.198970154508;5544.390117087117;5563.471470210324;5149.871253218699;5571.526420254243;6120.663363515049;6176.8764624707255;6202.627842329474;4507.323901414814;5358.223057656618;5514.448555144453;5578.609497335228;5698.373178950312;2683.7718793902736;2695;2968.990543215274;3205.8557364918634;3227.949587639658;5054.4692807254705;5957.79147729836;6232.688365581703;6314.526410519229;6328.936710632926;5694.804091140907;5892.322382925932;5993.940060599425;6168.660723660152;6382.6170429774365;6325.873482530324;6333.904991425133;6341.968290158519;6375.330590288138;6409.903851442139;5088.262201980766;5371;5631.662100273997;5714.742836572281;6190.81427557174;6075.453209211192;6117.724702388314;6119;6134.478569321657;6136.20229370179;5658.5190258826815;6174.6912654368;6337.524685648687;6453.128827608213;6500.934990650033;3740.4564895871067;3984.2230765000545;4001.0197501612292;4023.3102097118694;4028.71798974069;4986.32684587576;6029.758809647565;6093.881139842553;6126.866529692022;6216.906746398898;5707.543396528351;5783.843231353662;5906;5981.431763982415;6033.698315084316;4729.739864307496;5161.81933632321;5176.844694659167;5850.853728656818;5905.616134951386;5070.923936141035;5388.811391601275;5808.680522571299;5823.534117270693;5854.765809367748;5198.870028249324;5579.9163012555655;5594.13290939905;5844.532437405082;5965.612235204698;5347.1979203119845;5435.728213589226;5450.863728406663;5776.768929242783;5849.795257165979 04:38:19 INFO - PROCESS | 1648 | |5;yui.html;650.703929711981;689.3003601344633;724.9311315425033;804.3324041045933;810.7243537197352;15067.698646027073;15881.841181588181;16047.197640117976;16161.11113888737;16254.187290635451;14633.195174265411;14796;14797.112173269612;14800.851991480082;14840.183789891551;697.3653974882858;713.222587379756;864.8918885139358;866.376209129429;884.1909652667833;894.5482531321674;895.1987970766161;897.3404547657467;900.5407242306405;900.7432881628737;921.8202450522151;948.568401377375;951.7097285327973;953.1612181280464;955.4028732042474;775.9456838021341;938.9389689670184;943.2265542255353;943.3538026451903;947.7488465556632;587.6855882103097;602.7317843559574;622.841175500249;703.5637904499192;706.254901079361;13077.673058173526;14988.025778324483;15184.240787960669;15184.316705748268;15240.466583669519;12167;14472;14535.78196327056;14592.270386480739;14653.194074325907;11839.526418943233;11910.880891191064;11920.523179072827;11927.403629818562;11950.223235489751;3742.172671977046;3833.8083095845095;3842.0971071798385;3842.3660096084113;3993.600639936012;89712;92355.53822230913;105728;106984;108591;11029.22795404314;11143.052840508486;11210.551577936954;11241.887581124247;11320.660380188607;23329.766702333098;23341.183058593208;23387.41531461693;23397.649035264483;23416.64875026876;7947.602619868983;8131.390145738975;8164.306033987177;8206.056303525052;8341.666333346599;4391.121775644884;4492.887677808016;4500.662450316174;4507.143642707875;4512.932306015412;3578.159132603836;3681.153334732973;3685.686716629063;3686.115332320224;3689.335919534456;651.3258777165611;651.6513665189101;652.7780554611455;661.6228749612675;672.7510820996165;599.5755480031484;607.7022259093012;658.2200092889941;668.4117976181027;684.4079870911725;9214.585343659552;9324;9377.906220937839;9389.62441502346;9659.372140810825;603.7494439807518;646.3439608797074;655.3250152343094;659.3604203922188;700.1283402164338;4335.089631177488;4364.018095928391;4413.646908247332;4485.461744590605;4549.112922980054;107185.39221911678;112299.4385028086;153679.23160384127;158271;162791;122892;137016;139579;142292.28853855663;142406;103680;105686;107328.46335768272;107574.8485030295;109232;7296.327890769566;7575.848483030199;8730.734043563774;8904.86642700347;9117.316201284797;55026;58470;66586.66706666436;66966.330336696;68165.3183468159;49264.50735492599;66226.33773662202;98930.5053474728;102932;104657;11927.284362938251;13480.258585777876;13542.729145417234;13588.524401646291;13660.728519049595;14264.928675356558;14709.485168019068;14814.185219812583;14888.181150036851;15051.548453546411;13699.246541440316;13740.793888091886;13867.930660346634;14008.439662413797;14477.203753793643;13710.17738935667;13951.720965580835;14232.857671423153;14409;14471.565853024425;12913;12947.5468358607;13268.33658317061;13416.12795168311;13734.244616546186;11193.944030279796;11265.887341126812;11442.141839361915;11602.825957610812;11616.244944078608;10150.102982796756;10359.741006474596;10511.264211505428;10683.786324273626;10934.45327733626 04:38:19 INFO - PROCESS | 1648 | __end_tp_report 04:38:19 INFO - PROCESS | 1648 | __start_cc_report 04:38:19 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,367 04:38:19 INFO - PROCESS | 1648 | __end_cc_report 04:38:19 INFO - PROCESS | 1648 | __startTimestamp1476790699385__endTimestamp 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | ------- Summary: start ------- 04:38:19 INFO - PROCESS | 1648 | Number of tests: 6 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5741.19 Median:6050.73 stddev:692.74 (11.4%) stddev-sans-first:669.78 04:38:19 INFO - PROCESS | 1648 | Values: 3386.4 3395.9 3420.3 3434.1 3440.9 4655.9 4741.7 5633.4 5690.1 5739.9 5672.7 5732.4 5762.2 5789.3 5864.5 3824.8 4588.8 4712.6 5105.0 5260.8 5137.7 5367.0 5384.5 5421.2 5453.3 5013.6 5186.2 5207.7 5214.9 5369.4 4793.6 5045.8 5266.6 5325.2 5361.7 5192.2 5212.4 5248.8 5249.1 5292.8 4439.0 5687.5 5687.5 5843.8 5926.4 6129.4 6165.6 6243.8 6289.8 6321.5 6264.9 6265.4 6266.6 6291.2 6333.8 5747.7 5950.7 5963.5 5968.4 5979.5 6166.4 6174.8 6239.2 6264.3 6334.8 6219.7 6240.9 6242.3 6244.8 6271.0 6074.3 6267.6 6296.8 6299.4 6318.8 6150.9 6168.6 6206.5 6241.8 6261.1 6228.8 6278.2 6278.7 6278.9 6299.5 4982.4 4985.0 4989.5 5005.1 5032.9 4349.7 4356.1 4441.9 4470.7 4532.6 5837.4 5859.0 5901.7 5907.6 5937.1 6209.7 6267.8 6269.4 6275.6 6287.8 5731.7 5830.7 5856.9 5859.5 5909.8 5474.8 5485.9 5495.5 5504.6 5542.7 6400.2 6400.8 6404.2 6418.6 6435.1 6144.8 6181.4 6210.9 6273.8 6286.7 6232.3 6366.0 6368.6 6380.2 6382.8 6252.8 6258.5 6271.6 6273.1 6286.8 6096.3 6172.4 6227.2 6232.0 6265.2 6107.5 6117.3 6130.4 6202.3 6221.2 5232.3 6161.9 6217.3 6239.4 6244.5 4982.5 4994.0 5894.1 6015.7 6101.2 5946.8 6175.1 6188.7 6244.9 6254.5 5058.3 6050.7 6082.0 6093.5 6117.2 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:64455.54 Median:39433.90 stddev:138557.94 (351.4%) stddev-sans-first:138863.33 04:38:19 INFO - PROCESS | 1648 | Values: 463.7 488.9 513.9 520.5 559.8 30394.5 38061.4 39397.4 39412.8 39455.0 40386.2 41151.0 41156.6 41267.6 41378.6 9346.4 9630.3 9644.5 9646.8 9669.8 9456.3 9577.1 9577.3 9580.8 9601.5 9523.9 9584.2 9595.9 9606.2 9613.2 9486.1 9523.6 9551.3 9553.0 9575.3 9471.9 9592.0 9608.8 9617.6 9629.0 38475.6 38845.0 38914.6 39029.6 39078.8 3245.8 3402.3 3427.1 3511.7 4084.6 30318.8 30368.7 30841.2 30852.5 30963.5 2894.2 3124.5 3220.9 3301.0 3372.5 349519.0 405856.0 407316.0 408398.0 409159.0 55705.0 55730.0 56009.2 56045.9 56148.0 58405.8 58878.0 58914.8 59012.7 59369.0 14418.6 15380.8 15635.3 15719.2 15764.4 9224.5 10505.4 10807.6 12212.6 12234.3 12206.9 15172.8 15188.1 15230.2 15344.8 5698.2 6458.2 6539.8 6633.7 6634.2 775.7 891.5 967.5 975.3 1026.3 22560.7 29412.9 29471.6 29639.4 30267.5 930.6 981.4 1014.2 1067.3 1120.5 13992.9 14816.5 14855.9 15134.9 15173.2 563752.6 569080.0 570894.0 573351.0 573893.0 52772.5 52791.7 53051.7 53217.5 53238.0 1348.9 1351.6 1406.9 1408.1 1419.3 23751.9 29846.6 30234.8 30309.8 31464.7 47762.3 47899.8 48268.3 48354.5 48382.8 566215.0 569849.0 570040.0 571153.0 575014.0 24791.0 24868.0 25010.7 25313.0 25406.2 43008.4 43690.0 44484.3 44673.0 44752.3 37025.7 37159.4 38060.9 38163.2 38204.0 36714.0 36834.0 37102.8 37931.6 38001.8 36637.5 37726.6 38808.0 38844.8 39765.0 30409.0 34264.8 34318.1 34406.0 34484.7 25167.5 25469.9 25597.0 25627.2 26057.0 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:34286.78 Median:13540.73 stddev:99356.52 (733.8%) stddev-sans-first:99602.54 04:38:19 INFO - PROCESS | 1648 | Values: 336.6 375.4 378.6 387.1 401.8 18982.9 19970.4 20395.6 20675.3 20865.7 20216.0 20269.5 20555.3 20564.3 20870.9 3598.9 4441.7 4854.3 4907.1 5374.2 5135.5 6058.5 6101.7 6104.6 6123.0 5721.5 5944.8 6003.8 6045.7 6066.8 5835.2 5921.5 5945.0 5983.1 5998.7 4288.5 5062.5 5200.5 5279.2 5279.4 11287.4 13279.9 13402.0 18656.8 24767.0 1742.7 1981.4 2237.8 2256.8 2314.5 9996.3 10268.6 10528.7 11715.6 14711.5 1597.5 1610.6 1862.9 1883.9 1907.8 116429.5 122439.0 122838.0 123661.4 124314.4 10171.5 10657.5 11635.5 11636.5 11816.6 11961.4 14643.7 14702.6 14715.9 14726.5 8205.5 8487.8 8543.4 8585.4 8597.7 8374.0 8399.7 8468.0 8547.6 8618.9 4499.2 4588.9 4615.1 4676.8 4681.4 1668.3 1736.3 1882.3 1933.2 1960.3 795.4 883.3 899.9 938.1 963.9 19553.4 20926.7 21042.5 21179.8 21388.7 922.2 933.4 948.6 958.1 980.2 10802.8 11075.7 11137.3 11208.7 11232.9 562468.0 594765.0 604185.0 607530.0 608209.0 71251.0 117067.0 122330.0 124576.0 140448.0 899.2 932.8 955.4 958.2 959.2 18412.0 18542.4 18572.4 18638.4 18696.0 41923.0 48971.0 51560.0 53476.5 54020.5 71467.6 77710.0 78057.8 78785.0 85290.0 7435.6 7791.0 7934.4 8200.7 8215.9 12106.6 12124.0 12311.1 12477.4 12550.4 10480.3 10874.7 10885.2 11016.5 11148.7 10596.3 10802.7 10837.7 10924.9 10975.8 10177.6 10606.8 10834.7 10973.2 11309.2 7120.9 7839.0 8085.9 8091.2 8225.0 9941.0 10848.1 11125.9 11158.0 11216.2 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5654.71 Median:5035.39 stddev:3126.05 (62.1%) stddev-sans-first:3119.48 04:38:19 INFO - PROCESS | 1648 | Values: 1533.3 1571.3 1813.9 1881.9 1926.6 6449.9 6672.6 8264.1 9024.6 9054.5 7353.5 8770.3 8777.8 8859.2 8889.6 2027.2 2054.2 2346.3 2397.7 2399.7 2368.1 2407.4 2416.2 2418.8 2425.6 2397.2 2406.1 2417.3 2421.6 2429.2 2386.2 2391.2 2401.6 2414.0 2414.8 2002.2 2336.4 2363.0 2363.4 2365.0 8757.3 8844.1 8854.6 8880.9 8912.2 5253.9 5451.9 5454.1 5455.6 5461.3 3582.7 3605.6 3644.1 3681.7 3693.9 1061.9 1067.8 1077.0 1084.6 1085.2 11355.5 11498.4 11535.5 11629.5 11671.1 4223.9 4594.9 4785.0 4853.3 4900.2 8493.7 9878.5 9932.9 9944.0 9985.4 5282.3 5288.8 5298.0 5302.9 5308.2 3989.0 4561.8 4849.9 4893.3 4909.6 3944.7 3990.0 4078.9 4093.8 4128.8 1299.8 1301.9 1307.2 1314.2 1319.8 826.4 914.8 921.1 930.3 977.0 5769.9 6072.6 6084.1 6142.7 6191.4 920.5 982.1 986.4 994.8 1008.5 3864.0 3986.4 4066.1 4086.7 4166.6 10618.0 10890.3 10907.8 10985.5 10992.1 10913.0 10922.4 10924.7 10927.4 10940.2 7589.8 7751.8 7881.6 8320.0 8321.4 5853.5 5885.9 6000.5 6018.7 6021.7 8258.3 8739.5 9196.6 10093.0 10109.8 8591.9 9685.7 10062.8 10066.5 10196.3 6928.8 7009.5 7152.7 7176.8 7306.5 7534.2 8042.1 8231.8 8242.3 8276.6 7383.7 7489.6 7553.9 7581.4 7627.2 7268.4 7403.3 7440.9 7525.1 7532.3 5961.9 5963.7 6895.0 7026.3 7051.0 4800.4 4844.5 4912.4 5025.8 5045.0 4996.5 5098.3 5145.6 5251.5 5295.6 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4751.98 Median:5443.30 stddev:1516.81 (27.9%) stddev-sans-first:1483.84 04:38:19 INFO - PROCESS | 1648 | Values: 302.1 303.5 309.9 314.1 317.8 5481.3 5617.6 5617.8 5654.3 5674.0 5576.7 5583.4 5586.8 5590.2 5657.3 3548.9 3984.9 4052.9 4098.8 4099.6 3846.6 4148.6 4182.8 4185.8 4205.3 3265.9 4093.7 4174.8 4192.1 4220.0 3892.5 4159.1 4195.8 4198.0 4209.1 3166.2 3487.4 3539.8 3561.5 3602.4 4643.9 5417.8 5486.8 5516.6 5520.8 4514.8 4619.4 4796.7 4834.0 4850.0 5622.8 5855.7 5914.7 5942.1 5967.0 3754.7 3767.6 3949.8 3988.3 4013.9 5950.1 6112.5 6150.0 6217.8 6222.3 3583.2 3961.9 3972.7 3984.3 3991.0 3475.6 3880.2 4028.2 4081.4 4104.8 4528.9 4783.3 4789.9 4806.2 4813.4 4897.9 5841.0 5897.2 5929.6 5952.6 1212.9 1346.6 1361.1 1377.5 1387.9 718.6 721.8 780.5 814.4 841.9 4792.3 5088.9 5340.2 5544.4 5563.5 5149.9 5571.5 6120.7 6176.9 6202.6 4507.3 5358.2 5514.4 5578.6 5698.4 2683.8 2695.0 2969.0 3205.9 3227.9 5054.5 5957.8 6232.7 6314.5 6328.9 5694.8 5892.3 5993.9 6168.7 6382.6 6325.9 6333.9 6342.0 6375.3 6409.9 5088.3 5371.0 5631.7 5714.7 6190.8 6075.5 6117.7 6119.0 6134.5 6136.2 5658.5 6174.7 6337.5 6453.1 6500.9 3740.5 3984.2 4001.0 4023.3 4028.7 4986.3 6029.8 6093.9 6126.9 6216.9 5707.5 5783.8 5906.0 5981.4 6033.7 4729.7 5161.8 5176.8 5850.9 5905.6 5070.9 5388.8 5808.7 5823.5 5854.8 5198.9 5579.9 5594.1 5844.5 5965.6 5347.2 5435.7 5450.9 5776.8 5849.8 04:38:19 INFO - PROCESS | 1648 | 04:38:19 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:24359.80 Median:3629.66 stddev:38727.10 (1067.0%) stddev-sans-first:38794.83 04:38:19 INFO - PROCESS | 1648 | Values: 650.7 689.3 724.9 804.3 810.7 15067.7 15881.8 16047.2 16161.1 16254.2 14633.2 14796.0 14797.1 14800.9 14840.2 697.4 713.2 864.9 866.4 884.2 894.5 895.2 897.3 900.5 900.7 921.8 948.6 951.7 953.2 955.4 775.9 938.9 943.2 943.4 947.7 587.7 602.7 622.8 703.6 706.3 13077.7 14988.0 15184.2 15184.3 15240.5 12167.0 14472.0 14535.8 14592.3 14653.2 11839.5 11910.9 11920.5 11927.4 11950.2 3742.2 3833.8 3842.1 3842.4 3993.6 89712.0 92355.5 105728.0 106984.0 108591.0 11029.2 11143.1 11210.6 11241.9 11320.7 23329.8 23341.2 23387.4 23397.6 23416.6 7947.6 8131.4 8164.3 8206.1 8341.7 4391.1 4492.9 4500.7 4507.1 4512.9 3578.2 3681.2 3685.7 3686.1 3689.3 651.3 651.7 652.8 661.6 672.8 599.6 607.7 658.2 668.4 684.4 9214.6 9324.0 9377.9 9389.6 9659.4 603.7 646.3 655.3 659.4 700.1 4335.1 4364.0 4413.6 4485.5 4549.1 107185.4 112299.4 153679.2 158271.0 162791.0 122892.0 137016.0 139579.0 142292.3 142406.0 103680.0 105686.0 107328.5 107574.8 109232.0 7296.3 7575.8 8730.7 8904.9 9117.3 55026.0 58470.0 66586.7 66966.3 68165.3 49264.5 66226.3 98930.5 102932.0 104657.0 11927.3 13480.3 13542.7 13588.5 13660.7 14264.9 14709.5 14814.2 14888.2 15051.5 13699.2 13740.8 13867.9 14008.4 14477.2 13710.2 13951.7 14232.9 14409.0 14471.6 12913.0 12947.5 13268.3 13416.1 13734.2 11193.9 11265.9 11442.1 11602.8 11616.2 10150.1 10359.7 10511.3 10683.8 10934.5 04:38:19 INFO - PROCESS | 1648 | -------- Summary: end -------- 04:38:19 INFO - PROCESS | 1648 | 04:38:20 INFO - TEST-INFO | 1648: exit 0 04:38:20 INFO - TEST-OK | dromaeo_css | took 1115880ms 04:38:20 INFO - TEST-START | kraken 04:38:20 INFO - operating with platform_type : mac_ 04:38:20 INFO - Initialising browser for kraken test... 04:38:20 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmphqv84w/profile http://localhost:49272/getInfo.html) 04:38:22 INFO - PROCESS | 1660 | 2016-10-18 04:38:22.842 plugin-container[1661:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 04:38:22 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:22 INFO - PROCESS | 1660 | 2016-10-18 04:38:22.845 plugin-container[1661:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 04:38:22 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:22 INFO - PROCESS | 1660 | 2016-10-18 04:38:22.845 plugin-container[1661:10383] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:38:22 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:38:22 INFO - PROCESS | 1660 | colorDepth:24 04:38:22 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:38:22 INFO - PROCESS | 1660 | __metrics 04:38:22 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:38:23 INFO - TEST-INFO | 1660: exit 0 04:38:23 INFO - Browser initialized. 04:38:23 INFO - Running cycle 1/1 for kraken test... 04:38:23 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmphqv84w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:38:26 INFO - PROCESS | 1662 | 2016-10-18 04:38:26.035 plugin-container[1663:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 04:38:26 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:26 INFO - PROCESS | 1662 | 2016-10-18 04:38:26.035 plugin-container[1663:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 04:38:26 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:38:26 INFO - PROCESS | 1662 | 2016-10-18 04:38:26.036 plugin-container[1663:10497] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:38:35 INFO - PROCESS | 1662 | RSS: Main: 237154304 04:38:35 INFO - PROCESS | 1662 | 04:38:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 04:38:39 INFO - PROCESS | 1662 | RSS: Main: 249753600 04:38:39 INFO - PROCESS | 1662 | 04:38:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 04:38:43 INFO - PROCESS | 1662 | RSS: Main: 257384448 04:38:43 INFO - PROCESS | 1662 | 04:38:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 04:38:47 INFO - PROCESS | 1662 | RSS: Main: 260296704 04:38:47 INFO - PROCESS | 1662 | 04:38:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 04:38:50 INFO - PROCESS | 1662 | RSS: Main: 262467584 04:38:50 INFO - PROCESS | 1662 | 04:38:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:38:52 INFO - PROCESS | 1662 | RSS: Main: 262766592 04:38:52 INFO - PROCESS | 1662 | 04:38:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 04:38:56 INFO - PROCESS | 1662 | RSS: Main: 263127040 04:38:56 INFO - PROCESS | 1662 | 04:38:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 04:39:00 INFO - PROCESS | 1662 | RSS: Main: 263802880 04:39:00 INFO - PROCESS | 1662 | 04:39:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 04:39:03 INFO - PROCESS | 1662 | RSS: Main: 263921664 04:39:03 INFO - PROCESS | 1662 | 04:39:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:39:05 INFO - PROCESS | 1662 | RSS: Main: 263864320 04:39:05 INFO - PROCESS | 1662 | 04:39:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:39:08 INFO - PROCESS | 1662 | RSS: Main: 263733248 04:39:08 INFO - PROCESS | 1662 | 04:39:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:39:11 INFO - PROCESS | 1662 | RSS: Main: 263831552 04:39:11 INFO - PROCESS | 1662 | 04:39:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:39:15 INFO - PROCESS | 1662 | RSS: Main: 264192000 04:39:15 INFO - PROCESS | 1662 | 04:39:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:39:18 INFO - PROCESS | 1662 | RSS: Main: 264044544 04:39:18 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 04:39:20 INFO - PROCESS | 1662 | RSS: Main: 265764864 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | __start_tp_report 04:39:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 04:39:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 04:39:20 INFO - PROCESS | 1662 | |i|pagename|runs| 04:39:20 INFO - PROCESS | 1662 | |0;ai-astar;103;114;122;159;118;120;118;130;175;158 04:39:20 INFO - PROCESS | 1662 | |1;audio-beat-detection;161;142;145;172;120;116;117;117;142;118 04:39:20 INFO - PROCESS | 1662 | |2;audio-dft;218;240;209;279;274;187;242;210;269;229 04:39:20 INFO - PROCESS | 1662 | |3;audio-fft;82;61;76;113;88;62;61;81;70;75 04:39:20 INFO - PROCESS | 1662 | |4;audio-oscillator;90;71;88;73;70;74;124;100;73;88 04:39:20 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;91;87;90;87;88;86;90;87;88;85 04:39:20 INFO - PROCESS | 1662 | |6;imaging-darkroom;96;105;91;149;150;96;95;96;102;149 04:39:20 INFO - PROCESS | 1662 | |7;imaging-desaturate;112;114;86;87;91;89;90;114;74;135 04:39:20 INFO - PROCESS | 1662 | |8;json-parse-financial;57;55;62;82;49;50;57;75;83;81 04:39:20 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;71;75;74;83;77;69;69;69;71;72 04:39:20 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;95;91;94;89;93;95;105;111;113;104 04:39:20 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;148;167;161;167;154;147;148;168;263;150 04:39:20 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;175;177;204;237;195;181;181;181;178;176 04:39:20 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;72;74;72;70;70;71;70;72;70 04:39:20 INFO - PROCESS | 1662 | __end_tp_report 04:39:20 INFO - PROCESS | 1662 | __start_cc_report 04:39:20 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,955 04:39:20 INFO - PROCESS | 1662 | __end_cc_report 04:39:20 INFO - PROCESS | 1662 | __startTimestamp1476790760908__endTimestamp 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | ------- Summary: start ------- 04:39:20 INFO - PROCESS | 1662 | Number of tests: 14 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:131.70 Median:126.00 stddev:23.71 (18.8%) stddev-sans-first:22.76 04:39:20 INFO - PROCESS | 1662 | Values: 103.0 114.0 122.0 159.0 118.0 120.0 118.0 130.0 175.0 158.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:142.00 stddev:20.46 (14.4%) stddev-sans-first:19.41 04:39:20 INFO - PROCESS | 1662 | Values: 161.0 142.0 145.0 172.0 120.0 116.0 117.0 117.0 142.0 118.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:235.70 Median:241.00 stddev:30.93 (12.8%) stddev-sans-first:32.14 04:39:20 INFO - PROCESS | 1662 | Values: 218.0 240.0 209.0 279.0 274.0 187.0 242.0 210.0 269.0 229.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:76.90 Median:75.50 stddev:15.81 (20.9%) stddev-sans-first:16.66 04:39:20 INFO - PROCESS | 1662 | Values: 82.0 61.0 76.0 113.0 88.0 62.0 61.0 81.0 70.0 75.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:73.50 stddev:17.06 (23.2%) stddev-sans-first:18.00 04:39:20 INFO - PROCESS | 1662 | Values: 90.0 71.0 88.0 73.0 70.0 74.0 124.0 100.0 73.0 88.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:1.91 (2.2%) stddev-sans-first:1.67 04:39:20 INFO - PROCESS | 1662 | Values: 91.0 87.0 90.0 87.0 88.0 86.0 90.0 87.0 88.0 85.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:93.00 stddev:25.43 (27.3%) stddev-sans-first:26.23 04:39:20 INFO - PROCESS | 1662 | Values: 96.0 105.0 91.0 149.0 150.0 96.0 95.0 96.0 102.0 149.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:86.50 stddev:18.55 (21.4%) stddev-sans-first:19.09 04:39:20 INFO - PROCESS | 1662 | Values: 112.0 114.0 86.0 87.0 91.0 89.0 90.0 114.0 74.0 135.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:68.50 stddev:13.69 (20.0%) stddev-sans-first:14.20 04:39:20 INFO - PROCESS | 1662 | Values: 57.0 55.0 62.0 82.0 49.0 50.0 57.0 75.0 83.0 81.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:4.45 (6.1%) stddev-sans-first:4.66 04:39:20 INFO - PROCESS | 1662 | Values: 71.0 75.0 74.0 83.0 77.0 69.0 69.0 69.0 71.0 72.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:99.00 Median:92.00 stddev:8.55 (9.3%) stddev-sans-first:8.95 04:39:20 INFO - PROCESS | 1662 | Values: 95.0 91.0 94.0 89.0 93.0 95.0 105.0 111.0 113.0 104.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:167.30 Median:164.00 stddev:34.69 (21.2%) stddev-sans-first:36.09 04:39:20 INFO - PROCESS | 1662 | Values: 148.0 167.0 161.0 167.0 154.0 147.0 148.0 168.0 263.0 150.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.50 Median:181.00 stddev:19.39 (10.7%) stddev-sans-first:19.94 04:39:20 INFO - PROCESS | 1662 | Values: 175.0 177.0 204.0 237.0 195.0 181.0 181.0 181.0 178.0 176.0 04:39:20 INFO - PROCESS | 1662 | 04:39:20 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:71.50 stddev:1.66 (2.3%) stddev-sans-first:1.39 04:39:20 INFO - PROCESS | 1662 | Values: 68.0 72.0 74.0 72.0 70.0 70.0 71.0 70.0 72.0 70.0 04:39:20 INFO - PROCESS | 1662 | -------- Summary: end -------- 04:39:20 INFO - PROCESS | 1662 | 04:39:21 INFO - TEST-INFO | 1662: exit 0 04:39:21 INFO - TEST-OK | kraken | took 61744ms 04:39:21 INFO - SUITE-END | took 1177s 04:39:22 INFO - Completed test suite (00:19:37) 04:39:22 INFO - javascript benchmark 04:39:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7314.507457776733, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.3735208986323, 3395.864165433383, 3420.2646431017342, 3434.072800343906, 3440.896773096807, 4655.9058621223985, 4741.739204343759, 5633.436656334365, 5690.118031705082, 5739.942600573993, 5672.716364181795, 5732.426757324265, 5762.164486149506, 5789.305283366002, 5864.530837532998, 3824.7896365699876, 4588.839390621312, 4712.599429048535, 5105.0, 5260.789568417259, 5137.691738495683, 5366.953444078406, 5384.542313903304, 5421.21392398104, 5453.345598528183, 5013.573846223076, 5186.222066689989, 5207.713575753332, 5214.921776173361, 5369.355677318707, 4793.592544633675, 5045.814233655088, 5266.631335806495, 5325.201219817059, 5361.678299302054, 5192.247124166975, 5212.4266330703595, 5248.790048398059, 5249.1338929076655, 5292.761825717852, 4438.955610443886, 5687.459691329317, 5687.544996400319, 5843.795467158629, 5926.377730338296, 6129.389354848367, 6165.599236049642, 6243.781467648656, 6289.811305660792, 6321.462675672573, 6264.934961056586, 6265.404786545271, 6266.592671476339, 6291.245050593912, 6333.8416539586415, 5747.683877386784, 5950.672713000827, 5963.522918166536, 5968.37332080134, 5979.491743201833, 6166.352532984062, 6174.783882564178, 6239.220097487814, 6264.2796078451565, 6334.809955701337, 6219.689015549205, 6240.875182496325, 6242.344553821783, 6244.781432649836, 6271.0, 6074.301455332693, 6267.561270711098, 6296.779612713533, 6299.433051025429, 6318.810435686937, 6150.907736383958, 6168.629882207082, 6206.534509911775, 6241.750329986749, 6261.123442718023, 6228.813135605939, 6278.246610406781, 6278.6860656966965, 6278.905816412757, 6299.496040316763, 4982.377202849644, 4984.950150498521, 4989.476105008995, 5005.1491246488185, 5032.924506132411, 4349.695521313478, 4356.106998065389, 4441.933370999438, 4470.709403888737, 4532.5694059065, 5837.416258374129, 5858.970705146532, 5901.675407852609, 5907.645541267538, 5937.109433584997, 6209.658468784261, 6267.811965641038, 6269.373062693694, 6275.623462592259, 6287.7799277026, 5731.684757338387, 5830.7084645767545, 5856.882862342729, 5859.5351162209445, 5909.763609455574, 5474.781008759605, 5485.945140548623, 5495.450454954553, 5504.614676972654, 5542.695151766611, 6400.167978162809, 6400.775972840928, 6404.1674582304, 6418.550701450947, 6435.099086127946, 6144.771045790771, 6181.443670069715, 6210.937890621036, 6273.843153921189, 6286.74853005893, 6232.314445410911, 6366.0, 6368.554201205871, 6380.234371875219, 6382.776602818879, 6252.843678908063, 6258.499320054476, 6271.592346497463, 6273.059041143865, 6286.779962701284, 6096.2847974765855, 6172.382761723792, 6227.190465239671, 6232.0, 6265.248170219603, 6107.492261292593, 6117.279373342116, 6130.398136742091, 6202.317745047951, 6221.222347206599, 5232.319798426332, 6161.876762464636, 6217.347178546188, 6239.407256310643, 6244.500439964701, 4982.526659967298, 4993.950060499348, 5894.145348924296, 6015.72929218195, 6101.237345331833, 5946.751182251775, 6175.073738939195, 6188.659623720739, 6244.937550624617, 6254.530910181846, 5058.34241548611, 6050.727717252822, 6081.969590152198, 6093.451589356801, 6117.174181485442], "value": 5697.203916104543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.6568938985151, 488.8948875991662, 513.861257460486, 520.5003196930946, 559.8404454730405, 30394.54408183879, 38061.429078563844, 39397.40903886444, 39412.80293598532, 39455.0, 40386.192276154536, 41151.0, 41156.58843411565, 41267.58732412675, 41378.58621413785, 9346.39248448852, 9630.3258771886, 9644.469554174517, 9646.807063858718, 9669.806603867919, 9456.29077819163, 9577.138057574817, 9577.281703872202, 9580.760480987961, 9601.519924003807, 9523.904760952406, 9584.233261339112, 9595.904040959605, 9606.231501479864, 9613.182879455255, 9486.146246837783, 9523.57143928525, 9551.331406801526, 9553.0, 9575.281853860988, 9471.90528094717, 9591.952040239825, 9608.759781005461, 9617.615295388176, 9629.0, 38475.61524384748, 38845.0, 38914.61085389138, 39029.60970390288, 39078.80460597707, 3245.8377081146086, 3402.336544373848, 3427.057559171223, 3511.719062474996, 4084.550699423091, 30318.84840575783, 30368.696313037028, 30841.228969275722, 30852.53721194184, 30963.535546966814, 2894.160693398912, 3124.453220686371, 3220.8872689455757, 3301.0, 3372.5278461015478, 349519.0, 405856.0, 407316.0, 408398.0, 409159.0, 55705.0, 55730.0, 56009.15986260209, 56045.87908241812, 56148.0, 58405.831883362946, 58878.0, 58914.821703565685, 59012.70493647504, 59369.0, 14418.639534011523, 15380.769288460682, 15635.296411661502, 15719.214039298218, 15764.369425223093, 9224.492652904155, 10505.369677819355, 10807.567697292021, 12212.633620991384, 12234.265944043385, 12206.877931220573, 15172.78536899285, 15188.088714677155, 15230.238488075553, 15344.769828452581, 5698.230738850285, 6458.22501299847, 6539.771108011197, 6633.701483433257, 6634.23706273775, 775.716863344875, 891.5408564589242, 967.4630580028152, 975.2831668723483, 1026.3072426112344, 22560.66159007616, 29412.852935735184, 29471.557926631118, 29639.407211856073, 30267.545986810215, 930.5905401623264, 981.3768257156756, 1014.2342531388754, 1067.3115840283128, 1120.4789772755707, 13992.930035349758, 14816.481423150353, 14855.92572037133, 15134.940554161323, 15173.24133793328, 563752.6311316, 569080.0, 570894.0, 573351.0, 573893.0, 52772.47227527753, 52791.73604132031, 53051.73474132604, 53217.467825322026, 53238.0, 1348.8988325875598, 1351.6012776231034, 1406.9226192559304, 1408.084744915813, 1419.2690764256622, 23751.881240593688, 29846.55230171506, 30234.84882575661, 30309.848450757607, 31464.741410344246, 47762.28356574585, 47899.76050119727, 48268.27597585969, 48354.51645483641, 48382.790430238114, 566215.0, 569849.0, 570040.0, 571153.0, 575014.0, 24791.0, 24868.0, 25010.74989250084, 25312.98748050131, 25406.237812865642, 43008.354874677534, 43690.0, 44484.33273500836, 44673.0, 44752.328715069954, 37025.70410035636, 37159.442608360354, 38060.85817425482, 38163.236735265695, 38204.04489887775, 36714.0, 36834.0, 37102.814485927396, 37931.62068379281, 38001.80999094987, 36637.45043824398, 37726.62273377341, 38808.0, 38844.75595794644, 39765.0, 30409.0, 34264.828675856465, 34318.14204644904, 34406.0, 34484.65515344815, 25167.496650067264, 25469.87265063663, 25596.976120955696, 25627.231183064538, 26057.0], "value": 19049.529383816563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.60477321502367, 375.4480913057804, 378.594903453305, 387.078752568886, 401.78460158022, 18982.90508547457, 19970.400887973366, 20395.592088158228, 20675.276365327216, 20865.68701469479, 20216.0, 20269.493262668442, 20555.280565180223, 20564.280250191245, 20870.89564552179, 3598.85604575818, 4441.666874984381, 4854.320395144682, 4907.11671899058, 5374.247605335257, 5135.512126347991, 6058.485028772559, 6101.694915254242, 6104.572679912408, 6122.969385153068, 5721.513671337942, 5944.791932282371, 6003.759849606011, 6045.697715114248, 6066.848328791772, 5835.153902684087, 5921.467067963882, 5945.0, 5983.132445795335, 5998.670073145975, 4288.506821715511, 5062.519060689266, 5200.5059519345605, 5279.208118782175, 5279.366476022863, 11287.37919414432, 13279.933600331937, 13402.0, 18656.78730882488, 24767.0, 1742.6950283700303, 1981.4055783264962, 2237.7985981261763, 2256.751757306695, 2314.4676724353326, 9996.250281228937, 10268.589256429734, 10528.684139475828, 11715.589954351643, 14711.485098021518, 1597.456864666019, 1610.5892997285616, 1862.9441116766518, 1883.915223814918, 1907.83783378413, 116429.52134307868, 122439.0, 122838.0, 123661.38169309185, 124314.37842810819, 10171.4914254287, 10657.520411581498, 11635.476403561861, 11636.476358563885, 11816.586419475278, 11961.40192990347, 14643.707125857423, 14702.63243418923, 14715.852841471447, 14726.484573039892, 8205.466644668197, 8487.787805304917, 8543.444676096035, 8585.356098292654, 8597.699080532151, 8374.0, 8399.748007559783, 8468.039305574948, 8547.572621368907, 8618.870716939251, 4499.167653984024, 4588.862334129982, 4615.14619795339, 4676.7661616919695, 4681.438227412732, 1668.307652324296, 1736.2967997960845, 1882.2847318019064, 1933.1880610143774, 1960.2551030608277, 795.3915254830059, 883.337496877342, 899.9145081217274, 938.1134827587864, 963.9180669643018, 19553.413397598095, 20926.686099708517, 21042.530552986893, 21179.83510906884, 21388.679169812465, 922.2253307221965, 933.3793027636546, 948.6347756113807, 958.126861984818, 980.2158273381267, 10802.783944321069, 11075.72310692223, 11137.276077054965, 11208.719782005515, 11232.943835280934, 562468.0, 594765.0, 604185.0, 607530.0, 608209.0, 71251.0, 117067.0, 122330.0, 124576.0, 140448.0, 899.2221728205186, 932.7714709896119, 955.3885513271512, 958.2382006304865, 959.2421986630624, 18412.0, 18542.38423507403, 18572.421344185354, 18638.440846774618, 18696.0, 41923.0, 48971.02057958892, 51559.96880062303, 53476.46523534715, 54020.45979540154, 71467.64266178636, 77710.0, 78057.82913256192, 78785.0, 85290.0, 7435.628218589157, 7791.026121734783, 7934.350198908567, 8200.712975045844, 8215.917840821754, 12106.576269830513, 12124.0, 12311.076669250022, 12477.376131193223, 12550.372481375713, 10480.318779279627, 10874.67375978722, 10885.183611229348, 11016.504257308601, 11148.665540033811, 10596.258261921592, 10802.72993175177, 10837.729056773329, 10924.890751092706, 10975.780484390109, 10177.643782467578, 10606.840897386392, 10834.67495975122, 10973.177011723992, 11309.151813613847, 7120.928790712027, 7838.960805195938, 8085.878711819445, 8091.190880911861, 8224.958875205824, 9941.0, 10848.132149427869, 11125.944370278097, 11158.0, 11216.214864959375], "value": 9250.579566862023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.3133553091598, 1571.2929181868158, 1813.9479102120772, 1881.896495692738, 1926.5665225324294, 6449.8710025799455, 6672.566283191589, 8264.132266112061, 9024.593893274794, 9054.456732596049, 7353.48525603208, 8770.342224333168, 8777.824443511143, 8859.24696400805, 8889.59996800144, 2027.2093883385419, 2054.1680619349186, 2346.2843832631083, 2397.6883005209297, 2399.7360290368133, 2368.076450184421, 2407.374082738492, 2416.1664225842055, 2418.7581241875846, 2425.551273014498, 2397.1969390254285, 2406.097713357491, 2417.347316224618, 2421.5762241607736, 2429.2105065853666, 2386.2125498585424, 2391.246757271454, 2401.5677178107935, 2414.0, 2414.7585241475977, 2002.2391491233238, 2336.4392545788887, 2363.019346971022, 2363.409147713072, 2364.9645255321184, 8757.343199260082, 8844.11558844117, 8854.557272136368, 8880.8667869982, 8912.24245939096, 5253.921191182136, 5451.863703407407, 5454.127339625641, 5455.645383050089, 5461.344638643349, 3582.6954708849516, 3605.6214097519646, 3644.052546337945, 3681.7054635629083, 3693.9445908311395, 1061.946902654864, 1067.8291473364225, 1077.0, 1084.6474895658941, 1085.2294870641854, 11355.489002994802, 11498.42507874603, 11535.538578456852, 11629.47667354971, 11671.124665650026, 4223.852165174204, 4594.862154135314, 4784.976075119602, 4853.306414939742, 4900.240462728283, 8493.702720404879, 9878.506074696237, 9932.851007234898, 9943.955884632154, 9985.400875947467, 5282.283257941184, 5288.788448462095, 5297.973510132425, 5302.8939421212135, 5308.203769434615, 3988.980055099764, 4561.794719237642, 4849.9030019399415, 4893.314935908976, 4909.558139767438, 3944.684425245973, 3990.0, 4078.9184216316103, 4093.7543747375253, 4128.814203360856, 1299.844018717741, 1301.8828305452366, 1307.1634154141357, 1314.2443095220287, 1319.769040417923, 826.4297634632085, 914.8444764390069, 921.0789210789211, 930.265090578449, 976.9609215631295, 5769.8557536061935, 6072.605280656743, 6084.117802918552, 6142.723577439026, 6191.380861913773, 920.5167287174153, 982.1455333859587, 986.363795351994, 994.7910938702953, 1008.5159123620557, 3863.9806800965816, 3986.4418981342637, 4066.0851308455954, 4086.652634526039, 4166.64583510405, 10618.0, 10890.346579205274, 10907.781844363068, 10985.450727463754, 10992.120630349553, 10912.96326848948, 10922.399268040175, 10924.672259832218, 10927.3989930553, 10940.179486538542, 7589.810254743566, 7751.806204844924, 7881.605919704107, 8320.04319503265, 8321.41750077501, 5853.502452291603, 5885.882282354414, 6000.549958753199, 6018.6689732063405, 6021.698915054142, 8258.298044666271, 8739.519326437106, 9196.574530947713, 10093.0, 10109.848352274576, 8591.914080859362, 9685.6610018649, 10062.849057264293, 10066.496675166067, 10196.286259961731, 6928.757493487704, 7009.544379615309, 7152.749653762094, 7176.7846964591145, 7306.488545801955, 7534.171241163577, 8042.115367309708, 8231.753047408587, 8242.29940455068, 8276.627551760064, 7383.704651813868, 7489.625518724151, 7553.886691699519, 7581.393488520795, 7627.199144090013, 7268.41852651776, 7403.296686814744, 7440.925590744023, 7525.059367579052, 7532.322091011835, 5961.940380596138, 5963.701814909324, 6894.965525172342, 7026.297370262932, 7051.0, 4800.423949125988, 4844.539768721966, 4912.435069966999, 5025.7989680412375, 5044.991001799582, 4996.502448286142, 5098.311727916684, 5145.555860966011, 5251.474852514718, 5295.629305948547], "value": 4584.315219194431, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.1238408615016, 303.5371059134819, 309.8837935774085, 314.0797463432143, 317.8204314562272, 5481.287432633757, 5617.578681598881, 5617.831465056045, 5654.349749778771, 5674.0, 5576.665400075998, 5583.35791383991, 5586.765293882449, 5590.217369568264, 5657.264555607776, 3548.8757893473735, 3984.9278375244785, 4052.878413647595, 4098.815553300109, 4099.631033207011, 3846.5767665963494, 4148.5851414858425, 4182.79086045698, 4185.769782661952, 4205.306124489455, 3265.853036613358, 4093.672506199497, 4174.791260436981, 4192.140611174702, 4219.974412282107, 3892.455056292093, 4159.147374788161, 4195.769232692201, 4198.047907722924, 4209.116085622039, 3166.2401023754364, 3487.3897068013, 3539.8407071681577, 3561.5370001899587, 3602.4329416703818, 4643.931895664016, 5417.756200970965, 5486.753096110685, 5516.613837031409, 5520.806771762969, 4514.8194072237075, 4619.399478067863, 4796.664233503656, 4834.0, 4850.0, 5622.847316300149, 5855.707214639251, 5914.674692891889, 5942.108683697436, 5966.970165149147, 3754.6858599490233, 3767.6436482866147, 3949.8420063197436, 3988.282109220339, 4013.9397909031386, 5950.109391702227, 6112.51099912006, 6149.969250153766, 6217.844553886189, 6222.284437289679, 3583.2475180212446, 3961.9104746194453, 3972.662323702518, 3984.30274701926, 3991.0, 3475.6444986455303, 3880.243352546283, 4028.213479329421, 4081.3673880548563, 4104.8152833122585, 4528.864134075983, 4783.258594917794, 4789.856304310876, 4806.2310030394965, 4813.3983252093485, 4897.902041959141, 5840.970795145997, 5897.203877476486, 5929.644221346733, 5952.553558483131, 1212.8969037631723, 1346.6364081697886, 1361.1288775183891, 1377.5040985245303, 1387.9306034698225, 718.586812582767, 721.7942886277385, 780.5238804329355, 814.4339683919737, 841.87792770048, 4792.329073929653, 5088.949110508922, 5340.198970154508, 5544.390117087117, 5563.471470210324, 5149.871253218699, 5571.526420254243, 6120.663363515049, 6176.8764624707255, 6202.627842329474, 4507.323901414814, 5358.223057656618, 5514.448555144453, 5578.609497335228, 5698.373178950312, 2683.7718793902736, 2695.0, 2968.990543215274, 3205.8557364918634, 3227.949587639658, 5054.4692807254705, 5957.79147729836, 6232.688365581703, 6314.526410519229, 6328.936710632926, 5694.804091140907, 5892.322382925932, 5993.940060599425, 6168.660723660152, 6382.6170429774365, 6325.873482530324, 6333.904991425133, 6341.968290158519, 6375.330590288138, 6409.903851442139, 5088.262201980766, 5371.0, 5631.662100273997, 5714.742836572281, 6190.81427557174, 6075.453209211192, 6117.724702388314, 6119.0, 6134.478569321657, 6136.20229370179, 5658.5190258826815, 6174.6912654368, 6337.524685648687, 6453.128827608213, 6500.934990650033, 3740.4564895871067, 3984.2230765000545, 4001.0197501612292, 4023.3102097118694, 4028.71798974069, 4986.32684587576, 6029.758809647565, 6093.881139842553, 6126.866529692022, 6216.906746398898, 5707.543396528351, 5783.843231353662, 5906.0, 5981.431763982415, 6033.698315084316, 4729.739864307496, 5161.81933632321, 5176.844694659167, 5850.853728656818, 5905.616134951386, 5070.923936141035, 5388.811391601275, 5808.680522571299, 5823.534117270693, 5854.765809367748, 5198.870028249324, 5579.9163012555655, 5594.13290939905, 5844.532437405082, 5965.612235204698, 5347.1979203119845, 5435.728213589226, 5450.863728406663, 5776.768929242783, 5849.795257165979], "value": 4249.731369652485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.703929711981, 689.3003601344633, 724.9311315425033, 804.3324041045933, 810.7243537197352, 15067.698646027073, 15881.841181588181, 16047.197640117976, 16161.11113888737, 16254.187290635451, 14633.195174265411, 14796.0, 14797.112173269612, 14800.851991480082, 14840.183789891551, 697.3653974882858, 713.222587379756, 864.8918885139358, 866.376209129429, 884.1909652667833, 894.5482531321674, 895.1987970766161, 897.3404547657467, 900.5407242306405, 900.7432881628737, 921.8202450522151, 948.568401377375, 951.7097285327973, 953.1612181280464, 955.4028732042474, 775.9456838021341, 938.9389689670184, 943.2265542255353, 943.3538026451903, 947.7488465556632, 587.6855882103097, 602.7317843559574, 622.841175500249, 703.5637904499192, 706.254901079361, 13077.673058173526, 14988.025778324483, 15184.240787960669, 15184.316705748268, 15240.466583669519, 12167.0, 14472.0, 14535.78196327056, 14592.270386480739, 14653.194074325907, 11839.526418943233, 11910.880891191064, 11920.523179072827, 11927.403629818562, 11950.223235489751, 3742.172671977046, 3833.8083095845095, 3842.0971071798385, 3842.3660096084113, 3993.600639936012, 89712.0, 92355.53822230913, 105728.0, 106984.0, 108591.0, 11029.22795404314, 11143.052840508486, 11210.551577936954, 11241.887581124247, 11320.660380188607, 23329.766702333098, 23341.183058593208, 23387.41531461693, 23397.649035264483, 23416.64875026876, 7947.602619868983, 8131.390145738975, 8164.306033987177, 8206.056303525052, 8341.666333346599, 4391.121775644884, 4492.887677808016, 4500.662450316174, 4507.143642707875, 4512.932306015412, 3578.159132603836, 3681.153334732973, 3685.686716629063, 3686.115332320224, 3689.335919534456, 651.3258777165611, 651.6513665189101, 652.7780554611455, 661.6228749612675, 672.7510820996165, 599.5755480031484, 607.7022259093012, 658.2200092889941, 668.4117976181027, 684.4079870911725, 9214.585343659552, 9324.0, 9377.906220937839, 9389.62441502346, 9659.372140810825, 603.7494439807518, 646.3439608797074, 655.3250152343094, 659.3604203922188, 700.1283402164338, 4335.089631177488, 4364.018095928391, 4413.646908247332, 4485.461744590605, 4549.112922980054, 107185.39221911678, 112299.4385028086, 153679.23160384127, 158271.0, 162791.0, 122892.0, 137016.0, 139579.0, 142292.28853855663, 142406.0, 103680.0, 105686.0, 107328.46335768272, 107574.8485030295, 109232.0, 7296.327890769566, 7575.848483030199, 8730.734043563774, 8904.86642700347, 9117.316201284797, 55026.0, 58470.0, 66586.66706666436, 66966.330336696, 68165.3183468159, 49264.50735492599, 66226.33773662202, 98930.5053474728, 102932.0, 104657.0, 11927.284362938251, 13480.258585777876, 13542.729145417234, 13588.524401646291, 13660.728519049595, 14264.928675356558, 14709.485168019068, 14814.185219812583, 14888.181150036851, 15051.548453546411, 13699.246541440316, 13740.793888091886, 13867.930660346634, 14008.439662413797, 14477.203753793643, 13710.17738935667, 13951.720965580835, 14232.857671423153, 14409.0, 14471.565853024425, 12913.0, 12947.5468358607, 13268.33658317061, 13416.12795168311, 13734.244616546186, 11193.944030279796, 11265.887341126812, 11442.141839361915, 11602.825957610812, 11616.244944078608, 10150.102982796756, 10359.741006474596, 10511.264211505428, 10683.786324273626, 10934.45327733626], "value": 7829.201962660917, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1628.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 122.0, 159.0, 118.0, 120.0, 118.0, 130.0, 175.0, 158.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 142.0, 145.0, 172.0, 120.0, 116.0, 117.0, 117.0, 142.0, 118.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 240.0, 209.0, 279.0, 274.0, 187.0, 242.0, 210.0, 269.0, 229.0], "value": 235.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 76.0, 113.0, 88.0, 62.0, 61.0, 81.0, 70.0, 75.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 88.0, 73.0, 70.0, 74.0, 124.0, 100.0, 73.0, 88.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 87.0, 88.0, 86.0, 90.0, 87.0, 88.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 105.0, 91.0, 149.0, 150.0, 96.0, 95.0, 96.0, 102.0, 149.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 86.0, 87.0, 91.0, 89.0, 90.0, 114.0, 74.0, 135.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 62.0, 82.0, 49.0, 50.0, 57.0, 75.0, 83.0, 81.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 74.0, 83.0, 77.0, 69.0, 69.0, 69.0, 71.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 94.0, 89.0, 93.0, 95.0, 105.0, 111.0, 113.0, 104.0], "value": 99.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 167.0, 161.0, 167.0, 154.0, 147.0, 148.0, 168.0, 263.0, 150.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 204.0, 237.0, 195.0, 181.0, 181.0, 181.0, 178.0, 176.0], "value": 188.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 74.0, 72.0, 70.0, 70.0, 71.0, 70.0, 72.0, 70.0], "value": 70.9, "unit": "score"}]}]} 04:39:22 INFO - Return code: 0 04:39:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:39:22 ERROR - # TBPL SUCCESS # 04:39:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:39:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:39:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:39:22 INFO - 2016-10-18 04:39:22.228 screenresolution[1667:10880] starting screenresolution argv=screenresolution get 04:39:22 INFO - 2016-10-18 04:39:22.231 screenresolution[1667:10880] Display 0: 1600x1200x32@60 04:39:22 INFO - 2016-10-18 04:39:22.237 screenresolution[1668:10883] starting screenresolution argv=screenresolution list 04:39:22 INFO - Available Modes on Display 0 04:39:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:39:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:39:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:39:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:39:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:39:22 INFO - Intel Iris: 04:39:22 INFO - Chipset Model: Intel Iris 04:39:22 INFO - Type: GPU 04:39:22 INFO - Bus: Built-In 04:39:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:39:22 INFO - Vendor: Intel (0x8086) 04:39:22 INFO - Device ID: 0x0a2e 04:39:22 INFO - Revision ID: 0x0009 04:39:22 INFO - Displays: 04:39:22 INFO - Display: 04:39:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:39:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:39:22 INFO - Display Serial Number: 1600x1200 60 04:39:22 INFO - Main Display: Yes 04:39:22 INFO - Mirror: Off 04:39:22 INFO - Online: Yes 04:39:22 INFO - Rotation: Supported 04:39:22 INFO - Return code: 0 04:39:22 INFO - Running post-action listener: _resource_record_post_action 04:39:22 INFO - [mozharness: 2016-10-18 11:39:22.471899Z] Finished run-tests step (success) 04:39:22 INFO - Running post-run listener: _resource_record_post_run 04:39:22 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 39471616; Write bytes: 710604288; Read time: 389; Write time: 2040 04:39:22 INFO - TinderboxPrint: CPU usage
54.5% 04:39:22 INFO - TinderboxPrint: I/O read bytes / time
39,471,616 / 389 04:39:22 INFO - TinderboxPrint: I/O write bytes / time
710,604,288 / 2,040 04:39:22 INFO - TinderboxPrint: CPU idle
2,204.3 (45.5%) 04:39:22 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 04:39:22 INFO - TinderboxPrint: CPU user
1,665.7 (34.3%) 04:39:22 INFO - TinderboxPrint: Swap in / out
445,739,008 / 0 04:39:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 401734144; Write bytes: 395369984; Read time: 29625; Write time: 786 04:39:22 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 37353984; Write bytes: 298899456; Read time: 365; Write time: 1211 04:39:22 INFO - Running post-run listener: _upload_blobber_files 04:39:22 INFO - Blob upload gear active. 04:39:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:39:22 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 04:39:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:39:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:39:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:24 INFO - (blobuploader) - INFO - Done attempting. 04:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:25 INFO - (blobuploader) - INFO - Done attempting. 04:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:39:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:27 INFO - (blobuploader) - INFO - Done attempting. 04:39:27 INFO - (blobuploader) - INFO - Iteration through files over. 04:39:27 INFO - Return code: 0 04:39:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:39:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:39:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47ac1b8ab9ad6655c805789464d3ac713b0fb31afdece07a9d7e46b26c9dcaab43e7e4cef43ef0f763fd08c2673d442101318f4c4efba88eaa22d5ac6d699744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc02f202886c65b369803d246c6e557785744342e68984184964569f3ddfd44e21adee9bd4a9b5953abfcf193471f0595461274baeb834e378745178c209a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab7d5cb9f8ff1fb01b2876a17e801d915afc4cae5b6361a4ba6cb43d696314aa561f9c43e98257fbb4e40e2fbb00fee535c06bd38265a00acf823eacd17fcf34"} 04:39:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:39:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:39:27 INFO - Contents: 04:39:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47ac1b8ab9ad6655c805789464d3ac713b0fb31afdece07a9d7e46b26c9dcaab43e7e4cef43ef0f763fd08c2673d442101318f4c4efba88eaa22d5ac6d699744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc02f202886c65b369803d246c6e557785744342e68984184964569f3ddfd44e21adee9bd4a9b5953abfcf193471f0595461274baeb834e378745178c209a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab7d5cb9f8ff1fb01b2876a17e801d915afc4cae5b6361a4ba6cb43d696314aa561f9c43e98257fbb4e40e2fbb00fee535c06bd38265a00acf823eacd17fcf34"} 04:39:27 INFO - Running post-run listener: copy_logs_to_upload_dir 04:39:27 INFO - Copying logs to upload dir... 04:39:27 INFO - mkdir: /builds/slave/test/build/upload/logs 04:39:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.938894 ========= master_lag: 3.25 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-18 04:39:30.656184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 04:39:30.660965) ========= 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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47ac1b8ab9ad6655c805789464d3ac713b0fb31afdece07a9d7e46b26c9dcaab43e7e4cef43ef0f763fd08c2673d442101318f4c4efba88eaa22d5ac6d699744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc02f202886c65b369803d246c6e557785744342e68984184964569f3ddfd44e21adee9bd4a9b5953abfcf193471f0595461274baeb834e378745178c209a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab7d5cb9f8ff1fb01b2876a17e801d915afc4cae5b6361a4ba6cb43d696314aa561f9c43e98257fbb4e40e2fbb00fee535c06bd38265a00acf823eacd17fcf34"} build_url:https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010854 build_url: 'https://queue.taskcluster.net/v1/task/J9Z0R6nOQh-UusfZmEAnMg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47ac1b8ab9ad6655c805789464d3ac713b0fb31afdece07a9d7e46b26c9dcaab43e7e4cef43ef0f763fd08c2673d442101318f4c4efba88eaa22d5ac6d699744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bc02f202886c65b369803d246c6e557785744342e68984184964569f3ddfd44e21adee9bd4a9b5953abfcf193471f0595461274baeb834e378745178c209a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab7d5cb9f8ff1fb01b2876a17e801d915afc4cae5b6361a4ba6cb43d696314aa561f9c43e98257fbb4e40e2fbb00fee535c06bd38265a00acf823eacd17fcf34"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 04:39:30.702013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:39:30.702384) ========= 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.pbAmvOKLfy/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.LkMl76hvNJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005061 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 04:39:30.751433) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 04:39:30.754869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 04:39:31.032372) ========= ========= Total master_lag: 3.40 =========