builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1480485313.73 results: success (0) buildid: 20161129192900 builduid: 5f69be5de11a41faad5048a5ee33a52c revision: a69583d2dbc6fdc18f63761a89cf539c356668be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.729897) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.730343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.730615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.756883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.757238) ========= 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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 21:55:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2016-11-29 21:55:13 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110030 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.892892) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.893245) ========= 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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.025997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:13.945721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 21:55:13.946268) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a69583d2dbc6fdc18f63761a89cf539c356668be --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 a69583d2dbc6fdc18f63761a89cf539c356668be --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 21:55:14,047 truncating revision to first 12 chars 2016-11-29 21:55:14,047 Setting DEBUG logging. 2016-11-29 21:55:14,047 attempt 1/10 2016-11-29 21:55:14,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a69583d2dbc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 21:55:15,102 unpacking tar archive at: mozilla-central-a69583d2dbc6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.327410 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 21:55:15.300748) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:15.304091) ========= script_repo_revision: a69583d2dbc6fdc18f63761a89cf539c356668be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:15.304495) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:15.304780) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 21:55:15.324675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 21:55:15.324957) ========= /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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:15 INFO - MultiFileLogger online at 20161129 21:55:15 in /builds/slave/test 21:55:15 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 21:55:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:55:15 INFO - {'append_to_log': False, 21:55:15 INFO - 'base_work_dir': '/builds/slave/test', 21:55:15 INFO - 'blob_upload_branch': 'Firefox', 21:55:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:55:15 INFO - 'branch': 'Firefox', 21:55:15 INFO - 'buildbot_json_path': 'buildprops.json', 21:55:15 INFO - 'config_files': ('talos/mac_config.py',), 21:55:15 INFO - 'default_actions': ('clobber', 21:55:15 INFO - 'read-buildbot-config', 21:55:15 INFO - 'download-and-extract', 21:55:15 INFO - 'populate-webroot', 21:55:15 INFO - 'create-virtualenv', 21:55:15 INFO - 'install', 21:55:15 INFO - 'run-tests'), 21:55:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:55:15 INFO - 'download_minidump_stackwalk': True, 21:55:15 INFO - 'download_symbols': 'ondemand', 21:55:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:55:15 INFO - 'tooltool.py': '/tools/tooltool.py', 21:55:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:55:15 INFO - '/tools/misc-python/virtualenv.py')}, 21:55:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:55:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:55:15 INFO - 'installer_path': 'installer.exe', 21:55:15 INFO - 'log_level': 'info', 21:55:15 INFO - 'log_name': 'talos', 21:55:15 INFO - 'log_to_console': True, 21:55:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:55:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:55:15 INFO - 'opt_config_files': (), 21:55:15 INFO - 'pip_index': False, 21:55:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:55:15 INFO - 'cmd': ('bash', 21:55:15 INFO - '-c', 21:55:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:55:15 INFO - 'enabled': True, 21:55:15 INFO - 'halt_on_failure': False, 21:55:15 INFO - 'name': 'check_screen_resolution'},), 21:55:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:55:15 INFO - 'cmd': ('bash', 21:55:15 INFO - '-c', 21:55:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:55:15 INFO - 'enabled': True, 21:55:15 INFO - 'halt_on_failure': False, 21:55:15 INFO - 'name': 'check_screen_resolution'},), 21:55:15 INFO - 'run_cmd_checks_enabled': True, 21:55:15 INFO - 'sps_profile': False, 21:55:15 INFO - 'sps_profile_interval': 0, 21:55:15 INFO - 'suite': 'dromaeojs-e10s', 21:55:15 INFO - 'system_bits': '32', 21:55:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:55:15 INFO - 'title': 't-yosemite-r7-0082', 21:55:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:55:15 INFO - 'use_talos_json': True, 21:55:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:55:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:55:15 INFO - 'work_dir': 'build'} 21:55:15 INFO - [mozharness: 2016-11-30 05:55:15.535358Z] Running clobber step. 21:55:15 INFO - Running pre-action listener: _resource_record_pre_action 21:55:15 INFO - Running main action method: clobber 21:55:15 INFO - rmtree: /builds/slave/test/build 21:55:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:55:17 INFO - Running post-action listener: _resource_record_post_action 21:55:17 INFO - [mozharness: 2016-11-30 05:55:17.906244Z] Finished clobber step (success) 21:55:17 INFO - [mozharness: 2016-11-30 05:55:17.906364Z] Running read-buildbot-config step. 21:55:17 INFO - Running pre-action listener: _resource_record_pre_action 21:55:17 INFO - Running main action method: read_buildbot_config 21:55:17 INFO - Using buildbot properties: 21:55:17 INFO - { 21:55:17 INFO - "project": "", 21:55:17 INFO - "product": "firefox", 21:55:17 INFO - "script_repo_revision": "production", 21:55:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:55:17 INFO - "repository": "", 21:55:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:55:17 INFO - "buildid": "20161129192900", 21:55:17 INFO - "pgo_build": "False", 21:55:17 INFO - "basedir": "/builds/slave/test", 21:55:17 INFO - "buildnumber": 182, 21:55:17 INFO - "slavename": "t-yosemite-r7-0082", 21:55:17 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:55:17 INFO - "platform": "yosemite_r7", 21:55:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:55:17 INFO - "branch": "mozilla-central", 21:55:17 INFO - "repo_path": "mozilla-central", 21:55:17 INFO - "revision": "a69583d2dbc6fdc18f63761a89cf539c356668be", 21:55:17 INFO - "stage_platform": "macosx64", 21:55:17 INFO - "builduid": "5f69be5de11a41faad5048a5ee33a52c", 21:55:17 INFO - "slavebuilddir": "test" 21:55:17 INFO - } 21:55:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:55:17 INFO - Running post-action listener: _resource_record_post_action 21:55:17 INFO - [mozharness: 2016-11-30 05:55:17.909597Z] Finished read-buildbot-config step (success) 21:55:17 INFO - [mozharness: 2016-11-30 05:55:17.909730Z] Running download-and-extract step. 21:55:17 INFO - Running pre-action listener: _resource_record_pre_action 21:55:17 INFO - Running main action method: download_and_extract 21:55:17 INFO - mkdir: /builds/slave/test/build/tests 21:55:17 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')]} 21:55:17 INFO - trying https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:55:17 INFO - Downloading https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:55:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:55:19 INFO - Downloaded 1199 bytes. 21:55:19 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:55:19 INFO - Using the following test package requirements: 21:55:19 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:55:19 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:55:19 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:55:19 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'jsshell-mac.zip'], 21:55:19 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:55:19 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:55:19 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:55:19 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:55:19 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:55:19 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:19 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:55:19 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:55:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:55:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:55:19 INFO - Fetch https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:55:19 INFO - Http code: 200 21:55:19 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 21:55:19 INFO - x-amz-cf-id: uM5mYJCzejFll7lCqvIYmc_qXMKkhGsQbIpdbJ7ln2aThPduIhs0qg== 21:55:19 INFO - x-amz-version-id: celKo_KgzVBJeA.12Mv8SSBtfD4ZqQvG 21:55:20 INFO - Expected file size: 16286729 21:55:20 INFO - Obtained file size: 16286729 21:55:26 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:55:27 INFO - Http code: 200 21:55:27 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 21:55:27 INFO - x-amz-cf-id: F_19KqHT-D6KC7Cy_TnNnk6lYm1d4CX85ygNQayOBw0OA1fmUclRHw== 21:55:27 INFO - x-amz-version-id: celKo_KgzVBJeA.12Mv8SSBtfD4ZqQvG 21:55:27 INFO - Expected file size: 16286729 21:55:27 INFO - Obtained file size: 16286729 21:55:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:55:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:55:33 INFO - Fetch https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:55:34 INFO - Http code: 200 21:55:34 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 21:55:34 INFO - x-amz-cf-id: UjLxskronUenbW3QVjqhe4vTOzvgPis7BUie9ZGt5y0pKDjoJNCrTA== 21:55:34 INFO - x-amz-version-id: kwamtzVSxT7KXbuYmxCPERDzoh8Fyrkp 21:55:35 INFO - Expected file size: 14409702 21:55:35 INFO - Obtained file size: 14409702 21:55:35 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')]} 21:55:35 INFO - trying https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:55:35 INFO - Downloading https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:55:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:55:38 INFO - Downloaded 107590232 bytes. 21:55:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:55:38 INFO - mkdir: /builds/slave/test/properties 21:55:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:55:38 INFO - Writing to file /builds/slave/test/properties/build_url 21:55:38 INFO - Contents: 21:55:38 INFO - build_url:https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:55:38 INFO - Running post-action listener: _resource_record_post_action 21:55:38 INFO - Running post-action listener: set_extra_try_arguments 21:55:38 INFO - [mozharness: 2016-11-30 05:55:38.830817Z] Finished download-and-extract step (success) 21:55:38 INFO - [mozharness: 2016-11-30 05:55:38.830965Z] Running populate-webroot step. 21:55:38 INFO - Running pre-action listener: _resource_record_pre_action 21:55:38 INFO - Running main action method: populate_webroot 21:55:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:38 INFO - '--tppagecycles', 21:55:38 INFO - '7'], 21:55:38 INFO - 'tests': ['tsvgm']}}, 21:55:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:55:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:55:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:55:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['damp', 'tps']}, 21:55:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'tests': ['damp', 'tps']}, 21:55:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['dromaeo_dom']}, 21:55:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['basic_compositor_video']}, 21:55:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:55:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'other-e10s': {'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['a11yr', 21:55:38 INFO - 'ts_paint', 21:55:38 INFO - 'tpaint', 21:55:38 INFO - 'sessionrestore', 21:55:38 INFO - 'sessionrestore_no_auto_restore', 21:55:38 INFO - 'tabpaint']}, 21:55:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['tsvgx', 21:55:38 INFO - 'tsvgr_opacity', 21:55:38 INFO - 'tart', 21:55:38 INFO - 'tscrollx', 21:55:38 INFO - 'cart', 21:55:38 INFO - 'tsvg_static']}, 21:55:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:55:38 INFO - 'tsvgr_opacity', 21:55:38 INFO - 'tart', 21:55:38 INFO - 'tscrollx', 21:55:38 INFO - 'cart', 21:55:38 INFO - 'tsvg_static']}, 21:55:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'talos_options': ['--disable-e10s'], 21:55:38 INFO - 'tests': ['tp5o']}, 21:55:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:55:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'talos_options': ['--disable-e10s', 21:55:38 INFO - '--xperf_path', 21:55:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:38 INFO - 'tests': ['tp5n']}, 21:55:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:55:38 INFO - 'talos_options': ['--xperf_path', 21:55:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:38 INFO - 'tests': ['tp5n']}}, 21:55:38 INFO - 'talos.zip': {'path': '', 21:55:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:38 INFO - Running post-action listener: _resource_record_post_action 21:55:38 INFO - [mozharness: 2016-11-30 05:55:38.849055Z] Finished populate-webroot step (success) 21:55:38 INFO - [mozharness: 2016-11-30 05:55:38.849167Z] Running create-virtualenv step. 21:55:38 INFO - Running pre-action listener: _resource_record_pre_action 21:55:38 INFO - Running main action method: create_virtualenv 21:55:38 INFO - Creating virtualenv /builds/slave/test/build/venv 21:55:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:55:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:55:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:38 INFO - Using real prefix '/tools/python27' 21:55:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:55:39 INFO - Installing distribute.............................................................................................................................................................................................done. 21:55:42 INFO - Installing pip.................done. 21:55:42 INFO - Return code: 0 21:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:55:42 INFO - Reading from file tmpfile_stdout 21:55:42 INFO - Output received: 21:55:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:55:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:55:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:42 INFO - 'HOME': '/Users/cltbld', 21:55:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:42 INFO - 'LOGNAME': 'cltbld', 21:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:42 INFO - 'MOZ_NO_REMOTE': '1', 21:55:42 INFO - 'NO_EM_RESTART': '1', 21:55:42 INFO - 'PAGER': '/bin/cat', 21:55:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:42 INFO - 'PWD': '/builds/slave/test', 21:55:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:42 INFO - 'SHELL': '/bin/bash', 21:55:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:42 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:42 INFO - 'USER': 'cltbld', 21:55:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:42 INFO - 'XPC_FLAGS': '0x0', 21:55:42 INFO - 'XPC_SERVICE_NAME': '0', 21:55:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:43 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 21:55:43 INFO - Cleaning up... 21:55:43 INFO - Return code: 0 21:55:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:55: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')]} 21:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:43 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 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:43 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 21:55:43 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 21:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:43 INFO - 'HOME': '/Users/cltbld', 21:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:43 INFO - 'LOGNAME': 'cltbld', 21:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:43 INFO - 'MOZ_NO_REMOTE': '1', 21:55:43 INFO - 'NO_EM_RESTART': '1', 21:55:43 INFO - 'PAGER': '/bin/cat', 21:55:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:43 INFO - 'PWD': '/builds/slave/test', 21:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:43 INFO - 'SHELL': '/bin/bash', 21:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:43 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:43 INFO - 'USER': 'cltbld', 21:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:43 INFO - 'XPC_FLAGS': '0x0', 21:55:43 INFO - 'XPC_SERVICE_NAME': '0', 21:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:43 INFO - Downloading/unpacking psutil>=3.1.1 21:55:43 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 21:55:43 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 21:55:43 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 21:55:43 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 21:55:43 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 21:55:43 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 21:55:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:55:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:46 INFO - Installing collected packages: psutil 21:55:46 INFO - Running setup.py install for psutil 21:55:46 INFO - building 'psutil._psutil_osx' extension 21:55:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:55:47 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 21:55:47 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 21:55:47 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 21:55:47 INFO - building 'psutil._psutil_posix' extension 21:55:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:55:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:55:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:55:47 INFO - ^ 21:55:47 INFO - 1 warning generated. 21:55:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:55:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:48 INFO - Successfully installed psutil 21:55:48 INFO - Cleaning up... 21:55:48 INFO - Return code: 0 21:55:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:55: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')]} 21:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:55: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 mozsystemmonitor==0.3 21:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:48 INFO - 'HOME': '/Users/cltbld', 21:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:48 INFO - 'LOGNAME': 'cltbld', 21:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:48 INFO - 'MOZ_NO_REMOTE': '1', 21:55:48 INFO - 'NO_EM_RESTART': '1', 21:55:48 INFO - 'PAGER': '/bin/cat', 21:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:48 INFO - 'PWD': '/builds/slave/test', 21:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:48 INFO - 'SHELL': '/bin/bash', 21:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:48 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:48 INFO - 'USER': 'cltbld', 21:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:48 INFO - 'XPC_FLAGS': '0x0', 21:55:48 INFO - 'XPC_SERVICE_NAME': '0', 21:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:55:48 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 21:55:48 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 21:55:48 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 21:55:48 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 21:55:48 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 21:55:48 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 21:55:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:55:50 INFO - Installing collected packages: mozsystemmonitor 21:55:50 INFO - Running setup.py install for mozsystemmonitor 21:55:51 INFO - Successfully installed mozsystemmonitor 21:55:51 INFO - Cleaning up... 21:55:51 INFO - Return code: 0 21:55:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:55:51 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')]} 21:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:51 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')]} 21:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:51 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 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:51 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 21:55:51 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 21:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:51 INFO - 'HOME': '/Users/cltbld', 21:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:51 INFO - 'LOGNAME': 'cltbld', 21:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:51 INFO - 'MOZ_NO_REMOTE': '1', 21:55:51 INFO - 'NO_EM_RESTART': '1', 21:55:51 INFO - 'PAGER': '/bin/cat', 21:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:51 INFO - 'PWD': '/builds/slave/test', 21:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:51 INFO - 'SHELL': '/bin/bash', 21:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:51 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:51 INFO - 'USER': 'cltbld', 21:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:51 INFO - 'XPC_FLAGS': '0x0', 21:55:51 INFO - 'XPC_SERVICE_NAME': '0', 21:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:51 INFO - Downloading/unpacking jsonschema==2.5.1 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:54 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:54 INFO - warning: no files found matching '*.txt' 21:55:54 INFO - no previously-included directories found matching 'build' 21:55:54 INFO - no previously-included directories found matching 'dist' 21:55:54 INFO - no previously-included directories found matching '.git*' 21:55:54 INFO - Installing collected packages: jsonschema, functools32 21:55:54 INFO - Running setup.py install for functools32 21:55:54 INFO - warning: no files found matching '*.txt' 21:55:54 INFO - no previously-included directories found matching 'build' 21:55:54 INFO - no previously-included directories found matching 'dist' 21:55:54 INFO - no previously-included directories found matching '.git*' 21:55:54 INFO - Successfully installed jsonschema functools32 21:55:54 INFO - Cleaning up... 21:55:54 INFO - Return code: 0 21:55:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:54 INFO - 'HOME': '/Users/cltbld', 21:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:54 INFO - 'LOGNAME': 'cltbld', 21:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:54 INFO - 'MOZ_NO_REMOTE': '1', 21:55:54 INFO - 'NO_EM_RESTART': '1', 21:55:54 INFO - 'PAGER': '/bin/cat', 21:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:54 INFO - 'PWD': '/builds/slave/test', 21:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:54 INFO - 'SHELL': '/bin/bash', 21:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:54 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:54 INFO - 'USER': 'cltbld', 21:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:54 INFO - 'XPC_FLAGS': '0x0', 21:55:54 INFO - 'XPC_SERVICE_NAME': '0', 21:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:55:55 INFO - Cleaning up... 21:55:55 INFO - Return code: 0 21:55:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:55:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:55:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:55 INFO - 'HOME': '/Users/cltbld', 21:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:55 INFO - 'LOGNAME': 'cltbld', 21:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:55 INFO - 'MOZ_NO_REMOTE': '1', 21:55:55 INFO - 'NO_EM_RESTART': '1', 21:55:55 INFO - 'PAGER': '/bin/cat', 21:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:55 INFO - 'PWD': '/builds/slave/test', 21:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:55 INFO - 'SHELL': '/bin/bash', 21:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:55:55 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:55 INFO - 'USER': 'cltbld', 21:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:55 INFO - 'XPC_FLAGS': '0x0', 21:55:55 INFO - 'XPC_SERVICE_NAME': '0', 21:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:55 INFO - Downloading/unpacking blobuploader==1.2.4 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:57 INFO - Downloading blobuploader-1.2.4.tar.gz 21:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:55:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:55:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:59 INFO - Downloading docopt-0.6.1.tar.gz 21:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:59 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:59 INFO - Running setup.py install for blobuploader 21:55:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:55:59 INFO - Running setup.py install for requests 21:55:59 INFO - Running setup.py install for docopt 21:55:59 INFO - Successfully installed blobuploader requests docopt 21:55:59 INFO - Cleaning up... 21:56:00 INFO - Return code: 0 21:56:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:00 INFO - 'HOME': '/Users/cltbld', 21:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:00 INFO - 'LOGNAME': 'cltbld', 21:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:00 INFO - 'MOZ_NO_REMOTE': '1', 21:56:00 INFO - 'NO_EM_RESTART': '1', 21:56:00 INFO - 'PAGER': '/bin/cat', 21:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:00 INFO - 'PWD': '/builds/slave/test', 21:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:00 INFO - 'SHELL': '/bin/bash', 21:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:56:00 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:00 INFO - 'USER': 'cltbld', 21:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:00 INFO - 'XPC_FLAGS': '0x0', 21:56:00 INFO - 'XPC_SERVICE_NAME': '0', 21:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-BcItL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-yBYNKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Nx4KCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8sCE57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-w1V7I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-NMgurK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:00 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8wep0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ERp_pR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-wVG7aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-bg94C0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-kBjnty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-C0XaZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eS7TWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-fBC9wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-VkljGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-CP2aMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-orO_Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:56:02 INFO - Running setup.py install for manifestparser 21:56:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:56:02 INFO - Running setup.py install for mozcrash 21:56:02 INFO - Running setup.py install for mozdebug 21:56:02 INFO - Running setup.py install for mozdevice 21:56:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:56:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:56:02 INFO - Running setup.py install for mozfile 21:56:02 INFO - Running setup.py install for mozhttpd 21:56:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:56:02 INFO - Running setup.py install for mozinfo 21:56:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozInstall 21:56:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozleak 21:56:03 INFO - Running setup.py install for mozlog 21:56:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for moznetwork 21:56:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozprocess 21:56:03 INFO - Running setup.py install for mozprofile 21:56:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for mozrunner 21:56:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for mozscreenshot 21:56:04 INFO - Running setup.py install for moztest 21:56:04 INFO - Running setup.py install for mozversion 21:56:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:56:04 INFO - Cleaning up... 21:56:04 INFO - Return code: 0 21:56:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:04 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 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:56:04 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 21:56:04 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 21:56:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:56:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:04 INFO - 'HOME': '/Users/cltbld', 21:56:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:04 INFO - 'LOGNAME': 'cltbld', 21:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:04 INFO - 'MOZ_NO_REMOTE': '1', 21:56:04 INFO - 'NO_EM_RESTART': '1', 21:56:04 INFO - 'PAGER': '/bin/cat', 21:56:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:04 INFO - 'PWD': '/builds/slave/test', 21:56:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:04 INFO - 'SHELL': '/bin/bash', 21:56:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:56:04 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:56:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:04 INFO - 'USER': 'cltbld', 21:56:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:04 INFO - 'XPC_FLAGS': '0x0', 21:56:04 INFO - 'XPC_SERVICE_NAME': '0', 21:56:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-SZ73lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-JuA8Bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xiBbF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Hdj5zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vl13eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-MonRVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-4cM80B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-S9sWUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-RRh6Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Fo9Iq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7SLtCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ByTA3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-l1Sq_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-LReqVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-662P3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-j2aYhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-0AmBdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:06 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)) 21:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:56:06 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)) 21:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:56:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:09 INFO - Downloading blessings-1.6.tar.gz 21:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:56:09 INFO - Installing collected packages: blessings 21:56:09 INFO - Running setup.py install for blessings 21:56:09 INFO - Successfully installed blessings 21:56:09 INFO - Cleaning up... 21:56:09 INFO - Return code: 0 21:56:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:10 INFO - Reading from file tmpfile_stdout 21:56:10 INFO - Current package versions: 21:56:10 INFO - blessings == 1.6 21:56:10 INFO - blobuploader == 1.2.4 21:56:10 INFO - docopt == 0.6.1 21:56:10 INFO - functools32 == 3.2.3-2 21:56:10 INFO - jsonschema == 2.5.1 21:56:10 INFO - manifestparser == 1.1 21:56:10 INFO - mozInstall == 1.12 21:56:10 INFO - mozcrash == 1.0 21:56:10 INFO - mozdebug == 0.1 21:56:10 INFO - mozdevice == 0.49 21:56:10 INFO - mozfile == 1.2 21:56:10 INFO - mozhttpd == 0.7 21:56:10 INFO - mozinfo == 0.9 21:56:10 INFO - mozleak == 0.1 21:56:10 INFO - mozlog == 3.4 21:56:10 INFO - moznetwork == 0.27 21:56:10 INFO - mozprocess == 0.23 21:56:10 INFO - mozprofile == 0.28 21:56:10 INFO - mozrunner == 6.13 21:56:10 INFO - mozscreenshot == 0.1 21:56:10 INFO - mozsystemmonitor == 0.3 21:56:10 INFO - moztest == 0.7 21:56:10 INFO - mozversion == 1.4 21:56:10 INFO - psutil == 3.1.1 21:56:10 INFO - requests == 1.2.3 21:56:10 INFO - wsgiref == 0.1.2 21:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:10 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 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:56:10 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 21:56:10 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 21:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:10 INFO - 'HOME': '/Users/cltbld', 21:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:10 INFO - 'LOGNAME': 'cltbld', 21:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:10 INFO - 'MOZ_NO_REMOTE': '1', 21:56:10 INFO - 'NO_EM_RESTART': '1', 21:56:10 INFO - 'PAGER': '/bin/cat', 21:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:10 INFO - 'PWD': '/builds/slave/test', 21:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:10 INFO - 'SHELL': '/bin/bash', 21:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:56:10 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:10 INFO - 'USER': 'cltbld', 21:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:10 INFO - 'XPC_FLAGS': '0x0', 21:56:10 INFO - 'XPC_SERVICE_NAME': '0', 21:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:56:11 INFO - Cleaning up... 21:56:11 INFO - Return code: 0 21:56:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094f43f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5914c9490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, '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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 21:56:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:56:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:56:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:11 INFO - 'HOME': '/Users/cltbld', 21:56:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:11 INFO - 'LOGNAME': 'cltbld', 21:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:11 INFO - 'MOZ_NO_REMOTE': '1', 21:56:11 INFO - 'NO_EM_RESTART': '1', 21:56:11 INFO - 'PAGER': '/bin/cat', 21:56:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:11 INFO - 'PWD': '/builds/slave/test', 21:56:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:11 INFO - 'SHELL': '/bin/bash', 21:56:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:56:11 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:56:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:11 INFO - 'USER': 'cltbld', 21:56:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:11 INFO - 'XPC_FLAGS': '0x0', 21:56:11 INFO - 'XPC_SERVICE_NAME': '0', 21:56:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:56:11 INFO - Cleaning up... 21:56:11 INFO - Return code: 0 21:56:11 INFO - Running post-action listener: _resource_record_post_action 21:56:11 INFO - Running post-action listener: _start_resource_monitoring 21:56:11 INFO - Starting resource monitoring. 21:56:11 INFO - [mozharness: 2016-11-30 05:56:11.716054Z] Finished create-virtualenv step (success) 21:56:11 INFO - [mozharness: 2016-11-30 05:56:11.716993Z] Running install step. 21:56:11 INFO - Running pre-action listener: _resource_record_pre_action 21:56:11 INFO - Running main action method: install 21:56:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:11 INFO - Reading from file tmpfile_stdout 21:56:11 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:56:12 INFO - Reading from file tmpfile_stdout 21:56:12 INFO - Output received: 21:56:12 INFO - Usage: mozinstall [options] installer 21:56:12 INFO - Options: 21:56:12 INFO - -h, --help show this help message and exit 21:56:12 INFO - -d DEST, --destination=DEST 21:56:12 INFO - Directory to install application into. [default: 21:56:12 INFO - "/builds/slave/test"] 21:56:12 INFO - --app=APP Application being installed. [default: firefox] 21:56:12 INFO - mkdir: /builds/slave/test/build/application 21:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:56:45 INFO - Reading from file tmpfile_stdout 21:56:45 INFO - Output received: 21:56:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:56:45 INFO - Running post-action listener: _resource_record_post_action 21:56:45 INFO - [mozharness: 2016-11-30 05:56:45.224066Z] Finished install step (success) 21:56:45 INFO - [mozharness: 2016-11-30 05:56:45.224287Z] Running run-tests step. 21:56:45 INFO - Running pre-action listener: _resource_record_pre_action 21:56:45 INFO - Running main action method: run_tests 21:56:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:56:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:56:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:56:45 INFO - 2016-11-29 21:56:45.238 screenresolution[1628:7599] starting screenresolution argv=screenresolution get 21:56:45 INFO - 2016-11-29 21:56:45.241 screenresolution[1628:7599] Display 0: 1600x1200x32@60 21:56:45 INFO - 2016-11-29 21:56:45.247 screenresolution[1629:7602] starting screenresolution argv=screenresolution list 21:56:45 INFO - Available Modes on Display 0 21:56:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:56:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:56:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:56:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:56:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:56:45 INFO - Intel Iris: 21:56:45 INFO - Chipset Model: Intel Iris 21:56:45 INFO - Type: GPU 21:56:45 INFO - Bus: Built-In 21:56:45 INFO - VRAM (Dynamic, Max): 1536 MB 21:56:45 INFO - Vendor: Intel (0x8086) 21:56:45 INFO - Device ID: 0x0a2e 21:56:45 INFO - Revision ID: 0x0009 21:56:45 INFO - Displays: 21:56:45 INFO - Display: 21:56:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:56:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:56:45 INFO - Display Serial Number: 1600x1200 60 21:56:45 INFO - Main Display: Yes 21:56:45 INFO - Mirror: Off 21:56:45 INFO - Online: Yes 21:56:45 INFO - Rotation: Supported 21:56:45 INFO - Return code: 0 21:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:56:45 INFO - Python 2.7.3 21:56:45 INFO - Return code: 0 21:56:45 INFO - grabbing minidump binary from tooltool 21:56: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')]} 21:56:45 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 0x10930ad30>, '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 0x109171db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109302b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:56:45 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 21:56:45 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 21:56:45 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 21:56:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:56:45 INFO - Return code: 0 21:56:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:56:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:56:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:56:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:56:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:56:45 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/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 21:56:45 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/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --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 21:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GWxCMMkcD0/Render', 21:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:45 INFO - 'HOME': '/Users/cltbld', 21:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:45 INFO - 'LOGNAME': 'cltbld', 21:56:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:56:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:45 INFO - 'MOZ_NO_REMOTE': '1', 21:56:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:56:45 INFO - 'NO_EM_RESTART': '1', 21:56:45 INFO - 'PAGER': '/bin/cat', 21:56:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:45 INFO - 'PWD': '/builds/slave/test', 21:56:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:45 INFO - 'SHELL': '/bin/bash', 21:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjC3vKTEOX/Listeners', 21:56:45 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 21:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:45 INFO - 'USER': 'cltbld', 21:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:45 INFO - 'XPC_FLAGS': '0x0', 21:56:45 INFO - 'XPC_SERVICE_NAME': '0', 21:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:45 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/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 21:56:45 INFO - mozversion application_buildid: 20161129192900 21:56:45 INFO - mozversion application_changeset: a69583d2dbc6fdc18f63761a89cf539c356668be 21:56:45 INFO - mozversion application_display_name: Nightly 21:56:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:56:45 INFO - mozversion application_name: Firefox 21:56:45 INFO - mozversion application_remotingname: firefox 21:56:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:56:45 INFO - mozversion application_vendor: Mozilla 21:56:45 INFO - mozversion application_version: 53.0a1 21:56:45 INFO - mozversion platform_buildid: 20161129192900 21:56:45 INFO - mozversion platform_changeset: a69583d2dbc6fdc18f63761a89cf539c356668be 21:56:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:56:45 INFO - mozversion platform_version: 53.0a1 21:56:45 INFO - using testdate: 1480485405 21:56:45 INFO - actual date: 1480485405 21:56:45 INFO - starting webserver on 'localhost:49248' 21:56:45 INFO - SUITE-START | Running 2 tests 21:56:45 INFO - TEST-START | dromaeo_css 21:56:45 INFO - operating with platform_type : mac_ 21:56:45 INFO - Initialising browser for dromaeo_css test... 21:56:45 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp7z3yPr/profile http://localhost:49248/getInfo.html) 21:56:48 INFO - PROCESS | 1639 | 2016-11-29 21:56:48.802 plugin-container[1641:7778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9147, name = 'com.apple.tsm.portname' 21:56:48 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:56:48 INFO - PROCESS | 1639 | 2016-11-29 21:56:48.805 plugin-container[1641:7778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e47, name = 'com.apple.CFPasteboardClient' 21:56:48 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:56:48 INFO - PROCESS | 1639 | 2016-11-29 21:56:48.805 plugin-container[1641:7778] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:56:48 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 21:56:48 INFO - PROCESS | 1639 | colorDepth:24 21:56:48 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 21:56:48 INFO - PROCESS | 1639 | __metrics 21:56:48 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 21:56:49 INFO - TEST-INFO | 1639: exit 0 21:56:49 INFO - Browser initialized. 21:56:49 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:49 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp7z3yPr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:52 INFO - PROCESS | 1642 | 2016-11-29 21:56:52.286 plugin-container[1643:7897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 21:56:52 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:56:52 INFO - PROCESS | 1642 | 2016-11-29 21:56:52.286 plugin-container[1643:7897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 21:56:52 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:56:52 INFO - PROCESS | 1642 | 2016-11-29 21:56:52.286 plugin-container[1643:7897] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:57:01 INFO - PROCESS | 1642 | RSS: Main: 242749440 21:57:01 INFO - PROCESS | 1642 | 21:57:51 INFO - PROCESS | 1642 | 1480485471596 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] 22:00:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 22:00:06 INFO - PROCESS | 1642 | RSS: Main: 251248640 22:00:06 INFO - PROCESS | 1642 | 22:03:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 22:03:09 INFO - PROCESS | 1642 | RSS: Main: 266235904 22:03:09 INFO - PROCESS | 1642 | 22:06:11 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 22:06:12 INFO - PROCESS | 1642 | RSS: Main: 271839232 22:06:12 INFO - PROCESS | 1642 | 22:09:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 22:09:15 INFO - PROCESS | 1642 | RSS: Main: 270610432 22:09:15 INFO - PROCESS | 1642 | 22:12:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 22:12:18 INFO - PROCESS | 1642 | RSS: Main: 276566016 22:12:18 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 22:15:21 INFO - PROCESS | 1642 | RSS: Main: 279306240 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | __start_tp_report 22:15:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 22:15:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 22:15:21 INFO - PROCESS | 1642 | |i|pagename|runs| 22:15:21 INFO - PROCESS | 1642 | |0;dojo.html;3421.538092357531;3421.8118003509803;3427.2117412995026;3437.4843773434;3466.9306613867725;5653.180288858113;5761.078227483604;5771.365149833515;5783.190353350535;5828.271466066742;5642.576806739501;5731.340895796985;5762.798302059428;5815.069588865782;5816.680082595455;5160.406553246387;5203.947960520403;5242.764075616587;5283.07546179419;5332.946670533303;5235.685858848481;5345.893082138355;5357.4374690657505;5361.946380536184;5386.91919621204;5138.177891537373;5297.44376840432;5308.601854860901;5319.946800531984;5349.465053494659;5192.818251361222;5247.449017853147;5252.842414727565;5262.05283049051;5266.420693723688;5155.587552995751;5189.974050129763;5196.142636464979;5228.686278823245;5239.502247286502;5600.047991841397;5657.12314591239;5714.857128571777;5730.19777231188;5731.799387021476;6127.830981140938;6156.7537298508005;6170.074488826712;6189.257289125334;6193.566450348432;5906.763729450817;6231.813045608639;6248.406401391906;6280.591761535486;6298.905516417258;5360.624756267063;5403.972980135074;5907.350191478934;5915.142304365929;5915.911261330997;6118.893302669218;6167.660778657128;6182.412670796267;6324.051392291193;6334.271558770708;6130.049842274455;6155.261368635793;6157.907631385533;6164.8767024659255;6207.6585787782105;6224.937750622526;6237.157983672237;6248.9062664060075;6260.624362538262;6261.937380626136;6108.847278818065;6113.537117903944;6114.78598249068;6143.542582966759;6171.84570385735;6184.721687524072;6224.782132625336;6227.408396202353;6237;6243.843903902348;4910.189818679878;4942.110420124341;4960.355153830051;4962.156433406258;4984.675996060317;4377.759223242752;4394.802233899482;4402.119576084796;4429.579189976947;4467.061916997467;5678.971605141948;5809.331926828469;5824.737886795105;5838.970805145947;5845.620034697731;6168.013117901114;6219.689015549205;6235.282942461584;6243.43809057187;6252.218472690914;5637.408072152365;5675.971620141873;5826.242588463472;5913.674747888907;5925.111233315038;5367.731613419392;5373.677579345252;5377.650452720639;5382.650127741685;5394.757235924393;6242.625442473466;6248.937510624835;6271.278802937721;6284.622922624657;6297.685115744286;4632.652551058752;5332.520073193431;6222.09779581949;6230.813075607739;6264.780732674334;5285.973570132125;5335.813246536352;6392.904106438315;6425.678716064084;6428.4214420702365;6105.14527966094;6115.179495343419;6126.151269885628;6146.8935591593945;6191.25704915413;6136.1821086261625;6151.446369826737;6166.568340216142;6233.781817636361;6256.405641464054;5043.44522102561;6042.848928776816;6077.331493535796;6096.055111457731;6126.479249263684;5978.611390259619;6118.143459915705;6122.663253521099;6140.508759299335;6148.538859585459;5773.624714393551;5929.792457263975;5942.197803296673;5979.701014949323;5996.220491336098;6132.724027418688;6170.382961703794;6171.105189747551;6177.505799535937;6188.226471691039;4839.564439200489;5625;6032.698365081641;6040.879182416415;6059.242594675666 22:15:21 INFO - PROCESS | 1642 | |1;ext.html;448.15075432056256;475.83583663636034;494.7996061595055;517.3300575754399;522.1384715219887;39320.213595728106;41200;41277.79361103198;43033.7848310758;43123.35314970278;41562.37656435156;41574;41963.58036419635;42115.15769684612;42140.78592140784;8348.290395316406;9284.675036373728;9440.622375105022;9446.763830904249;9474.336796424253;8426.78933026673;11084.445777711082;11430.657080287563;11474.598389056384;11648.242864213798;11352.943235283812;11376.317420954727;11426.542938282459;11435.371054591995;11458.140639452033;11392.316461012366;11456.713582160472;11462.140339474572;11481.253718508271;11513.827292590619;11229.27009744364;11313.717157071056;11333.09335253186;11339.546418143264;11365.488553015135;39307.82076537709;39478.802605987075;39614;39713.80143099295;39753;3243.3026899216547;3253.755968302387;3356.597208334991;3364.276680513701;3424.845881935294;29400.11799646014;29512;29863.850680746673;30388.784448622027;30881.69118308833;3186.665400132999;3194.872205111793;3204.871805127792;3311.205310725433;3355.429576971896;458011;458276;458660;460626;463633;60978.69510652418;61183;61269.69365153235;61403;61526;64361;64385.67807160934;64445.35554644487;64977;65133.69732605321;15744.763828542582;15874.285657145456;15984.36062557508;15985.920070399574;16040.518784436486;10947.178961577914;11184.161187910939;11982;12114.394280285951;12132.150749447454;18303.99328036971;18755.718664220047;18992.715109273373;19158.61682766337;19258.518537036405;6443.226812782403;6931.272216417204;7237.203907570163;7346.5224760391475;7827.25641064098;855.5465603230298;911.6307895302413;912.5619702542684;971.455984648599;1026.825439675257;24950.750492495205;25902.154041808808;25963;28279.858600707277;29811.254718632208;922.294444749767;1034.2450011491503;1034.6275340877278;1038.3147122899002;1055.8996895294936;14857.925710371595;15325.693486130214;15519.349483582992;15722;15766.054036757601;591698;600005;604961.6030717532;612363;618930;56849;57618.7119064402;57803.421965780646;57833;57867.84264314774;1294.5145570411096;1326.6666999665274;1333.7999300105062;1355.2546099645156;1357.8574249703836;28481.43037139287;29247.7075229245;29250;29764.95822646197;29838;50270.748646256536;50599.74700126476;50897;50908.49091509038;50943.49056509536;507983;515856;517901;525496;527556;23280;26131.346716332242;26226.344341391617;26338.60492092582;26421.33946651349;43271.56728432801;44260;46776.7661161692;47741.283880741124;48625.75687121542;31778.364432711678;34772.82613586916;37624;37637.81181094077;37880.62119378771;37714.55816910358;37905.62094379131;38706.0323491915;38948;39353;36875.81562092173;37185.81407093055;37241.44137837989;37293.4405983905;38216.61783382242;33041.33917321592;33940.49089263614;33975.830120849234;34200.31599367949;34249.65750342465;24928.501429970936;25060.874695626404;25069.247922562354;25093.247202583952;25951.22146335613 22:15:21 INFO - PROCESS | 1642 | |2;jquery.html;368.25612263228294;368.4451614188125;370.5886466022715;373.74958777619;396.22537938330566;18413.17140728667;18575.72136417953;18775.342862999798;19277.325293614707;19423.223071077176;11486;11912.940435297833;11926.701832454193;11931.58239461619;11968.042556595474;5196.662216955905;5788.594798364116;5853.76584936602;5865.178874957509;5875.441833025856;3805.429185622151;4033.455483509718;5655.7172141392975;5835.766569337221;5962.701864906759;6021.2473440819895;6054.5459090568165;6060.33336333003;6075.45320921117;6080.14877917092;5775.711214439282;5929.436703513181;5990.251218597676;6016.699165041774;6019.307779605358;4983.900321993576;5667.178259152441;5726.3414707308775;5782.161586569966;5798.797042103506;22672.31983040495;22743.43141421461;23409.180678676334;23527.764722352727;23949.640755388682;2137.5083730742017;2146.914123435061;2154.0091557883393;2340.5903966805913;2499.262717498342;9219.078092190794;9658.130768230892;9783.41299522031;9816.018398160199;10150;1666.924988375526;1759.6744602248625;1765.3556451895095;1813.5103522049105;1821.6720990221754;113589.86410135958;118690.21964670623;118831.40584297023;119004;119919.80080199173;11223.270487418291;11311.547538098384;11767.88232117685;11821.704457388634;11916.702082447835;14313.926455515879;14380.928095359666;14385.208813515148;14398.280085995659;14426;7994.120646728855;8240.752777416566;8259.339252859876;8374.790630234293;8388.035375931724;8011.399145064143;8027.1972802719265;8039.477433966774;8196.754097377088;8203.015638123463;4407.515173330931;4448.266036104006;4472.351509031172;4485.753283569435;4537.956270057905;1572.512521118457;1654.24649871137;1666.991665041667;1888.376835644234;1964.88210707358;868.3617541107282;893.2898345815092;894.5348418822176;951.695457453622;984.4241118945497;19509.122089506007;20134.798652013586;20539.38381848547;20607.278745243846;20615.86612736314;873.6549063120032;905.7509184974185;935.9672411465566;938.6808485115093;1010.9191264698807;10444.947775261075;10896;10902.236843421044;10927.508262128224;10945.781084378268;513066;515560;525690;527953;537063;91719.54140229255;95426.04573954172;119995.8000420002;128122;132995;843.8649816029406;932.0027570499636;954.3128947158035;969.6266937229218;992.6178421307849;13659.79510307366;16201.351945922503;16887.155642217593;16893.831061689227;16984.13174550823;39111;52551.9489610198;52699;52851.94296113979;53104;53250.86996520251;58125;75213.62393187999;76348.61825690836;77521;6167.5374346925055;7026.543274687129;7113.75101871432;7324.304191101837;7398.445116616168;11459.140564457532;12134.817977730165;12209.027781229424;12297.32364719949;12419.875801241871;9684.515774211402;10355.223358248311;10366.948165259126;10741.570337186426;10913.290636108628;9619.807603847745;10378.688639340831;10662.786744265226;10755.139588833032;10798.244122911321;10597.841032384365;10687.946560267148;10831.512581933674;11000.339979600929;11138.777224455303;6400.903986440114;6659.235302644706;7089.0784198053925;7129.572225666477;7312;9369.812603748023;10782.73043173927;10793.40636265013;10881.945590271998;11116.22186446941 22:15:21 INFO - PROCESS | 1642 | |3;mootools.html;1395.4418232706917;1455.6870272891329;1627;1766.3111386559235;1795.4523870219587;7506.474546781727;7782;8035.196480351962;8460.153984601544;8469.364797640186;7926.445148839583;8238.50568965861;8243.422960392774;8315.334773218143;8332.91667083329;2263.5246598214353;2290.80528155107;2294.369048511656;2294.7590502997195;2301.884905754714;2276.4195130241765;2307.5846347657416;2308.9653655195184;2329.0800133947164;2331.603627383349;2254.8196144308417;2256.131389415079;2286.0627142871426;2290.7938285554296;2291.9770802292014;2253.864768113918;2255.2670382125875;2274.533720587284;2276.738175109867;2286.2798218561184;2273.1248469339425;2291.4271432141963;2293.2317673579373;2299.2182657896396;2301.5742087713825;6642.900356494656;7079.292070792931;7931.405144614177;8324.750257492286;8362.038365587974;4938.777755000998;5148.382194136728;5166.3800343958965;5166.974165129151;5172.327597412313;2779.332960089564;3154.6372167200834;3537.398642230827;3542.4509201073874;3542.964570354289;905.7735566108473;1049.4333060147512;1065.55779351569;1069.3637285815005;1071.7621147574487;9728.027197280286;10206.693799185961;10629.46852657364;10810.67567972962;10968.287061340987;4263.722858014219;4364.301711726109;4411.647068234533;4468.4637843458995;4489.259272220047;7915.970923779872;8024.759257222293;8605.354598405145;8619.267362274277;8659.870101948476;5053.140966035709;5054.115529782273;5059.367579052619;5068.518490743373;5086.872828179251;3717.4609681596016;4120.8145633445965;4221.366794980778;4668.743219122981;4721.811127554928;3544.3088597723195;3573.5175751273764;3662.5788034375855;3677.282929828658;3698.2973235085246;1140.6920131564432;1150.413289222486;1153.6020073074776;1156.3697784707354;1161.3380373187372;871.9912800871911;874.2219424712011;883.7525492862009;906.4561263242002;909.5634095634133;5332.520073193431;5387.868547604969;5572.108462645957;5651.124075768263;5777.566682498829;914.1041779056429;930.0467021303218;934.4580143516744;951.8524628682566;986.3588667366126;3661.7802931824176;3680.650338217865;3703.203811180609;3836.5612895164313;3844.7116466265143;9816;10163.644272450429;10177.54201060954;10181.796364072678;10208.387496750218;8402.873956890651;9755.804883902283;9864.654737084167;10113.494325283706;10157.847632285522;6669.7999060027305;7618.428617853683;7829.295363417206;7911.169327220674;7925.167857375008;5419.593530485151;5506.146547285177;5516.724163791875;5543.861403464945;5596.3284405871555;8937.329700272376;9180.495072771062;9242.953785231031;9292.535373231176;9302.534873256445;7664.578448185181;9309.813803723751;9325.487098209664;9390.765230869283;9495.765550478394;6336.239651241881;6546.803595892131;6883.8967415487805;7040.5071644984355;7181.964090179516;7457.888131678138;7565.962170189114;7596.924030759622;7619.466637335333;7621.885671714817;7013.929860701532;7123.643817808985;7195.676194571152;7254.5647261164495;7261.564306141649;6988.126484189476;7028.929710702828;7031.191412987572;7040.3663670269925;7099.751508697171;6566.179227596551;6623.370779775913;6686.465082793462;6687.398134167754;6689.2307384651385;4722.220833562561;4753.762311884323;4769.403824521934;4821.397325334333;4868.196747536616;4115.485564304462;4749.453812811571;4855.6601037927;4882.6826256293225;4994.200927851526 22:15:21 INFO - PROCESS | 1642 | |4;prototype.html;257.8659097269421;262.41743329807827;293.1454809231092;298.0908229898808;301.60339154012473;4368.890777730554;4971.403431588205;5191.948080519193;5270.92093618596;5418.674879507233;5031.42138654054;5123.820666276681;5268.736563171836;5319.627626066177;5357.8392648220615;3642.7068390721374;4107.371282379279;4107.691923105764;4137.524184718751;4141.192467468844;3274.330091653262;3492.5983511896075;3763.1344790698017;3906.335922893101;4111.938320925188;3163.9242657496447;3853.287141878747;3961.3661814109746;3972.56301806801;4026.637602615764;3529.735269854771;3974.9006274843214;3976.6619837314006;3977.528314523616;3991.8004099795185;3421.8460169292443;3442.397580423441;3458.6195518492946;3468.9653103468895;3471.861125554975;4678.988035144886;4953.9504604953845;4988.251762235657;5136.43499215086;5200.193969934666;3856.9807150964066;3875.1280961783373;4537.115262523809;4578.473475550321;4581.67928245023;4529;5619.381867994558;5643.379228284885;5667.773289068432;5668.68822214778;3542.3800834853796;3780.652249912595;3782.375907975181;3790.6588407043223;3796.2597293527774;4466.865994020184;5942.701800500764;5946.375630558772;5956.672383018932;6070.423309785563;3645.5260816093737;3840.807959602065;3845.1732877431486;3858.498395208605;3865.497485326946;3774.542992552469;3873.186630807562;3904.570497245301;3905.628965248297;3915.0799562102884;4488.842890498817;4529.6376289896725;4543.659225558096;4558.9514411685495;4578.038611891474;4922.8769280768265;5198.948010519846;5280.339957505312;5743.684097374685;5778.133280007948;1203.0375699440413;1221.7006833325893;1236.3200239868036;1249.2697613804946;1265.9367031648565;733.4059411876398;777.5218240781992;780.8165081205912;797.8324551844178;829.8257365953096;4343.026131090017;4447.442720435698;4785.59322457595;5090.796368145307;5101.20931255656;4565.474970378449;4981.975090124526;5490.588205884575;5532.723363831792;5595.38450770415;4227.991703940604;4530.433695788027;4643.628509719214;5059.747012649353;5100.0819852426175;2331.498727773503;2668.9065882694013;2710.539208334588;2768.667759868829;2873.2960424695666;4493.438320209973;5018.759148519495;5080.74596270185;5669.914951275734;5709.3719690834805;4714.363560919301;5264.341957255343;5415.6479828811;5501.14732216507;5731.828045158652;4580.702254353523;4904.337914381513;5288.444713305202;5500.862478438071;5721.771129154788;4567.429071366079;4597.195490789031;4811.711297322172;4841.636877234292;5565.387807341108;4678.485366609738;5105.591552675852;5288.524032837063;5386.8383948481605;5464.153056276284;5476.72616369172;5693.829185124453;5700.25896633435;5741.110127930177;5831.79588714393;3136.40408322418;3151.4776296015293;3372.5109859069807;3630.546181727284;3641.5266015417824;4585.81656733727;4639.08516641285;5124.257752364214;5403.8108666196495;5523.25436066142;4837.95162048375;4961.627877909099;5217.504272137753;5282.920756188737;5495.258140151029;4602.723836569817;5174.379074511083;5562.694051827189;5780.508656764222;5808.215890854681;5316.25204231024;5661.547076233974;5688.288963879515;5696.287964004499;5696.629719068247;4738.691985020962;4848.660593758403;5731.770729170953;5754.935336962675;5763.14724211518;5232.3459567554055;5663.065594176915;5681.659100453986;5690;5691.459311365414 22:15:21 INFO - PROCESS | 1642 | |5;yui.html;596.6181643748002;714.167994286656;718.3821913154686;737.4100719424459;762.904636920385;14511.927440362797;15384.000039997392;15449.304781284842;15463.536093917186;15488.303026363812;13930.791038134435;14033.719325613481;14040.648983775385;14063.367148478317;14068.296585170727;635.8442181665498;658.4436151452039;781.5662307419379;794.7854079398559;806.4838503357855;797.1510341486298;804.6017221475378;814.4339683919678;814.5072231300067;819.2503858969066;872.4721543466206;872.5288344294073;873.2620935309653;873.8596132047675;874.3092956564307;863.3093525179862;865.3380164174412;865.8571335729597;869.3088994249555;869.7129947117436;625.9248890133213;649.4836604899117;650.4146268358468;650.8015055408099;653.3629711031754;14246.358913848902;14768;15106.622334441618;15244.771328430083;15283.617909552238;14021.298935053206;14432.855671443256;14452.783208251885;14628.780568291379;14637.926810365987;11559.678786971868;11750.353730544817;11853.288802671867;12030.578929737418;12168.026557875348;3669.8975243305504;3679.8344074516713;3695.371786796224;3723.6648701616714;3833.923321533554;90486.09513904843;121369;121541.17688234683;124962.1270830104;126773.36613316875;11003.61487347955;11103.611373601885;11131.053860421793;11194.944025279821;11590.942045289888;17276.395326163776;17363.56591085233;22243.11027558912;22441;23995.44029638068;6743.325667433218;7744.3417309528195;7769.145394006655;7782.610869456505;7875.369970402354;4234.513031001413;4283.528811830696;4313.525512193656;4350.1734670412525;4454.888627784267;3349.480830471281;3389.5424117744283;3446.8965931022108;3476.4877278383983;3499.5975462822103;620.7734177025353;630.7540059376847;634.9619022858643;641.0544446940818;648.0829626079045;562.1595714406906;593.7298529169218;622.243973572112;644.7582156691241;664.8836453620597;8848.336374771918;9341.08353381248;9431.339806213637;9587.233021358275;9600.711978640651;618.950483961282;653.2879161713755;658.6048370977471;668.8796763100083;670.6613160354087;4463.152001119777;4648.535146485392;4664.440267167962;4672.822560519092;4751.548602882721;99919.50040249752;100152.49923750335;105593.94406055995;108142;109714;77491;83378.9155271123;88921.2842543547;91437.08562914285;94843;89923.55038224767;90578.54710726536;91697.54151229335;92836;93269.53365233266;8107.716229931924;8941.776455588662;8948.418352807046;8958.596863141043;9046.728598142066;54589.72705136449;55435.445645543026;56153;56784.716076419354;58118.7094064527;72529;76003.61998189974;79627.60186199032;81479.59260203662;82052.17947820446;11570.132240082195;11743.88256117428;11792.882071179522;11891.643250702493;12418.81371779442;12703.36483175856;12860;13061.804072938723;13132.868671313547;13518.25649589282;11897.739429508365;11945.581904633296;12086.39568021613;12231.69420764488;12581.559645412366;10038.891104906088;11864.228825126684;11934.343611101473;12123.818142728043;12458.813117803422;9589.184919281939;10215.233857460571;10278.33190842593;10594.894051059699;10606.946965265433;9787.657431989846;10032.147267482345;10056.748581285234;10408.843867342148;10683.412412317397;8600.78498037554;9842.261830362895;9870.802583948425;10360.637377691744;10526.736831579272 22:15:21 INFO - PROCESS | 1642 | __end_tp_report 22:15:21 INFO - PROCESS | 1642 | __start_cc_report 22:15:21 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,378 22:15:21 INFO - PROCESS | 1642 | __end_cc_report 22:15:21 INFO - PROCESS | 1642 | __startTimestamp1480486521392__endTimestamp 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | ------- Summary: start ------- 22:15:21 INFO - PROCESS | 1642 | Number of tests: 6 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5716.88 Median:5915.91 stddev:629.11 (10.6%) stddev-sans-first:604.72 22:15:21 INFO - PROCESS | 1642 | Values: 3421.5 3421.8 3427.2 3437.5 3466.9 5653.2 5761.1 5771.4 5783.2 5828.3 5642.6 5731.3 5762.8 5815.1 5816.7 5160.4 5203.9 5242.8 5283.1 5332.9 5235.7 5345.9 5357.4 5361.9 5386.9 5138.2 5297.4 5308.6 5319.9 5349.5 5192.8 5247.4 5252.8 5262.1 5266.4 5155.6 5190.0 5196.1 5228.7 5239.5 5600.0 5657.1 5714.9 5730.2 5731.8 6127.8 6156.8 6170.1 6189.3 6193.6 5906.8 6231.8 6248.4 6280.6 6298.9 5360.6 5404.0 5907.4 5915.1 5915.9 6118.9 6167.7 6182.4 6324.1 6334.3 6130.0 6155.3 6157.9 6164.9 6207.7 6224.9 6237.2 6248.9 6260.6 6261.9 6108.8 6113.5 6114.8 6143.5 6171.8 6184.7 6224.8 6227.4 6237.0 6243.8 4910.2 4942.1 4960.4 4962.2 4984.7 4377.8 4394.8 4402.1 4429.6 4467.1 5679.0 5809.3 5824.7 5839.0 5845.6 6168.0 6219.7 6235.3 6243.4 6252.2 5637.4 5676.0 5826.2 5913.7 5925.1 5367.7 5373.7 5377.7 5382.7 5394.8 6242.6 6248.9 6271.3 6284.6 6297.7 4632.7 5332.5 6222.1 6230.8 6264.8 5286.0 5335.8 6392.9 6425.7 6428.4 6105.1 6115.2 6126.2 6146.9 6191.3 6136.2 6151.4 6166.6 6233.8 6256.4 5043.4 6042.8 6077.3 6096.1 6126.5 5978.6 6118.1 6122.7 6140.5 6148.5 5773.6 5929.8 5942.2 5979.7 5996.2 6132.7 6170.4 6171.1 6177.5 6188.2 4839.6 5625.0 6032.7 6040.9 6059.2 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:66788.11 Median:35824.32 stddev:141702.83 (395.5%) stddev-sans-first:142012.80 22:15:21 INFO - PROCESS | 1642 | Values: 448.2 475.8 494.8 517.3 522.1 39320.2 41200.0 41277.8 43033.8 43123.4 41562.4 41574.0 41963.6 42115.2 42140.8 8348.3 9284.7 9440.6 9446.8 9474.3 8426.8 11084.4 11430.7 11474.6 11648.2 11352.9 11376.3 11426.5 11435.4 11458.1 11392.3 11456.7 11462.1 11481.3 11513.8 11229.3 11313.7 11333.1 11339.5 11365.5 39307.8 39478.8 39614.0 39713.8 39753.0 3243.3 3253.8 3356.6 3364.3 3424.8 29400.1 29512.0 29863.9 30388.8 30881.7 3186.7 3194.9 3204.9 3311.2 3355.4 458011.0 458276.0 458660.0 460626.0 463633.0 60978.7 61183.0 61269.7 61403.0 61526.0 64361.0 64385.7 64445.4 64977.0 65133.7 15744.8 15874.3 15984.4 15985.9 16040.5 10947.2 11184.2 11982.0 12114.4 12132.2 18304.0 18755.7 18992.7 19158.6 19258.5 6443.2 6931.3 7237.2 7346.5 7827.3 855.5 911.6 912.6 971.5 1026.8 24950.8 25902.2 25963.0 28279.9 29811.3 922.3 1034.2 1034.6 1038.3 1055.9 14857.9 15325.7 15519.3 15722.0 15766.1 591698.0 600005.0 604961.6 612363.0 618930.0 56849.0 57618.7 57803.4 57833.0 57867.8 1294.5 1326.7 1333.8 1355.3 1357.9 28481.4 29247.7 29250.0 29765.0 29838.0 50270.7 50599.7 50897.0 50908.5 50943.5 507983.0 515856.0 517901.0 525496.0 527556.0 23280.0 26131.3 26226.3 26338.6 26421.3 43271.6 44260.0 46776.8 47741.3 48625.8 31778.4 34772.8 37624.0 37637.8 37880.6 37714.6 37905.6 38706.0 38948.0 39353.0 36875.8 37185.8 37241.4 37293.4 38216.6 33041.3 33940.5 33975.8 34200.3 34249.7 24928.5 25060.9 25069.2 25093.2 25951.2 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:31585.31 Median:2089.59 stddev:87851.15 (4204.2%) stddev-sans-first:88066.33 22:15:21 INFO - PROCESS | 1642 | Values: 368.3 368.4 370.6 373.7 396.2 18413.2 18575.7 18775.3 19277.3 19423.2 11486.0 11912.9 11926.7 11931.6 11968.0 5196.7 5788.6 5853.8 5865.2 5875.4 3805.4 4033.5 5655.7 5835.8 5962.7 6021.2 6054.5 6060.3 6075.5 6080.1 5775.7 5929.4 5990.3 6016.7 6019.3 4983.9 5667.2 5726.3 5782.2 5798.8 22672.3 22743.4 23409.2 23527.8 23949.6 2137.5 2146.9 2154.0 2340.6 2499.3 9219.1 9658.1 9783.4 9816.0 10150.0 1666.9 1759.7 1765.4 1813.5 1821.7 113589.9 118690.2 118831.4 119004.0 119919.8 11223.3 11311.5 11767.9 11821.7 11916.7 14313.9 14380.9 14385.2 14398.3 14426.0 7994.1 8240.8 8259.3 8374.8 8388.0 8011.4 8027.2 8039.5 8196.8 8203.0 4407.5 4448.3 4472.4 4485.8 4538.0 1572.5 1654.2 1667.0 1888.4 1964.9 868.4 893.3 894.5 951.7 984.4 19509.1 20134.8 20539.4 20607.3 20615.9 873.7 905.8 936.0 938.7 1010.9 10444.9 10896.0 10902.2 10927.5 10945.8 513066.0 515560.0 525690.0 527953.0 537063.0 91719.5 95426.0 119995.8 128122.0 132995.0 843.9 932.0 954.3 969.6 992.6 13659.8 16201.4 16887.2 16893.8 16984.1 39111.0 52551.9 52699.0 52851.9 53104.0 53250.9 58125.0 75213.6 76348.6 77521.0 6167.5 7026.5 7113.8 7324.3 7398.4 11459.1 12134.8 12209.0 12297.3 12419.9 9684.5 10355.2 10366.9 10741.6 10913.3 9619.8 10378.7 10662.8 10755.1 10798.2 10597.8 10687.9 10831.5 11000.3 11138.8 6400.9 6659.2 7089.1 7129.6 7312.0 9369.8 10782.7 10793.4 10881.9 11116.2 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5299.16 Median:5157.38 stddev:2881.62 (55.9%) stddev-sans-first:2874.77 22:15:21 INFO - PROCESS | 1642 | Values: 1395.4 1455.7 1627.0 1766.3 1795.5 7506.5 7782.0 8035.2 8460.2 8469.4 7926.4 8238.5 8243.4 8315.3 8332.9 2263.5 2290.8 2294.4 2294.8 2301.9 2276.4 2307.6 2309.0 2329.1 2331.6 2254.8 2256.1 2286.1 2290.8 2292.0 2253.9 2255.3 2274.5 2276.7 2286.3 2273.1 2291.4 2293.2 2299.2 2301.6 6642.9 7079.3 7931.4 8324.8 8362.0 4938.8 5148.4 5166.4 5167.0 5172.3 2779.3 3154.6 3537.4 3542.5 3543.0 905.8 1049.4 1065.6 1069.4 1071.8 9728.0 10206.7 10629.5 10810.7 10968.3 4263.7 4364.3 4411.6 4468.5 4489.3 7916.0 8024.8 8605.4 8619.3 8659.9 5053.1 5054.1 5059.4 5068.5 5086.9 3717.5 4120.8 4221.4 4668.7 4721.8 3544.3 3573.5 3662.6 3677.3 3698.3 1140.7 1150.4 1153.6 1156.4 1161.3 872.0 874.2 883.8 906.5 909.6 5332.5 5387.9 5572.1 5651.1 5777.6 914.1 930.0 934.5 951.9 986.4 3661.8 3680.7 3703.2 3836.6 3844.7 9816.0 10163.6 10177.5 10181.8 10208.4 8402.9 9755.8 9864.7 10113.5 10157.8 6669.8 7618.4 7829.3 7911.2 7925.2 5419.6 5506.1 5516.7 5543.9 5596.3 8937.3 9180.5 9243.0 9292.5 9302.5 7664.6 9309.8 9325.5 9390.8 9495.8 6336.2 6546.8 6883.9 7040.5 7182.0 7457.9 7566.0 7596.9 7619.5 7621.9 7013.9 7123.6 7195.7 7254.6 7261.6 6988.1 7028.9 7031.2 7040.4 7099.8 6566.2 6623.4 6686.5 6687.4 6689.2 4722.2 4753.8 4769.4 4821.4 4868.2 4115.5 4749.5 4855.7 4882.7 4994.2 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4376.86 Median:4798.65 stddev:1366.18 (28.5%) stddev-sans-first:1334.58 22:15:21 INFO - PROCESS | 1642 | Values: 257.9 262.4 293.1 298.1 301.6 4368.9 4971.4 5191.9 5270.9 5418.7 5031.4 5123.8 5268.7 5319.6 5357.8 3642.7 4107.4 4107.7 4137.5 4141.2 3274.3 3492.6 3763.1 3906.3 4111.9 3163.9 3853.3 3961.4 3972.6 4026.6 3529.7 3974.9 3976.7 3977.5 3991.8 3421.8 3442.4 3458.6 3469.0 3471.9 4679.0 4954.0 4988.3 5136.4 5200.2 3857.0 3875.1 4537.1 4578.5 4581.7 4529.0 5619.4 5643.4 5667.8 5668.7 3542.4 3780.7 3782.4 3790.7 3796.3 4466.9 5942.7 5946.4 5956.7 6070.4 3645.5 3840.8 3845.2 3858.5 3865.5 3774.5 3873.2 3904.6 3905.6 3915.1 4488.8 4529.6 4543.7 4559.0 4578.0 4922.9 5198.9 5280.3 5743.7 5778.1 1203.0 1221.7 1236.3 1249.3 1265.9 733.4 777.5 780.8 797.8 829.8 4343.0 4447.4 4785.6 5090.8 5101.2 4565.5 4982.0 5490.6 5532.7 5595.4 4228.0 4530.4 4643.6 5059.7 5100.1 2331.5 2668.9 2710.5 2768.7 2873.3 4493.4 5018.8 5080.7 5669.9 5709.4 4714.4 5264.3 5415.6 5501.1 5731.8 4580.7 4904.3 5288.4 5500.9 5721.8 4567.4 4597.2 4811.7 4841.6 5565.4 4678.5 5105.6 5288.5 5386.8 5464.2 5476.7 5693.8 5700.3 5741.1 5831.8 3136.4 3151.5 3372.5 3630.5 3641.5 4585.8 4639.1 5124.3 5403.8 5523.3 4838.0 4961.6 5217.5 5282.9 5495.3 4602.7 5174.4 5562.7 5780.5 5808.2 5316.3 5661.5 5688.3 5696.3 5696.6 4738.7 4848.7 5731.8 5754.9 5763.1 5232.3 5663.1 5681.7 5690.0 5691.5 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:21250.70 Median:4668.63 stddev:32024.78 (686.0%) stddev-sans-first:32077.07 22:15:21 INFO - PROCESS | 1642 | Values: 596.6 714.2 718.4 737.4 762.9 14511.9 15384.0 15449.3 15463.5 15488.3 13930.8 14033.7 14040.6 14063.4 14068.3 635.8 658.4 781.6 794.8 806.5 797.2 804.6 814.4 814.5 819.3 872.5 872.5 873.3 873.9 874.3 863.3 865.3 865.9 869.3 869.7 625.9 649.5 650.4 650.8 653.4 14246.4 14768.0 15106.6 15244.8 15283.6 14021.3 14432.9 14452.8 14628.8 14637.9 11559.7 11750.4 11853.3 12030.6 12168.0 3669.9 3679.8 3695.4 3723.7 3833.9 90486.1 121369.0 121541.2 124962.1 126773.4 11003.6 11103.6 11131.1 11194.9 11590.9 17276.4 17363.6 22243.1 22441.0 23995.4 6743.3 7744.3 7769.1 7782.6 7875.4 4234.5 4283.5 4313.5 4350.2 4454.9 3349.5 3389.5 3446.9 3476.5 3499.6 620.8 630.8 635.0 641.1 648.1 562.2 593.7 622.2 644.8 664.9 8848.3 9341.1 9431.3 9587.2 9600.7 619.0 653.3 658.6 668.9 670.7 4463.2 4648.5 4664.4 4672.8 4751.5 99919.5 100152.5 105593.9 108142.0 109714.0 77491.0 83378.9 88921.3 91437.1 94843.0 89923.6 90578.5 91697.5 92836.0 93269.5 8107.7 8941.8 8948.4 8958.6 9046.7 54589.7 55435.4 56153.0 56784.7 58118.7 72529.0 76003.6 79627.6 81479.6 82052.2 11570.1 11743.9 11792.9 11891.6 12418.8 12703.4 12860.0 13061.8 13132.9 13518.3 11897.7 11945.6 12086.4 12231.7 12581.6 10038.9 11864.2 11934.3 12123.8 12458.8 9589.2 10215.2 10278.3 10594.9 10606.9 9787.7 10032.1 10056.7 10408.8 10683.4 8600.8 9842.3 9870.8 10360.6 10526.7 22:15:21 INFO - PROCESS | 1642 | -------- Summary: end -------- 22:15:21 INFO - PROCESS | 1642 | 22:15:21 INFO - TEST-INFO | 1642: exit 0 22:15:22 INFO - TEST-OK | dromaeo_css | took 1116197ms 22:15:22 INFO - TEST-START | kraken 22:15:22 INFO - operating with platform_type : mac_ 22:15:22 INFO - Initialising browser for kraken test... 22:15:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpORGCmJ/profile http://localhost:49248/getInfo.html) 22:15:25 INFO - PROCESS | 1653 | 2016-11-29 22:15:25.104 plugin-container[1654:10372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 22:15:25 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:25 INFO - PROCESS | 1653 | 2016-11-29 22:15:25.107 plugin-container[1654:10372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 22:15:25 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:25 INFO - PROCESS | 1653 | 2016-11-29 22:15:25.108 plugin-container[1654:10372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:15:25 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 22:15:25 INFO - PROCESS | 1653 | colorDepth:24 22:15:25 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 22:15:25 INFO - PROCESS | 1653 | __metrics 22:15:25 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 22:15:26 INFO - TEST-INFO | 1653: exit 0 22:15:26 INFO - Browser initialized. 22:15:26 INFO - Running cycle 1/1 for kraken test... 22:15:26 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpORGCmJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:28 INFO - PROCESS | 1655 | 2016-11-29 22:15:28.880 plugin-container[1656:10486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 22:15:28 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:28 INFO - PROCESS | 1655 | 2016-11-29 22:15:28.880 plugin-container[1656:10486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 22:15:28 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:28 INFO - PROCESS | 1655 | 2016-11-29 22:15:28.880 plugin-container[1656:10486] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:15:38 INFO - PROCESS | 1655 | RSS: Main: 243142656 22:15:38 INFO - PROCESS | 1655 | 22:15:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 22:15:42 INFO - PROCESS | 1655 | RSS: Main: 255324160 22:15:42 INFO - PROCESS | 1655 | 22:15:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 22:15:45 INFO - PROCESS | 1655 | RSS: Main: 262955008 22:15:45 INFO - PROCESS | 1655 | 22:15:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 22:15:49 INFO - PROCESS | 1655 | RSS: Main: 265609216 22:15:49 INFO - PROCESS | 1655 | 22:15:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 22:15:52 INFO - PROCESS | 1655 | RSS: Main: 267792384 22:15:52 INFO - PROCESS | 1655 | 22:15:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:54 INFO - PROCESS | 1655 | RSS: Main: 268128256 22:15:54 INFO - PROCESS | 1655 | 22:15:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:58 INFO - PROCESS | 1655 | RSS: Main: 274022400 22:15:58 INFO - PROCESS | 1655 | 22:16:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 22:16:02 INFO - PROCESS | 1655 | RSS: Main: 273645568 22:16:02 INFO - PROCESS | 1655 | 22:16:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 22:16:05 INFO - PROCESS | 1655 | RSS: Main: 274116608 22:16:05 INFO - PROCESS | 1655 | 22:16:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:16:07 INFO - PROCESS | 1655 | RSS: Main: 273039360 22:16:07 INFO - PROCESS | 1655 | 22:16:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:16:10 INFO - PROCESS | 1655 | RSS: Main: 273121280 22:16:10 INFO - PROCESS | 1655 | 22:16:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:16:13 INFO - PROCESS | 1655 | RSS: Main: 273051648 22:16:13 INFO - PROCESS | 1655 | 22:16:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:16:17 INFO - PROCESS | 1655 | RSS: Main: 273334272 22:16:17 INFO - PROCESS | 1655 | 22:16:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:16:20 INFO - PROCESS | 1655 | RSS: Main: 273403904 22:16:20 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 22:16:22 INFO - PROCESS | 1655 | RSS: Main: 274993152 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | __start_tp_report 22:16:22 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 22:16:22 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 22:16:22 INFO - PROCESS | 1655 | |i|pagename|runs| 22:16:22 INFO - PROCESS | 1655 | |0;ai-astar;105;118;118;118;118;118;117;118;117;119 22:16:22 INFO - PROCESS | 1655 | |1;audio-beat-detection;150;117;115;151;114;128;106;139;122;142 22:16:22 INFO - PROCESS | 1655 | |2;audio-dft;200;236;199;198;196;197;198;193;200;190 22:16:22 INFO - PROCESS | 1655 | |3;audio-fft;88;51;75;105;80;77;75;51;87;76 22:16:22 INFO - PROCESS | 1655 | |4;audio-oscillator;86;72;82;72;68;70;84;55;77;105 22:16:22 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;111;86;89;87;89;99;90;75;125;123 22:16:22 INFO - PROCESS | 1655 | |6;imaging-darkroom;97;97;97;97;98;98;142;101;104;146 22:16:22 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;104;105;123;92;92;91;94;92;92 22:16:22 INFO - PROCESS | 1655 | |8;json-parse-financial;58;57;59;59;60;61;57;59;59;58 22:16:22 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;90;70;68;81;74;72;70;71;73;76 22:16:22 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;100;104;89;90;90;88;90;90;89;88 22:16:22 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;168;174;162;158;150;151;171;162;221;154 22:16:22 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;178;179;173;172;173;174;173;180;181;230 22:16:22 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;64;88;85;70;73;70;67;69;68;64 22:16:22 INFO - PROCESS | 1655 | __end_tp_report 22:16:22 INFO - PROCESS | 1655 | __start_cc_report 22:16:22 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,961 22:16:22 INFO - PROCESS | 1655 | __end_cc_report 22:16:22 INFO - PROCESS | 1655 | __startTimestamp1480486582745__endTimestamp 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | ------- Summary: start ------- 22:16:22 INFO - PROCESS | 1655 | Number of tests: 14 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.00 stddev:4.12 (3.5%) stddev-sans-first:0.60 22:16:22 INFO - PROCESS | 1655 | Values: 105.0 118.0 118.0 118.0 118.0 118.0 117.0 118.0 117.0 119.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:133.50 stddev:16.11 (12.1%) stddev-sans-first:15.07 22:16:22 INFO - PROCESS | 1655 | Values: 150.0 117.0 115.0 151.0 114.0 128.0 106.0 139.0 122.0 142.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:200.70 Median:198.50 stddev:12.80 (6.4%) stddev-sans-first:13.57 22:16:22 INFO - PROCESS | 1655 | Values: 200.0 236.0 199.0 198.0 196.0 197.0 198.0 193.0 200.0 190.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:76.50 Median:76.50 stddev:16.24 (21.2%) stddev-sans-first:16.68 22:16:22 INFO - PROCESS | 1655 | Values: 88.0 51.0 75.0 105.0 80.0 77.0 75.0 51.0 87.0 76.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:74.50 stddev:13.35 (17.9%) stddev-sans-first:13.76 22:16:22 INFO - PROCESS | 1655 | Values: 86.0 72.0 82.0 72.0 68.0 70.0 84.0 55.0 77.0 105.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:88.00 stddev:16.80 (19.1%) stddev-sans-first:17.08 22:16:22 INFO - PROCESS | 1655 | Values: 111.0 86.0 89.0 87.0 89.0 99.0 90.0 75.0 125.0 123.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:97.00 stddev:19.29 (19.9%) stddev-sans-first:20.07 22:16:22 INFO - PROCESS | 1655 | Values: 97.0 97.0 97.0 97.0 98.0 98.0 142.0 101.0 104.0 146.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:92.00 stddev:10.36 (11.3%) stddev-sans-first:10.71 22:16:22 INFO - PROCESS | 1655 | Values: 91.0 104.0 105.0 123.0 92.0 92.0 91.0 94.0 92.0 92.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:1.25 (2.1%) stddev-sans-first:1.30 22:16:22 INFO - PROCESS | 1655 | Values: 58.0 57.0 59.0 59.0 60.0 61.0 57.0 59.0 59.0 58.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:73.50 stddev:6.57 (8.9%) stddev-sans-first:3.90 22:16:22 INFO - PROCESS | 1655 | Values: 90.0 70.0 68.0 81.0 74.0 72.0 70.0 71.0 73.0 76.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:89.50 stddev:5.51 (6.2%) stddev-sans-first:4.99 22:16:22 INFO - PROCESS | 1655 | Values: 100.0 104.0 89.0 90.0 90.0 88.0 90.0 90.0 89.0 88.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:167.10 Median:165.00 stddev:20.62 (12.5%) stddev-sans-first:21.87 22:16:22 INFO - PROCESS | 1655 | Values: 168.0 174.0 162.0 158.0 150.0 151.0 171.0 162.0 221.0 154.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:178.50 stddev:17.44 (9.8%) stddev-sans-first:18.45 22:16:22 INFO - PROCESS | 1655 | Values: 178.0 179.0 173.0 172.0 173.0 174.0 173.0 180.0 181.0 230.0 22:16:22 INFO - PROCESS | 1655 | 22:16:22 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:70.00 stddev:8.24 (11.8%) stddev-sans-first:8.25 22:16:22 INFO - PROCESS | 1655 | Values: 64.0 88.0 85.0 70.0 73.0 70.0 67.0 69.0 68.0 64.0 22:16:22 INFO - PROCESS | 1655 | -------- Summary: end -------- 22:16:22 INFO - PROCESS | 1655 | 22:16:23 INFO - TEST-INFO | 1655: exit 0 22:16:23 INFO - TEST-OK | kraken | took 61775ms 22:16:23 INFO - SUITE-END | took 1177s 22:16:23 INFO - Completed test suite (00:19:38) 22:16:23 INFO - javascript benchmark 22:16:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7070.295353212801, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.538092357531, 3421.8118003509803, 3427.2117412995026, 3437.4843773434, 3466.9306613867725, 5653.180288858113, 5761.078227483604, 5771.365149833515, 5783.190353350535, 5828.271466066742, 5642.576806739501, 5731.340895796985, 5762.798302059428, 5815.069588865782, 5816.680082595455, 5160.406553246387, 5203.947960520403, 5242.764075616587, 5283.07546179419, 5332.946670533303, 5235.685858848481, 5345.893082138355, 5357.4374690657505, 5361.946380536184, 5386.91919621204, 5138.177891537373, 5297.44376840432, 5308.601854860901, 5319.946800531984, 5349.465053494659, 5192.818251361222, 5247.449017853147, 5252.842414727565, 5262.05283049051, 5266.420693723688, 5155.587552995751, 5189.974050129763, 5196.142636464979, 5228.686278823245, 5239.502247286502, 5600.047991841397, 5657.12314591239, 5714.857128571777, 5730.19777231188, 5731.799387021476, 6127.830981140938, 6156.7537298508005, 6170.074488826712, 6189.257289125334, 6193.566450348432, 5906.763729450817, 6231.813045608639, 6248.406401391906, 6280.591761535486, 6298.905516417258, 5360.624756267063, 5403.972980135074, 5907.350191478934, 5915.142304365929, 5915.911261330997, 6118.893302669218, 6167.660778657128, 6182.412670796267, 6324.051392291193, 6334.271558770708, 6130.049842274455, 6155.261368635793, 6157.907631385533, 6164.8767024659255, 6207.6585787782105, 6224.937750622526, 6237.157983672237, 6248.9062664060075, 6260.624362538262, 6261.937380626136, 6108.847278818065, 6113.537117903944, 6114.78598249068, 6143.542582966759, 6171.84570385735, 6184.721687524072, 6224.782132625336, 6227.408396202353, 6237.0, 6243.843903902348, 4910.189818679878, 4942.110420124341, 4960.355153830051, 4962.156433406258, 4984.675996060317, 4377.759223242752, 4394.802233899482, 4402.119576084796, 4429.579189976947, 4467.061916997467, 5678.971605141948, 5809.331926828469, 5824.737886795105, 5838.970805145947, 5845.620034697731, 6168.013117901114, 6219.689015549205, 6235.282942461584, 6243.43809057187, 6252.218472690914, 5637.408072152365, 5675.971620141873, 5826.242588463472, 5913.674747888907, 5925.111233315038, 5367.731613419392, 5373.677579345252, 5377.650452720639, 5382.650127741685, 5394.757235924393, 6242.625442473466, 6248.937510624835, 6271.278802937721, 6284.622922624657, 6297.685115744286, 4632.652551058752, 5332.520073193431, 6222.09779581949, 6230.813075607739, 6264.780732674334, 5285.973570132125, 5335.813246536352, 6392.904106438315, 6425.678716064084, 6428.4214420702365, 6105.14527966094, 6115.179495343419, 6126.151269885628, 6146.8935591593945, 6191.25704915413, 6136.1821086261625, 6151.446369826737, 6166.568340216142, 6233.781817636361, 6256.405641464054, 5043.44522102561, 6042.848928776816, 6077.331493535796, 6096.055111457731, 6126.479249263684, 5978.611390259619, 6118.143459915705, 6122.663253521099, 6140.508759299335, 6148.538859585459, 5773.624714393551, 5929.792457263975, 5942.197803296673, 5979.701014949323, 5996.220491336098, 6132.724027418688, 6170.382961703794, 6171.105189747551, 6177.505799535937, 6188.226471691039, 4839.564439200489, 5625.0, 6032.698365081641, 6040.879182416415, 6059.242594675666], "value": 5679.943660035955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.15075432056256, 475.83583663636034, 494.7996061595055, 517.3300575754399, 522.1384715219887, 39320.213595728106, 41200.0, 41277.79361103198, 43033.7848310758, 43123.35314970278, 41562.37656435156, 41574.0, 41963.58036419635, 42115.15769684612, 42140.78592140784, 8348.290395316406, 9284.675036373728, 9440.622375105022, 9446.763830904249, 9474.336796424253, 8426.78933026673, 11084.445777711082, 11430.657080287563, 11474.598389056384, 11648.242864213798, 11352.943235283812, 11376.317420954727, 11426.542938282459, 11435.371054591995, 11458.140639452033, 11392.316461012366, 11456.713582160472, 11462.140339474572, 11481.253718508271, 11513.827292590619, 11229.27009744364, 11313.717157071056, 11333.09335253186, 11339.546418143264, 11365.488553015135, 39307.82076537709, 39478.802605987075, 39614.0, 39713.80143099295, 39753.0, 3243.3026899216547, 3253.755968302387, 3356.597208334991, 3364.276680513701, 3424.845881935294, 29400.11799646014, 29512.0, 29863.850680746673, 30388.784448622027, 30881.69118308833, 3186.665400132999, 3194.872205111793, 3204.871805127792, 3311.205310725433, 3355.429576971896, 458011.0, 458276.0, 458660.0, 460626.0, 463633.0, 60978.69510652418, 61183.0, 61269.69365153235, 61403.0, 61526.0, 64361.0, 64385.67807160934, 64445.35554644487, 64977.0, 65133.69732605321, 15744.763828542582, 15874.285657145456, 15984.36062557508, 15985.920070399574, 16040.518784436486, 10947.178961577914, 11184.161187910939, 11982.0, 12114.394280285951, 12132.150749447454, 18303.99328036971, 18755.718664220047, 18992.715109273373, 19158.61682766337, 19258.518537036405, 6443.226812782403, 6931.272216417204, 7237.203907570163, 7346.5224760391475, 7827.25641064098, 855.5465603230298, 911.6307895302413, 912.5619702542684, 971.455984648599, 1026.825439675257, 24950.750492495205, 25902.154041808808, 25963.0, 28279.858600707277, 29811.254718632208, 922.294444749767, 1034.2450011491503, 1034.6275340877278, 1038.3147122899002, 1055.8996895294936, 14857.925710371595, 15325.693486130214, 15519.349483582992, 15722.0, 15766.054036757601, 591698.0, 600005.0, 604961.6030717532, 612363.0, 618930.0, 56849.0, 57618.7119064402, 57803.421965780646, 57833.0, 57867.84264314774, 1294.5145570411096, 1326.6666999665274, 1333.7999300105062, 1355.2546099645156, 1357.8574249703836, 28481.43037139287, 29247.7075229245, 29250.0, 29764.95822646197, 29838.0, 50270.748646256536, 50599.74700126476, 50897.0, 50908.49091509038, 50943.49056509536, 507983.0, 515856.0, 517901.0, 525496.0, 527556.0, 23280.0, 26131.346716332242, 26226.344341391617, 26338.60492092582, 26421.33946651349, 43271.56728432801, 44260.0, 46776.7661161692, 47741.283880741124, 48625.75687121542, 31778.364432711678, 34772.82613586916, 37624.0, 37637.81181094077, 37880.62119378771, 37714.55816910358, 37905.62094379131, 38706.0323491915, 38948.0, 39353.0, 36875.81562092173, 37185.81407093055, 37241.44137837989, 37293.4405983905, 38216.61783382242, 33041.33917321592, 33940.49089263614, 33975.830120849234, 34200.31599367949, 34249.65750342465, 24928.501429970936, 25060.874695626404, 25069.247922562354, 25093.247202583952, 25951.22146335613], "value": 19881.9987730572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.25612263228294, 368.4451614188125, 370.5886466022715, 373.74958777619, 396.22537938330566, 18413.17140728667, 18575.72136417953, 18775.342862999798, 19277.325293614707, 19423.223071077176, 11486.0, 11912.940435297833, 11926.701832454193, 11931.58239461619, 11968.042556595474, 5196.662216955905, 5788.594798364116, 5853.76584936602, 5865.178874957509, 5875.441833025856, 3805.429185622151, 4033.455483509718, 5655.7172141392975, 5835.766569337221, 5962.701864906759, 6021.2473440819895, 6054.5459090568165, 6060.33336333003, 6075.45320921117, 6080.14877917092, 5775.711214439282, 5929.436703513181, 5990.251218597676, 6016.699165041774, 6019.307779605358, 4983.900321993576, 5667.178259152441, 5726.3414707308775, 5782.161586569966, 5798.797042103506, 22672.31983040495, 22743.43141421461, 23409.180678676334, 23527.764722352727, 23949.640755388682, 2137.5083730742017, 2146.914123435061, 2154.0091557883393, 2340.5903966805913, 2499.262717498342, 9219.078092190794, 9658.130768230892, 9783.41299522031, 9816.018398160199, 10150.0, 1666.924988375526, 1759.6744602248625, 1765.3556451895095, 1813.5103522049105, 1821.6720990221754, 113589.86410135958, 118690.21964670623, 118831.40584297023, 119004.0, 119919.80080199173, 11223.270487418291, 11311.547538098384, 11767.88232117685, 11821.704457388634, 11916.702082447835, 14313.926455515879, 14380.928095359666, 14385.208813515148, 14398.280085995659, 14426.0, 7994.120646728855, 8240.752777416566, 8259.339252859876, 8374.790630234293, 8388.035375931724, 8011.399145064143, 8027.1972802719265, 8039.477433966774, 8196.754097377088, 8203.015638123463, 4407.515173330931, 4448.266036104006, 4472.351509031172, 4485.753283569435, 4537.956270057905, 1572.512521118457, 1654.24649871137, 1666.991665041667, 1888.376835644234, 1964.88210707358, 868.3617541107282, 893.2898345815092, 894.5348418822176, 951.695457453622, 984.4241118945497, 19509.122089506007, 20134.798652013586, 20539.38381848547, 20607.278745243846, 20615.86612736314, 873.6549063120032, 905.7509184974185, 935.9672411465566, 938.6808485115093, 1010.9191264698807, 10444.947775261075, 10896.0, 10902.236843421044, 10927.508262128224, 10945.781084378268, 513066.0, 515560.0, 525690.0, 527953.0, 537063.0, 91719.54140229255, 95426.04573954172, 119995.8000420002, 128122.0, 132995.0, 843.8649816029406, 932.0027570499636, 954.3128947158035, 969.6266937229218, 992.6178421307849, 13659.79510307366, 16201.351945922503, 16887.155642217593, 16893.831061689227, 16984.13174550823, 39111.0, 52551.9489610198, 52699.0, 52851.94296113979, 53104.0, 53250.86996520251, 58125.0, 75213.62393187999, 76348.61825690836, 77521.0, 6167.5374346925055, 7026.543274687129, 7113.75101871432, 7324.304191101837, 7398.445116616168, 11459.140564457532, 12134.817977730165, 12209.027781229424, 12297.32364719949, 12419.875801241871, 9684.515774211402, 10355.223358248311, 10366.948165259126, 10741.570337186426, 10913.290636108628, 9619.807603847745, 10378.688639340831, 10662.786744265226, 10755.139588833032, 10798.244122911321, 10597.841032384365, 10687.946560267148, 10831.512581933674, 11000.339979600929, 11138.777224455303, 6400.903986440114, 6659.235302644706, 7089.0784198053925, 7129.572225666477, 7312.0, 9369.812603748023, 10782.73043173927, 10793.40636265013, 10881.945590271998, 11116.22186446941], "value": 8961.850113968336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.4418232706917, 1455.6870272891329, 1627.0, 1766.3111386559235, 1795.4523870219587, 7506.474546781727, 7782.0, 8035.196480351962, 8460.153984601544, 8469.364797640186, 7926.445148839583, 8238.50568965861, 8243.422960392774, 8315.334773218143, 8332.91667083329, 2263.5246598214353, 2290.80528155107, 2294.369048511656, 2294.7590502997195, 2301.884905754714, 2276.4195130241765, 2307.5846347657416, 2308.9653655195184, 2329.0800133947164, 2331.603627383349, 2254.8196144308417, 2256.131389415079, 2286.0627142871426, 2290.7938285554296, 2291.9770802292014, 2253.864768113918, 2255.2670382125875, 2274.533720587284, 2276.738175109867, 2286.2798218561184, 2273.1248469339425, 2291.4271432141963, 2293.2317673579373, 2299.2182657896396, 2301.5742087713825, 6642.900356494656, 7079.292070792931, 7931.405144614177, 8324.750257492286, 8362.038365587974, 4938.777755000998, 5148.382194136728, 5166.3800343958965, 5166.974165129151, 5172.327597412313, 2779.332960089564, 3154.6372167200834, 3537.398642230827, 3542.4509201073874, 3542.964570354289, 905.7735566108473, 1049.4333060147512, 1065.55779351569, 1069.3637285815005, 1071.7621147574487, 9728.027197280286, 10206.693799185961, 10629.46852657364, 10810.67567972962, 10968.287061340987, 4263.722858014219, 4364.301711726109, 4411.647068234533, 4468.4637843458995, 4489.259272220047, 7915.970923779872, 8024.759257222293, 8605.354598405145, 8619.267362274277, 8659.870101948476, 5053.140966035709, 5054.115529782273, 5059.367579052619, 5068.518490743373, 5086.872828179251, 3717.4609681596016, 4120.8145633445965, 4221.366794980778, 4668.743219122981, 4721.811127554928, 3544.3088597723195, 3573.5175751273764, 3662.5788034375855, 3677.282929828658, 3698.2973235085246, 1140.6920131564432, 1150.413289222486, 1153.6020073074776, 1156.3697784707354, 1161.3380373187372, 871.9912800871911, 874.2219424712011, 883.7525492862009, 906.4561263242002, 909.5634095634133, 5332.520073193431, 5387.868547604969, 5572.108462645957, 5651.124075768263, 5777.566682498829, 914.1041779056429, 930.0467021303218, 934.4580143516744, 951.8524628682566, 986.3588667366126, 3661.7802931824176, 3680.650338217865, 3703.203811180609, 3836.5612895164313, 3844.7116466265143, 9816.0, 10163.644272450429, 10177.54201060954, 10181.796364072678, 10208.387496750218, 8402.873956890651, 9755.804883902283, 9864.654737084167, 10113.494325283706, 10157.847632285522, 6669.7999060027305, 7618.428617853683, 7829.295363417206, 7911.169327220674, 7925.167857375008, 5419.593530485151, 5506.146547285177, 5516.724163791875, 5543.861403464945, 5596.3284405871555, 8937.329700272376, 9180.495072771062, 9242.953785231031, 9292.535373231176, 9302.534873256445, 7664.578448185181, 9309.813803723751, 9325.487098209664, 9390.765230869283, 9495.765550478394, 6336.239651241881, 6546.803595892131, 6883.8967415487805, 7040.5071644984355, 7181.964090179516, 7457.888131678138, 7565.962170189114, 7596.924030759622, 7619.466637335333, 7621.885671714817, 7013.929860701532, 7123.643817808985, 7195.676194571152, 7254.5647261164495, 7261.564306141649, 6988.126484189476, 7028.929710702828, 7031.191412987572, 7040.3663670269925, 7099.751508697171, 6566.179227596551, 6623.370779775913, 6686.465082793462, 6687.398134167754, 6689.2307384651385, 4722.220833562561, 4753.762311884323, 4769.403824521934, 4821.397325334333, 4868.196747536616, 4115.485564304462, 4749.453812811571, 4855.6601037927, 4882.6826256293225, 4994.200927851526], "value": 4315.160781210318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.8659097269421, 262.41743329807827, 293.1454809231092, 298.0908229898808, 301.60339154012473, 4368.890777730554, 4971.403431588205, 5191.948080519193, 5270.92093618596, 5418.674879507233, 5031.42138654054, 5123.820666276681, 5268.736563171836, 5319.627626066177, 5357.8392648220615, 3642.7068390721374, 4107.371282379279, 4107.691923105764, 4137.524184718751, 4141.192467468844, 3274.330091653262, 3492.5983511896075, 3763.1344790698017, 3906.335922893101, 4111.938320925188, 3163.9242657496447, 3853.287141878747, 3961.3661814109746, 3972.56301806801, 4026.637602615764, 3529.735269854771, 3974.9006274843214, 3976.6619837314006, 3977.528314523616, 3991.8004099795185, 3421.8460169292443, 3442.397580423441, 3458.6195518492946, 3468.9653103468895, 3471.861125554975, 4678.988035144886, 4953.9504604953845, 4988.251762235657, 5136.43499215086, 5200.193969934666, 3856.9807150964066, 3875.1280961783373, 4537.115262523809, 4578.473475550321, 4581.67928245023, 4529.0, 5619.381867994558, 5643.379228284885, 5667.773289068432, 5668.68822214778, 3542.3800834853796, 3780.652249912595, 3782.375907975181, 3790.6588407043223, 3796.2597293527774, 4466.865994020184, 5942.701800500764, 5946.375630558772, 5956.672383018932, 6070.423309785563, 3645.5260816093737, 3840.807959602065, 3845.1732877431486, 3858.498395208605, 3865.497485326946, 3774.542992552469, 3873.186630807562, 3904.570497245301, 3905.628965248297, 3915.0799562102884, 4488.842890498817, 4529.6376289896725, 4543.659225558096, 4558.9514411685495, 4578.038611891474, 4922.8769280768265, 5198.948010519846, 5280.339957505312, 5743.684097374685, 5778.133280007948, 1203.0375699440413, 1221.7006833325893, 1236.3200239868036, 1249.2697613804946, 1265.9367031648565, 733.4059411876398, 777.5218240781992, 780.8165081205912, 797.8324551844178, 829.8257365953096, 4343.026131090017, 4447.442720435698, 4785.59322457595, 5090.796368145307, 5101.20931255656, 4565.474970378449, 4981.975090124526, 5490.588205884575, 5532.723363831792, 5595.38450770415, 4227.991703940604, 4530.433695788027, 4643.628509719214, 5059.747012649353, 5100.0819852426175, 2331.498727773503, 2668.9065882694013, 2710.539208334588, 2768.667759868829, 2873.2960424695666, 4493.438320209973, 5018.759148519495, 5080.74596270185, 5669.914951275734, 5709.3719690834805, 4714.363560919301, 5264.341957255343, 5415.6479828811, 5501.14732216507, 5731.828045158652, 4580.702254353523, 4904.337914381513, 5288.444713305202, 5500.862478438071, 5721.771129154788, 4567.429071366079, 4597.195490789031, 4811.711297322172, 4841.636877234292, 5565.387807341108, 4678.485366609738, 5105.591552675852, 5288.524032837063, 5386.8383948481605, 5464.153056276284, 5476.72616369172, 5693.829185124453, 5700.25896633435, 5741.110127930177, 5831.79588714393, 3136.40408322418, 3151.4776296015293, 3372.5109859069807, 3630.546181727284, 3641.5266015417824, 4585.81656733727, 4639.08516641285, 5124.257752364214, 5403.8108666196495, 5523.25436066142, 4837.95162048375, 4961.627877909099, 5217.504272137753, 5282.920756188737, 5495.258140151029, 4602.723836569817, 5174.379074511083, 5562.694051827189, 5780.508656764222, 5808.215890854681, 5316.25204231024, 5661.547076233974, 5688.288963879515, 5696.287964004499, 5696.629719068247, 4738.691985020962, 4848.660593758403, 5731.770729170953, 5754.935336962675, 5763.14724211518, 5232.3459567554055, 5663.065594176915, 5681.659100453986, 5690.0, 5691.459311365414], "value": 3931.761207136259, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.6181643748002, 714.167994286656, 718.3821913154686, 737.4100719424459, 762.904636920385, 14511.927440362797, 15384.000039997392, 15449.304781284842, 15463.536093917186, 15488.303026363812, 13930.791038134435, 14033.719325613481, 14040.648983775385, 14063.367148478317, 14068.296585170727, 635.8442181665498, 658.4436151452039, 781.5662307419379, 794.7854079398559, 806.4838503357855, 797.1510341486298, 804.6017221475378, 814.4339683919678, 814.5072231300067, 819.2503858969066, 872.4721543466206, 872.5288344294073, 873.2620935309653, 873.8596132047675, 874.3092956564307, 863.3093525179862, 865.3380164174412, 865.8571335729597, 869.3088994249555, 869.7129947117436, 625.9248890133213, 649.4836604899117, 650.4146268358468, 650.8015055408099, 653.3629711031754, 14246.358913848902, 14768.0, 15106.622334441618, 15244.771328430083, 15283.617909552238, 14021.298935053206, 14432.855671443256, 14452.783208251885, 14628.780568291379, 14637.926810365987, 11559.678786971868, 11750.353730544817, 11853.288802671867, 12030.578929737418, 12168.026557875348, 3669.8975243305504, 3679.8344074516713, 3695.371786796224, 3723.6648701616714, 3833.923321533554, 90486.09513904843, 121369.0, 121541.17688234683, 124962.1270830104, 126773.36613316875, 11003.61487347955, 11103.611373601885, 11131.053860421793, 11194.944025279821, 11590.942045289888, 17276.395326163776, 17363.56591085233, 22243.11027558912, 22441.0, 23995.44029638068, 6743.325667433218, 7744.3417309528195, 7769.145394006655, 7782.610869456505, 7875.369970402354, 4234.513031001413, 4283.528811830696, 4313.525512193656, 4350.1734670412525, 4454.888627784267, 3349.480830471281, 3389.5424117744283, 3446.8965931022108, 3476.4877278383983, 3499.5975462822103, 620.7734177025353, 630.7540059376847, 634.9619022858643, 641.0544446940818, 648.0829626079045, 562.1595714406906, 593.7298529169218, 622.243973572112, 644.7582156691241, 664.8836453620597, 8848.336374771918, 9341.08353381248, 9431.339806213637, 9587.233021358275, 9600.711978640651, 618.950483961282, 653.2879161713755, 658.6048370977471, 668.8796763100083, 670.6613160354087, 4463.152001119777, 4648.535146485392, 4664.440267167962, 4672.822560519092, 4751.548602882721, 99919.50040249752, 100152.49923750335, 105593.94406055995, 108142.0, 109714.0, 77491.0, 83378.9155271123, 88921.2842543547, 91437.08562914285, 94843.0, 89923.55038224767, 90578.54710726536, 91697.54151229335, 92836.0, 93269.53365233266, 8107.716229931924, 8941.776455588662, 8948.418352807046, 8958.596863141043, 9046.728598142066, 54589.72705136449, 55435.445645543026, 56153.0, 56784.716076419354, 58118.7094064527, 72529.0, 76003.61998189974, 79627.60186199032, 81479.59260203662, 82052.17947820446, 11570.132240082195, 11743.88256117428, 11792.882071179522, 11891.643250702493, 12418.81371779442, 12703.36483175856, 12860.0, 13061.804072938723, 13132.868671313547, 13518.25649589282, 11897.739429508365, 11945.581904633296, 12086.39568021613, 12231.69420764488, 12581.559645412366, 10038.891104906088, 11864.228825126684, 11934.343611101473, 12123.818142728043, 12458.813117803422, 9589.184919281939, 10215.233857460571, 10278.33190842593, 10594.894051059699, 10606.946965265433, 9787.657431989846, 10032.147267482345, 10056.748581285234, 10408.843867342148, 10683.412412317397, 8600.78498037554, 9842.261830362895, 9870.802583948425, 10360.637377691744, 10526.736831579272], "value": 7274.2876632810685, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0, 119.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 115.0, 151.0, 114.0, 128.0, 106.0, 139.0, 122.0, 142.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 236.0, 199.0, 198.0, 196.0, 197.0, 198.0, 193.0, 200.0, 190.0], "value": 200.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 51.0, 75.0, 105.0, 80.0, 77.0, 75.0, 51.0, 87.0, 76.0], "value": 76.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 82.0, 72.0, 68.0, 70.0, 84.0, 55.0, 77.0, 105.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 86.0, 89.0, 87.0, 89.0, 99.0, 90.0, 75.0, 125.0, 123.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 98.0, 98.0, 142.0, 101.0, 104.0, 146.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 104.0, 105.0, 123.0, 92.0, 92.0, 91.0, 94.0, 92.0, 92.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 59.0, 60.0, 61.0, 57.0, 59.0, 59.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 68.0, 81.0, 74.0, 72.0, 70.0, 71.0, 73.0, 76.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 104.0, 89.0, 90.0, 90.0, 88.0, 90.0, 90.0, 89.0, 88.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 174.0, 162.0, 158.0, 150.0, 151.0, 171.0, 162.0, 221.0, 154.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 179.0, 173.0, 172.0, 173.0, 174.0, 173.0, 180.0, 181.0, 230.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 88.0, 85.0, 70.0, 73.0, 70.0, 67.0, 69.0, 68.0, 64.0], "value": 71.8, "unit": "score"}]}]} 22:16:24 INFO - Return code: 0 22:16:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:16:24 ERROR - # TBPL SUCCESS # 22:16:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:16:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:16:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:16:24 INFO - 2016-11-29 22:16:24.108 screenresolution[1660:10881] starting screenresolution argv=screenresolution get 22:16:24 INFO - 2016-11-29 22:16:24.111 screenresolution[1660:10881] Display 0: 1600x1200x32@60 22:16:24 INFO - 2016-11-29 22:16:24.117 screenresolution[1661:10884] starting screenresolution argv=screenresolution list 22:16:24 INFO - Available Modes on Display 0 22:16:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:16:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:16:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:16:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:16:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:16:24 INFO - Intel Iris: 22:16:24 INFO - Chipset Model: Intel Iris 22:16:24 INFO - Type: GPU 22:16:24 INFO - Bus: Built-In 22:16:24 INFO - VRAM (Dynamic, Max): 1536 MB 22:16:24 INFO - Vendor: Intel (0x8086) 22:16:24 INFO - Device ID: 0x0a2e 22:16:24 INFO - Revision ID: 0x0009 22:16:24 INFO - Displays: 22:16:24 INFO - Display: 22:16:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:16:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:16:24 INFO - Display Serial Number: 1600x1200 60 22:16:24 INFO - Main Display: Yes 22:16:24 INFO - Mirror: Off 22:16:24 INFO - Online: Yes 22:16:24 INFO - Rotation: Supported 22:16:24 INFO - Return code: 0 22:16:24 INFO - Running post-action listener: _resource_record_post_action 22:16:24 INFO - [mozharness: 2016-11-30 06:16:24.366702Z] Finished run-tests step (success) 22:16:24 INFO - Running post-run listener: _resource_record_post_run 22:16:24 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 39811584; Write bytes: 696287232; Read time: 391; Write time: 1925 22:16:24 INFO - TinderboxPrint: CPU usage
54.6% 22:16:24 INFO - TinderboxPrint: I/O read bytes / time
39,811,584 / 391 22:16:24 INFO - TinderboxPrint: I/O write bytes / time
696,287,232 / 1,925 22:16:24 INFO - TinderboxPrint: CPU idle
2,201.1 (45.4%) 22:16:24 INFO - TinderboxPrint: CPU system
977.6 (20.2%) 22:16:24 INFO - TinderboxPrint: CPU user
1,671.1 (34.5%) 22:16:24 INFO - TinderboxPrint: Swap in / out
422,952,960 / 0 22:16:24 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 374618624; Write bytes: 368433152; Read time: 28443; Write time: 649 22:16:24 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 37788160; Write bytes: 309553152; Read time: 367; Write time: 1236 22:16:24 INFO - Running post-run listener: _upload_blobber_files 22:16:24 INFO - Blob upload gear active. 22:16:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:16:24 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 22:16:24 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'] 22:16:24 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 22:16:25 INFO - (blobuploader) - INFO - Open directory for files ... 22:16:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:16:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:16:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:26 INFO - (blobuploader) - INFO - Done attempting. 22:16:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:16:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:27 INFO - (blobuploader) - INFO - Done attempting. 22:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:16:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:30 INFO - (blobuploader) - INFO - Done attempting. 22:16:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:30 INFO - Return code: 0 22:16:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:16:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/649831b262bbde268dc5fbea99a19af87ac02d64b94380a480e1fa0c2bfde4719b74108035997d0b93b5e4f39fd44e2cce9f3495e8bd3a7d35dfd30a0d1702c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/038ca77e105f7cebe5787747b74d16e513a0cde3d4449cb84ed91982762c094599f1adfdce918395a9319e1955b7afdc77efc0dc8c0a54e0b18a2af0755db987", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80073008cc814a5ea155cc94f8bc363425ee785fe107019d109a1dff707ae22de52a018227d78a4ebceccea6b76b874d45556cc303611ef1cd7800ff7f61c4e"} 22:16:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:16:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:16:30 INFO - Contents: 22:16:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/649831b262bbde268dc5fbea99a19af87ac02d64b94380a480e1fa0c2bfde4719b74108035997d0b93b5e4f39fd44e2cce9f3495e8bd3a7d35dfd30a0d1702c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/038ca77e105f7cebe5787747b74d16e513a0cde3d4449cb84ed91982762c094599f1adfdce918395a9319e1955b7afdc77efc0dc8c0a54e0b18a2af0755db987", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80073008cc814a5ea155cc94f8bc363425ee785fe107019d109a1dff707ae22de52a018227d78a4ebceccea6b76b874d45556cc303611ef1cd7800ff7f61c4e"} 22:16:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:16:30 INFO - Copying logs to upload dir... 22:16:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:16:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.717551 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 22:16:30.240028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-11-29 22:16:30.241146) ========= 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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/649831b262bbde268dc5fbea99a19af87ac02d64b94380a480e1fa0c2bfde4719b74108035997d0b93b5e4f39fd44e2cce9f3495e8bd3a7d35dfd30a0d1702c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/038ca77e105f7cebe5787747b74d16e513a0cde3d4449cb84ed91982762c094599f1adfdce918395a9319e1955b7afdc77efc0dc8c0a54e0b18a2af0755db987", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80073008cc814a5ea155cc94f8bc363425ee785fe107019d109a1dff707ae22de52a018227d78a4ebceccea6b76b874d45556cc303611ef1cd7800ff7f61c4e"} build_url:https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010924 build_url: 'https://queue.taskcluster.net/v1/task/d_vSb0R7THiN-2NHuTgisg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/649831b262bbde268dc5fbea99a19af87ac02d64b94380a480e1fa0c2bfde4719b74108035997d0b93b5e4f39fd44e2cce9f3495e8bd3a7d35dfd30a0d1702c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/038ca77e105f7cebe5787747b74d16e513a0cde3d4449cb84ed91982762c094599f1adfdce918395a9319e1955b7afdc77efc0dc8c0a54e0b18a2af0755db987", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80073008cc814a5ea155cc94f8bc363425ee785fe107019d109a1dff707ae22de52a018227d78a4ebceccea6b76b874d45556cc303611ef1cd7800ff7f61c4e"}' ========= master_lag: 3.68 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-11-29 22:16:33.929871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:16:33.930225) ========= 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.GWxCMMkcD0/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.SjC3vKTEOX/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005127 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:16:33.965610) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:16:33.965896) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:16:33.966191) ========= ========= Total master_lag: 3.98 =========