builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1491496459.75 results: success (0) buildid: 20170406075933 builduid: 010248e66b8145a493d40f7320541605 revision: facaf90aeaaf6d7cf5e2966f9f918319536bddea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.748021) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.748462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.748733) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.777607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.778233) ========= 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.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-06 09:34:19-- 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% 8.88M=0.001s 2017-04-06 09:34:19 (8.88 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092660 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.895914) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.896236) ========= 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.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.029698 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:19.948007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 09:34:19.948357) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev facaf90aeaaf6d7cf5e2966f9f918319536bddea --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 facaf90aeaaf6d7cf5e2966f9f918319536bddea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-06 09:34:20,055 truncating revision to first 12 chars 2017-04-06 09:34:20,055 Setting DEBUG logging. 2017-04-06 09:34:20,055 attempt 1/10 2017-04-06 09:34:20,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/facaf90aeaaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 09:34:21,672 unpacking tar archive at: mozilla-central-facaf90aeaaf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.914262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 09:34:21.886053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:21.886391) ========= script_repo_revision: facaf90aeaaf6d7cf5e2966f9f918319536bddea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:21.886766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:21.887027) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 09:34:21.906840) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 09:34:21.907226) ========= /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.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:34:22 INFO - MultiFileLogger online at 20170406 09:34:22 in /builds/slave/test 09:34:22 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 09:34:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:22 INFO - {'append_to_log': False, 09:34:22 INFO - 'base_work_dir': '/builds/slave/test', 09:34:22 INFO - 'blob_upload_branch': 'Firefox', 09:34:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:22 INFO - 'branch': 'Firefox', 09:34:22 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:22 INFO - 'config_files': ('talos/mac_config.py',), 09:34:22 INFO - 'default_actions': ('clobber', 09:34:22 INFO - 'read-buildbot-config', 09:34:22 INFO - 'download-and-extract', 09:34:22 INFO - 'populate-webroot', 09:34:22 INFO - 'create-virtualenv', 09:34:22 INFO - 'install', 09:34:22 INFO - 'run-tests'), 09:34:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:22 INFO - 'download_minidump_stackwalk': True, 09:34:22 INFO - 'download_symbols': 'ondemand', 09:34:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:22 INFO - 'gecko_profile': False, 09:34:22 INFO - 'gecko_profile_interval': 0, 09:34:22 INFO - 'installer_path': 'installer.exe', 09:34:22 INFO - 'log_level': 'info', 09:34:22 INFO - 'log_name': 'talos', 09:34:22 INFO - 'log_to_console': True, 09:34:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:34:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:34:22 INFO - 'opt_config_files': (), 09:34:22 INFO - 'pip_index': False, 09:34:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:22 INFO - 'cmd': ('bash', 09:34:22 INFO - '-c', 09:34:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:22 INFO - 'enabled': True, 09:34:22 INFO - 'halt_on_failure': False, 09:34:22 INFO - 'name': 'check_screen_resolution'},), 09:34:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:22 INFO - 'cmd': ('bash', 09:34:22 INFO - '-c', 09:34:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:22 INFO - 'enabled': True, 09:34:22 INFO - 'halt_on_failure': False, 09:34:22 INFO - 'name': 'check_screen_resolution'},), 09:34:22 INFO - 'run_cmd_checks_enabled': True, 09:34:22 INFO - 'suite': 'dromaeojs-e10s', 09:34:22 INFO - 'system_bits': '32', 09:34:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:22 INFO - 'title': 't-yosemite-r7-0151', 09:34:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:22 INFO - 'use_talos_json': True, 09:34:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:22 INFO - 'work_dir': 'build'} 09:34:22 INFO - [mozharness: 2017-04-06 16:34:22.121136Z] Running clobber step. 09:34:22 INFO - Running pre-action listener: _resource_record_pre_action 09:34:22 INFO - Running main action method: clobber 09:34:22 INFO - rmtree: /builds/slave/test/build 09:34:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:24 INFO - Running post-action listener: _resource_record_post_action 09:34:24 INFO - [mozharness: 2017-04-06 16:34:24.733524Z] Finished clobber step (success) 09:34:24 INFO - [mozharness: 2017-04-06 16:34:24.733792Z] Running read-buildbot-config step. 09:34:24 INFO - Running pre-action listener: _resource_record_pre_action 09:34:24 INFO - Running main action method: read_buildbot_config 09:34:24 INFO - Using buildbot properties: 09:34:24 INFO - { 09:34:24 INFO - "project": "", 09:34:24 INFO - "product": "firefox", 09:34:24 INFO - "script_repo_revision": "production", 09:34:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:34:24 INFO - "repository": "", 09:34:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:34:24 INFO - "buildid": "20170406075933", 09:34:24 INFO - "pgo_build": "False", 09:34:24 INFO - "basedir": "/builds/slave/test", 09:34:24 INFO - "buildnumber": 38, 09:34:24 INFO - "slavename": "t-yosemite-r7-0151", 09:34:24 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:34:24 INFO - "platform": "yosemite_r7", 09:34:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:34:24 INFO - "branch": "mozilla-central", 09:34:24 INFO - "repo_path": "mozilla-central", 09:34:24 INFO - "revision": "facaf90aeaaf6d7cf5e2966f9f918319536bddea", 09:34:24 INFO - "stage_platform": "macosx64", 09:34:24 INFO - "builduid": "010248e66b8145a493d40f7320541605", 09:34:24 INFO - "slavebuilddir": "test" 09:34:24 INFO - } 09:34:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:34:24 INFO - Running post-action listener: _resource_record_post_action 09:34:24 INFO - [mozharness: 2017-04-06 16:34:24.739535Z] Finished read-buildbot-config step (success) 09:34:24 INFO - [mozharness: 2017-04-06 16:34:24.739742Z] Running download-and-extract step. 09:34:24 INFO - Running pre-action listener: _resource_record_pre_action 09:34:24 INFO - Running main action method: download_and_extract 09:34:24 INFO - mkdir: /builds/slave/test/build/tests 09:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:24 INFO - trying https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:34:24 INFO - Downloading https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:34:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:34:26 INFO - Downloaded 1325 bytes. 09:34:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:34:26 INFO - Using the following test package requirements: 09:34:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:34:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:34:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:34:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:34:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'jsshell-mac.zip'], 09:34:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:34:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:34:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:34:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:34:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:34:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:34:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:34:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:34:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:34:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:34:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:34:27 INFO - Expected file size: 52518655 09:34:27 INFO - Obtained file size: 52518655 09:34:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:34:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:34:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:34:43 INFO - Fetch https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:34:45 INFO - Expected file size: 52518655 09:34:45 INFO - Obtained file size: 52518655 09:35:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:35:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:35:00 INFO - Fetch https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:35:01 INFO - Expected file size: 14404138 09:35:01 INFO - Obtained file size: 14404138 09:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:02 INFO - trying https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:35:02 INFO - Downloading https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:35:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:35:04 INFO - Downloaded 70875131 bytes. 09:35:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:35:04 INFO - mkdir: /builds/slave/test/properties 09:35:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:35:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:35:04 INFO - Contents: 09:35:04 INFO - build_url:https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:35:05 INFO - Running post-action listener: _resource_record_post_action 09:35:05 INFO - Running post-action listener: set_extra_try_arguments 09:35:05 INFO - [mozharness: 2017-04-06 16:35:05.272709Z] Finished download-and-extract step (success) 09:35:05 INFO - [mozharness: 2017-04-06 16:35:05.272832Z] Running populate-webroot step. 09:35:05 INFO - Running pre-action listener: _resource_record_pre_action 09:35:05 INFO - Running main action method: populate_webroot 09:35:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:35:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:35:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:35:05 INFO - '--tppagecycles', 09:35:05 INFO - '7'], 09:35:05 INFO - 'tests': ['tsvgm']}}, 09:35:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:35:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:35:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:35:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:35:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['damp', 'tps']}, 09:35:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'tests': ['damp', 'tps']}, 09:35:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['dromaeo_dom']}, 09:35:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:35:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['basic_compositor_video']}, 09:35:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:35:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['a11yr', 09:35:05 INFO - 'ts_paint', 09:35:05 INFO - 'tpaint', 09:35:05 INFO - 'sessionrestore', 09:35:05 INFO - 'sessionrestore_no_auto_restore', 09:35:05 INFO - 'tabpaint']}, 09:35:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['tsvgx', 09:35:05 INFO - 'tsvgr_opacity', 09:35:05 INFO - 'tart', 09:35:05 INFO - 'tscrollx', 09:35:05 INFO - 'cart', 09:35:05 INFO - 'tsvg_static']}, 09:35:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:35:05 INFO - 'tsvgr_opacity', 09:35:05 INFO - 'tart', 09:35:05 INFO - 'tscrollx', 09:35:05 INFO - 'cart', 09:35:05 INFO - 'tsvg_static']}, 09:35:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'talos_options': ['--disable-e10s'], 09:35:05 INFO - 'tests': ['tp5o']}, 09:35:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:35:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'talos_options': ['--disable-e10s', 09:35:05 INFO - '--xperf_path', 09:35:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:05 INFO - 'tests': ['tp5n']}, 09:35:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:35:05 INFO - 'talos_options': ['--xperf_path', 09:35:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:05 INFO - 'tests': ['tp5n']}}, 09:35:05 INFO - 'talos.zip': {'path': '', 09:35:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:35:05 INFO - Running post-action listener: _resource_record_post_action 09:35:05 INFO - [mozharness: 2017-04-06 16:35:05.291324Z] Finished populate-webroot step (success) 09:35:05 INFO - [mozharness: 2017-04-06 16:35:05.291439Z] Running create-virtualenv step. 09:35:05 INFO - Running pre-action listener: _resource_record_pre_action 09:35:05 INFO - Running main action method: create_virtualenv 09:35:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:35:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:35:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:35:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:35:05 INFO - Using real prefix '/tools/python27' 09:35:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:35:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:09 INFO - Installing pip.................done. 09:35:09 INFO - Return code: 0 09:35:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:35:09 INFO - Reading from file tmpfile_stdout 09:35:09 INFO - Output received: 09:35:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:35:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:09 INFO - 'HOME': '/Users/cltbld', 09:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:09 INFO - 'LOGNAME': 'cltbld', 09:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:09 INFO - 'MOZ_NO_REMOTE': '1', 09:35:09 INFO - 'NO_EM_RESTART': '1', 09:35:09 INFO - 'PAGER': '/bin/cat', 09:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:09 INFO - 'PWD': '/builds/slave/test', 09:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:09 INFO - 'SHELL': '/bin/bash', 09:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:09 INFO - 'USER': 'cltbld', 09:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:09 INFO - 'XPC_FLAGS': '0x0', 09:35:09 INFO - 'XPC_SERVICE_NAME': '0', 09:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:35:09 INFO - Cleaning up... 09:35:09 INFO - Return code: 0 09:35:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:09 INFO - 'HOME': '/Users/cltbld', 09:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:09 INFO - 'LOGNAME': 'cltbld', 09:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:09 INFO - 'MOZ_NO_REMOTE': '1', 09:35:09 INFO - 'NO_EM_RESTART': '1', 09:35:09 INFO - 'PAGER': '/bin/cat', 09:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:09 INFO - 'PWD': '/builds/slave/test', 09:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:09 INFO - 'SHELL': '/bin/bash', 09:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:09 INFO - 'USER': 'cltbld', 09:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:09 INFO - 'XPC_FLAGS': '0x0', 09:35:09 INFO - 'XPC_SERVICE_NAME': '0', 09:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:09 INFO - Downloading/unpacking psutil>=3.1.1 09:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:13 INFO - Installing collected packages: psutil 09:35:13 INFO - Running setup.py install for psutil 09:35:13 INFO - building 'psutil._psutil_osx' extension 09:35:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:35:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:35:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:35:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:35:14 INFO - building 'psutil._psutil_posix' extension 09:35:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:35:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:35:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:35:14 INFO - ^ 09:35:14 INFO - 1 warning generated. 09:35:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:35:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:14 INFO - Successfully installed psutil 09:35:14 INFO - Cleaning up... 09:35:14 INFO - Return code: 0 09:35:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:14 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:14 INFO - 'HOME': '/Users/cltbld', 09:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:14 INFO - 'LOGNAME': 'cltbld', 09:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:14 INFO - 'MOZ_NO_REMOTE': '1', 09:35:14 INFO - 'NO_EM_RESTART': '1', 09:35:14 INFO - 'PAGER': '/bin/cat', 09:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:14 INFO - 'PWD': '/builds/slave/test', 09:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:14 INFO - 'SHELL': '/bin/bash', 09:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:14 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:14 INFO - 'USER': 'cltbld', 09:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:14 INFO - 'XPC_FLAGS': '0x0', 09:35:14 INFO - 'XPC_SERVICE_NAME': '0', 09:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:17 INFO - Installing collected packages: mozsystemmonitor 09:35:17 INFO - Running setup.py install for mozsystemmonitor 09:35:17 INFO - Successfully installed mozsystemmonitor 09:35:17 INFO - Cleaning up... 09:35:17 INFO - Return code: 0 09:35:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:18 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:35:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:18 INFO - 'HOME': '/Users/cltbld', 09:35:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:18 INFO - 'LOGNAME': 'cltbld', 09:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:18 INFO - 'MOZ_NO_REMOTE': '1', 09:35:18 INFO - 'NO_EM_RESTART': '1', 09:35:18 INFO - 'PAGER': '/bin/cat', 09:35:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:18 INFO - 'PWD': '/builds/slave/test', 09:35:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:18 INFO - 'SHELL': '/bin/bash', 09:35:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:18 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:18 INFO - 'USER': 'cltbld', 09:35:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:18 INFO - 'XPC_FLAGS': '0x0', 09:35:18 INFO - 'XPC_SERVICE_NAME': '0', 09:35:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:35:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:35:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:35:22 INFO - warning: no files found matching '*.txt' 09:35:22 INFO - no previously-included directories found matching 'build' 09:35:22 INFO - no previously-included directories found matching 'dist' 09:35:22 INFO - no previously-included directories found matching '.git*' 09:35:22 INFO - Installing collected packages: jsonschema, functools32 09:35:22 INFO - Running setup.py install for functools32 09:35:22 INFO - warning: no files found matching '*.txt' 09:35:22 INFO - no previously-included directories found matching 'build' 09:35:22 INFO - no previously-included directories found matching 'dist' 09:35:22 INFO - no previously-included directories found matching '.git*' 09:35:22 INFO - Successfully installed jsonschema functools32 09:35:22 INFO - Cleaning up... 09:35:22 INFO - Return code: 0 09:35:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:22 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:22 INFO - 'HOME': '/Users/cltbld', 09:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:22 INFO - 'LOGNAME': 'cltbld', 09:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:22 INFO - 'MOZ_NO_REMOTE': '1', 09:35:22 INFO - 'NO_EM_RESTART': '1', 09:35:22 INFO - 'PAGER': '/bin/cat', 09:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:22 INFO - 'PWD': '/builds/slave/test', 09:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:22 INFO - 'SHELL': '/bin/bash', 09:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:22 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:22 INFO - 'USER': 'cltbld', 09:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:22 INFO - 'XPC_FLAGS': '0x0', 09:35:22 INFO - 'XPC_SERVICE_NAME': '0', 09:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:35:22 INFO - Cleaning up... 09:35:22 INFO - Return code: 0 09:35:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:22 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:22 INFO - 'HOME': '/Users/cltbld', 09:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:22 INFO - 'LOGNAME': 'cltbld', 09:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:22 INFO - 'MOZ_NO_REMOTE': '1', 09:35:22 INFO - 'NO_EM_RESTART': '1', 09:35:22 INFO - 'PAGER': '/bin/cat', 09:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:22 INFO - 'PWD': '/builds/slave/test', 09:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:22 INFO - 'SHELL': '/bin/bash', 09:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:22 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:22 INFO - 'USER': 'cltbld', 09:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:22 INFO - 'XPC_FLAGS': '0x0', 09:35:22 INFO - 'XPC_SERVICE_NAME': '0', 09:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:26 INFO - Downloading docopt-0.6.1.tar.gz 09:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:26 INFO - Running setup.py install for blobuploader 09:35:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:27 INFO - Running setup.py install for requests 09:35:27 INFO - Running setup.py install for docopt 09:35:27 INFO - Successfully installed blobuploader requests docopt 09:35:27 INFO - Cleaning up... 09:35:27 INFO - Return code: 0 09:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:27 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:27 INFO - 'HOME': '/Users/cltbld', 09:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:27 INFO - 'LOGNAME': 'cltbld', 09:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:27 INFO - 'MOZ_NO_REMOTE': '1', 09:35:27 INFO - 'NO_EM_RESTART': '1', 09:35:27 INFO - 'PAGER': '/bin/cat', 09:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:27 INFO - 'PWD': '/builds/slave/test', 09:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:27 INFO - 'SHELL': '/bin/bash', 09:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:27 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:27 INFO - 'USER': 'cltbld', 09:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:27 INFO - 'XPC_FLAGS': '0x0', 09:35:27 INFO - 'XPC_SERVICE_NAME': '0', 09:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-YopkKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-944Mbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vaKpRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vMqut8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-m1GZcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-c2rEYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-0mvO1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-isAeMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-w30Rd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dhFodw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-iQH5iX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-j3wgmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-t2r893-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vWrLb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fDHt_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-D5sNNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-CdgB92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:29 INFO - Running setup.py install for manifestparser 09:35:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:29 INFO - Running setup.py install for mozcrash 09:35:30 INFO - Running setup.py install for mozdebug 09:35:30 INFO - Running setup.py install for mozdevice 09:35:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Running setup.py install for mozfile 09:35:30 INFO - Running setup.py install for mozhttpd 09:35:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Running setup.py install for mozinfo 09:35:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Running setup.py install for mozInstall 09:35:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:30 INFO - Running setup.py install for mozleak 09:35:31 INFO - Running setup.py install for mozlog 09:35:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Running setup.py install for moznetwork 09:35:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Running setup.py install for mozprocess 09:35:31 INFO - Running setup.py install for mozprofile 09:35:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Running setup.py install for mozrunner 09:35:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:35:31 INFO - Running setup.py install for mozscreenshot 09:35:32 INFO - Running setup.py install for moztest 09:35:32 INFO - Running setup.py install for mozversion 09:35:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:35:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:35:32 INFO - Cleaning up... 09:35:32 INFO - Return code: 0 09:35:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:32 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:32 INFO - 'HOME': '/Users/cltbld', 09:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:32 INFO - 'LOGNAME': 'cltbld', 09:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:32 INFO - 'MOZ_NO_REMOTE': '1', 09:35:32 INFO - 'NO_EM_RESTART': '1', 09:35:32 INFO - 'PAGER': '/bin/cat', 09:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:32 INFO - 'PWD': '/builds/slave/test', 09:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:32 INFO - 'SHELL': '/bin/bash', 09:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:32 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:32 INFO - 'USER': 'cltbld', 09:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:32 INFO - 'XPC_FLAGS': '0x0', 09:35:32 INFO - 'XPC_SERVICE_NAME': '0', 09:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:32 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KTHH4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:32 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SjY8ZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:32 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1ETm64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:32 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-qJ4YYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-xgCzM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-AhMcNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-eqERQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-iBiOVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-MCrHnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-sE1RsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-eWm1BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-U0V8tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:33 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NwyLYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:34 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ixrZCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:34 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-gPXFBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:34 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-g3IBVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:35:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:34 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-BNLS7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:35:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:35:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:35:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:35:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:37 INFO - Downloading blessings-1.6.tar.gz 09:35:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:37 INFO - Installing collected packages: blessings 09:35:37 INFO - Running setup.py install for blessings 09:35:37 INFO - Successfully installed blessings 09:35:37 INFO - Cleaning up... 09:35:37 INFO - Return code: 0 09:35:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:37 INFO - Reading from file tmpfile_stdout 09:35:37 INFO - Current package versions: 09:35:37 INFO - blessings == 1.6 09:35:37 INFO - blobuploader == 1.2.4 09:35:37 INFO - docopt == 0.6.1 09:35:37 INFO - functools32 == 3.2.3-2 09:35:37 INFO - jsonschema == 2.5.1 09:35:37 INFO - manifestparser == 1.1 09:35:37 INFO - mozInstall == 1.12 09:35:37 INFO - mozcrash == 1.0 09:35:37 INFO - mozdebug == 0.1 09:35:37 INFO - mozdevice == 0.50 09:35:37 INFO - mozfile == 1.2 09:35:37 INFO - mozhttpd == 0.7 09:35:37 INFO - mozinfo == 0.9 09:35:37 INFO - mozleak == 0.1 09:35:37 INFO - mozlog == 3.4 09:35:37 INFO - moznetwork == 0.27 09:35:37 INFO - mozprocess == 0.25 09:35:37 INFO - mozprofile == 0.28 09:35:37 INFO - mozrunner == 6.13 09:35:37 INFO - mozscreenshot == 0.1 09:35:37 INFO - mozsystemmonitor == 0.3 09:35:37 INFO - moztest == 0.8 09:35:37 INFO - mozversion == 1.4 09:35:37 INFO - psutil == 3.1.1 09:35:37 INFO - requests == 1.2.3 09:35:37 INFO - wsgiref == 0.1.2 09:35:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:37 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:35:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:37 INFO - 'HOME': '/Users/cltbld', 09:35:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:37 INFO - 'LOGNAME': 'cltbld', 09:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:37 INFO - 'MOZ_NO_REMOTE': '1', 09:35:37 INFO - 'NO_EM_RESTART': '1', 09:35:37 INFO - 'PAGER': '/bin/cat', 09:35:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:37 INFO - 'PWD': '/builds/slave/test', 09:35:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:37 INFO - 'SHELL': '/bin/bash', 09:35:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:37 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:37 INFO - 'USER': 'cltbld', 09:35:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:37 INFO - 'XPC_FLAGS': '0x0', 09:35:37 INFO - 'XPC_SERVICE_NAME': '0', 09:35:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:35:38 INFO - Cleaning up... 09:35:38 INFO - Return code: 0 09:35:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:38 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 0x10cd02b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd05768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb04bcd1eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, '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.AGV2qF36ce/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.Y0dvI9SafX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:38 INFO - 'HOME': '/Users/cltbld', 09:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:38 INFO - 'LOGNAME': 'cltbld', 09:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:38 INFO - 'MOZ_NO_REMOTE': '1', 09:35:38 INFO - 'NO_EM_RESTART': '1', 09:35:38 INFO - 'PAGER': '/bin/cat', 09:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:38 INFO - 'PWD': '/builds/slave/test', 09:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:38 INFO - 'SHELL': '/bin/bash', 09:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:38 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:38 INFO - 'USER': 'cltbld', 09:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:38 INFO - 'XPC_FLAGS': '0x0', 09:35:38 INFO - 'XPC_SERVICE_NAME': '0', 09:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:38 INFO - Cleaning up... 09:35:38 INFO - Return code: 0 09:35:38 INFO - Running post-action listener: _resource_record_post_action 09:35:38 INFO - Running post-action listener: _start_resource_monitoring 09:35:38 INFO - Starting resource monitoring. 09:35:38 INFO - [mozharness: 2017-04-06 16:35:38.345694Z] Finished create-virtualenv step (success) 09:35:38 INFO - [mozharness: 2017-04-06 16:35:38.346634Z] Running install step. 09:35:38 INFO - Running pre-action listener: _resource_record_pre_action 09:35:38 INFO - Running main action method: install 09:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:38 INFO - Reading from file tmpfile_stdout 09:35:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:38 INFO - Reading from file tmpfile_stdout 09:35:38 INFO - Output received: 09:35:38 INFO - Usage: mozinstall [options] installer 09:35:38 INFO - Options: 09:35:38 INFO - -h, --help show this help message and exit 09:35:38 INFO - -d DEST, --destination=DEST 09:35:38 INFO - Directory to install application into. [default: 09:35:38 INFO - "/builds/slave/test"] 09:35:38 INFO - --app=APP Application being installed. [default: firefox] 09:35:38 INFO - mkdir: /builds/slave/test/build/application 09:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:35:58 INFO - Reading from file tmpfile_stdout 09:35:58 INFO - Output received: 09:35:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:35:58 INFO - Running post-action listener: _resource_record_post_action 09:35:58 INFO - [mozharness: 2017-04-06 16:35:58.346315Z] Finished install step (success) 09:35:58 INFO - [mozharness: 2017-04-06 16:35:58.346469Z] Running run-tests step. 09:35:58 INFO - Running pre-action listener: _resource_record_pre_action 09:35:58 INFO - Running main action method: run_tests 09:35:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:58 INFO - 2017-04-06 09:35:58.361 screenresolution[1624:7469] starting screenresolution argv=screenresolution get 09:35:58 INFO - 2017-04-06 09:35:58.363 screenresolution[1624:7469] Display 0: 1600x1200x32@60 09:35:58 INFO - 2017-04-06 09:35:58.369 screenresolution[1625:7472] starting screenresolution argv=screenresolution list 09:35:58 INFO - Available Modes on Display 0 09:35:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:58 INFO - Intel Iris: 09:35:58 INFO - Chipset Model: Intel Iris 09:35:58 INFO - Type: GPU 09:35:58 INFO - Bus: Built-In 09:35:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:58 INFO - Vendor: Intel (0x8086) 09:35:58 INFO - Device ID: 0x0a2e 09:35:58 INFO - Revision ID: 0x0009 09:35:58 INFO - Displays: 09:35:58 INFO - Display: 09:35:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:58 INFO - Display Serial Number: 1600x1200 60 09:35:58 INFO - Main Display: Yes 09:35:58 INFO - Mirror: Off 09:35:58 INFO - Online: Yes 09:35:58 INFO - Rotation: Supported 09:35:58 INFO - Return code: 0 09:35:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:58 INFO - Python 2.7.3 09:35:58 INFO - Return code: 0 09:35:58 INFO - grabbing minidump binary from tooltool 09:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:58 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 0x10cae0d30>, '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 0x10ca07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:35:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:35:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:35:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:58 INFO - Return code: 0 09:35:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:35:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:58 INFO - ENV: RUST_BACKTRACE is now 1 09:35:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:35:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:58 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/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:35:58 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/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:35:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AGV2qF36ce/Render', 09:35:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:58 INFO - 'HOME': '/Users/cltbld', 09:35:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:58 INFO - 'LOGNAME': 'cltbld', 09:35:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:35:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:58 INFO - 'MOZ_NO_REMOTE': '1', 09:35:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:58 INFO - 'NO_EM_RESTART': '1', 09:35:58 INFO - 'PAGER': '/bin/cat', 09:35:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:58 INFO - 'PWD': '/builds/slave/test', 09:35:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:58 INFO - 'RUST_BACKTRACE': '1', 09:35:58 INFO - 'SHELL': '/bin/bash', 09:35:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y0dvI9SafX/Listeners', 09:35:58 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 09:35:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:58 INFO - 'USER': 'cltbld', 09:35:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:58 INFO - 'XPC_FLAGS': '0x0', 09:35:58 INFO - 'XPC_SERVICE_NAME': '0', 09:35:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:58 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/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:35:58 INFO - mozversion application_buildid: 20170406075933 09:35:58 INFO - mozversion application_changeset: facaf90aeaaf6d7cf5e2966f9f918319536bddea 09:35:58 INFO - mozversion application_display_name: Nightly 09:35:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:58 INFO - mozversion application_name: Firefox 09:35:58 INFO - mozversion application_remotingname: firefox 09:35:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:35:58 INFO - mozversion application_vendor: Mozilla 09:35:58 INFO - mozversion application_version: 55.0a1 09:35:58 INFO - mozversion platform_buildid: 20170406075933 09:35:58 INFO - mozversion platform_changeset: facaf90aeaaf6d7cf5e2966f9f918319536bddea 09:35:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:35:58 INFO - mozversion platform_version: 55.0a1 09:35:58 INFO - using testdate: 1491496558 09:35:58 INFO - actual date: 1491496558 09:35:58 INFO - starting webserver on 'localhost:49241' 09:35:58 INFO - SUITE-START | Running 2 tests 09:35:58 INFO - TEST-START | dromaeo_css 09:35:58 INFO - operating with platform_type : mac_ 09:35:58 INFO - Initialising browser for dromaeo_css test... 09:35:58 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpSjsRxB/profile http://localhost:49241/getInfo.html) 09:35:59 INFO - PID 1635 | 1491496559626 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 09:35:59 INFO - PID 1635 | 1491496559627 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 09:35:59 INFO - PID 1635 | 1491496559679 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 09:36:02 INFO - PID 1635 | 2017-04-06 09:36:02.210 plugin-container[1637:7652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924f, name = 'com.apple.tsm.portname' 09:36:02 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:02 INFO - PID 1635 | 2017-04-06 09:36:02.213 plugin-container[1637:7652] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9703, name = 'com.apple.CFPasteboardClient' 09:36:02 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:02 INFO - PID 1635 | 2017-04-06 09:36:02.213 plugin-container[1637:7652] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:02 INFO - PID 1635 | __metrics Screen width/height:1600/1200 09:36:02 INFO - PID 1635 | colorDepth:24 09:36:02 INFO - PID 1635 | Browser inner width/height: 1024/689 09:36:02 INFO - PID 1635 | __metrics 09:36:02 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 09:36:02 INFO - PID 1635 | 1491496562403 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 09:36:03 INFO - TEST-INFO | 1635: exit 0 09:36:03 INFO - Browser initialized. 09:36:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:36:03 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpSjsRxB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:03 INFO - PID 1638 | 1491496563669 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 09:36:03 INFO - PID 1638 | 1491496563670 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 09:36:06 INFO - PID 1638 | 2017-04-06 09:36:06.074 plugin-container[1639:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:36:06 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:06 INFO - PID 1638 | 2017-04-06 09:36:06.075 plugin-container[1639:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:36:06 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:06 INFO - PID 1638 | 2017-04-06 09:36:06.075 plugin-container[1639:7782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:15 INFO - PID 1638 | RSS: Main: 234160128 09:36:15 INFO - PID 1638 | 09:39:20 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:39:22 INFO - PID 1638 | RSS: Main: 252530688 09:39:22 INFO - PID 1638 | 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 09:41:03 INFO - PID 1638 | 09:41:03 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:41:03 INFO - PID 1638 | Full stack: 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 09:41:03 INFO - PID 1638 | 09:41:03 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:41:03 INFO - PID 1638 | Full stack: 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 09:41:03 INFO - PID 1638 | 09:41:03 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:41:03 INFO - PID 1638 | Full stack: 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | ************************* 09:41:03 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 09:41:03 INFO - PID 1638 | 09:41:03 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:41:03 INFO - PID 1638 | Full stack: 09:41:03 INFO - PID 1638 | ************************* 09:42:23 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:42:24 INFO - PID 1638 | RSS: Main: 260505600 09:42:24 INFO - PID 1638 | 09:45:26 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:45:27 INFO - PID 1638 | RSS: Main: 272605184 09:45:27 INFO - PID 1638 | 09:48:28 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:48:29 INFO - PID 1638 | RSS: Main: 268038144 09:48:29 INFO - PID 1638 | 09:51:32 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:51:33 INFO - PID 1638 | RSS: Main: 278335488 09:51:33 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:54:35 INFO - PID 1638 | RSS: Main: 272068608 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | __start_tp_report 09:54:35 INFO - PID 1638 | _x_x_mozilla_page_load 09:54:35 INFO - PID 1638 | _x_x_mozilla_page_load_details 09:54:35 INFO - PID 1638 | |i|pagename|runs| 09:54:35 INFO - PID 1638 | |0;dojo.html;3533.116720819795;3601.5858176309716;3676.761010534316;3678.2643471305746;3699.4820725098475;6273.843153921154;6276.278228003775;6298.149749783784;6320.747170113202;6368.904466433001;6286.402791734789;6359.936400635992;6374.3625637436235;6394.872102557946;6399.976003839387;5084.694918304895;5444.40111587725;5556.527695145917;5607.691576963265;5746.683932383718;4204.5585213552595;5630.943690563083;5677.716114194294;5693.459121383462;5740.885182296352;4821.276808478721;5445.401005889349;5470.890582188334;5618;5665.745041473102;5592.021396255639;5596.608237423382;5602.663840169603;5612.494875461187;5620.915686264669;4858.392700912386;5380.7086299288285;5525.889482210333;5554.166874968747;5554.72226388683;4911.7544122793715;5233.869153271199;6051.636901785863;6268.216472940882;6290.716917738713;6121.265448146251;6498.667773106502;6841.62371069591;6867;6869.484788640872;6968.651567421659;6971.128608923885;6986.056882320872;6994.370506654425;7011.614361210131;6248.75004999804;6273.686315684197;6343.9365606343345;6396.5202609804455;6402.775902843378;7074.327938845801;7104.183018952886;7134.357907788324;7157.785266442015;7164.068671072727;6404.327545607644;6529.3470652935275;6557.114789503451;6653.600783952978;6705.061291419205;6961.860762784716;6965.199002114821;6967.407770339578;6969.0940177777575;6983.127109111361;6514.185726784152;6635.170603674541;6855.245922948472;6874.2782007889455;7054.92945070553;6629.370209830058;6723.0923825283935;6737.59574425536;6753.425958793558;6851.383375496229;5465.4261302563455;5473.507384335349;5479.369872464717;5481.095619222863;5515.283013208257;4839.370881785416;4856.514348565115;4857.441394239637;4884.340614017134;4892.975535122301;6119.877602447926;6250.812475625738;6311.2426508819235;6326.114343991845;6340.3342649022115;6940.548864323904;6983.685734141975;6992.405645520086;6992.510524263253;6999.23008469068;6283.842903927347;6388.201474815648;6397.96801015992;6401.359864013654;6407.679616019181;5879.706014699248;5972.5520585955355;6004.219451471281;6139.416755408229;6147.508199344131;6959.304069593;6974.267701891431;6975.337342952412;7042.471814613822;7096.893546596909;6924.3421874921805;6933.757318493828;6955;6970.546914450747;6986.301369862973;7128.85742285141;7138.072050633592;7169.354758071799;7170.533915295489;7187.640617968976;5641.294838145232;6546.770863019771;6617.536772425884;6845.0759147514445;6854.34883686049;6653.900191497229;6862.691178896862;6954.756583519553;6955.617441040791;6962.756303529555;6394.808155755335;6627.801165965029;6715.966420167868;6717.798466046026;6731.932680673326;6696.330366963265;6705.027770973279;6710.1276834013215;6710.83222919431;6717.093192418961;6319.526035547445;6365.522585806176;6422.389872962061;6477.060826180272;6536.444402225864;6610.3389661035435;6698;6713.093732346071;6736.932630673827;6738.393544581011;4935;5638.154276858504;6538.705758240986;6545.476361891133;6559.639219842764 09:54:35 INFO - PID 1638 | |1;ext.html;682.7098483144664;990.7176454710407;1125.48227815205;1262.8358313419253;1294.158796782091;28958.85520572397;37422.064448388766;37437.251254974886;37482.62517374825;37492.62507374925;31709.524357134662;36837.447438288444;37285.44071838925;37737;37837.67568135116;6170.50635949123;6279.780207692732;8010.359171266299;8032.959835200845;8524.70163544273;8638.222559969601;8652.48085114892;8719.389642725011;8723.476591404504;8729.170728780755;8649.783755406103;8670.609822557968;8692.826143477127;8710.564471776417;8741.562921853882;8653.610587523544;8691.782705432352;8694;8704.608292626815;8711.520866352348;8488.66045358185;8551.059383467813;8551.401401901869;8566.957165214197;8581.485110893367;37274.88175354744;37429.43855842164;37454.81272593647;37512.437313440045;37604.81197594022;3413.573303337083;3425.383430982422;3449.3618680543927;3451.6030656474327;3452.9827350863084;29177.70822291771;29489.557656635166;29597;29597.66810493533;29658.110256692333;3017.9396412071856;3059.4645936961165;3086.876524939;3088.320569474712;3099.643540992792;208773.95613021989;267712;269596;269836;270449;39746.60253397487;40435.39346909799;47333;48071.278930816065;48499.75750121227;50459.24311135336;50657.493425066015;50702.49297507051;50739;50873;11896.821547676791;14281;14372.784408233885;14383.640408989859;14388.640283992983;11565.884341156649;11646;11683.948444640024;11687;11855.644330670093;12217.205881617667;12347.75304493905;12357.38213089342;12379.947704445043;12441.688957776129;5658.688772117573;5659.207710920475;5665.206871038058;5672.404397538282;5685.317761868602;963.4460185393286;994.2145704893198;997.8453339732353;998.9332748462151;1025.7025462615907;22761.203357882394;23513.41216469602;23931.401714957265;23933.760662393503;23946.40133996664;1027.9617586237805;1085.4247248958065;1090.1878100814943;1125.1448898836943;1140.680609429361;9609;11737.222779616373;12074.479213365563;12186.878131218751;12200.267983920992;346520;347099;347254.2637286848;347353.2632336822;349426;50275.49724502708;50365.74085647815;50434.74782626063;50525.24212136821;50677.493225068014;1374.786908029237;1445.9060161089496;1477.1801650084305;1526.7328217561883;1527;21864;23400.64899026482;23534.882325588263;23549.64675529834;23613;29361;37293.06767330838;37314.62685373111;37370.813145934095;37419.81290093641;311656;312799;312880;313027;313081;31548.211294717814;32177.678223217466;32358.35283294368;32394;32394.704211831264;34365.82817085998;40537.79731101326;40663.59336406598;41007.38488922609;41062;27902;27988;28475.715242848135;29096;29451;28619.713802861705;29240.853795730884;29265.268368290963;29710.405791883608;29723;33627.15932101717;34697.479537807456;34767.30465390627;34913.82543087268;34964.6014159441;23494.76505234926;27708.030218943048;28753;29750.95871644482;29759.25601859971;19042.629625194677;23899.402514937265;24131.75868241295;24574.13990510395;24687 09:54:35 INFO - PID 1638 | |2;jquery.html;423.6250917937624;440.2405849908907;442.72551018368614;444.8954495693512;455.95896369326755;19879.30422435217;21530.784692153073;22085.779142208572;22370.32889013328;22404.88797556012;22345.329640110824;22411.99146038428;22601.886990565064;22838.429039274026;23352.766472335272;6063.30272018718;6159.692015399234;6308.179936608234;6310.905336419957;6317.305096439411;5881;6149.631022138664;6194.5973511721595;6211.534134939875;6214.471769899541;5105.259737338102;5309.044372013036;5919.970400147993;6139.969300153493;6176.073588961669;6119.418655227746;6148.108524263979;6174.475169610589;6187.381261873822;6227.875442491148;4579.4504659440745;5389.487998640123;5458.044842152607;5542.251796007568;5542.722863856791;26483.205503834724;26548;26628;26690.866545667148;26951.59572606393;1996.9900150499154;2125.6811478278228;2242.495438526335;2243.23729931824;2257.582347265761;17557.034363110022;17584.472465826046;17803.219678032223;18015.549611259692;18191.99944003079;2098.4019554426914;2117.333040092366;2165.361218440564;2178.2485042660255;2181.5091604389045;110489.89510104796;146417;148101;148410;150215;10841.186910981707;10863.782724345469;11220.775584488265;12296.815547766791;12896.226226426445;14881.437449067693;16452.588685282964;16474.258658360402;16488.83511164897;16498.34006639721;7432.591207483641;7433.479656423999;8180.550069746221;9094.499802510925;9123.726288211232;8559.914400856036;8564.357673174536;8614.009388920354;8758.255548278341;8758.956205218934;7889.368850491946;7931.246531579491;7977.680892764341;8033;8054.355651547862;2160.6542953127737;2182.410749097735;2182.443476913377;2190.835687323457;2203.9559208816054;932.7295084425441;934.2292608597934;943.990560094404;972.7324985628867;973.9513024348754;17670.38153664641;19894.70157947632;19899.801001990083;21317.46706332323;21417.46456338604;928.3965422475445;944.7434911567599;960.6349587156975;968.3415277611292;1013.5945621751212;10804.297720648132;11186.720331991603;11187.440627968568;11214.271072380268;11224.719382015515;716393;730661;735966;738433;758991;138667;144858;149790;161451;169466;1172.1560476456937;1179.186361410624;1231.759806837658;1233.993830030844;1234.512367614787;21296.25463108784;21675.783242167377;22578;22615.773842261366;22657.320280391614;63625.363746361945;64049.35950640434;64205.678971604844;64932.67533662301;65862;86655.13344866723;87192;87326;87864;88034.55982720046;8783.682447632906;8985.371024028254;9013.549322533978;9106.134917182857;9143.725688229364;12985.870141298845;13730.313484325943;13907.443702251796;14210.928945355206;14338.283085845875;10538.209634277306;11185.832212516656;11743.236689615147;11797.115216358636;11973.640790776291;10973.615923442641;11077.335359878432;11156.888431115585;11393.715157120807;11421.714457138638;11821.40892955366;12154.513819447124;12258.448369823202;12269.815952760537;12393.132480726263;7245.746398876014;7307.890381644173;9059.912810462787;9167.404118732262;9188.632454701738;11658.18392712502;11748.236364636272;12027.338496382783;12079.214851035005;12162.75674486523 09:54:35 INFO - PID 1638 | |3;mootools.html;1265.3230521670905;1439.7408466476036;1441.2217402602596;1454.3818876977282;1463.1148155366;11453.484593193305;11703.414829258545;11800.291982481047;11942.522299108025;12073;11835.40822958853;11863.52545898163;12048.81926771097;12049.638510844667;12073.517059317639;2467.1858286765323;2496.0265496456304;2501.262127672341;2504.8497090174556;2507.8244522883406;2074.9896250518727;2436.926892193237;2447.400386905211;2472.344828620417;2494.3639371960217;2404.5551572959057;2481.1812102006293;2495.887685054168;2500.762427569378;2503.824732268742;2420.770026847447;2428.08946645009;2445.572024895645;2461.876906154685;2478.9752102479106;2397.3886658902043;2468.0991438125134;2484.1553871683714;2484.6769919910475;2499.9500009999697;11984.880151198551;12059.276443413422;12062.45718942641;12066.276023438622;12099.213551119148;6485;6738.157730283715;6741.426978706816;6747.089142965686;6755.5271131020845;3266.5100234964707;4159.292920203573;4234.153169366139;4247.3628955656895;4250.404943307939;1215.3497878634976;1224.1492162946756;1261.186534685123;1271.5486002469174;1274.241826113461;14496.56510304682;14679.119252844863;16442.177891105515;16518.091504967222;16672.666546669;5416.945830541723;5492.1212605982855;5667.80162694312;5683.175939488751;5686.004949133885;12399;12403.317817519943;12436.00511959041;12480.625581232578;12485.31330776816;6553.737850485927;6584.8024559263295;6593.736250549924;6598.208215014229;6606.6036037837885;5705.686187259741;6003.849903752353;6085.117657939574;6165.260168779775;6240.687965601702;4035.8183881725395;4335.80488877995;4434.645228381722;4471.575200355961;4475.4181956345465;1285.774989376874;1298.5519995601503;1308.9332194261647;1323.0936808286162;1323.4044679894087;742.759591482226;748.7753673897809;761.8666733321646;768.1166658342838;802.1457148137215;6048;6222.751089956441;6289.7484100636375;6353.682315884187;7150.034745309421;864.1272314961934;891.5319457284977;894.9955250223837;902.2331018634239;905.8686490458979;4193.245215861113;4198.9370159447635;4311.33174357975;4342.218400687843;4470.061287129674;12249.958753505873;14990.925045374703;15411;15619.921900390425;15635.765463518057;12615.30615816139;12653.87346126527;15325.923370383076;15493.302801373966;15504;10900.400477973788;11165.665030049111;11170.832437513443;11174.60888868902;11185.720356990978;5848.385919478394;6603.514209302868;6758.493113016445;7052.717891284291;7231.891521627285;10754.784904302027;13016.088873778745;13228.206307621573;13282.269475178959;13307.534236301683;12910.67723306925;13338;13388.598342049754;13425.194488330731;13548.796768048684;9557.23542116643;9598.376105553116;9624.51877406141;9728.319017668695;9732.902670973199;10682.03861652455;10874;10960.451977401257;10966.232363734462;10982.835257470984;9147.63409463614;9220.077992200726;9307;9327.81344373095;9349.719508414759;8938.597763100546;9028.052054534179;9120.361574689974;9167;9185.540722963959;8117;8242.546659933761;8259.669613215405;8312.459690120122;8330.166983301622;6031.5476339273855;6049.939500604937;6178.598391104564;6227.501799856092;6255.56211065221;5549;6072.605280656743;6207.203112591705;6266.404691554295;6430.035494675649 09:54:35 INFO - PID 1638 | |4;prototype.html;301.81890865480716;332.72566071944453;377.94897688812;387.71859005985226;398.3785294939894;4892.168331383665;5507.5593952483805;6220.284667263266;6307.653079080649;6420.1974753155855;5253.369595648518;6050.425209605091;6213.161223234862;6274.56078074535;6288.339724328936;3994.161226142499;4328.740275583459;4361.847335343264;4485.1254005468945;4517.186906356855;4469.396631454744;4472.642188624902;4474.8881277967985;4475.7762111894435;4525.977370113144;3842.615738426163;4435.223835828733;4441.800118994653;4442.600165985061;4461.531539188387;3434;4168.95776055986;4235.555266697014;4408.845690400821;4510.413646226002;3196.9680303197138;3409.488576713488;3746.3256613809504;3834.578196398394;3854.402567602026;5755.4244575542525;6024.789132380346;6053.394660533955;6066.363031881677;6099.329073801879;4048.838046478137;4671.6262698984;5299;5385.973070134663;5405.891882162335;5520.447955204488;6164.784232551884;6669.399754022162;6702.4302934250645;6711.697973591151;4088.4276201331836;4158.500979882403;4257.5529569395385;4396.07682386696;4396.78016099197;6459.612423254667;6796.558223715443;6960.721571137148;7089.326513981164;7167.74912878052;3861.942070868939;4351.108022855307;4446.777661116931;4459.142841442194;4473.338363673609;4525.411696479437;4527.683062185682;4530.79611417487;4549.451427886254;4579.793909274024;4452.732836029849;5124.359455068116;5326.813561525328;5355;5381.4349493303425;6661.566998145105;6734;6740.281228286755;6747.831304217434;6748.358905903931;1490.4112875414366;1498.9250537473083;1516.916569588661;1541.421966762464;1546.2887071946805;861.7285555050139;863.4905405810476;1004.1815920025156;1006.6677996261217;1016.8322226832638;6240.31356550779;6243.469305109026;6255.843603909939;6267.65527895961;6277.497800175975;5679.642948176461;6107.6640784757265;6926.238113807477;6971.268016858156;6997.825054373681;5264.499872512105;5833.854153646108;6062.302835174011;6237.968810156011;6344.175257216624;2998.610180676542;3479.4780782882262;3522;3576.0497589488778;3629.7295946418544;6626.237982632059;6795.3204679531655;7059.258777828357;7096.687112884133;7129.251428600026;6336.631685156481;6878.14290141669;7003.299670033058;7039.120109986252;7269.709211631582;6003.969980150071;6455.354464553507;7059.117610298713;7059.71761129549;7208.27917208275;6334.683265836782;6361.777337793155;6974.825629359307;7018.473614479037;7019.753678822728;5653.802116925888;6001.609895356788;6764.019217213575;6800.489963252677;6803.489738269551;5638.126090455986;6827.48793840454;7106.218315985341;7212.862404853905;7231.674574644048;4230.5134909485805;4386.692931494892;4403.251447254039;4429.557044295545;4442.955570444255;5587.99416105105;6159;7027.297270272932;7087.653345864269;7100.325469080429;6421.7110230038825;6771.356721111487;6789.355011273921;6810.353016463428;6840.41856442208;5672.581854536366;6378.362163783585;6644.368784965421;6751.088603038527;6773.729050837911;6230.532710046674;6618.510835062072;6655.600663960178;6655.866882662416;6668;5508.834734957958;6331.620102793663;6656;6674.532782705164;6717.16035495563;6154.415330543591;6516.185476815398;6545.476361890942;6568.770093046721;6568.934310656832 09:54:35 INFO - PID 1638 | |5;yui.html;713.3045280851171;798.2336956521739;804.778685861388;824.4723377038695;824.8534536993576;16214.270357833897;16465.83534164658;16762.329506819744;16797.58006049848;16901.239444225008;15076.773848392255;15264.107652548611;15396.461123860667;15530.45643402481;15608.687826243467;886.3263919421254;964.6430820596389;986.4229425785924;995;998.4658207858797;970.6166064404556;991.940483570988;992.2012779712329;1017.8320577104769;1021.4177918586408;1048.2871647279846;1055.9683209503728;1063.1601035182198;1063.2822844579878;1063.6064656077262;1034.487928475402;1058.0424715632364;1060.6923992042298;1062.7661914378864;1062.9362238265728;755.5391211361066;784.9097353804272;786.7364432914981;788.996055019727;794.6265255329986;16461.176941153015;16949.49151525456;17068.658626827393;17073.146342682816;17098.230579623945;14619.926900365537;14641.41434342625;14730;14791.630209244748;14858.2570871456;13128.86871131286;13132.540361087411;13284.202947823162;13372.130811497222;13450.461981520826;3300.7029367356813;3320.236345640492;3333.9998000599967;3335.299587086715;3348.8827891023698;91263;112642.43678781553;113704.43147784291;115046;116317;11359.432028398547;11369.2609980351;11390.37352945596;11602.535898564132;12058.879411205775;24948.12681556137;25430.49139017246;25460.745392546207;25475.61786573203;25477;7840.647170877324;7910.881336779953;8054.718084867001;8075.434719569691;8151;3476.5480487536965;4149.6265336119895;4185.895352616209;4206.137741762993;4233;3643.3259846928404;4000.6197861737655;4164;4284.036091879365;4293.957060429356;827.3133299361635;885.0574138542431;885.4377470306401;895.1719659315107;896.8744375787395;613.5766321238332;615.2985596420085;635.6916895305773;639.964801935898;656.9474442044625;9609;10049.59801607942;10079.798404031879;10180.898191017995;10266.332688375232;586.7824264650867;595.5235811350902;622.00168729189;650.1645385679379;659.9307072757388;3892.5328960524353;4696.272077827942;4711;4711.599514041345;4743.762811859393;82390.76413853797;99997;104240.91518169593;104463;107613.92386076196;81187;84757.57621211854;90836;92601;92801;62827.6858615704;81447.55657330289;82054;82607.5869620648;83031;9012.594433250651;9155.633774648939;9261.16649501548;9439.292053095907;9475.952620236854;40071;41251.79374103231;41517.16965660609;41789;41993.79003104965;69614.65192674004;70724;72438;75040.62479687567;75380.62309688417;13416.932915335361;13590.660233494242;13703.794443083163;14043.50847720325;14217.928910355382;14406.567802965928;14528.982971191916;14556.781648275073;14834.480793172188;14882.404703811726;13467.932660336635;13515.459381624625;13596.59210223695;13924.721505569629;14326;13741.931290343484;13895.58313250604;14003.299835008413;14480.493182738554;14725.705485890436;13500.45998160102;13740.587782366545;13769.586912392644;13834.457863673962;13867.930660346634;9502.572384242587;9975.301728878909;10033.749156271151;10640.41477718702;10777.245592808427;9763.560639771085;10835.458227088771;11140;11156;11288.774224515628 09:54:35 INFO - PID 1638 | __end_tp_report 09:54:35 INFO - PID 1638 | __start_cc_report 09:54:35 INFO - PID 1638 | _x_x_mozilla_cycle_collect,424 09:54:35 INFO - PID 1638 | __end_cc_report 09:54:35 INFO - PID 1638 | __startTimestamp1491497675819__endTimestamp 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | ------- Summary: start ------- 09:54:35 INFO - PID 1638 | Number of tests: 6 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6257.31 Median:6407.68 stddev:795.39 (12.4%) stddev-sans-first:768.58 09:54:35 INFO - PID 1638 | Values: 3533.1 3601.6 3676.8 3678.3 3699.5 6273.8 6276.3 6298.1 6320.7 6368.9 6286.4 6359.9 6374.4 6394.9 6400.0 5084.7 5444.4 5556.5 5607.7 5746.7 4204.6 5630.9 5677.7 5693.5 5740.9 4821.3 5445.4 5470.9 5618.0 5665.7 5592.0 5596.6 5602.7 5612.5 5620.9 4858.4 5380.7 5525.9 5554.2 5554.7 4911.8 5233.9 6051.6 6268.2 6290.7 6121.3 6498.7 6841.6 6867.0 6869.5 6968.7 6971.1 6986.1 6994.4 7011.6 6248.8 6273.7 6343.9 6396.5 6402.8 7074.3 7104.2 7134.4 7157.8 7164.1 6404.3 6529.3 6557.1 6653.6 6705.1 6961.9 6965.2 6967.4 6969.1 6983.1 6514.2 6635.2 6855.2 6874.3 7054.9 6629.4 6723.1 6737.6 6753.4 6851.4 5465.4 5473.5 5479.4 5481.1 5515.3 4839.4 4856.5 4857.4 4884.3 4893.0 6119.9 6250.8 6311.2 6326.1 6340.3 6940.5 6983.7 6992.4 6992.5 6999.2 6283.8 6388.2 6398.0 6401.4 6407.7 5879.7 5972.6 6004.2 6139.4 6147.5 6959.3 6974.3 6975.3 7042.5 7096.9 6924.3 6933.8 6955.0 6970.5 6986.3 7128.9 7138.1 7169.4 7170.5 7187.6 5641.3 6546.8 6617.5 6845.1 6854.3 6653.9 6862.7 6954.8 6955.6 6962.8 6394.8 6627.8 6716.0 6717.8 6731.9 6696.3 6705.0 6710.1 6710.8 6717.1 6319.5 6365.5 6422.4 6477.1 6536.4 6610.3 6698.0 6713.1 6736.9 6738.4 4935.0 5638.2 6538.7 6545.5 6559.6 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:44685.08 Median:33010.93 stddev:81139.86 (245.8%) stddev-sans-first:81300.22 09:54:35 INFO - PID 1638 | Values: 682.7 990.7 1125.5 1262.8 1294.2 28958.9 37422.1 37437.3 37482.6 37492.6 31709.5 36837.4 37285.4 37737.0 37837.7 6170.5 6279.8 8010.4 8033.0 8524.7 8638.2 8652.5 8719.4 8723.5 8729.2 8649.8 8670.6 8692.8 8710.6 8741.6 8653.6 8691.8 8694.0 8704.6 8711.5 8488.7 8551.1 8551.4 8567.0 8581.5 37274.9 37429.4 37454.8 37512.4 37604.8 3413.6 3425.4 3449.4 3451.6 3453.0 29177.7 29489.6 29597.0 29597.7 29658.1 3017.9 3059.5 3086.9 3088.3 3099.6 208774.0 267712.0 269596.0 269836.0 270449.0 39746.6 40435.4 47333.0 48071.3 48499.8 50459.2 50657.5 50702.5 50739.0 50873.0 11896.8 14281.0 14372.8 14383.6 14388.6 11565.9 11646.0 11683.9 11687.0 11855.6 12217.2 12347.8 12357.4 12379.9 12441.7 5658.7 5659.2 5665.2 5672.4 5685.3 963.4 994.2 997.8 998.9 1025.7 22761.2 23513.4 23931.4 23933.8 23946.4 1028.0 1085.4 1090.2 1125.1 1140.7 9609.0 11737.2 12074.5 12186.9 12200.3 346520.0 347099.0 347254.3 347353.3 349426.0 50275.5 50365.7 50434.7 50525.2 50677.5 1374.8 1445.9 1477.2 1526.7 1527.0 21864.0 23400.6 23534.9 23549.6 23613.0 29361.0 37293.1 37314.6 37370.8 37419.8 311656.0 312799.0 312880.0 313027.0 313081.0 31548.2 32177.7 32358.4 32394.0 32394.7 34365.8 40537.8 40663.6 41007.4 41062.0 27902.0 27988.0 28475.7 29096.0 29451.0 28619.7 29240.9 29265.3 29710.4 29723.0 33627.2 34697.5 34767.3 34913.8 34964.6 23494.8 27708.0 28753.0 29751.0 29759.3 19042.6 23899.4 24131.8 24574.1 24687.0 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:41502.73 Median:22589.94 stddev:122822.69 (543.7%) stddev-sans-first:123128.51 09:54:35 INFO - PID 1638 | Values: 423.6 440.2 442.7 444.9 456.0 19879.3 21530.8 22085.8 22370.3 22404.9 22345.3 22412.0 22601.9 22838.4 23352.8 6063.3 6159.7 6308.2 6310.9 6317.3 5881.0 6149.6 6194.6 6211.5 6214.5 5105.3 5309.0 5920.0 6140.0 6176.1 6119.4 6148.1 6174.5 6187.4 6227.9 4579.5 5389.5 5458.0 5542.3 5542.7 26483.2 26548.0 26628.0 26690.9 26951.6 1997.0 2125.7 2242.5 2243.2 2257.6 17557.0 17584.5 17803.2 18015.5 18192.0 2098.4 2117.3 2165.4 2178.2 2181.5 110489.9 146417.0 148101.0 148410.0 150215.0 10841.2 10863.8 11220.8 12296.8 12896.2 14881.4 16452.6 16474.3 16488.8 16498.3 7432.6 7433.5 8180.6 9094.5 9123.7 8559.9 8564.4 8614.0 8758.3 8759.0 7889.4 7931.2 7977.7 8033.0 8054.4 2160.7 2182.4 2182.4 2190.8 2204.0 932.7 934.2 944.0 972.7 974.0 17670.4 19894.7 19899.8 21317.5 21417.5 928.4 944.7 960.6 968.3 1013.6 10804.3 11186.7 11187.4 11214.3 11224.7 716393.0 730661.0 735966.0 738433.0 758991.0 138667.0 144858.0 149790.0 161451.0 169466.0 1172.2 1179.2 1231.8 1234.0 1234.5 21296.3 21675.8 22578.0 22615.8 22657.3 63625.4 64049.4 64205.7 64932.7 65862.0 86655.1 87192.0 87326.0 87864.0 88034.6 8783.7 8985.4 9013.5 9106.1 9143.7 12985.9 13730.3 13907.4 14210.9 14338.3 10538.2 11185.8 11743.2 11797.1 11973.6 10973.6 11077.3 11156.9 11393.7 11421.7 11821.4 12154.5 12258.4 12269.8 12393.1 7245.7 7307.9 9059.9 9167.4 9188.6 11658.2 11748.2 12027.3 12079.2 12162.8 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:7174.91 Median:2501.01 stddev:4504.32 (180.1%) stddev-sans-first:4495.06 09:54:35 INFO - PID 1638 | Values: 1265.3 1439.7 1441.2 1454.4 1463.1 11453.5 11703.4 11800.3 11942.5 12073.0 11835.4 11863.5 12048.8 12049.6 12073.5 2467.2 2496.0 2501.3 2504.8 2507.8 2075.0 2436.9 2447.4 2472.3 2494.4 2404.6 2481.2 2495.9 2500.8 2503.8 2420.8 2428.1 2445.6 2461.9 2479.0 2397.4 2468.1 2484.2 2484.7 2500.0 11984.9 12059.3 12062.5 12066.3 12099.2 6485.0 6738.2 6741.4 6747.1 6755.5 3266.5 4159.3 4234.2 4247.4 4250.4 1215.3 1224.1 1261.2 1271.5 1274.2 14496.6 14679.1 16442.2 16518.1 16672.7 5416.9 5492.1 5667.8 5683.2 5686.0 12399.0 12403.3 12436.0 12480.6 12485.3 6553.7 6584.8 6593.7 6598.2 6606.6 5705.7 6003.8 6085.1 6165.3 6240.7 4035.8 4335.8 4434.6 4471.6 4475.4 1285.8 1298.6 1308.9 1323.1 1323.4 742.8 748.8 761.9 768.1 802.1 6048.0 6222.8 6289.7 6353.7 7150.0 864.1 891.5 895.0 902.2 905.9 4193.2 4198.9 4311.3 4342.2 4470.1 12250.0 14990.9 15411.0 15619.9 15635.8 12615.3 12653.9 15325.9 15493.3 15504.0 10900.4 11165.7 11170.8 11174.6 11185.7 5848.4 6603.5 6758.5 7052.7 7231.9 10754.8 13016.1 13228.2 13282.3 13307.5 12910.7 13338.0 13388.6 13425.2 13548.8 9557.2 9598.4 9624.5 9728.3 9732.9 10682.0 10874.0 10960.5 10966.2 10982.8 9147.6 9220.1 9307.0 9327.8 9349.7 8938.6 9028.1 9120.4 9167.0 9185.5 8117.0 8242.5 8259.7 8312.5 8330.2 6031.5 6049.9 6178.6 6227.5 6255.6 5549.0 6072.6 6207.2 6266.4 6430.0 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5308.31 Median:6057.85 stddev:1731.58 (28.6%) stddev-sans-first:1695.17 09:54:35 INFO - PID 1638 | Values: 301.8 332.7 377.9 387.7 398.4 4892.2 5507.6 6220.3 6307.7 6420.2 5253.4 6050.4 6213.2 6274.6 6288.3 3994.2 4328.7 4361.8 4485.1 4517.2 4469.4 4472.6 4474.9 4475.8 4526.0 3842.6 4435.2 4441.8 4442.6 4461.5 3434.0 4169.0 4235.6 4408.8 4510.4 3197.0 3409.5 3746.3 3834.6 3854.4 5755.4 6024.8 6053.4 6066.4 6099.3 4048.8 4671.6 5299.0 5386.0 5405.9 5520.4 6164.8 6669.4 6702.4 6711.7 4088.4 4158.5 4257.6 4396.1 4396.8 6459.6 6796.6 6960.7 7089.3 7167.7 3861.9 4351.1 4446.8 4459.1 4473.3 4525.4 4527.7 4530.8 4549.5 4579.8 4452.7 5124.4 5326.8 5355.0 5381.4 6661.6 6734.0 6740.3 6747.8 6748.4 1490.4 1498.9 1516.9 1541.4 1546.3 861.7 863.5 1004.2 1006.7 1016.8 6240.3 6243.5 6255.8 6267.7 6277.5 5679.6 6107.7 6926.2 6971.3 6997.8 5264.5 5833.9 6062.3 6238.0 6344.2 2998.6 3479.5 3522.0 3576.0 3629.7 6626.2 6795.3 7059.3 7096.7 7129.3 6336.6 6878.1 7003.3 7039.1 7269.7 6004.0 6455.4 7059.1 7059.7 7208.3 6334.7 6361.8 6974.8 7018.5 7019.8 5653.8 6001.6 6764.0 6800.5 6803.5 5638.1 6827.5 7106.2 7212.9 7231.7 4230.5 4386.7 4403.3 4429.6 4443.0 5588.0 6159.0 7027.3 7087.7 7100.3 6421.7 6771.4 6789.4 6810.4 6840.4 5672.6 6378.4 6644.4 6751.1 6773.7 6230.5 6618.5 6655.6 6655.9 6668.0 5508.8 6331.6 6656.0 6674.5 6717.2 6154.4 6516.2 6545.5 6568.8 6568.9 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:20734.00 Median:3310.47 stddev:29536.74 (892.2%) stddev-sans-first:29581.34 09:54:35 INFO - PID 1638 | Values: 713.3 798.2 804.8 824.5 824.9 16214.3 16465.8 16762.3 16797.6 16901.2 15076.8 15264.1 15396.5 15530.5 15608.7 886.3 964.6 986.4 995.0 998.5 970.6 991.9 992.2 1017.8 1021.4 1048.3 1056.0 1063.2 1063.3 1063.6 1034.5 1058.0 1060.7 1062.8 1062.9 755.5 784.9 786.7 789.0 794.6 16461.2 16949.5 17068.7 17073.1 17098.2 14619.9 14641.4 14730.0 14791.6 14858.3 13128.9 13132.5 13284.2 13372.1 13450.5 3300.7 3320.2 3334.0 3335.3 3348.9 91263.0 112642.4 113704.4 115046.0 116317.0 11359.4 11369.3 11390.4 11602.5 12058.9 24948.1 25430.5 25460.7 25475.6 25477.0 7840.6 7910.9 8054.7 8075.4 8151.0 3476.5 4149.6 4185.9 4206.1 4233.0 3643.3 4000.6 4164.0 4284.0 4294.0 827.3 885.1 885.4 895.2 896.9 613.6 615.3 635.7 640.0 656.9 9609.0 10049.6 10079.8 10180.9 10266.3 586.8 595.5 622.0 650.2 659.9 3892.5 4696.3 4711.0 4711.6 4743.8 82390.8 99997.0 104240.9 104463.0 107613.9 81187.0 84757.6 90836.0 92601.0 92801.0 62827.7 81447.6 82054.0 82607.6 83031.0 9012.6 9155.6 9261.2 9439.3 9476.0 40071.0 41251.8 41517.2 41789.0 41993.8 69614.7 70724.0 72438.0 75040.6 75380.6 13416.9 13590.7 13703.8 14043.5 14217.9 14406.6 14529.0 14556.8 14834.5 14882.4 13467.9 13515.5 13596.6 13924.7 14326.0 13741.9 13895.6 14003.3 14480.5 14725.7 13500.5 13740.6 13769.6 13834.5 13867.9 9502.6 9975.3 10033.7 10640.4 10777.2 9763.6 10835.5 11140.0 11156.0 11288.8 09:54:35 INFO - PID 1638 | -------- Summary: end -------- 09:54:35 INFO - PID 1638 | 09:54:35 INFO - PID 1638 | 1491497675848 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 09:54:36 INFO - TEST-INFO | 1638: exit 0 09:54:36 INFO - TEST-OK | dromaeo_css | took 1117540ms 09:54:36 INFO - TEST-START | kraken 09:54:36 INFO - operating with platform_type : mac_ 09:54:36 INFO - Initialising browser for kraken test... 09:54:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp5BbX6C/profile http://localhost:49241/getInfo.html) 09:54:37 INFO - PID 1650 | 1491497677103 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 09:54:37 INFO - PID 1650 | 1491497677104 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 09:54:37 INFO - PID 1650 | 1491497677154 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 09:54:39 INFO - PID 1650 | 2017-04-06 09:54:39.583 plugin-container[1651:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:54:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:39 INFO - PID 1650 | 2017-04-06 09:54:39.587 plugin-container[1651:10223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 09:54:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:39 INFO - PID 1650 | 2017-04-06 09:54:39.588 plugin-container[1651:10223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:39 INFO - PID 1650 | __metrics Screen width/height:1600/1200 09:54:39 INFO - PID 1650 | colorDepth:24 09:54:39 INFO - PID 1650 | Browser inner width/height: 1024/689 09:54:39 INFO - PID 1650 | __metrics 09:54:39 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 09:54:39 INFO - PID 1650 | 1491497679781 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 09:54:40 INFO - TEST-INFO | 1650: exit 0 09:54:40 INFO - Browser initialized. 09:54:40 INFO - Running cycle 1/1 for kraken test... 09:54:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp5BbX6C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:41 INFO - PID 1652 | 1491497681227 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 09:54:41 INFO - PID 1652 | 1491497681228 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 09:54:43 INFO - PID 1652 | 2017-04-06 09:54:43.343 plugin-container[1653:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:54:43 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:43 INFO - PID 1652 | 2017-04-06 09:54:43.344 plugin-container[1653:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:54:43 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:43 INFO - PID 1652 | 2017-04-06 09:54:43.344 plugin-container[1653:10347] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:53 INFO - PID 1652 | RSS: Main: 235483136 09:54:53 INFO - PID 1652 | 09:54:56 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:57 INFO - PID 1652 | RSS: Main: 249819136 09:54:57 INFO - PID 1652 | 09:55:00 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:55:00 INFO - PID 1652 | RSS: Main: 258424832 09:55:00 INFO - PID 1652 | 09:55:04 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:55:05 INFO - PID 1652 | RSS: Main: 259444736 09:55:05 INFO - PID 1652 | 09:55:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:55:08 INFO - PID 1652 | RSS: Main: 261414912 09:55:08 INFO - PID 1652 | 09:55:10 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:55:11 INFO - PID 1652 | RSS: Main: 261763072 09:55:11 INFO - PID 1652 | 09:55:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:55:14 INFO - PID 1652 | RSS: Main: 267599872 09:55:14 INFO - PID 1652 | 09:55:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:55:19 INFO - PID 1652 | RSS: Main: 264818688 09:55:19 INFO - PID 1652 | 09:55:22 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:55:23 INFO - PID 1652 | RSS: Main: 265883648 09:55:23 INFO - PID 1652 | 09:55:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:25 INFO - PID 1652 | RSS: Main: 266158080 09:55:25 INFO - PID 1652 | 09:55:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:27 INFO - PID 1652 | RSS: Main: 266326016 09:55:27 INFO - PID 1652 | 09:55:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:30 INFO - PID 1652 | RSS: Main: 266637312 09:55:30 INFO - PID 1652 | 09:55:34 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:34 INFO - PID 1652 | RSS: Main: 267210752 09:55:34 INFO - PID 1652 | 09:55:38 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:38 INFO - PID 1652 | RSS: Main: 266588160 09:55:38 INFO - PID 1652 | 09:55:40 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:55:41 INFO - PID 1652 | RSS: Main: 268652544 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | __start_tp_report 09:55:41 INFO - PID 1652 | _x_x_mozilla_page_load 09:55:41 INFO - PID 1652 | _x_x_mozilla_page_load_details 09:55:41 INFO - PID 1652 | |i|pagename|runs| 09:55:41 INFO - PID 1652 | |0;ai-astar;126;124;108;150;151;158;122;184;187;125 09:55:41 INFO - PID 1652 | |1;audio-beat-detection;162;121;145;182;159;159;102;104;161;137 09:55:41 INFO - PID 1652 | |2;audio-dft;284;298;294;220;220;303;245;253;301;249 09:55:41 INFO - PID 1652 | |3;audio-fft;85;59;81;95;57;88;60;89;105;88 09:55:41 INFO - PID 1652 | |4;audio-oscillator;89;75;93;76;73;78;82;112;112;126 09:55:41 INFO - PID 1652 | |5;imaging-gaussian-blur;94;120;93;94;97;94;95;98;145;131 09:55:41 INFO - PID 1652 | |6;imaging-darkroom;166;160;101;116;161;157;103;102;102;103 09:55:41 INFO - PID 1652 | |7;imaging-desaturate;104;103;105;104;104;103;104;102;140;101 09:55:41 INFO - PID 1652 | |8;json-parse-financial;53;53;53;56;55;54;54;54;54;54 09:55:41 INFO - PID 1652 | |9;json-stringify-tinderbox;60;67;69;61;60;60;63;65;61;67 09:55:41 INFO - PID 1652 | |10;stanford-crypto-aes;88;90;103;81;122;114;81;117;118;115 09:55:41 INFO - PID 1652 | |11;stanford-crypto-ccm;168;150;158;168;150;147;146;171;222;187 09:55:41 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;173;253;249;236;244;240;202;201;198;206 09:55:41 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;74;97;64;93;105;99;101;63;95;105 09:55:41 INFO - PID 1652 | __end_tp_report 09:55:41 INFO - PID 1652 | __start_cc_report 09:55:41 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1026 09:55:41 INFO - PID 1652 | __end_cc_report 09:55:41 INFO - PID 1652 | __startTimestamp1491497741183__endTimestamp 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | ------- Summary: start ------- 09:55:41 INFO - PID 1652 | Number of tests: 14 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:143.50 Median:150.50 stddev:27.06 (18.0%) stddev-sans-first:27.96 09:55:41 INFO - PID 1652 | Values: 126.0 124.0 108.0 150.0 151.0 158.0 122.0 184.0 187.0 125.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:143.20 Median:159.00 stddev:26.76 (16.8%) stddev-sans-first:27.50 09:55:41 INFO - PID 1652 | Values: 162.0 121.0 145.0 182.0 159.0 159.0 102.0 104.0 161.0 137.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:266.70 Median:289.00 stddev:33.09 (11.4%) stddev-sans-first:34.50 09:55:41 INFO - PID 1652 | Values: 284.0 298.0 294.0 220.0 220.0 303.0 245.0 253.0 301.0 249.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:80.70 Median:86.50 stddev:16.50 (19.1%) stddev-sans-first:17.43 09:55:41 INFO - PID 1652 | Values: 85.0 59.0 81.0 95.0 57.0 88.0 60.0 89.0 105.0 88.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:77.00 stddev:18.76 (24.4%) stddev-sans-first:19.87 09:55:41 INFO - PID 1652 | Values: 89.0 75.0 93.0 76.0 73.0 78.0 82.0 112.0 112.0 126.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:106.10 Median:94.00 stddev:18.88 (20.1%) stddev-sans-first:19.51 09:55:41 INFO - PID 1652 | Values: 94.0 120.0 93.0 94.0 97.0 94.0 95.0 98.0 145.0 131.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:127.10 Median:136.50 stddev:29.56 (21.7%) stddev-sans-first:27.80 09:55:41 INFO - PID 1652 | Values: 166.0 160.0 101.0 116.0 161.0 157.0 103.0 102.0 102.0 103.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:107.00 Median:104.00 stddev:11.65 (11.2%) stddev-sans-first:12.31 09:55:41 INFO - PID 1652 | Values: 104.0 103.0 105.0 104.0 104.0 103.0 104.0 102.0 140.0 101.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 09:55:41 INFO - PID 1652 | Values: 53.0 53.0 53.0 56.0 55.0 54.0 54.0 54.0 54.0 54.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:64.00 stddev:3.43 (5.4%) stddev-sans-first:3.43 09:55:41 INFO - PID 1652 | Values: 60.0 67.0 69.0 61.0 60.0 60.0 63.0 65.0 61.0 67.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:102.90 Median:101.50 stddev:16.36 (16.1%) stddev-sans-first:16.44 09:55:41 INFO - PID 1652 | Values: 88.0 90.0 103.0 81.0 122.0 114.0 81.0 117.0 118.0 115.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:166.70 Median:168.00 stddev:23.43 (13.9%) stddev-sans-first:24.85 09:55:41 INFO - PID 1652 | Values: 168.0 150.0 158.0 168.0 150.0 147.0 146.0 171.0 222.0 187.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:220.20 Median:238.00 stddev:27.36 (11.5%) stddev-sans-first:23.08 09:55:41 INFO - PID 1652 | Values: 173.0 253.0 249.0 236.0 244.0 240.0 202.0 201.0 198.0 206.0 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:89.60 Median:83.50 stddev:16.31 (19.5%) stddev-sans-first:16.29 09:55:41 INFO - PID 1652 | Values: 74.0 97.0 64.0 93.0 105.0 99.0 101.0 63.0 95.0 105.0 09:55:41 INFO - PID 1652 | -------- Summary: end -------- 09:55:41 INFO - PID 1652 | 09:55:41 INFO - PID 1652 | 1491497741210 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 09:55:42 INFO - TEST-INFO | 1652: exit 0 09:55:42 INFO - TEST-OK | kraken | took 65764ms 09:55:42 INFO - SUITE-END | took 1183s 09:55:42 INFO - Completed test suite (00:19:43) 09:55:42 INFO - javascript benchmark 09:55:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7799.560217821811, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3533.116720819795, 3601.5858176309716, 3676.761010534316, 3678.2643471305746, 3699.4820725098475, 6273.843153921154, 6276.278228003775, 6298.149749783784, 6320.747170113202, 6368.904466433001, 6286.402791734789, 6359.936400635992, 6374.3625637436235, 6394.872102557946, 6399.976003839387, 5084.694918304895, 5444.40111587725, 5556.527695145917, 5607.691576963265, 5746.683932383718, 4204.5585213552595, 5630.943690563083, 5677.716114194294, 5693.459121383462, 5740.885182296352, 4821.276808478721, 5445.401005889349, 5470.890582188334, 5618.0, 5665.745041473102, 5592.021396255639, 5596.608237423382, 5602.663840169603, 5612.494875461187, 5620.915686264669, 4858.392700912386, 5380.7086299288285, 5525.889482210333, 5554.166874968747, 5554.72226388683, 4911.7544122793715, 5233.869153271199, 6051.636901785863, 6268.216472940882, 6290.716917738713, 6121.265448146251, 6498.667773106502, 6841.62371069591, 6867.0, 6869.484788640872, 6968.651567421659, 6971.128608923885, 6986.056882320872, 6994.370506654425, 7011.614361210131, 6248.75004999804, 6273.686315684197, 6343.9365606343345, 6396.5202609804455, 6402.775902843378, 7074.327938845801, 7104.183018952886, 7134.357907788324, 7157.785266442015, 7164.068671072727, 6404.327545607644, 6529.3470652935275, 6557.114789503451, 6653.600783952978, 6705.061291419205, 6961.860762784716, 6965.199002114821, 6967.407770339578, 6969.0940177777575, 6983.127109111361, 6514.185726784152, 6635.170603674541, 6855.245922948472, 6874.2782007889455, 7054.92945070553, 6629.370209830058, 6723.0923825283935, 6737.59574425536, 6753.425958793558, 6851.383375496229, 5465.4261302563455, 5473.507384335349, 5479.369872464717, 5481.095619222863, 5515.283013208257, 4839.370881785416, 4856.514348565115, 4857.441394239637, 4884.340614017134, 4892.975535122301, 6119.877602447926, 6250.812475625738, 6311.2426508819235, 6326.114343991845, 6340.3342649022115, 6940.548864323904, 6983.685734141975, 6992.405645520086, 6992.510524263253, 6999.23008469068, 6283.842903927347, 6388.201474815648, 6397.96801015992, 6401.359864013654, 6407.679616019181, 5879.706014699248, 5972.5520585955355, 6004.219451471281, 6139.416755408229, 6147.508199344131, 6959.304069593, 6974.267701891431, 6975.337342952412, 7042.471814613822, 7096.893546596909, 6924.3421874921805, 6933.757318493828, 6955.0, 6970.546914450747, 6986.301369862973, 7128.85742285141, 7138.072050633592, 7169.354758071799, 7170.533915295489, 7187.640617968976, 5641.294838145232, 6546.770863019771, 6617.536772425884, 6845.0759147514445, 6854.34883686049, 6653.900191497229, 6862.691178896862, 6954.756583519553, 6955.617441040791, 6962.756303529555, 6394.808155755335, 6627.801165965029, 6715.966420167868, 6717.798466046026, 6731.932680673326, 6696.330366963265, 6705.027770973279, 6710.1276834013215, 6710.83222919431, 6717.093192418961, 6319.526035547445, 6365.522585806176, 6422.389872962061, 6477.060826180272, 6536.444402225864, 6610.3389661035435, 6698.0, 6713.093732346071, 6736.932630673827, 6738.393544581011, 4935.0, 5638.154276858504, 6538.705758240986, 6545.476361891133, 6559.639219842764], "value": 6203.849835265015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.7098483144664, 990.7176454710407, 1125.48227815205, 1262.8358313419253, 1294.158796782091, 28958.85520572397, 37422.064448388766, 37437.251254974886, 37482.62517374825, 37492.62507374925, 31709.524357134662, 36837.447438288444, 37285.44071838925, 37737.0, 37837.67568135116, 6170.50635949123, 6279.780207692732, 8010.359171266299, 8032.959835200845, 8524.70163544273, 8638.222559969601, 8652.48085114892, 8719.389642725011, 8723.476591404504, 8729.170728780755, 8649.783755406103, 8670.609822557968, 8692.826143477127, 8710.564471776417, 8741.562921853882, 8653.610587523544, 8691.782705432352, 8694.0, 8704.608292626815, 8711.520866352348, 8488.66045358185, 8551.059383467813, 8551.401401901869, 8566.957165214197, 8581.485110893367, 37274.88175354744, 37429.43855842164, 37454.81272593647, 37512.437313440045, 37604.81197594022, 3413.573303337083, 3425.383430982422, 3449.3618680543927, 3451.6030656474327, 3452.9827350863084, 29177.70822291771, 29489.557656635166, 29597.0, 29597.66810493533, 29658.110256692333, 3017.9396412071856, 3059.4645936961165, 3086.876524939, 3088.320569474712, 3099.643540992792, 208773.95613021989, 267712.0, 269596.0, 269836.0, 270449.0, 39746.60253397487, 40435.39346909799, 47333.0, 48071.278930816065, 48499.75750121227, 50459.24311135336, 50657.493425066015, 50702.49297507051, 50739.0, 50873.0, 11896.821547676791, 14281.0, 14372.784408233885, 14383.640408989859, 14388.640283992983, 11565.884341156649, 11646.0, 11683.948444640024, 11687.0, 11855.644330670093, 12217.205881617667, 12347.75304493905, 12357.38213089342, 12379.947704445043, 12441.688957776129, 5658.688772117573, 5659.207710920475, 5665.206871038058, 5672.404397538282, 5685.317761868602, 963.4460185393286, 994.2145704893198, 997.8453339732353, 998.9332748462151, 1025.7025462615907, 22761.203357882394, 23513.41216469602, 23931.401714957265, 23933.760662393503, 23946.40133996664, 1027.9617586237805, 1085.4247248958065, 1090.1878100814943, 1125.1448898836943, 1140.680609429361, 9609.0, 11737.222779616373, 12074.479213365563, 12186.878131218751, 12200.267983920992, 346520.0, 347099.0, 347254.2637286848, 347353.2632336822, 349426.0, 50275.49724502708, 50365.74085647815, 50434.74782626063, 50525.24212136821, 50677.493225068014, 1374.786908029237, 1445.9060161089496, 1477.1801650084305, 1526.7328217561883, 1527.0, 21864.0, 23400.64899026482, 23534.882325588263, 23549.64675529834, 23613.0, 29361.0, 37293.06767330838, 37314.62685373111, 37370.813145934095, 37419.81290093641, 311656.0, 312799.0, 312880.0, 313027.0, 313081.0, 31548.211294717814, 32177.678223217466, 32358.35283294368, 32394.0, 32394.704211831264, 34365.82817085998, 40537.79731101326, 40663.59336406598, 41007.38488922609, 41062.0, 27902.0, 27988.0, 28475.715242848135, 29096.0, 29451.0, 28619.713802861705, 29240.853795730884, 29265.268368290963, 29710.405791883608, 29723.0, 33627.15932101717, 34697.479537807456, 34767.30465390627, 34913.82543087268, 34964.6014159441, 23494.76505234926, 27708.030218943048, 28753.0, 29750.95871644482, 29759.25601859971, 19042.629625194677, 23899.402514937265, 24131.75868241295, 24574.13990510395, 24687.0], "value": 17035.728147330203, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.6250917937624, 440.2405849908907, 442.72551018368614, 444.8954495693512, 455.95896369326755, 19879.30422435217, 21530.784692153073, 22085.779142208572, 22370.32889013328, 22404.88797556012, 22345.329640110824, 22411.99146038428, 22601.886990565064, 22838.429039274026, 23352.766472335272, 6063.30272018718, 6159.692015399234, 6308.179936608234, 6310.905336419957, 6317.305096439411, 5881.0, 6149.631022138664, 6194.5973511721595, 6211.534134939875, 6214.471769899541, 5105.259737338102, 5309.044372013036, 5919.970400147993, 6139.969300153493, 6176.073588961669, 6119.418655227746, 6148.108524263979, 6174.475169610589, 6187.381261873822, 6227.875442491148, 4579.4504659440745, 5389.487998640123, 5458.044842152607, 5542.251796007568, 5542.722863856791, 26483.205503834724, 26548.0, 26628.0, 26690.866545667148, 26951.59572606393, 1996.9900150499154, 2125.6811478278228, 2242.495438526335, 2243.23729931824, 2257.582347265761, 17557.034363110022, 17584.472465826046, 17803.219678032223, 18015.549611259692, 18191.99944003079, 2098.4019554426914, 2117.333040092366, 2165.361218440564, 2178.2485042660255, 2181.5091604389045, 110489.89510104796, 146417.0, 148101.0, 148410.0, 150215.0, 10841.186910981707, 10863.782724345469, 11220.775584488265, 12296.815547766791, 12896.226226426445, 14881.437449067693, 16452.588685282964, 16474.258658360402, 16488.83511164897, 16498.34006639721, 7432.591207483641, 7433.479656423999, 8180.550069746221, 9094.499802510925, 9123.726288211232, 8559.914400856036, 8564.357673174536, 8614.009388920354, 8758.255548278341, 8758.956205218934, 7889.368850491946, 7931.246531579491, 7977.680892764341, 8033.0, 8054.355651547862, 2160.6542953127737, 2182.410749097735, 2182.443476913377, 2190.835687323457, 2203.9559208816054, 932.7295084425441, 934.2292608597934, 943.990560094404, 972.7324985628867, 973.9513024348754, 17670.38153664641, 19894.70157947632, 19899.801001990083, 21317.46706332323, 21417.46456338604, 928.3965422475445, 944.7434911567599, 960.6349587156975, 968.3415277611292, 1013.5945621751212, 10804.297720648132, 11186.720331991603, 11187.440627968568, 11214.271072380268, 11224.719382015515, 716393.0, 730661.0, 735966.0, 738433.0, 758991.0, 138667.0, 144858.0, 149790.0, 161451.0, 169466.0, 1172.1560476456937, 1179.186361410624, 1231.759806837658, 1233.993830030844, 1234.512367614787, 21296.25463108784, 21675.783242167377, 22578.0, 22615.773842261366, 22657.320280391614, 63625.363746361945, 64049.35950640434, 64205.678971604844, 64932.67533662301, 65862.0, 86655.13344866723, 87192.0, 87326.0, 87864.0, 88034.55982720046, 8783.682447632906, 8985.371024028254, 9013.549322533978, 9106.134917182857, 9143.725688229364, 12985.870141298845, 13730.313484325943, 13907.443702251796, 14210.928945355206, 14338.283085845875, 10538.209634277306, 11185.832212516656, 11743.236689615147, 11797.115216358636, 11973.640790776291, 10973.615923442641, 11077.335359878432, 11156.888431115585, 11393.715157120807, 11421.714457138638, 11821.40892955366, 12154.513819447124, 12258.448369823202, 12269.815952760537, 12393.132480726263, 7245.746398876014, 7307.890381644173, 9059.912810462787, 9167.404118732262, 9188.632454701738, 11658.18392712502, 11748.236364636272, 12027.338496382783, 12079.214851035005, 12162.75674486523], "value": 10584.934571970716, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1265.3230521670905, 1439.7408466476036, 1441.2217402602596, 1454.3818876977282, 1463.1148155366, 11453.484593193305, 11703.414829258545, 11800.291982481047, 11942.522299108025, 12073.0, 11835.40822958853, 11863.52545898163, 12048.81926771097, 12049.638510844667, 12073.517059317639, 2467.1858286765323, 2496.0265496456304, 2501.262127672341, 2504.8497090174556, 2507.8244522883406, 2074.9896250518727, 2436.926892193237, 2447.400386905211, 2472.344828620417, 2494.3639371960217, 2404.5551572959057, 2481.1812102006293, 2495.887685054168, 2500.762427569378, 2503.824732268742, 2420.770026847447, 2428.08946645009, 2445.572024895645, 2461.876906154685, 2478.9752102479106, 2397.3886658902043, 2468.0991438125134, 2484.1553871683714, 2484.6769919910475, 2499.9500009999697, 11984.880151198551, 12059.276443413422, 12062.45718942641, 12066.276023438622, 12099.213551119148, 6485.0, 6738.157730283715, 6741.426978706816, 6747.089142965686, 6755.5271131020845, 3266.5100234964707, 4159.292920203573, 4234.153169366139, 4247.3628955656895, 4250.404943307939, 1215.3497878634976, 1224.1492162946756, 1261.186534685123, 1271.5486002469174, 1274.241826113461, 14496.56510304682, 14679.119252844863, 16442.177891105515, 16518.091504967222, 16672.666546669, 5416.945830541723, 5492.1212605982855, 5667.80162694312, 5683.175939488751, 5686.004949133885, 12399.0, 12403.317817519943, 12436.00511959041, 12480.625581232578, 12485.31330776816, 6553.737850485927, 6584.8024559263295, 6593.736250549924, 6598.208215014229, 6606.6036037837885, 5705.686187259741, 6003.849903752353, 6085.117657939574, 6165.260168779775, 6240.687965601702, 4035.8183881725395, 4335.80488877995, 4434.645228381722, 4471.575200355961, 4475.4181956345465, 1285.774989376874, 1298.5519995601503, 1308.9332194261647, 1323.0936808286162, 1323.4044679894087, 742.759591482226, 748.7753673897809, 761.8666733321646, 768.1166658342838, 802.1457148137215, 6048.0, 6222.751089956441, 6289.7484100636375, 6353.682315884187, 7150.034745309421, 864.1272314961934, 891.5319457284977, 894.9955250223837, 902.2331018634239, 905.8686490458979, 4193.245215861113, 4198.9370159447635, 4311.33174357975, 4342.218400687843, 4470.061287129674, 12249.958753505873, 14990.925045374703, 15411.0, 15619.921900390425, 15635.765463518057, 12615.30615816139, 12653.87346126527, 15325.923370383076, 15493.302801373966, 15504.0, 10900.400477973788, 11165.665030049111, 11170.832437513443, 11174.60888868902, 11185.720356990978, 5848.385919478394, 6603.514209302868, 6758.493113016445, 7052.717891284291, 7231.891521627285, 10754.784904302027, 13016.088873778745, 13228.206307621573, 13282.269475178959, 13307.534236301683, 12910.67723306925, 13338.0, 13388.598342049754, 13425.194488330731, 13548.796768048684, 9557.23542116643, 9598.376105553116, 9624.51877406141, 9728.319017668695, 9732.902670973199, 10682.03861652455, 10874.0, 10960.451977401257, 10966.232363734462, 10982.835257470984, 9147.63409463614, 9220.077992200726, 9307.0, 9327.81344373095, 9349.719508414759, 8938.597763100546, 9028.052054534179, 9120.361574689974, 9167.0, 9185.540722963959, 8117.0, 8242.546659933761, 8259.669613215405, 8312.459690120122, 8330.166983301622, 6031.5476339273855, 6049.939500604937, 6178.598391104564, 6227.501799856092, 6255.56211065221, 5549.0, 6072.605280656743, 6207.203112591705, 6266.404691554295, 6430.035494675649], "value": 5409.630869862312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.81890865480716, 332.72566071944453, 377.94897688812, 387.71859005985226, 398.3785294939894, 4892.168331383665, 5507.5593952483805, 6220.284667263266, 6307.653079080649, 6420.1974753155855, 5253.369595648518, 6050.425209605091, 6213.161223234862, 6274.56078074535, 6288.339724328936, 3994.161226142499, 4328.740275583459, 4361.847335343264, 4485.1254005468945, 4517.186906356855, 4469.396631454744, 4472.642188624902, 4474.8881277967985, 4475.7762111894435, 4525.977370113144, 3842.615738426163, 4435.223835828733, 4441.800118994653, 4442.600165985061, 4461.531539188387, 3434.0, 4168.95776055986, 4235.555266697014, 4408.845690400821, 4510.413646226002, 3196.9680303197138, 3409.488576713488, 3746.3256613809504, 3834.578196398394, 3854.402567602026, 5755.4244575542525, 6024.789132380346, 6053.394660533955, 6066.363031881677, 6099.329073801879, 4048.838046478137, 4671.6262698984, 5299.0, 5385.973070134663, 5405.891882162335, 5520.447955204488, 6164.784232551884, 6669.399754022162, 6702.4302934250645, 6711.697973591151, 4088.4276201331836, 4158.500979882403, 4257.5529569395385, 4396.07682386696, 4396.78016099197, 6459.612423254667, 6796.558223715443, 6960.721571137148, 7089.326513981164, 7167.74912878052, 3861.942070868939, 4351.108022855307, 4446.777661116931, 4459.142841442194, 4473.338363673609, 4525.411696479437, 4527.683062185682, 4530.79611417487, 4549.451427886254, 4579.793909274024, 4452.732836029849, 5124.359455068116, 5326.813561525328, 5355.0, 5381.4349493303425, 6661.566998145105, 6734.0, 6740.281228286755, 6747.831304217434, 6748.358905903931, 1490.4112875414366, 1498.9250537473083, 1516.916569588661, 1541.421966762464, 1546.2887071946805, 861.7285555050139, 863.4905405810476, 1004.1815920025156, 1006.6677996261217, 1016.8322226832638, 6240.31356550779, 6243.469305109026, 6255.843603909939, 6267.65527895961, 6277.497800175975, 5679.642948176461, 6107.6640784757265, 6926.238113807477, 6971.268016858156, 6997.825054373681, 5264.499872512105, 5833.854153646108, 6062.302835174011, 6237.968810156011, 6344.175257216624, 2998.610180676542, 3479.4780782882262, 3522.0, 3576.0497589488778, 3629.7295946418544, 6626.237982632059, 6795.3204679531655, 7059.258777828357, 7096.687112884133, 7129.251428600026, 6336.631685156481, 6878.14290141669, 7003.299670033058, 7039.120109986252, 7269.709211631582, 6003.969980150071, 6455.354464553507, 7059.117610298713, 7059.71761129549, 7208.27917208275, 6334.683265836782, 6361.777337793155, 6974.825629359307, 7018.473614479037, 7019.753678822728, 5653.802116925888, 6001.609895356788, 6764.019217213575, 6800.489963252677, 6803.489738269551, 5638.126090455986, 6827.48793840454, 7106.218315985341, 7212.862404853905, 7231.674574644048, 4230.5134909485805, 4386.692931494892, 4403.251447254039, 4429.557044295545, 4442.955570444255, 5587.99416105105, 6159.0, 7027.297270272932, 7087.653345864269, 7100.325469080429, 6421.7110230038825, 6771.356721111487, 6789.355011273921, 6810.353016463428, 6840.41856442208, 5672.581854536366, 6378.362163783585, 6644.368784965421, 6751.088603038527, 6773.729050837911, 6230.532710046674, 6618.510835062072, 6655.600663960178, 6655.866882662416, 6668.0, 5508.834734957958, 6331.620102793663, 6656.0, 6674.532782705164, 6717.16035495563, 6154.415330543591, 6516.185476815398, 6545.476361890942, 6568.770093046721, 6568.934310656832], "value": 4753.219606489873, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.3045280851171, 798.2336956521739, 804.778685861388, 824.4723377038695, 824.8534536993576, 16214.270357833897, 16465.83534164658, 16762.329506819744, 16797.58006049848, 16901.239444225008, 15076.773848392255, 15264.107652548611, 15396.461123860667, 15530.45643402481, 15608.687826243467, 886.3263919421254, 964.6430820596389, 986.4229425785924, 995.0, 998.4658207858797, 970.6166064404556, 991.940483570988, 992.2012779712329, 1017.8320577104769, 1021.4177918586408, 1048.2871647279846, 1055.9683209503728, 1063.1601035182198, 1063.2822844579878, 1063.6064656077262, 1034.487928475402, 1058.0424715632364, 1060.6923992042298, 1062.7661914378864, 1062.9362238265728, 755.5391211361066, 784.9097353804272, 786.7364432914981, 788.996055019727, 794.6265255329986, 16461.176941153015, 16949.49151525456, 17068.658626827393, 17073.146342682816, 17098.230579623945, 14619.926900365537, 14641.41434342625, 14730.0, 14791.630209244748, 14858.2570871456, 13128.86871131286, 13132.540361087411, 13284.202947823162, 13372.130811497222, 13450.461981520826, 3300.7029367356813, 3320.236345640492, 3333.9998000599967, 3335.299587086715, 3348.8827891023698, 91263.0, 112642.43678781553, 113704.43147784291, 115046.0, 116317.0, 11359.432028398547, 11369.2609980351, 11390.37352945596, 11602.535898564132, 12058.879411205775, 24948.12681556137, 25430.49139017246, 25460.745392546207, 25475.61786573203, 25477.0, 7840.647170877324, 7910.881336779953, 8054.718084867001, 8075.434719569691, 8151.0, 3476.5480487536965, 4149.6265336119895, 4185.895352616209, 4206.137741762993, 4233.0, 3643.3259846928404, 4000.6197861737655, 4164.0, 4284.036091879365, 4293.957060429356, 827.3133299361635, 885.0574138542431, 885.4377470306401, 895.1719659315107, 896.8744375787395, 613.5766321238332, 615.2985596420085, 635.6916895305773, 639.964801935898, 656.9474442044625, 9609.0, 10049.59801607942, 10079.798404031879, 10180.898191017995, 10266.332688375232, 586.7824264650867, 595.5235811350902, 622.00168729189, 650.1645385679379, 659.9307072757388, 3892.5328960524353, 4696.272077827942, 4711.0, 4711.599514041345, 4743.762811859393, 82390.76413853797, 99997.0, 104240.91518169593, 104463.0, 107613.92386076196, 81187.0, 84757.57621211854, 90836.0, 92601.0, 92801.0, 62827.6858615704, 81447.55657330289, 82054.0, 82607.5869620648, 83031.0, 9012.594433250651, 9155.633774648939, 9261.16649501548, 9439.292053095907, 9475.952620236854, 40071.0, 41251.79374103231, 41517.16965660609, 41789.0, 41993.79003104965, 69614.65192674004, 70724.0, 72438.0, 75040.62479687567, 75380.62309688417, 13416.932915335361, 13590.660233494242, 13703.794443083163, 14043.50847720325, 14217.928910355382, 14406.567802965928, 14528.982971191916, 14556.781648275073, 14834.480793172188, 14882.404703811726, 13467.932660336635, 13515.459381624625, 13596.59210223695, 13924.721505569629, 14326.0, 13741.931290343484, 13895.58313250604, 14003.299835008413, 14480.493182738554, 14725.705485890436, 13500.45998160102, 13740.587782366545, 13769.586912392644, 13834.457863673962, 13867.930660346634, 9502.572384242587, 9975.301728878909, 10033.749156271151, 10640.41477718702, 10777.245592808427, 9763.560639771085, 10835.458227088771, 11140.0, 11156.0, 11288.774224515628], "value": 7825.7406604865255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1762.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 124.0, 108.0, 150.0, 151.0, 158.0, 122.0, 184.0, 187.0, 125.0], "value": 143.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 121.0, 145.0, 182.0, 159.0, 159.0, 102.0, 104.0, 161.0, 137.0], "value": 143.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [284.0, 298.0, 294.0, 220.0, 220.0, 303.0, 245.0, 253.0, 301.0, 249.0], "value": 266.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 81.0, 95.0, 57.0, 88.0, 60.0, 89.0, 105.0, 88.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 75.0, 93.0, 76.0, 73.0, 78.0, 82.0, 112.0, 112.0, 126.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 120.0, 93.0, 94.0, 97.0, 94.0, 95.0, 98.0, 145.0, 131.0], "value": 106.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 101.0, 116.0, 161.0, 157.0, 103.0, 102.0, 102.0, 103.0], "value": 127.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 105.0, 104.0, 104.0, 103.0, 104.0, 102.0, 140.0, 101.0], "value": 107.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 56.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 69.0, 61.0, 60.0, 60.0, 63.0, 65.0, 61.0, 67.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 103.0, 81.0, 122.0, 114.0, 81.0, 117.0, 118.0, 115.0], "value": 102.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 150.0, 158.0, 168.0, 150.0, 147.0, 146.0, 171.0, 222.0, 187.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 253.0, 249.0, 236.0, 244.0, 240.0, 202.0, 201.0, 198.0, 206.0], "value": 220.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 97.0, 64.0, 93.0, 105.0, 99.0, 101.0, 63.0, 95.0, 105.0], "value": 89.6, "unit": "score"}]}]} 09:55:42 INFO - Return code: 0 09:55:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:42 ERROR - # TBPL SUCCESS # 09:55:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:42 INFO - 2017-04-06 09:55:42.460 screenresolution[1657:10742] starting screenresolution argv=screenresolution get 09:55:42 INFO - 2017-04-06 09:55:42.462 screenresolution[1657:10742] Display 0: 1600x1200x32@60 09:55:42 INFO - 2017-04-06 09:55:42.469 screenresolution[1658:10745] starting screenresolution argv=screenresolution list 09:55:42 INFO - Available Modes on Display 0 09:55:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:42 INFO - Intel Iris: 09:55:42 INFO - Chipset Model: Intel Iris 09:55:42 INFO - Type: GPU 09:55:42 INFO - Bus: Built-In 09:55:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:42 INFO - Vendor: Intel (0x8086) 09:55:42 INFO - Device ID: 0x0a2e 09:55:42 INFO - Revision ID: 0x0009 09:55:42 INFO - Displays: 09:55:42 INFO - Display: 09:55:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:42 INFO - Display Serial Number: 1600x1200 60 09:55:42 INFO - Main Display: Yes 09:55:42 INFO - Mirror: Off 09:55:42 INFO - Online: Yes 09:55:42 INFO - Rotation: Supported 09:55:42 INFO - Return code: 0 09:55:42 INFO - Running post-action listener: _resource_record_post_action 09:55:42 INFO - [mozharness: 2017-04-06 16:55:42.712649Z] Finished run-tests step (success) 09:55:42 INFO - Running post-run listener: _resource_record_post_run 09:55:42 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 40225280; Write bytes: 519442944; Read time: 392; Write time: 1789 09:55:42 INFO - TinderboxPrint: CPU usage
54.4% 09:55:42 INFO - TinderboxPrint: I/O read bytes / time
40,225,280 / 392 09:55:42 INFO - TinderboxPrint: I/O write bytes / time
519,442,944 / 1,789 09:55:42 INFO - TinderboxPrint: CPU idle
2,195.8 (45.6%) 09:55:42 INFO - TinderboxPrint: CPU system
974.5 (20.2%) 09:55:42 INFO - TinderboxPrint: CPU user
1,643.5 (34.1%) 09:55:42 INFO - TinderboxPrint: Swap in / out
247,742,464 / 0 09:55:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 192918016; Write bytes: 194479616; Read time: 14969; Write time: 419 09:55:42 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 39262720; Write bytes: 308071424; Read time: 372; Write time: 1316 09:55:43 INFO - Running post-run listener: _upload_blobber_files 09:55:43 INFO - Blob upload gear active. 09:55:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:55:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:55:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:55:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:44 INFO - (blobuploader) - INFO - Done attempting. 09:55:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:55:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:55:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:45 INFO - (blobuploader) - INFO - Done attempting. 09:55:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:55:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:55:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:46 INFO - (blobuploader) - INFO - Done attempting. 09:55:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:55:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:49 INFO - (blobuploader) - INFO - Done attempting. 09:55:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:49 INFO - Return code: 0 09:55:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e9c14ac68f20c07610a8d9302cbe16d14b4cf036885d31111095aa1034981ec698b4a2ef7035c9ad6cb4a648b602acad5d2c8cafeee07d27a410f21ac10516d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac48ce55d65d90a83532a5795fde9123b2df13413e83697ddb8a813ebcf856fc2f93b5c261d7ca3747d9de30f7c2f885167cf067b53719f438ebdfed1edbf8e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8edc037b99e6813b4c6114db66818bbc2e97ed8ea3cb0c5bfe13fc9560090a7b520a8d123fcafacbe6bc9cbe546ad65eb82d795873cb3f0cd2eb6de3a0d860c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a5864a25bc70c82e4a2d8b9275daded13f52d8284ccc7122a705b8f286e470b3c130fe8336201de8f7cdd7d4a8c02d06344e571b0ffe395ef6a063f82ab1dac"} 09:55:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:49 INFO - Contents: 09:55:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e9c14ac68f20c07610a8d9302cbe16d14b4cf036885d31111095aa1034981ec698b4a2ef7035c9ad6cb4a648b602acad5d2c8cafeee07d27a410f21ac10516d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac48ce55d65d90a83532a5795fde9123b2df13413e83697ddb8a813ebcf856fc2f93b5c261d7ca3747d9de30f7c2f885167cf067b53719f438ebdfed1edbf8e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8edc037b99e6813b4c6114db66818bbc2e97ed8ea3cb0c5bfe13fc9560090a7b520a8d123fcafacbe6bc9cbe546ad65eb82d795873cb3f0cd2eb6de3a0d860c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a5864a25bc70c82e4a2d8b9275daded13f52d8284ccc7122a705b8f286e470b3c130fe8336201de8f7cdd7d4a8c02d06344e571b0ffe395ef6a063f82ab1dac"} 09:55:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:49 INFO - Copying logs to upload dir... 09:55:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.232353 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 09:55:49.198167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 09:55:49.201568) ========= 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.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/3e9c14ac68f20c07610a8d9302cbe16d14b4cf036885d31111095aa1034981ec698b4a2ef7035c9ad6cb4a648b602acad5d2c8cafeee07d27a410f21ac10516d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac48ce55d65d90a83532a5795fde9123b2df13413e83697ddb8a813ebcf856fc2f93b5c261d7ca3747d9de30f7c2f885167cf067b53719f438ebdfed1edbf8e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8edc037b99e6813b4c6114db66818bbc2e97ed8ea3cb0c5bfe13fc9560090a7b520a8d123fcafacbe6bc9cbe546ad65eb82d795873cb3f0cd2eb6de3a0d860c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a5864a25bc70c82e4a2d8b9275daded13f52d8284ccc7122a705b8f286e470b3c130fe8336201de8f7cdd7d4a8c02d06344e571b0ffe395ef6a063f82ab1dac"} build_url:https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/TNVBKOaVQjmnIXBcKRBIrQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e9c14ac68f20c07610a8d9302cbe16d14b4cf036885d31111095aa1034981ec698b4a2ef7035c9ad6cb4a648b602acad5d2c8cafeee07d27a410f21ac10516d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac48ce55d65d90a83532a5795fde9123b2df13413e83697ddb8a813ebcf856fc2f93b5c261d7ca3747d9de30f7c2f885167cf067b53719f438ebdfed1edbf8e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8edc037b99e6813b4c6114db66818bbc2e97ed8ea3cb0c5bfe13fc9560090a7b520a8d123fcafacbe6bc9cbe546ad65eb82d795873cb3f0cd2eb6de3a0d860c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a5864a25bc70c82e4a2d8b9275daded13f52d8284ccc7122a705b8f286e470b3c130fe8336201de8f7cdd7d4a8c02d06344e571b0ffe395ef6a063f82ab1dac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 09:55:49.241694) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:55:49.242046) ========= 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.AGV2qF36ce/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.Y0dvI9SafX/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005244 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 09:55:49.293917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 09:55:49.294656) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 09:55:49.295424) ========= ========= Total master_lag: 0.20 =========