builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1494425118.28 results: success (0) revision: f8d40e7fe132b7c73a8f610484b790e6063006e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.280314) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.280809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.281090) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.307372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.307778) ========= 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.tm8RqZlEZY/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.mtXnJCTC9O/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-05-10 07:05:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2017-05-10 07:05:18 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.460115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.460483) ========= 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.tm8RqZlEZY/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.mtXnJCTC9O/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.031276 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:18.516506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 07:05:18.516870) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8d40e7fe132b7c73a8f610484b790e6063006e3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev f8d40e7fe132b7c73a8f610484b790e6063006e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tm8RqZlEZY/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.mtXnJCTC9O/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-05-10 07:05:18,618 truncating revision to first 12 chars 2017-05-10 07:05:18,618 Setting DEBUG logging. 2017-05-10 07:05:18,618 attempt 1/10 2017-05-10 07:05:18,618 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8d40e7fe132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 07:05:20,018 unpacking tar archive at: mozilla-central-f8d40e7fe132/testing/mozharness/ program finished with exit code 0 elapsedTime=1.662854 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 07:05:20.200269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:20.200763) ========= script_repo_revision: f8d40e7fe132b7c73a8f610484b790e6063006e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:20.201296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:20.201702) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 07:05:20.220430) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-10 07:05:20.220829) ========= /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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:05:20 INFO - MultiFileLogger online at 20170510 07:05:20 in /builds/slave/test 07:05:20 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 07:05:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:05:20 INFO - {'append_to_log': False, 07:05:20 INFO - 'base_work_dir': '/builds/slave/test', 07:05:20 INFO - 'blob_upload_branch': 'Firefox', 07:05:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:05:20 INFO - 'branch': 'Firefox', 07:05:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:05:20 INFO - 'config_files': ('talos/mac_config.py',), 07:05:20 INFO - 'default_actions': ('clobber', 07:05:20 INFO - 'read-buildbot-config', 07:05:20 INFO - 'download-and-extract', 07:05:20 INFO - 'populate-webroot', 07:05:20 INFO - 'create-virtualenv', 07:05:20 INFO - 'install', 07:05:20 INFO - 'run-tests'), 07:05:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:05:20 INFO - 'download_minidump_stackwalk': True, 07:05:20 INFO - 'download_symbols': 'ondemand', 07:05:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:05:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:05:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:05:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:05:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:05:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:05:20 INFO - 'gecko_profile': False, 07:05:20 INFO - 'gecko_profile_interval': 0, 07:05:20 INFO - 'installer_path': 'installer.exe', 07:05:20 INFO - 'log_level': 'info', 07:05:20 INFO - 'log_name': 'talos', 07:05:20 INFO - 'log_to_console': True, 07:05:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:05:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:05:20 INFO - 'opt_config_files': (), 07:05:20 INFO - 'pip_index': False, 07:05:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:05:20 INFO - 'cmd': ('bash', 07:05:20 INFO - '-c', 07:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:05:20 INFO - 'enabled': True, 07:05:20 INFO - 'halt_on_failure': False, 07:05:20 INFO - 'name': 'check_screen_resolution'},), 07:05:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:05:20 INFO - 'cmd': ('bash', 07:05:20 INFO - '-c', 07:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:05:20 INFO - 'enabled': True, 07:05:20 INFO - 'halt_on_failure': False, 07:05:20 INFO - 'name': 'check_screen_resolution'},), 07:05:20 INFO - 'run_cmd_checks_enabled': True, 07:05:20 INFO - 'suite': 'dromaeojs-e10s', 07:05:20 INFO - 'system_bits': '32', 07:05:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:05:20 INFO - 'title': 't-yosemite-r7-0151', 07:05:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:05:20 INFO - 'use_talos_json': True, 07:05:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:05:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:05:20 INFO - 'work_dir': 'build'} 07:05:20 INFO - [mozharness: 2017-05-10 14:05:20.425451Z] Running clobber step. 07:05:20 INFO - Running pre-action listener: _resource_record_pre_action 07:05:20 INFO - Running main action method: clobber 07:05:20 INFO - rmtree: /builds/slave/test/build 07:05:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:05:23 INFO - Running post-action listener: _resource_record_post_action 07:05:23 INFO - [mozharness: 2017-05-10 14:05:23.044479Z] Finished clobber step (success) 07:05:23 INFO - [mozharness: 2017-05-10 14:05:23.044646Z] Running read-buildbot-config step. 07:05:23 INFO - Running pre-action listener: _resource_record_pre_action 07:05:23 INFO - Running main action method: read_buildbot_config 07:05:23 INFO - Using buildbot properties: 07:05:23 INFO - { 07:05:23 INFO - "project": "", 07:05:23 INFO - "product": "firefox", 07:05:23 INFO - "who": "cbook@mozilla.com", 07:05:23 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:05:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:05:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:05:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:05:23 INFO - "stage_platform": "macosx64", 07:05:23 INFO - "basedir": "/builds/slave/test", 07:05:23 INFO - "buildnumber": 58, 07:05:23 INFO - "platform": "yosemite_r7", 07:05:23 INFO - "parent_task_id": "MGU9MRTwSzur05Fp4DmOvA", 07:05:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:05:23 INFO - "slavebuilddir": "test", 07:05:23 INFO - "taskId": "b_Vd5lv3SeOmeZdQcuD_xA", 07:05:23 INFO - "branch": "mozilla-central", 07:05:23 INFO - "script_repo_revision": "production", 07:05:23 INFO - "revision": "f8d40e7fe132b7c73a8f610484b790e6063006e3", 07:05:23 INFO - "slavename": "t-yosemite-r7-0151", 07:05:23 INFO - "repo_path": "mozilla-central" 07:05:23 INFO - } 07:05:23 INFO - Finding installer, test and symbols from parent task. 07:05:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_Vd5lv3SeOmeZdQcuD_xA'}, attempt #1 07:05:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_Vd5lv3SeOmeZdQcuD_xA'}, attempt #1 07:05:24 INFO - Task dependencies: QebKra3aTxuPMxU_HoRMTQ 07:05:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA'}, attempt #1 07:05:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:05:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/test_packages.json 07:05:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/target.crashreporter-symbols.zip 07:05:24 INFO - Running post-action listener: _resource_record_post_action 07:05:24 INFO - [mozharness: 2017-05-10 14:05:24.834238Z] Finished read-buildbot-config step (success) 07:05:24 INFO - [mozharness: 2017-05-10 14:05:24.834346Z] Running download-and-extract step. 07:05:24 INFO - Running pre-action listener: _resource_record_pre_action 07:05:24 INFO - Running main action method: download_and_extract 07:05:24 INFO - mkdir: /builds/slave/test/build/tests 07:05: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')]} 07:05:24 INFO - trying https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:05:24 INFO - Downloading https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/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 07:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/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 07:05:25 INFO - Downloaded 1325 bytes. 07:05:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:05:25 INFO - Using the following test package requirements: 07:05:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:05:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:05:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:05:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:05:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'jsshell-mac.zip'], 07:05:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:05:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:05:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:05:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:05:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:05:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:05:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:05:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:05:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:05:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:05:25 INFO - Fetch https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:05:27 INFO - Content-Length response header: 52707641 07:05:27 INFO - Bytes received: 52707641 07:05:44 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 07:05:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:05:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:05:44 INFO - Fetch https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:05:46 INFO - Content-Length response header: 52707641 07:05:46 INFO - Bytes received: 52707641 07:06:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:06:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:06:01 INFO - Fetch https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:06:02 INFO - Content-Length response header: 14409362 07:06:02 INFO - Bytes received: 14409362 07:06:03 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')]} 07:06:03 INFO - trying https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:06:03 INFO - Downloading https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:06:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/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 07:06:05 INFO - Downloaded 75528687 bytes. 07:06:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:06:05 INFO - mkdir: /builds/slave/test/properties 07:06:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:06:05 INFO - Writing to file /builds/slave/test/properties/build_url 07:06:05 INFO - Contents: 07:06:05 INFO - build_url:https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:06:05 INFO - Running post-action listener: _resource_record_post_action 07:06:05 INFO - Running post-action listener: set_extra_try_arguments 07:06:05 INFO - [mozharness: 2017-05-10 14:06:05.122642Z] Finished download-and-extract step (success) 07:06:05 INFO - [mozharness: 2017-05-10 14:06:05.122800Z] Running populate-webroot step. 07:06:05 INFO - Running pre-action listener: _resource_record_pre_action 07:06:05 INFO - Running main action method: populate_webroot 07:06:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:06:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:06:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:06:05 INFO - '--tppagecycles', 07:06:05 INFO - '7'], 07:06:05 INFO - 'tests': ['tsvgm']}}, 07:06:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:06:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:06:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:06:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:06:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['damp', 'tps']}, 07:06:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'tests': ['damp', 'tps']}, 07:06:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['dromaeo_dom']}, 07:06:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:06:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:06:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:06:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['a11yr', 07:06:05 INFO - 'ts_paint', 07:06:05 INFO - 'tpaint', 07:06:05 INFO - 'sessionrestore', 07:06:05 INFO - 'sessionrestore_no_auto_restore', 07:06:05 INFO - 'tabpaint']}, 07:06:05 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:06:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:06:05 INFO - 'bloom_basic_ref']}, 07:06:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['tsvgx', 07:06:05 INFO - 'tsvgr_opacity', 07:06:05 INFO - 'tart', 07:06:05 INFO - 'tscrollx', 07:06:05 INFO - 'cart', 07:06:05 INFO - 'tsvg_static']}, 07:06:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:06:05 INFO - 'tsvgr_opacity', 07:06:05 INFO - 'tart', 07:06:05 INFO - 'tscrollx', 07:06:05 INFO - 'cart', 07:06:05 INFO - 'tsvg_static']}, 07:06:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'talos_options': ['--disable-e10s'], 07:06:05 INFO - 'tests': ['tp5o']}, 07:06:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:06:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'talos_options': ['--disable-e10s', 07:06:05 INFO - '--xperf_path', 07:06:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:05 INFO - 'tests': ['tp5n']}, 07:06:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:06:05 INFO - 'talos_options': ['--xperf_path', 07:06:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:05 INFO - 'tests': ['tp5n']}}, 07:06:05 INFO - 'talos.zip': {'path': '', 07:06:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:06:05 INFO - Running post-action listener: _resource_record_post_action 07:06:05 INFO - [mozharness: 2017-05-10 14:06:05.143546Z] Finished populate-webroot step (success) 07:06:05 INFO - [mozharness: 2017-05-10 14:06:05.143703Z] Running create-virtualenv step. 07:06:05 INFO - Running pre-action listener: _resource_record_pre_action 07:06:05 INFO - Running main action method: create_virtualenv 07:06:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:06: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 07:06:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:06:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:06:05 INFO - Using real prefix '/tools/python27' 07:06:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:06:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:06:08 INFO - Installing pip.................done. 07:06:08 INFO - Return code: 0 07:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:06:09 INFO - Reading from file tmpfile_stdout 07:06:09 INFO - Output received: 07:06: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) 07:06:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06: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 07:06: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 07:06:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:09 INFO - 'HOME': '/Users/cltbld', 07:06:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:09 INFO - 'LOGNAME': 'cltbld', 07:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:09 INFO - 'MOZ_NO_REMOTE': '1', 07:06:09 INFO - 'NO_EM_RESTART': '1', 07:06:09 INFO - 'PAGER': '/bin/cat', 07:06:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:09 INFO - 'PWD': '/builds/slave/test', 07:06:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:09 INFO - 'SHELL': '/bin/bash', 07:06:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:09 INFO - 'USER': 'cltbld', 07:06:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:09 INFO - 'XPC_FLAGS': '0x0', 07:06:09 INFO - 'XPC_SERVICE_NAME': '0', 07:06:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06: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 07:06:09 INFO - Cleaning up... 07:06:09 INFO - Return code: 0 07:06:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06: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 07:06: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 07:06:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:09 INFO - 'HOME': '/Users/cltbld', 07:06:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:09 INFO - 'LOGNAME': 'cltbld', 07:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:09 INFO - 'MOZ_NO_REMOTE': '1', 07:06:09 INFO - 'NO_EM_RESTART': '1', 07:06:09 INFO - 'PAGER': '/bin/cat', 07:06:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:09 INFO - 'PWD': '/builds/slave/test', 07:06:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:09 INFO - 'SHELL': '/bin/bash', 07:06:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:09 INFO - 'USER': 'cltbld', 07:06:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:09 INFO - 'XPC_FLAGS': '0x0', 07:06:09 INFO - 'XPC_SERVICE_NAME': '0', 07:06:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:09 INFO - Downloading/unpacking psutil>=3.1.1 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:06:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:12 INFO - Installing collected packages: psutil 07:06:12 INFO - Running setup.py install for psutil 07:06:12 INFO - building 'psutil._psutil_osx' extension 07:06:12 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 07:06:12 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 07:06:12 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 07:06:12 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 07:06:12 INFO - building 'psutil._psutil_posix' extension 07:06:12 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 07:06:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:06:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:06:13 INFO - ^ 07:06:13 INFO - 1 warning generated. 07:06:13 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 07:06:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:13 INFO - Successfully installed psutil 07:06:13 INFO - Cleaning up... 07:06:13 INFO - Return code: 0 07:06:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:06:13 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')]} 07:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:13 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')]} 07:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:13 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:13 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 07:06:13 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 07:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:13 INFO - 'HOME': '/Users/cltbld', 07:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:13 INFO - 'LOGNAME': 'cltbld', 07:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:13 INFO - 'MOZ_NO_REMOTE': '1', 07:06:13 INFO - 'NO_EM_RESTART': '1', 07:06:13 INFO - 'PAGER': '/bin/cat', 07:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:13 INFO - 'PWD': '/builds/slave/test', 07:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:13 INFO - 'SHELL': '/bin/bash', 07:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:13 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:13 INFO - 'USER': 'cltbld', 07:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:13 INFO - 'XPC_FLAGS': '0x0', 07:06:13 INFO - 'XPC_SERVICE_NAME': '0', 07:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:06:13 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 07:06:13 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 07:06:13 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 07:06:13 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 07:06:13 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 07:06:13 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 07:06:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:06:14 INFO - Installing collected packages: mozsystemmonitor 07:06:14 INFO - Running setup.py install for mozsystemmonitor 07:06:15 INFO - Successfully installed mozsystemmonitor 07:06:15 INFO - Cleaning up... 07:06:15 INFO - Return code: 0 07:06:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:15 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:15 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 07:06:15 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 07:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:15 INFO - 'HOME': '/Users/cltbld', 07:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:15 INFO - 'LOGNAME': 'cltbld', 07:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:15 INFO - 'MOZ_NO_REMOTE': '1', 07:06:15 INFO - 'NO_EM_RESTART': '1', 07:06:15 INFO - 'PAGER': '/bin/cat', 07:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:15 INFO - 'PWD': '/builds/slave/test', 07:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:15 INFO - 'SHELL': '/bin/bash', 07:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:15 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:15 INFO - 'USER': 'cltbld', 07:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:15 INFO - 'XPC_FLAGS': '0x0', 07:06:15 INFO - 'XPC_SERVICE_NAME': '0', 07:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:06:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:06:17 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 07:06:17 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 07:06:17 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 07:06:17 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 07:06:17 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 07:06:17 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 07:06:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:06:18 INFO - warning: no files found matching '*.txt' 07:06:18 INFO - no previously-included directories found matching 'build' 07:06:18 INFO - no previously-included directories found matching 'dist' 07:06:18 INFO - no previously-included directories found matching '.git*' 07:06:18 INFO - Installing collected packages: jsonschema, functools32 07:06:18 INFO - Running setup.py install for functools32 07:06:18 INFO - warning: no files found matching '*.txt' 07:06:18 INFO - no previously-included directories found matching 'build' 07:06:18 INFO - no previously-included directories found matching 'dist' 07:06:18 INFO - no previously-included directories found matching '.git*' 07:06:18 INFO - Successfully installed jsonschema functools32 07:06:18 INFO - Cleaning up... 07:06:18 INFO - Return code: 0 07:06:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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', '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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:06: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 functools32==3.2.3-2 07:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:18 INFO - 'HOME': '/Users/cltbld', 07:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:18 INFO - 'LOGNAME': 'cltbld', 07:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:18 INFO - 'MOZ_NO_REMOTE': '1', 07:06:18 INFO - 'NO_EM_RESTART': '1', 07:06:18 INFO - 'PAGER': '/bin/cat', 07:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:18 INFO - 'PWD': '/builds/slave/test', 07:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:18 INFO - 'SHELL': '/bin/bash', 07:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:18 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:18 INFO - 'USER': 'cltbld', 07:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:18 INFO - 'XPC_FLAGS': '0x0', 07:06:18 INFO - 'XPC_SERVICE_NAME': '0', 07:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:06:18 INFO - Cleaning up... 07:06:18 INFO - Return code: 0 07:06:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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', '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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:06: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 blobuploader==1.2.4 07:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:18 INFO - 'HOME': '/Users/cltbld', 07:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:18 INFO - 'LOGNAME': 'cltbld', 07:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:18 INFO - 'MOZ_NO_REMOTE': '1', 07:06:18 INFO - 'NO_EM_RESTART': '1', 07:06:18 INFO - 'PAGER': '/bin/cat', 07:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:18 INFO - 'PWD': '/builds/slave/test', 07:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:18 INFO - 'SHELL': '/bin/bash', 07:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:18 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:18 INFO - 'USER': 'cltbld', 07:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:18 INFO - 'XPC_FLAGS': '0x0', 07:06:18 INFO - 'XPC_SERVICE_NAME': '0', 07:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:06:19 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 07:06:19 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 07:06:19 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 07:06:19 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 07:06:19 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 07:06:19 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 07:06:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:06:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:06:20 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 07:06:20 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 07:06:20 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 07:06:20 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 07:06:20 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 07:06:20 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 07:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:06:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06:21 INFO - Downloading docopt-0.6.1.tar.gz 07:06:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:06:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:06:21 INFO - Running setup.py install for blobuploader 07:06:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:06:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:06:21 INFO - Running setup.py install for requests 07:06:22 INFO - Running setup.py install for docopt 07:06:22 INFO - Successfully installed blobuploader requests docopt 07:06:22 INFO - Cleaning up... 07:06:22 INFO - Return code: 0 07:06:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:22 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:22 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 07:06:22 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 07:06:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:22 INFO - 'HOME': '/Users/cltbld', 07:06:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:22 INFO - 'LOGNAME': 'cltbld', 07:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:22 INFO - 'MOZ_NO_REMOTE': '1', 07:06:22 INFO - 'NO_EM_RESTART': '1', 07:06:22 INFO - 'PAGER': '/bin/cat', 07:06:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:22 INFO - 'PWD': '/builds/slave/test', 07:06:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:22 INFO - 'SHELL': '/bin/bash', 07:06:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:22 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:22 INFO - 'USER': 'cltbld', 07:06:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:22 INFO - 'XPC_FLAGS': '0x0', 07:06:22 INFO - 'XPC_SERVICE_NAME': '0', 07:06:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:06:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-I8iHJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:06:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-MhaAIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:06:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-M60Qpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:06:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lrE4Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:06:22 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-cyTUOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dkHgtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZOZyG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZhkQxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-DwoCkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fSO7Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-d091bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-gQZ8Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-r4OkpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:06:23 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-mOnBvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6FjC2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:06:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-U6B0Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:06:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-HIgOfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:06:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:06:24 INFO - Running setup.py install for manifestparser 07:06:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:06:24 INFO - Running setup.py install for mozcrash 07:06:24 INFO - Running setup.py install for mozdebug 07:06:24 INFO - Running setup.py install for mozdevice 07:06:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:06:24 INFO - Running setup.py install for mozfile 07:06:25 INFO - Running setup.py install for mozhttpd 07:06:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Running setup.py install for mozinfo 07:06:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Running setup.py install for mozInstall 07:06:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Running setup.py install for mozleak 07:06:25 INFO - Running setup.py install for mozlog 07:06:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Running setup.py install for moznetwork 07:06:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:06:25 INFO - Running setup.py install for mozprocess 07:06:26 INFO - Running setup.py install for mozprofile 07:06:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:06:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:06:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:06:26 INFO - Running setup.py install for mozrunner 07:06:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:06:26 INFO - Running setup.py install for mozscreenshot 07:06:26 INFO - Running setup.py install for moztest 07:06:26 INFO - Running setup.py install for mozversion 07:06:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:06:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:06:26 INFO - Cleaning up... 07:06:26 INFO - Return code: 0 07:06:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06:26 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')]} 07:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:26 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')]} 07:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:26 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:26 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 07:06:26 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 07:06:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:26 INFO - 'HOME': '/Users/cltbld', 07:06:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:26 INFO - 'LOGNAME': 'cltbld', 07:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:26 INFO - 'MOZ_NO_REMOTE': '1', 07:06:26 INFO - 'NO_EM_RESTART': '1', 07:06:26 INFO - 'PAGER': '/bin/cat', 07:06:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:26 INFO - 'PWD': '/builds/slave/test', 07:06:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:26 INFO - 'SHELL': '/bin/bash', 07:06:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:26 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:26 INFO - 'USER': 'cltbld', 07:06:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:26 INFO - 'XPC_FLAGS': '0x0', 07:06:26 INFO - 'XPC_SERVICE_NAME': '0', 07:06:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-76gPgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LXAedJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-eNGrYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FS2F6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-7jJru2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ipmXVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-BqiKep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-A2Bbl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:06:27 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)) 07:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:06:27 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ouVbTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dNRdo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jHuo2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dr1Eim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-85sv61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-iY6t9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-a2RiYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-AF91wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:06:28 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)) 07:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:06:28 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-DHC3KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:06:28 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)) 07:06:28 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)) 07:06:28 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)) 07:06:28 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)) 07:06:28 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)) 07:06:28 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)) 07:06:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:06:28 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 07:06:28 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 07:06:28 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 07:06:28 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 07:06:28 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 07:06:28 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 07:06:30 INFO - Downloading blessings-1.6.tar.gz 07:06:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:06:30 INFO - Installing collected packages: blessings 07:06:30 INFO - Running setup.py install for blessings 07:06:30 INFO - Successfully installed blessings 07:06:30 INFO - Cleaning up... 07:06:30 INFO - Return code: 0 07:06:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:06:30 INFO - Reading from file tmpfile_stdout 07:06:30 INFO - Current package versions: 07:06:30 INFO - blessings == 1.6 07:06:30 INFO - blobuploader == 1.2.4 07:06:30 INFO - docopt == 0.6.1 07:06:30 INFO - functools32 == 3.2.3-2 07:06:30 INFO - jsonschema == 2.5.1 07:06:30 INFO - manifestparser == 1.1 07:06:30 INFO - mozInstall == 1.12 07:06:30 INFO - mozcrash == 1.0 07:06:30 INFO - mozdebug == 0.1 07:06:30 INFO - mozdevice == 0.50 07:06:30 INFO - mozfile == 1.2 07:06:30 INFO - mozhttpd == 0.7 07:06:30 INFO - mozinfo == 0.9 07:06:30 INFO - mozleak == 0.1 07:06:30 INFO - mozlog == 3.4 07:06:30 INFO - moznetwork == 0.27 07:06:30 INFO - mozprocess == 0.25 07:06:30 INFO - mozprofile == 0.28 07:06:30 INFO - mozrunner == 6.13 07:06:30 INFO - mozscreenshot == 0.1 07:06:30 INFO - mozsystemmonitor == 0.3 07:06:30 INFO - moztest == 0.8 07:06:30 INFO - mozversion == 1.4 07:06:30 INFO - psutil == 3.1.1 07:06:30 INFO - requests == 1.2.3 07:06:30 INFO - wsgiref == 0.1.2 07:06:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06:30 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')]} 07:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:30 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')]} 07:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:30 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:30 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 07:06:30 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 07:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:30 INFO - 'HOME': '/Users/cltbld', 07:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:30 INFO - 'LOGNAME': 'cltbld', 07:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:30 INFO - 'MOZ_NO_REMOTE': '1', 07:06:30 INFO - 'NO_EM_RESTART': '1', 07:06:30 INFO - 'PAGER': '/bin/cat', 07:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:30 INFO - 'PWD': '/builds/slave/test', 07:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:30 INFO - 'SHELL': '/bin/bash', 07:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:30 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:30 INFO - 'USER': 'cltbld', 07:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:30 INFO - 'XPC_FLAGS': '0x0', 07:06:30 INFO - 'XPC_SERVICE_NAME': '0', 07:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 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)) 07:06:31 INFO - Cleaning up... 07:06:31 INFO - Return code: 0 07:06:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:06:31 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')]} 07:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:31 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')]} 07:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:31 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 0x10e57db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e580768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f864bda10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, '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.tm8RqZlEZY/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.mtXnJCTC9O/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 07:06:31 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 07:06:31 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 07:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:31 INFO - 'HOME': '/Users/cltbld', 07:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:31 INFO - 'LOGNAME': 'cltbld', 07:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:31 INFO - 'MOZ_NO_REMOTE': '1', 07:06:31 INFO - 'NO_EM_RESTART': '1', 07:06:31 INFO - 'PAGER': '/bin/cat', 07:06:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:31 INFO - 'PWD': '/builds/slave/test', 07:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:31 INFO - 'SHELL': '/bin/bash', 07:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:31 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:31 INFO - 'USER': 'cltbld', 07:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:31 INFO - 'XPC_FLAGS': '0x0', 07:06:31 INFO - 'XPC_SERVICE_NAME': '0', 07:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:06:31 INFO - Cleaning up... 07:06:31 INFO - Return code: 0 07:06:31 INFO - Running post-action listener: _resource_record_post_action 07:06:31 INFO - Running post-action listener: _start_resource_monitoring 07:06:31 INFO - Starting resource monitoring. 07:06:31 INFO - [mozharness: 2017-05-10 14:06:31.542895Z] Finished create-virtualenv step (success) 07:06:31 INFO - [mozharness: 2017-05-10 14:06:31.543820Z] Running install step. 07:06:31 INFO - Running pre-action listener: _resource_record_pre_action 07:06:31 INFO - Running main action method: install 07:06:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:06:31 INFO - Reading from file tmpfile_stdout 07:06:31 INFO - Detecting whether we're running mozinstall >=1.0... 07:06:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:06:31 INFO - Reading from file tmpfile_stdout 07:06:31 INFO - Output received: 07:06:31 INFO - Usage: mozinstall [options] installer 07:06:31 INFO - Options: 07:06:31 INFO - -h, --help show this help message and exit 07:06:31 INFO - -d DEST, --destination=DEST 07:06:31 INFO - Directory to install application into. [default: 07:06:31 INFO - "/builds/slave/test"] 07:06:31 INFO - --app=APP Application being installed. [default: firefox] 07:06:31 INFO - mkdir: /builds/slave/test/build/application 07:06:31 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'] 07:06:31 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 07:06:52 INFO - Reading from file tmpfile_stdout 07:06:52 INFO - Output received: 07:06:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:06:52 INFO - Running post-action listener: _resource_record_post_action 07:06:52 INFO - [mozharness: 2017-05-10 14:06:52.744039Z] Finished install step (success) 07:06:52 INFO - [mozharness: 2017-05-10 14:06:52.744190Z] Running run-tests step. 07:06:52 INFO - Running pre-action listener: _resource_record_pre_action 07:06:52 INFO - Running main action method: run_tests 07:06:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:06:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:06:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:06:52 INFO - 2017-05-10 07:06:52.758 screenresolution[1623:7372] starting screenresolution argv=screenresolution get 07:06:52 INFO - 2017-05-10 07:06:52.761 screenresolution[1623:7372] Display 0: 1600x1200x32@60 07:06:52 INFO - 2017-05-10 07:06:52.767 screenresolution[1624:7374] starting screenresolution argv=screenresolution list 07:06:52 INFO - Available Modes on Display 0 07:06:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:06:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:06:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:06:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:06:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:06:52 INFO - Intel Iris: 07:06:52 INFO - Chipset Model: Intel Iris 07:06:52 INFO - Type: GPU 07:06:52 INFO - Bus: Built-In 07:06:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:06:52 INFO - Vendor: Intel (0x8086) 07:06:52 INFO - Device ID: 0x0a2e 07:06:52 INFO - Revision ID: 0x0009 07:06:52 INFO - Displays: 07:06:52 INFO - Display: 07:06:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:06:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:06:52 INFO - Display Serial Number: 1600x1200 60 07:06:52 INFO - Main Display: Yes 07:06:52 INFO - Mirror: Off 07:06:52 INFO - Online: Yes 07:06:52 INFO - Rotation: Supported 07:06:52 INFO - Return code: 0 07:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:06:53 INFO - Python 2.7.3 07:06:53 INFO - Return code: 0 07:06:53 INFO - grabbing minidump binary from tooltool 07:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:53 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 0x10e28ed30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e282be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:06:53 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 07:06:53 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 07:06:53 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 07:06:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:06:53 INFO - Return code: 0 07:06:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:06:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:06:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:06:53 INFO - ENV: RUST_BACKTRACE is now 1 07:06:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:06:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:06:53 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/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/target.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 07:06:53 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/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/target.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 07:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tm8RqZlEZY/Render', 07:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:53 INFO - 'HOME': '/Users/cltbld', 07:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:53 INFO - 'LOGNAME': 'cltbld', 07:06:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:06:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:53 INFO - 'MOZ_NO_REMOTE': '1', 07:06:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:06:53 INFO - 'NO_EM_RESTART': '1', 07:06:53 INFO - 'PAGER': '/bin/cat', 07:06:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:53 INFO - 'PWD': '/builds/slave/test', 07:06:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:53 INFO - 'RUST_BACKTRACE': '1', 07:06:53 INFO - 'SHELL': '/bin/bash', 07:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mtXnJCTC9O/Listeners', 07:06:53 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:53 INFO - 'USER': 'cltbld', 07:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:53 INFO - 'XPC_FLAGS': '0x0', 07:06:53 INFO - 'XPC_SERVICE_NAME': '0', 07:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:53 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/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/target.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 07:06:53 INFO - mozversion application_buildid: 20170510050843 07:06:53 INFO - mozversion application_changeset: f8d40e7fe132b7c73a8f610484b790e6063006e3 07:06:53 INFO - mozversion application_display_name: Nightly 07:06:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:06:53 INFO - mozversion application_name: Firefox 07:06:53 INFO - mozversion application_remotingname: firefox 07:06:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:06:53 INFO - mozversion application_vendor: Mozilla 07:06:53 INFO - mozversion application_version: 55.0a1 07:06:53 INFO - mozversion platform_buildid: 20170510050843 07:06:53 INFO - mozversion platform_changeset: f8d40e7fe132b7c73a8f610484b790e6063006e3 07:06:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:06:53 INFO - mozversion platform_version: 55.0a1 07:06:53 INFO - using testdate: 1494425213 07:06:53 INFO - actual date: 1494425213 07:06:53 INFO - starting webserver on 'localhost:49241' 07:06:53 INFO - SUITE-START | Running 2 tests 07:06:53 INFO - TEST-START | dromaeo_css 07:06:53 INFO - operating with platform_type : mac_ 07:06:53 INFO - Initialising browser for dromaeo_css test... 07:06:53 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpI_D01o/profile http://localhost:49241/getInfo.html) 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.192 plugin-container[1636:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x972b, name = 'com.apple.tsm.portname' 07:06:56 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.195 plugin-container[1636:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 07:06:56 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.195 plugin-container[1636:7561] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:06:56 INFO - PID 1634 | __metrics Screen width/height:1600/1200 07:06:56 INFO - PID 1634 | colorDepth:24 07:06:56 INFO - PID 1634 | Browser inner width/height: 1024/691 07:06:56 INFO - PID 1634 | __metrics 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.739 plugin-container[1637:7621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:06:56 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.740 plugin-container[1637:7621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x961b, name = 'com.apple.CFPasteboardClient' 07:06:56 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:56 INFO - PID 1634 | 2017-05-10 07:06:56.740 plugin-container[1637:7621] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:06:57 INFO - TEST-INFO | 1634: exit 0 07:06:57 INFO - Browser initialized. 07:06:57 INFO - Running cycle 1/1 for dromaeo_css test... 07:06:57 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpI_D01o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:06:59 INFO - PID 1638 | 2017-05-10 07:06:59.639 plugin-container[1639:7730] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 07:06:59 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:59 INFO - PID 1638 | 2017-05-10 07:06:59.641 plugin-container[1639:7730] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 07:06:59 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:06:59 INFO - PID 1638 | 2017-05-10 07:06:59.641 plugin-container[1639:7730] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:07:00 INFO - PID 1638 | 2017-05-10 07:07:00.004 plugin-container[1640:7775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 07:07:00 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:00 INFO - PID 1638 | 2017-05-10 07:07:00.006 plugin-container[1640:7775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 07:07:00 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:00 INFO - PID 1638 | 2017-05-10 07:07:00.006 plugin-container[1640:7775] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:07:09 INFO - PID 1638 | RSS: Main: 227979264 07:07:09 INFO - PID 1638 | 07:10:13 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:10:15 INFO - PID 1638 | RSS: Main: 252620800 07:10:15 INFO - PID 1638 | 07:13:17 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:13:18 INFO - PID 1638 | RSS: Main: 255655936 07:13:18 INFO - PID 1638 | 07:16:19 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:16:20 INFO - PID 1638 | RSS: Main: 267149312 07:16:20 INFO - PID 1638 | 07:19:22 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 07:19:22 INFO - PID 1638 | RSS: Main: 259989504 07:19:22 INFO - PID 1638 | 07:22:25 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 07:22:26 INFO - PID 1638 | RSS: Main: 263774208 07:22:26 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 07:25:28 INFO - PID 1638 | RSS: Main: 269807616 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | __start_tp_report 07:25:28 INFO - PID 1638 | _x_x_mozilla_page_load 07:25:28 INFO - PID 1638 | _x_x_mozilla_page_load_details 07:25:28 INFO - PID 1638 | |i|pagename|runs| 07:25:28 INFO - PID 1638 | |0;dojo.html;3176.841157942102;3552.0764601203678;3613.4941108244852;3647.215848592552;3657.689096426807;4814.301926220704;5131.794728210867;5492.533134683556;6159.3224745277985;6181.6600086995195;6199.938000619993;6227.719752611132;6251.624902505853;6303;6326.683665816702;5282.471752824706;5467.617266791326;5477.1510415885405;5494.752736126884;5619.381867994517;5606.046972014768;5611.130274807411;5611.719414029302;5612.522935550483;5636.408177141403;5363.115086010804;5427.728613569306;5462.20797984294;5531.502164805167;5554.833354999357;5458.372287186985;5502.504774570308;5509.696966666832;5514.862128446781;5552.11166213408;5484.643498172606;5485.6982865942355;5493.478119578634;5494.615376923617;5506.834794956158;6148.262208535004;6218.378162183791;6273.059041143865;6324.177856878622;6338.30278669346;6401.263854656728;6486.740530378779;6604.141461610008;6675.132232809704;6702.396784289389;6711.798646040626;6797.626130562817;6799.694013769343;6809.863802723968;6823.965880170617;5093.23601459784;6333.683315834236;6356.809295721136;6364.045393191012;6378.979363301896;6701.329867013357;6851.2806155353965;6889.827754306182;7000.579965202104;7023;6455.7417703292285;6580.76967306142;6588.901166482507;6599.208095028627;6618.635975021324;6701.698423570951;6704.96647516769;6777.152855893013;6794.422474089758;6806.591604503746;6411.871762564722;6596.934030659728;6660.067590537328;6667.399934005865;6672.96663516689;6504.349565043458;6607.471402287805;6675.265720770712;6689.130413046273;6714.362135597111;5127.051495473349;5301.257823904656;5324;5333.013392522395;5356.1697936819855;4054.878353649395;4694.483606803249;4695.694779839299;4711.316859055486;4749.26386410107;5946.70266486674;6285.59143655661;6340.556161068773;6378.362163783678;6399.584027038228;6821.1814582250445;6866.690998905061;6900.482463815234;6917.308269173042;6925.065116209348;6381.776637817654;6391.776287829996;6410.64741439216;6417.069524918954;6428.067930150102;5814.505767009851;5899.911501327483;5938.8812223755285;5953.851153721192;6016.879662406727;6864.931350686529;6883.3116688330765;6959.443244540324;7011.859762804817;7048.718051277891;6605.834854128685;6700.363465470772;6789.388954994074;6902.482313826383;6955.8956615649795;5866;6981.476389270927;7026.754063607749;7061.929380706127;7081.398081163159;6693.732250710112;6699.8325041872395;6701.899471507833;6711.060451536887;6743.966280168764;6610.173728283965;6694.899576506259;6769.052332673331;6872.41584465326;6900.275471075464;6581.967090164519;6587.110740050032;6601.966990165019;6607.933920660732;6637.435817955527;6460.935390646034;6588.505862060269;6605.900911486236;6615.470762339098;6635.668216589248;5003.499650035113;6204.441600255998;6300.180976473029;6423.42189202974;6444.645544495098;6464.191976003;6510.869782604416;6588.538802283794;6614.074029635759;6617.239017512855;6442.613443193423;6444;6462.095306657166;6467.126937863328;6491.221053473613 07:25:28 INFO - PID 1638 | |1;ext.html;803.8030682482791;811.330652211925;1210.7094297368628;1266.03781126344;1283.4801905228385;38162.809185954065;38840.41739373912;38849.41725874111;38907.22185556287;38912.61087389125;31041.844790776013;39070;39306.80346598263;39365.21269574614;39536.40695389571;7856.096548896893;8302.252797248246;8483.321334293241;8487.363447741413;8492.830143397128;6944.652767361637;8503.744887653349;8604.52675102869;8635.266002389804;8664.523451210182;7696.923030769676;8607.053224145338;8621.827563448727;8657.091005444432;8675.175858293484;7577.355924746404;8327.95900512439;8618.698345557907;8635.568221588896;8652.134786521361;8346.582670866494;8446.915530844675;8447.070822209553;8449.577521123982;8453.323734101257;37934.62065379366;38223.808880955694;38227.80886095552;38245.23509529794;38265.617343826765;3614.2410093880317;3633.7819730815972;3636.1455058061333;3642;3648.558819266375;30889.382212355853;31165.688343116504;31182.532262016088;31225.750969961173;31252.531212031838;2692.5422678144764;3190.88831890885;3201.199700074981;3209.8604995226824;3210.855511501988;288694.55652721814;290222;290736;291416;294571;41113.79443102765;44151.33772993408;45490.77254613706;47247.76376118166;47396.52603473921;48888.51111488911;50369.74815125901;50378;52050.47949520532;52056.739716301934;14373.137611743328;14423.711525769635;14500.347484363228;14518.709625807636;14529.34617942174;11570.826437603442;11642.301461912313;11664.300141991507;11664.358460284597;11682.824757628643;11174.66476005721;13816.032877698464;13844.238566878717;13847.446102156002;13881.167129972235;5886.411358864079;5922.200502932049;5957.255343082114;5994.880102398015;5996.72124592652;1009.9747506312401;1015.6749840051112;1016.4003238089415;1040.1107053469327;1043.749500119981;23721.762782371956;24623.38441538976;24792.38019049502;24979.750202498108;25017.87491062533;1062.2245760594656;1078.5577913055608;1103.6192513582962;1137.6871360375796;1147.2141583015662;9892.505374731234;12101.757964840654;12737;12936.288504132175;12958.963282937342;293531.5323423412;298598;299639;300334;326929;57714;57835;58408.831823363296;58435.415645843;58514.70742646259;1479.2899408284084;1499.4976682811161;1514.893957422992;1516.9848301517063;1532.601523603871;26981.516016619273;28061.71938280673;29354.706452935196;29886.60907127468;31257.687423125477;39768.40347394849;39949.2010159801;39995.40006899841;40061.599384005785;40120.79637610877;271291;271747.64126179245;271959;272657;272684;34394;34608.65391346155;35340.82329588439;35446.822765886005;35540;32248.83875580701;42163.94590135271;44960.77519612381;45235.54764452445;45328.093438130396;26604.733952660226;28728.569071463528;30113.548296776004;30773.538396923617;30967.380652387277;30749.53875691911;31049.689503105583;31101.844490778305;31116.53325200169;31160.37679246357;29314.560281595368;29681;37285.62714372821;37428.06429839167;37847.621523785514;29778.724340322115;30736.077917662507;31134.37731245408;31236.687633123376;31366.52950205703;23710;24311.392215194763;24358.87820560886;24712.50574988454;25142.62286065747 07:25:28 INFO - PID 1638 | |2;jquery.html;427.34616037462695;436.4391756592778;436.91862639966087;451.93672885795996;454.82943896038967;18225.450836678898;19860.404187874352;21202.681959770598;21743.456413589647;22078.78566678836;21579.352619421403;22113.557728845415;22599.983000764965;22616;23771.405714857137;6031.427014433632;6245.594036387621;6250.156228909107;6258.655773932432;6259.655718935457;5775.653460792366;6076.665783381913;6133.723982420779;6137.17148184996;6162.1681073055015;5042.773075211624;5879.11813228017;6089.634621922677;6112.144299798032;6123.785667501638;5931.466168044875;6041.486473649745;6048.637081775086;6106.694665266719;6112.663803490784;5368.4363141869935;5378.704171270579;5439.83680489582;5440.972795136038;5460.5085542301385;26890.193294201206;27060.86469567659;27446.45107097847;27798.3050423739;27846;2011.7485314335709;2178.313831143185;2225.098834971839;2247.213475283654;2269.3305474884946;14279.928600356932;17927.01401422921;18122.63754724898;18148.274069037223;18237.908810455996;1989.9303524376721;2090.64108329587;2135.380739585534;2157.622416077196;2194.1113848891223;152002;153266;154592;156976;157052;10482.842757358494;12250.203736757083;12919;12932.806007909889;13002.284874332003;13851.930740346234;16971.06659133735;16990;17004.979701217966;17006.48980530586;7503.324700776847;8874.511901845332;9089.50007749567;9127.77180570491;9185.862212066824;6998.75504357345;7182.928170718331;8571.271441927382;8717.997430295598;8772.78068048304;7872.134065252818;7916.960415197887;8018.55897925608;8049.51702897828;8094.554799486084;1952.3557226115347;2185.530111026137;2188.0810059774612;2203.272919936417;2205.558888222362;807.9757607271791;956.4930586789013;962.8988956159642;998.7253505286104;1002.2232769603615;20382.855467837668;21797.45506362322;21830.672539911913;21845.781542184373;21956.78043219547;984.4388698441962;985.9507024648739;1013.2400699475394;1015.304516406264;1019.9592016319413;10759.731006724895;11189.216754827085;11294.322340659586;11308.547658093748;11318.660440186808;419975;535781;540327;550427;553571;123166;123372;128014;128144;129149;1207.7584483103415;1217.3365515793914;1261.79180435229;1263.829383033297;1268.150339272708;22377.552448951257;22651.20720774765;22947.426314342276;23081.30699088205;23513;55016.44983550273;56555.586110345925;56762.7161864188;57597.4240257592;58226.708866455396;75242;75967;76208.61895690486;76315.61842190976;77182;9062.909370906471;9101.726948191565;9113.362064655676;9217.354785164975;9256.213221876216;13890.513832015831;13931.930340348234;14041.578752637437;14234.786478202628;14236.288185590885;9932.255080869108;11726.88273117258;11933.105017123924;12139.939300303442;12234.938825306173;8772;10064.597416103274;11044;11325.093992480417;11343.943280283545;12111.091668124749;12192.268463892195;12202.328871911775;12292.754144916873;12406.689832754253;9034.864477032981;9111.681091161778;9156.725298241063;9163.358564900393;9181.816363672822;11850.170488065753;11972;11997;12023.699407514532;12163.513459461523 07:25:28 INFO - PID 1638 | |3;mootools.html;1327.9203247805133;1419.5173640962078;1439.6760728836014;1439.856014398561;1442.516756886443;11341.149413793968;11384.487698053586;11897.167198296112;11915.165938384316;12090.818637720442;11681.415929203527;11844.58543950962;11889.464974076165;11892.881071189286;11913.106517011238;2145.8712477251415;2527.7345878682704;2564.3717289264155;2571.3571607098224;2572.7298633643477;2491.9252422427357;2527.9873600631972;2550.7321731218235;2551.910683126091;2552.923412297634;2467.925962221127;2488.5396201702742;2503.0488414402503;2522.1928982725535;2528.1277959104173;2286.211257116292;2313.27131953435;2475.2450502596703;2530.949655892802;2531.8101142414303;2449.5835707929696;2488.1664642344836;2511.8367306125224;2513.070164039317;2555.3611597100726;9606.519674016272;9643.517824108767;11734;11783.7054073648;11788.29270243788;6587.93412065878;6830.214525329601;6836.794896153124;6858.931410685879;6860.176778786527;3998.540167880702;4357.978210108961;4401.383806267125;4434.800433980478;4437.46750389952;1233;1238.919470234441;1248.7440074784647;1268.220044672529;1285.768561658901;13759.931200344034;14989.70020599582;15579.376824926989;16333.91833040839;16663.41678041275;5449.155380915964;5538.556915446755;5668.858278543069;5759.8560035999435;5760.423957604206;11639.301641901513;11741.765164696659;11743.354115523729;11745.647630571097;11753.823692644617;6558.704858281389;6616.503762217757;6619.304972977864;6632;6634.0712300278;5514.310711161105;6056;6140.846478838065;6198.349173336825;6224.439800417984;4297.335651895844;4394.011347446799;4394.626456751148;4442.178197033494;4442.400275962768;1126.6169502369235;1269.9238045717286;1291.8514370847472;1306.7778477658821;1321.9226330540578;709.9609521476265;770.9884351734728;780.4185881518305;807.7657479331045;815.514768712615;6193.040078787795;6266.342034086447;6298.496120310364;6368.936310636927;6405.7117429714845;573.58988323349;578.6701580099387;598.494272339872;601.883506096194;601.9285671504729;3504.7021003215013;3581.194231297937;4338.652907767371;4363.498197707241;4457.821687132543;14823.62821948483;15028.647421731877;15345.846541534442;15351.002184857949;15359.462418815512;14840.777388339184;14869.925650371679;15160.545183644508;15269.465568705042;15283.923580382248;7762.262585054411;8784.604692788713;8802.295816334807;8809.251213646783;8809.33929955256;5380.300560927211;6591.736330546916;6852.560962197994;7041.4014808739785;7216.206217315986;13091.738165236833;13358.732825343634;13404.597862064154;13743.587692369245;13764.17414955106;11242.887571124184;13664.521741738992;13962.371693273624;13980.930095349458;14215.928920355333;9275.21160701348;9435.622575096919;9436;9462.290328225274;9470.763230919281;10577.894221057692;10922.617708380169;10923.508442119964;11057.778844422906;11062.889371106186;8063.596820159086;9009.27925765924;9080.500572468578;9213.76965575866;9282.35023548345;7421.517601355895;7505.662245199088;8790.516521591373;8919.464832110094;9050.592723327334;8166.4283500154415;8283.08886022525;8287;8289.378296627656;8299;6055.636661800306;6075.696215189135;6159.938400615936;6227.9377206227355;6228.564000520101;6103.633781973096;6146.815595532141;6232.753449310247;6238.750449982132;6298.527610429328 07:25:28 INFO - PID 1638 | |4;prototype.html;396.6608549690013;401.8747506980456;402.8851777243485;404.42975404679396;412.66780241905275;5820.679862607561;6001.439625697318;6417.711202995864;6569.671516424178;6591.802245932629;4953.455119936804;5839.79560715374;6007.969960150204;6153.261608606962;6409.647469389181;4844.491328410501;4848.127337079333;4852.199387101142;4875.00062487191;4886.48691887352;4758.238681810911;4882.56056954874;4893.241547560134;4895.853124406273;4899.828506002291;4786.018866132435;4798.328234047219;4803.783829727653;4804.327394164819;4814.687045342059;4067.532233793122;4790.832320868771;4834.806607735686;4842.757862106916;4878.146324393252;3739.383001804699;3951.471058522548;4287.506936702288;4305.74165550068;4306.246406878815;5490.945090549123;6094;6150.477209437203;6186.133941248228;6193.318734939153;5459.5359394451125;5491.807786727445;5517.062099443105;5518.392976772552;5554;6653.7671181508895;6764.864702705919;6765.188177418692;6821.624810635413;6858.794236172923;3936.645701886844;4419.270820314644;4421.159979603865;4424.336349547593;4453.665975051884;6834.282400347942;7162;7203.819904502377;7278.526895751759;7308;4478.790726503826;4941.160002799533;5182.1190397631735;5182.689038657692;5229.973850130801;4118.876433706993;5197.038547868657;5326.334208223972;5380.3274590676165;5387.434319396485;5749.25686308797;5838.5037271831525;5864.765409383662;5870.972579798518;5872;6921.653917304115;6921.861562768717;6924.4460443165435;6939.895901561481;6966.5471744336455;1421.4882642248783;1514.4320879670124;1528.915909624959;1751.7810273715786;1798.5144011116925;1226.497136174182;1239.9132060755842;1243.8258643789877;1251.7934540800848;1255.5749224629928;6541.411272985359;6590.176227971504;6603.76886808969;6609.3060228675295;6630.9668451657435;6280.434760871543;6857.177138743383;6970.581765094111;7224.530405523624;7252.87580425035;5074.188129899272;5823.806119745442;6188.938110618927;6191.226096737908;6527.706253218522;3222.3072039511617;3525.894223173309;3706.4069748840056;3720.925581488409;3723.72072094594;6074.271087469532;7397.593132377771;7432.364879726451;7462.626868656545;7475.289847464483;6968.686409111602;7071.363577277967;7391.963040184764;7398.815029624302;7408.333250007525;7419.406447484188;7428.925710742931;7439.367653749276;7469.365103966115;7484.251574842581;6568.408843204134;6681.29846366144;7070.929290707027;7107.7561426751145;7157;6630.469562434897;6975.79072627822;7017.298270172942;7066.692661857367;7067.540609860343;6427.742890284523;6782.236618479133;6857.074294970115;7000.05997481049;7459.552426854406;3560.893173204808;4074.348104303297;4552.476465206543;4552.658550608651;4594.1730488512385;6472.579282346633;6694;6883.552569082994;6888.52246040078;7196.280371962762;5828.562857785768;6184.969075154775;6567.704453299518;6586.70359833799;6963;6296.653684047421;6844.520883538304;6909.616182417058;6922.5154239202775;6938.27148149437;5384.480907070284;6488.740450381932;6587.9670601646685;6613.437857782143;6813.04617353561;6300.306966233802;6424.453921416732;6830.897536537055;6834.300451321799;6876.7650513584285;5517.779288828562;5854.0048191806645;5870.9412905872105;6376.298607153302;6682.9641405582215 07:25:28 INFO - PID 1638 | |5;yui.html;746.7162478258261;832.3216578488535;835.6156168162642;862.7325529085983;868.7263511993724;16190.514284571453;16713.916430417845;16736.581585460368;17466.737998929995;19042.33351832686;17647.47057588271;18034.27862885483;18113.909430452863;18295.268189272447;18327.25891040349;979.9363041402304;988.7874107066979;993.1607791416242;994.1748348870457;995.144176008632;989.1888651305932;1001.7245257554194;1005.4269066632023;1008.9293749437543;1009.5759780892006;1070.0797314309727;1072.6674730833427;1075.0324707763036;1075.4461452352045;1075.8816530181673;1050.9106725928305;1057.7197042783669;1067.8505009298703;1068.5779117248683;1070.929070929075;740.7999840043158;752.2252080357241;754.7848863073998;767.098659075584;767.9692812287502;16378.83621163785;16500.83499165005;16552.75170872438;16709.498715038568;16717.49847504577;17065.14674266282;17388.391406300845;17635.382761603283;17811;17831.286748530045;13896.513622023282;14003.579892603137;14194;14208.076475029193;14227;3487.163080860575;3525.964740352589;3550.3076900004507;3553.040679016651;3566;109692.90307096907;117351.82648173494;128590.3570482151;133075;133101;13331.133476324201;13418.597442076752;13553.45786168562;13556.728865422636;13589.388477518536;25267.610281434696;25613.871930640602;25792.226233213034;26159.991600966132;26297.474050518882;6869.793906182823;6989.951507273848;7158.642067896584;7223.133224013152;7254.8911766323545;3360.479125735515;3421.853679017557;3979.263836190314;3992.5208974923194;4036.0717035081507;4377.299632058855;4456.821727130879;4458.375827384169;4462.642988560972;4473.664475164375;815.8653822119284;824.5588610093691;827.606886728799;931.469062634292;969.6703120938914;630.8454428665007;635.4154178156108;674.2482132422314;681.5637991685326;693.4313862632694;11201.719957000978;11539.826902596467;11835.88164118365;12002.699932501582;12166.45250963691;644.3910504573133;660.976865809704;667.9933200667931;675.1493118670412;688.1845013658831;5533.916991245134;5546.807436401113;5618.185363122325;5798.623089499169;5828.5337173026055;74840;104045;109795;111105;111232;74119;84025.15974840174;86468.56765716131;89708.10291897129;91326;72436;74585;77255.61372193103;78129.2187078122;80562.59718701369;11762.94118529402;12581.93709031449;12644.178128421623;12649.367531623566;12657.493700251887;37852.86441406762;38639;42146;43042.56957430386;43482;60101.699491502266;61445.07832382429;80614.596927015;81861;83135.58432207801;17001.914990424968;17620;17874.642507150045;17934.282628694706;17979.280828766703;18603.534911626775;18677.813221867607;19041.33355332612;19135.8086419134;19233.711494327876;15110.244487775348;17356.82643173552;17999.370022049166;18117.45647630573;18417.907910460362;17834.10829458548;18278.908605456887;18342.17460214267;18448.354307599708;18568.350107746162;16672.249748761093;17255.137243137542;17500.737488937422;17599.472015839547;17751;11724.58963936258;11970.042396608076;12122.696932576404;12426.75146497083;12430.81353779676;13553.661158471117;13737.107088039245;14293;14322.355494003003;14326.928365358523 07:25:28 INFO - PID 1638 | __end_tp_report 07:25:28 INFO - PID 1638 | __start_cc_report 07:25:28 INFO - PID 1638 | _x_x_mozilla_cycle_collect,415 07:25:28 INFO - PID 1638 | __end_cc_report 07:25:28 INFO - PID 1638 | __startTimestamp1494426328402__endTimestamp 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | ------- Summary: start ------- 07:25:28 INFO - PID 1638 | Number of tests: 6 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6174.26 Median:6442.61 stddev:770.25 (12.0%) stddev-sans-first:735.85 07:25:28 INFO - PID 1638 | Values: 3176.8 3552.1 3613.5 3647.2 3657.7 4814.3 5131.8 5492.5 6159.3 6181.7 6199.9 6227.7 6251.6 6303.0 6326.7 5282.5 5467.6 5477.2 5494.8 5619.4 5606.0 5611.1 5611.7 5612.5 5636.4 5363.1 5427.7 5462.2 5531.5 5554.8 5458.4 5502.5 5509.7 5514.9 5552.1 5484.6 5485.7 5493.5 5494.6 5506.8 6148.3 6218.4 6273.1 6324.2 6338.3 6401.3 6486.7 6604.1 6675.1 6702.4 6711.8 6797.6 6799.7 6809.9 6824.0 5093.2 6333.7 6356.8 6364.0 6379.0 6701.3 6851.3 6889.8 7000.6 7023.0 6455.7 6580.8 6588.9 6599.2 6618.6 6701.7 6705.0 6777.2 6794.4 6806.6 6411.9 6596.9 6660.1 6667.4 6673.0 6504.3 6607.5 6675.3 6689.1 6714.4 5127.1 5301.3 5324.0 5333.0 5356.2 4054.9 4694.5 4695.7 4711.3 4749.3 5946.7 6285.6 6340.6 6378.4 6399.6 6821.2 6866.7 6900.5 6917.3 6925.1 6381.8 6391.8 6410.6 6417.1 6428.1 5814.5 5899.9 5938.9 5953.9 6016.9 6864.9 6883.3 6959.4 7011.9 7048.7 6605.8 6700.4 6789.4 6902.5 6955.9 5866.0 6981.5 7026.8 7061.9 7081.4 6693.7 6699.8 6701.9 6711.1 6744.0 6610.2 6694.9 6769.1 6872.4 6900.3 6582.0 6587.1 6602.0 6607.9 6637.4 6460.9 6588.5 6605.9 6615.5 6635.7 5003.5 6204.4 6300.2 6423.4 6444.6 6464.2 6510.9 6588.5 6614.1 6617.2 6442.6 6444.0 6462.1 6467.1 6491.2 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:44399.32 Median:3196.04 stddev:75735.26 (2369.7%) stddev-sans-first:75876.98 07:25:28 INFO - PID 1638 | Values: 803.8 811.3 1210.7 1266.0 1283.5 38162.8 38840.4 38849.4 38907.2 38912.6 31041.8 39070.0 39306.8 39365.2 39536.4 7856.1 8302.3 8483.3 8487.4 8492.8 6944.7 8503.7 8604.5 8635.3 8664.5 7696.9 8607.1 8621.8 8657.1 8675.2 7577.4 8328.0 8618.7 8635.6 8652.1 8346.6 8446.9 8447.1 8449.6 8453.3 37934.6 38223.8 38227.8 38245.2 38265.6 3614.2 3633.8 3636.1 3642.0 3648.6 30889.4 31165.7 31182.5 31225.8 31252.5 2692.5 3190.9 3201.2 3209.9 3210.9 288694.6 290222.0 290736.0 291416.0 294571.0 41113.8 44151.3 45490.8 47247.8 47396.5 48888.5 50369.7 50378.0 52050.5 52056.7 14373.1 14423.7 14500.3 14518.7 14529.3 11570.8 11642.3 11664.3 11664.4 11682.8 11174.7 13816.0 13844.2 13847.4 13881.2 5886.4 5922.2 5957.3 5994.9 5996.7 1010.0 1015.7 1016.4 1040.1 1043.7 23721.8 24623.4 24792.4 24979.8 25017.9 1062.2 1078.6 1103.6 1137.7 1147.2 9892.5 12101.8 12737.0 12936.3 12959.0 293531.5 298598.0 299639.0 300334.0 326929.0 57714.0 57835.0 58408.8 58435.4 58514.7 1479.3 1499.5 1514.9 1517.0 1532.6 26981.5 28061.7 29354.7 29886.6 31257.7 39768.4 39949.2 39995.4 40061.6 40120.8 271291.0 271747.6 271959.0 272657.0 272684.0 34394.0 34608.7 35340.8 35446.8 35540.0 32248.8 42163.9 44960.8 45235.5 45328.1 26604.7 28728.6 30113.5 30773.5 30967.4 30749.5 31049.7 31101.8 31116.5 31160.4 29314.6 29681.0 37285.6 37428.1 37847.6 29778.7 30736.1 31134.4 31236.7 31366.5 23710.0 24311.4 24358.9 24712.5 25142.6 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:34710.32 Median:22633.60 stddev:88971.69 (393.1%) stddev-sans-first:89184.04 07:25:28 INFO - PID 1638 | Values: 427.3 436.4 436.9 451.9 454.8 18225.5 19860.4 21202.7 21743.5 22078.8 21579.4 22113.6 22600.0 22616.0 23771.4 6031.4 6245.6 6250.2 6258.7 6259.7 5775.7 6076.7 6133.7 6137.2 6162.2 5042.8 5879.1 6089.6 6112.1 6123.8 5931.5 6041.5 6048.6 6106.7 6112.7 5368.4 5378.7 5439.8 5441.0 5460.5 26890.2 27060.9 27446.5 27798.3 27846.0 2011.7 2178.3 2225.1 2247.2 2269.3 14279.9 17927.0 18122.6 18148.3 18237.9 1989.9 2090.6 2135.4 2157.6 2194.1 152002.0 153266.0 154592.0 156976.0 157052.0 10482.8 12250.2 12919.0 12932.8 13002.3 13851.9 16971.1 16990.0 17005.0 17006.5 7503.3 8874.5 9089.5 9127.8 9185.9 6998.8 7182.9 8571.3 8718.0 8772.8 7872.1 7917.0 8018.6 8049.5 8094.6 1952.4 2185.5 2188.1 2203.3 2205.6 808.0 956.5 962.9 998.7 1002.2 20382.9 21797.5 21830.7 21845.8 21956.8 984.4 986.0 1013.2 1015.3 1020.0 10759.7 11189.2 11294.3 11308.5 11318.7 419975.0 535781.0 540327.0 550427.0 553571.0 123166.0 123372.0 128014.0 128144.0 129149.0 1207.8 1217.3 1261.8 1263.8 1268.2 22377.6 22651.2 22947.4 23081.3 23513.0 55016.4 56555.6 56762.7 57597.4 58226.7 75242.0 75967.0 76208.6 76315.6 77182.0 9062.9 9101.7 9113.4 9217.4 9256.2 13890.5 13931.9 14041.6 14234.8 14236.3 9932.3 11726.9 11933.1 12139.9 12234.9 8772.0 10064.6 11044.0 11325.1 11343.9 12111.1 12192.3 12202.3 12292.8 12406.7 9034.9 9111.7 9156.7 9163.4 9181.8 11850.2 11972.0 11997.0 12023.7 12163.5 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:7059.29 Median:4147.94 stddev:4461.86 (107.6%) stddev-sans-first:4453.59 07:25:28 INFO - PID 1638 | Values: 1327.9 1419.5 1439.7 1439.9 1442.5 11341.1 11384.5 11897.2 11915.2 12090.8 11681.4 11844.6 11889.5 11892.9 11913.1 2145.9 2527.7 2564.4 2571.4 2572.7 2491.9 2528.0 2550.7 2551.9 2552.9 2467.9 2488.5 2503.0 2522.2 2528.1 2286.2 2313.3 2475.2 2530.9 2531.8 2449.6 2488.2 2511.8 2513.1 2555.4 9606.5 9643.5 11734.0 11783.7 11788.3 6587.9 6830.2 6836.8 6858.9 6860.2 3998.5 4358.0 4401.4 4434.8 4437.5 1233.0 1238.9 1248.7 1268.2 1285.8 13759.9 14989.7 15579.4 16333.9 16663.4 5449.2 5538.6 5668.9 5759.9 5760.4 11639.3 11741.8 11743.4 11745.6 11753.8 6558.7 6616.5 6619.3 6632.0 6634.1 5514.3 6056.0 6140.8 6198.3 6224.4 4297.3 4394.0 4394.6 4442.2 4442.4 1126.6 1269.9 1291.9 1306.8 1321.9 710.0 771.0 780.4 807.8 815.5 6193.0 6266.3 6298.5 6368.9 6405.7 573.6 578.7 598.5 601.9 601.9 3504.7 3581.2 4338.7 4363.5 4457.8 14823.6 15028.6 15345.8 15351.0 15359.5 14840.8 14869.9 15160.5 15269.5 15283.9 7762.3 8784.6 8802.3 8809.3 8809.3 5380.3 6591.7 6852.6 7041.4 7216.2 13091.7 13358.7 13404.6 13743.6 13764.2 11242.9 13664.5 13962.4 13980.9 14215.9 9275.2 9435.6 9436.0 9462.3 9470.8 10577.9 10922.6 10923.5 11057.8 11062.9 8063.6 9009.3 9080.5 9213.8 9282.4 7421.5 7505.7 8790.5 8919.5 9050.6 8166.4 8283.1 8287.0 8289.4 8299.0 6055.6 6075.7 6159.9 6227.9 6228.6 6103.6 6146.8 6232.8 6238.8 6298.5 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5568.60 Median:6151.87 stddev:1722.37 (28.0%) stddev-sans-first:1682.89 07:25:28 INFO - PID 1638 | Values: 396.7 401.9 402.9 404.4 412.7 5820.7 6001.4 6417.7 6569.7 6591.8 4953.5 5839.8 6008.0 6153.3 6409.6 4844.5 4848.1 4852.2 4875.0 4886.5 4758.2 4882.6 4893.2 4895.9 4899.8 4786.0 4798.3 4803.8 4804.3 4814.7 4067.5 4790.8 4834.8 4842.8 4878.1 3739.4 3951.5 4287.5 4305.7 4306.2 5490.9 6094.0 6150.5 6186.1 6193.3 5459.5 5491.8 5517.1 5518.4 5554.0 6653.8 6764.9 6765.2 6821.6 6858.8 3936.6 4419.3 4421.2 4424.3 4453.7 6834.3 7162.0 7203.8 7278.5 7308.0 4478.8 4941.2 5182.1 5182.7 5230.0 4118.9 5197.0 5326.3 5380.3 5387.4 5749.3 5838.5 5864.8 5871.0 5872.0 6921.7 6921.9 6924.4 6939.9 6966.5 1421.5 1514.4 1528.9 1751.8 1798.5 1226.5 1239.9 1243.8 1251.8 1255.6 6541.4 6590.2 6603.8 6609.3 6631.0 6280.4 6857.2 6970.6 7224.5 7252.9 5074.2 5823.8 6188.9 6191.2 6527.7 3222.3 3525.9 3706.4 3720.9 3723.7 6074.3 7397.6 7432.4 7462.6 7475.3 6968.7 7071.4 7392.0 7398.8 7408.3 7419.4 7428.9 7439.4 7469.4 7484.3 6568.4 6681.3 7070.9 7107.8 7157.0 6630.5 6975.8 7017.3 7066.7 7067.5 6427.7 6782.2 6857.1 7000.1 7459.6 3560.9 4074.3 4552.5 4552.7 4594.2 6472.6 6694.0 6883.6 6888.5 7196.3 5828.6 6185.0 6567.7 6586.7 6963.0 6296.7 6844.5 6909.6 6922.5 6938.3 5384.5 6488.7 6588.0 6613.4 6813.0 6300.3 6424.5 6830.9 6834.3 6876.8 5517.8 5854.0 5870.9 6376.3 6683.0 07:25:28 INFO - PID 1638 | 07:25:28 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:22169.24 Median:18508.35 stddev:30584.88 (165.2%) stddev-sans-first:30628.37 07:25:28 INFO - PID 1638 | Values: 746.7 832.3 835.6 862.7 868.7 16190.5 16713.9 16736.6 17466.7 19042.3 17647.5 18034.3 18113.9 18295.3 18327.3 979.9 988.8 993.2 994.2 995.1 989.2 1001.7 1005.4 1008.9 1009.6 1070.1 1072.7 1075.0 1075.4 1075.9 1050.9 1057.7 1067.9 1068.6 1070.9 740.8 752.2 754.8 767.1 768.0 16378.8 16500.8 16552.8 16709.5 16717.5 17065.1 17388.4 17635.4 17811.0 17831.3 13896.5 14003.6 14194.0 14208.1 14227.0 3487.2 3526.0 3550.3 3553.0 3566.0 109692.9 117351.8 128590.4 133075.0 133101.0 13331.1 13418.6 13553.5 13556.7 13589.4 25267.6 25613.9 25792.2 26160.0 26297.5 6869.8 6990.0 7158.6 7223.1 7254.9 3360.5 3421.9 3979.3 3992.5 4036.1 4377.3 4456.8 4458.4 4462.6 4473.7 815.9 824.6 827.6 931.5 969.7 630.8 635.4 674.2 681.6 693.4 11201.7 11539.8 11835.9 12002.7 12166.5 644.4 661.0 668.0 675.1 688.2 5533.9 5546.8 5618.2 5798.6 5828.5 74840.0 104045.0 109795.0 111105.0 111232.0 74119.0 84025.2 86468.6 89708.1 91326.0 72436.0 74585.0 77255.6 78129.2 80562.6 11762.9 12581.9 12644.2 12649.4 12657.5 37852.9 38639.0 42146.0 43042.6 43482.0 60101.7 61445.1 80614.6 81861.0 83135.6 17001.9 17620.0 17874.6 17934.3 17979.3 18603.5 18677.8 19041.3 19135.8 19233.7 15110.2 17356.8 17999.4 18117.5 18417.9 17834.1 18278.9 18342.2 18448.4 18568.4 16672.2 17255.1 17500.7 17599.5 17751.0 11724.6 11970.0 12122.7 12426.8 12430.8 13553.7 13737.1 14293.0 14322.4 14326.9 07:25:28 INFO - PID 1638 | -------- Summary: end -------- 07:25:28 INFO - PID 1638 | 07:25:29 INFO - TEST-INFO | 1638: exit 0 07:25:29 INFO - TEST-OK | dromaeo_css | took 1115682ms 07:25:29 INFO - TEST-START | kraken 07:25:29 INFO - operating with platform_type : mac_ 07:25:29 INFO - Initialising browser for kraken test... 07:25:29 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpMmJMM1/profile http://localhost:49241/getInfo.html) 07:25:31 INFO - PID 1652 | 2017-05-10 07:25:31.516 plugin-container[1653:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 07:25:31 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:31 INFO - PID 1652 | 2017-05-10 07:25:31.520 plugin-container[1653:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 07:25:31 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:31 INFO - PID 1652 | 2017-05-10 07:25:31.520 plugin-container[1653:10180] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:25:31 INFO - PID 1652 | __metrics Screen width/height:1600/1200 07:25:31 INFO - PID 1652 | colorDepth:24 07:25:31 INFO - PID 1652 | Browser inner width/height: 1024/691 07:25:31 INFO - PID 1652 | __metrics 07:25:32 INFO - TEST-INFO | 1652: exit 0 07:25:32 INFO - Browser initialized. 07:25:32 INFO - Running cycle 1/1 for kraken test... 07:25:32 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpMmJMM1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:25:34 INFO - PID 1655 | 2017-05-10 07:25:34.808 plugin-container[1656:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 07:25:34 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:34 INFO - PID 1655 | 2017-05-10 07:25:34.809 plugin-container[1656:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 07:25:34 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:34 INFO - PID 1655 | 2017-05-10 07:25:34.809 plugin-container[1656:10339] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:25:35 INFO - PID 1655 | 2017-05-10 07:25:35.197 plugin-container[1657:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 07:25:35 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:35 INFO - PID 1655 | 2017-05-10 07:25:35.197 plugin-container[1657:10383] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9903, name = 'com.apple.CFPasteboardClient' 07:25:35 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:35 INFO - PID 1655 | 2017-05-10 07:25:35.197 plugin-container[1657:10383] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:25:44 INFO - PID 1655 | RSS: Main: 230752256 07:25:44 INFO - PID 1655 | 07:25:47 INFO - PID 1655 | 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) 07:25:48 INFO - PID 1655 | RSS: Main: 242774016 07:25:48 INFO - PID 1655 | 07:25:50 INFO - PID 1655 | 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) 07:25:51 INFO - PID 1655 | RSS: Main: 250490880 07:25:51 INFO - PID 1655 | 07:25:55 INFO - PID 1655 | 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) 07:25:55 INFO - PID 1655 | RSS: Main: 252977152 07:25:55 INFO - PID 1655 | 07:25:57 INFO - PID 1655 | 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) 07:25:58 INFO - PID 1655 | RSS: Main: 255135744 07:25:58 INFO - PID 1655 | 07:26:00 INFO - PID 1655 | 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) 07:26:00 INFO - PID 1655 | RSS: Main: 255672320 07:26:00 INFO - PID 1655 | 07:26:03 INFO - PID 1655 | 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) 07:26:04 INFO - PID 1655 | RSS: Main: 261308416 07:26:04 INFO - PID 1655 | 07:26:07 INFO - PID 1655 | 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) 07:26:08 INFO - PID 1655 | RSS: Main: 262762496 07:26:08 INFO - PID 1655 | 07:26:11 INFO - PID 1655 | 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) 07:26:12 INFO - PID 1655 | RSS: Main: 262455296 07:26:12 INFO - PID 1655 | 07:26:13 INFO - PID 1655 | 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) 07:26:14 INFO - PID 1655 | RSS: Main: 262422528 07:26:14 INFO - PID 1655 | 07:26:15 INFO - PID 1655 | 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) 07:26:16 INFO - PID 1655 | RSS: Main: 262672384 07:26:16 INFO - PID 1655 | 07:26:18 INFO - PID 1655 | 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) 07:26:19 INFO - PID 1655 | RSS: Main: 262139904 07:26:19 INFO - PID 1655 | 07:26:22 INFO - PID 1655 | 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) 07:26:23 INFO - PID 1655 | RSS: Main: 262500352 07:26:23 INFO - PID 1655 | 07:26:26 INFO - PID 1655 | 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) 07:26:27 INFO - PID 1655 | RSS: Main: 262213632 07:26:27 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | 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) 07:26:29 INFO - PID 1655 | RSS: Main: 264015872 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | __start_tp_report 07:26:29 INFO - PID 1655 | _x_x_mozilla_page_load 07:26:29 INFO - PID 1655 | _x_x_mozilla_page_load_details 07:26:29 INFO - PID 1655 | |i|pagename|runs| 07:26:29 INFO - PID 1655 | |0;ai-astar;102;116;114;116;112;116;114;115;117;116 07:26:29 INFO - PID 1655 | |1;audio-beat-detection;186;96;148;186;146;119;111;114;139;111 07:26:29 INFO - PID 1655 | |2;audio-dft;194;281;277;217;204;252;256;203;198;200 07:26:29 INFO - PID 1655 | |3;audio-fft;80;58;57;83;58;61;58;58;81;67 07:26:29 INFO - PID 1655 | |4;audio-oscillator;90;70;81;71;72;71;89;72;69;91 07:26:29 INFO - PID 1655 | |5;imaging-gaussian-blur;126;106;92;93;87;102;105;80;136;132 07:26:29 INFO - PID 1655 | |6;imaging-darkroom;99;99;105;127;107;129;112;137;99;102 07:26:29 INFO - PID 1655 | |7;imaging-desaturate;101;94;90;91;89;90;92;112;132;130 07:26:29 INFO - PID 1655 | |8;json-parse-financial;50;50;55;66;60;53;49;49;49;51 07:26:29 INFO - PID 1655 | |9;json-stringify-tinderbox;60;64;62;69;65;58;58;55;77;86 07:26:29 INFO - PID 1655 | |10;stanford-crypto-aes;82;83;83;83;79;103;107;115;106;97 07:26:29 INFO - PID 1655 | |11;stanford-crypto-ccm;155;142;146;152;160;144;146;158;259;153 07:26:29 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;226;177;228;184;219;245;187;185;188;184 07:26:29 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;70;71;67;70;69;70;69;66;76;85 07:26:29 INFO - PID 1655 | __end_tp_report 07:26:29 INFO - PID 1655 | __start_cc_report 07:26:29 INFO - PID 1655 | _x_x_mozilla_cycle_collect,1011 07:26:29 INFO - PID 1655 | __end_cc_report 07:26:29 INFO - PID 1655 | __startTimestamp1494426389324__endTimestamp 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | ------- Summary: start ------- 07:26:29 INFO - PID 1655 | Number of tests: 14 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:113.80 Median:116.00 stddev:4.39 (3.8%) stddev-sans-first:1.54 07:26:29 INFO - PID 1655 | Values: 102.0 116.0 114.0 116.0 112.0 116.0 114.0 115.0 117.0 116.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:147.00 stddev:31.40 (21.4%) stddev-sans-first:27.50 07:26:29 INFO - PID 1655 | Values: 186.0 96.0 148.0 186.0 146.0 119.0 111.0 114.0 139.0 111.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:228.20 Median:234.50 stddev:34.53 (14.7%) stddev-sans-first:34.34 07:26:29 INFO - PID 1655 | Values: 194.0 281.0 277.0 217.0 204.0 252.0 256.0 203.0 198.0 200.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:66.10 Median:64.00 stddev:10.92 (17.1%) stddev-sans-first:10.36 07:26:29 INFO - PID 1655 | Values: 80.0 58.0 57.0 83.0 58.0 61.0 58.0 58.0 81.0 67.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:76.50 stddev:9.17 (12.0%) stddev-sans-first:8.56 07:26:29 INFO - PID 1655 | Values: 90.0 70.0 81.0 71.0 72.0 71.0 89.0 72.0 69.0 91.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:105.90 Median:108.00 stddev:19.42 (18.0%) stddev-sans-first:19.19 07:26:29 INFO - PID 1655 | Values: 126.0 106.0 92.0 93.0 87.0 102.0 105.0 80.0 136.0 132.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:133.00 stddev:14.21 (10.7%) stddev-sans-first:14.33 07:26:29 INFO - PID 1655 | Values: 99.0 99.0 105.0 127.0 107.0 129.0 112.0 137.0 99.0 102.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:90.00 stddev:16.76 (18.6%) stddev-sans-first:17.77 07:26:29 INFO - PID 1655 | Values: 101.0 94.0 90.0 91.0 89.0 90.0 92.0 112.0 132.0 130.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:52.00 stddev:5.69 (10.9%) stddev-sans-first:5.92 07:26:29 INFO - PID 1655 | Values: 50.0 50.0 55.0 66.0 60.0 53.0 49.0 49.0 49.0 51.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:64.50 stddev:9.62 (14.9%) stddev-sans-first:10.00 07:26:29 INFO - PID 1655 | Values: 60.0 64.0 62.0 69.0 65.0 58.0 58.0 55.0 77.0 86.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:82.50 stddev:13.23 (16.0%) stddev-sans-first:13.33 07:26:29 INFO - PID 1655 | Values: 82.0 83.0 83.0 83.0 79.0 103.0 107.0 115.0 106.0 97.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:161.50 Median:154.00 stddev:34.79 (22.6%) stddev-sans-first:36.82 07:26:29 INFO - PID 1655 | Values: 155.0 142.0 146.0 152.0 160.0 144.0 146.0 158.0 259.0 153.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.30 Median:203.50 stddev:24.43 (12.0%) stddev-sans-first:24.36 07:26:29 INFO - PID 1655 | Values: 226.0 177.0 228.0 184.0 219.0 245.0 187.0 185.0 188.0 184.0 07:26:29 INFO - PID 1655 | 07:26:29 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.00 stddev:5.50 (7.9%) stddev-sans-first:5.81 07:26:29 INFO - PID 1655 | Values: 70.0 71.0 67.0 70.0 69.0 70.0 69.0 66.0 76.0 85.0 07:26:29 INFO - PID 1655 | -------- Summary: end -------- 07:26:29 INFO - PID 1655 | 07:26:30 INFO - TEST-INFO | 1655: exit 0 07:26:30 INFO - TEST-OK | kraken | took 61305ms 07:26:30 INFO - SUITE-END | took 1176s 07:26:30 INFO - Completed test suite (00:19:37) 07:26:30 INFO - javascript benchmark 07:26:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7973.831348633663, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3176.841157942102, 3552.0764601203678, 3613.4941108244852, 3647.215848592552, 3657.689096426807, 4814.301926220704, 5131.794728210867, 5492.533134683556, 6159.3224745277985, 6181.6600086995195, 6199.938000619993, 6227.719752611132, 6251.624902505853, 6303.0, 6326.683665816702, 5282.471752824706, 5467.617266791326, 5477.1510415885405, 5494.752736126884, 5619.381867994517, 5606.046972014768, 5611.130274807411, 5611.719414029302, 5612.522935550483, 5636.408177141403, 5363.115086010804, 5427.728613569306, 5462.20797984294, 5531.502164805167, 5554.833354999357, 5458.372287186985, 5502.504774570308, 5509.696966666832, 5514.862128446781, 5552.11166213408, 5484.643498172606, 5485.6982865942355, 5493.478119578634, 5494.615376923617, 5506.834794956158, 6148.262208535004, 6218.378162183791, 6273.059041143865, 6324.177856878622, 6338.30278669346, 6401.263854656728, 6486.740530378779, 6604.141461610008, 6675.132232809704, 6702.396784289389, 6711.798646040626, 6797.626130562817, 6799.694013769343, 6809.863802723968, 6823.965880170617, 5093.23601459784, 6333.683315834236, 6356.809295721136, 6364.045393191012, 6378.979363301896, 6701.329867013357, 6851.2806155353965, 6889.827754306182, 7000.579965202104, 7023.0, 6455.7417703292285, 6580.76967306142, 6588.901166482507, 6599.208095028627, 6618.635975021324, 6701.698423570951, 6704.96647516769, 6777.152855893013, 6794.422474089758, 6806.591604503746, 6411.871762564722, 6596.934030659728, 6660.067590537328, 6667.399934005865, 6672.96663516689, 6504.349565043458, 6607.471402287805, 6675.265720770712, 6689.130413046273, 6714.362135597111, 5127.051495473349, 5301.257823904656, 5324.0, 5333.013392522395, 5356.1697936819855, 4054.878353649395, 4694.483606803249, 4695.694779839299, 4711.316859055486, 4749.26386410107, 5946.70266486674, 6285.59143655661, 6340.556161068773, 6378.362163783678, 6399.584027038228, 6821.1814582250445, 6866.690998905061, 6900.482463815234, 6917.308269173042, 6925.065116209348, 6381.776637817654, 6391.776287829996, 6410.64741439216, 6417.069524918954, 6428.067930150102, 5814.505767009851, 5899.911501327483, 5938.8812223755285, 5953.851153721192, 6016.879662406727, 6864.931350686529, 6883.3116688330765, 6959.443244540324, 7011.859762804817, 7048.718051277891, 6605.834854128685, 6700.363465470772, 6789.388954994074, 6902.482313826383, 6955.8956615649795, 5866.0, 6981.476389270927, 7026.754063607749, 7061.929380706127, 7081.398081163159, 6693.732250710112, 6699.8325041872395, 6701.899471507833, 6711.060451536887, 6743.966280168764, 6610.173728283965, 6694.899576506259, 6769.052332673331, 6872.41584465326, 6900.275471075464, 6581.967090164519, 6587.110740050032, 6601.966990165019, 6607.933920660732, 6637.435817955527, 6460.935390646034, 6588.505862060269, 6605.900911486236, 6615.470762339098, 6635.668216589248, 5003.499650035113, 6204.441600255998, 6300.180976473029, 6423.42189202974, 6444.645544495098, 6464.191976003, 6510.869782604416, 6588.538802283794, 6614.074029635759, 6617.239017512855, 6442.613443193423, 6444.0, 6462.095306657166, 6467.126937863328, 6491.221053473613], "value": 6121.383594877982, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.8030682482791, 811.330652211925, 1210.7094297368628, 1266.03781126344, 1283.4801905228385, 38162.809185954065, 38840.41739373912, 38849.41725874111, 38907.22185556287, 38912.61087389125, 31041.844790776013, 39070.0, 39306.80346598263, 39365.21269574614, 39536.40695389571, 7856.096548896893, 8302.252797248246, 8483.321334293241, 8487.363447741413, 8492.830143397128, 6944.652767361637, 8503.744887653349, 8604.52675102869, 8635.266002389804, 8664.523451210182, 7696.923030769676, 8607.053224145338, 8621.827563448727, 8657.091005444432, 8675.175858293484, 7577.355924746404, 8327.95900512439, 8618.698345557907, 8635.568221588896, 8652.134786521361, 8346.582670866494, 8446.915530844675, 8447.070822209553, 8449.577521123982, 8453.323734101257, 37934.62065379366, 38223.808880955694, 38227.80886095552, 38245.23509529794, 38265.617343826765, 3614.2410093880317, 3633.7819730815972, 3636.1455058061333, 3642.0, 3648.558819266375, 30889.382212355853, 31165.688343116504, 31182.532262016088, 31225.750969961173, 31252.531212031838, 2692.5422678144764, 3190.88831890885, 3201.199700074981, 3209.8604995226824, 3210.855511501988, 288694.55652721814, 290222.0, 290736.0, 291416.0, 294571.0, 41113.79443102765, 44151.33772993408, 45490.77254613706, 47247.76376118166, 47396.52603473921, 48888.51111488911, 50369.74815125901, 50378.0, 52050.47949520532, 52056.739716301934, 14373.137611743328, 14423.711525769635, 14500.347484363228, 14518.709625807636, 14529.34617942174, 11570.826437603442, 11642.301461912313, 11664.300141991507, 11664.358460284597, 11682.824757628643, 11174.66476005721, 13816.032877698464, 13844.238566878717, 13847.446102156002, 13881.167129972235, 5886.411358864079, 5922.200502932049, 5957.255343082114, 5994.880102398015, 5996.72124592652, 1009.9747506312401, 1015.6749840051112, 1016.4003238089415, 1040.1107053469327, 1043.749500119981, 23721.762782371956, 24623.38441538976, 24792.38019049502, 24979.750202498108, 25017.87491062533, 1062.2245760594656, 1078.5577913055608, 1103.6192513582962, 1137.6871360375796, 1147.2141583015662, 9892.505374731234, 12101.757964840654, 12737.0, 12936.288504132175, 12958.963282937342, 293531.5323423412, 298598.0, 299639.0, 300334.0, 326929.0, 57714.0, 57835.0, 58408.831823363296, 58435.415645843, 58514.70742646259, 1479.2899408284084, 1499.4976682811161, 1514.893957422992, 1516.9848301517063, 1532.601523603871, 26981.516016619273, 28061.71938280673, 29354.706452935196, 29886.60907127468, 31257.687423125477, 39768.40347394849, 39949.2010159801, 39995.40006899841, 40061.599384005785, 40120.79637610877, 271291.0, 271747.64126179245, 271959.0, 272657.0, 272684.0, 34394.0, 34608.65391346155, 35340.82329588439, 35446.822765886005, 35540.0, 32248.83875580701, 42163.94590135271, 44960.77519612381, 45235.54764452445, 45328.093438130396, 26604.733952660226, 28728.569071463528, 30113.548296776004, 30773.538396923617, 30967.380652387277, 30749.53875691911, 31049.689503105583, 31101.844490778305, 31116.53325200169, 31160.37679246357, 29314.560281595368, 29681.0, 37285.62714372821, 37428.06429839167, 37847.621523785514, 29778.724340322115, 30736.077917662507, 31134.37731245408, 31236.687633123376, 31366.52950205703, 23710.0, 24311.392215194763, 24358.87820560886, 24712.50574988454, 25142.62286065747], "value": 17651.241098113092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.34616037462695, 436.4391756592778, 436.91862639966087, 451.93672885795996, 454.82943896038967, 18225.450836678898, 19860.404187874352, 21202.681959770598, 21743.456413589647, 22078.78566678836, 21579.352619421403, 22113.557728845415, 22599.983000764965, 22616.0, 23771.405714857137, 6031.427014433632, 6245.594036387621, 6250.156228909107, 6258.655773932432, 6259.655718935457, 5775.653460792366, 6076.665783381913, 6133.723982420779, 6137.17148184996, 6162.1681073055015, 5042.773075211624, 5879.11813228017, 6089.634621922677, 6112.144299798032, 6123.785667501638, 5931.466168044875, 6041.486473649745, 6048.637081775086, 6106.694665266719, 6112.663803490784, 5368.4363141869935, 5378.704171270579, 5439.83680489582, 5440.972795136038, 5460.5085542301385, 26890.193294201206, 27060.86469567659, 27446.45107097847, 27798.3050423739, 27846.0, 2011.7485314335709, 2178.313831143185, 2225.098834971839, 2247.213475283654, 2269.3305474884946, 14279.928600356932, 17927.01401422921, 18122.63754724898, 18148.274069037223, 18237.908810455996, 1989.9303524376721, 2090.64108329587, 2135.380739585534, 2157.622416077196, 2194.1113848891223, 152002.0, 153266.0, 154592.0, 156976.0, 157052.0, 10482.842757358494, 12250.203736757083, 12919.0, 12932.806007909889, 13002.284874332003, 13851.930740346234, 16971.06659133735, 16990.0, 17004.979701217966, 17006.48980530586, 7503.324700776847, 8874.511901845332, 9089.50007749567, 9127.77180570491, 9185.862212066824, 6998.75504357345, 7182.928170718331, 8571.271441927382, 8717.997430295598, 8772.78068048304, 7872.134065252818, 7916.960415197887, 8018.55897925608, 8049.51702897828, 8094.554799486084, 1952.3557226115347, 2185.530111026137, 2188.0810059774612, 2203.272919936417, 2205.558888222362, 807.9757607271791, 956.4930586789013, 962.8988956159642, 998.7253505286104, 1002.2232769603615, 20382.855467837668, 21797.45506362322, 21830.672539911913, 21845.781542184373, 21956.78043219547, 984.4388698441962, 985.9507024648739, 1013.2400699475394, 1015.304516406264, 1019.9592016319413, 10759.731006724895, 11189.216754827085, 11294.322340659586, 11308.547658093748, 11318.660440186808, 419975.0, 535781.0, 540327.0, 550427.0, 553571.0, 123166.0, 123372.0, 128014.0, 128144.0, 129149.0, 1207.7584483103415, 1217.3365515793914, 1261.79180435229, 1263.829383033297, 1268.150339272708, 22377.552448951257, 22651.20720774765, 22947.426314342276, 23081.30699088205, 23513.0, 55016.44983550273, 56555.586110345925, 56762.7161864188, 57597.4240257592, 58226.708866455396, 75242.0, 75967.0, 76208.61895690486, 76315.61842190976, 77182.0, 9062.909370906471, 9101.726948191565, 9113.362064655676, 9217.354785164975, 9256.213221876216, 13890.513832015831, 13931.930340348234, 14041.578752637437, 14234.786478202628, 14236.288185590885, 9932.255080869108, 11726.88273117258, 11933.105017123924, 12139.939300303442, 12234.938825306173, 8772.0, 10064.597416103274, 11044.0, 11325.093992480417, 11343.943280283545, 12111.091668124749, 12192.268463892195, 12202.328871911775, 12292.754144916873, 12406.689832754253, 9034.864477032981, 9111.681091161778, 9156.725298241063, 9163.358564900393, 9181.816363672822, 11850.170488065753, 11972.0, 11997.0, 12023.699407514532, 12163.513459461523], "value": 10445.777401741323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.9203247805133, 1419.5173640962078, 1439.6760728836014, 1439.856014398561, 1442.516756886443, 11341.149413793968, 11384.487698053586, 11897.167198296112, 11915.165938384316, 12090.818637720442, 11681.415929203527, 11844.58543950962, 11889.464974076165, 11892.881071189286, 11913.106517011238, 2145.8712477251415, 2527.7345878682704, 2564.3717289264155, 2571.3571607098224, 2572.7298633643477, 2491.9252422427357, 2527.9873600631972, 2550.7321731218235, 2551.910683126091, 2552.923412297634, 2467.925962221127, 2488.5396201702742, 2503.0488414402503, 2522.1928982725535, 2528.1277959104173, 2286.211257116292, 2313.27131953435, 2475.2450502596703, 2530.949655892802, 2531.8101142414303, 2449.5835707929696, 2488.1664642344836, 2511.8367306125224, 2513.070164039317, 2555.3611597100726, 9606.519674016272, 9643.517824108767, 11734.0, 11783.7054073648, 11788.29270243788, 6587.93412065878, 6830.214525329601, 6836.794896153124, 6858.931410685879, 6860.176778786527, 3998.540167880702, 4357.978210108961, 4401.383806267125, 4434.800433980478, 4437.46750389952, 1233.0, 1238.919470234441, 1248.7440074784647, 1268.220044672529, 1285.768561658901, 13759.931200344034, 14989.70020599582, 15579.376824926989, 16333.91833040839, 16663.41678041275, 5449.155380915964, 5538.556915446755, 5668.858278543069, 5759.8560035999435, 5760.423957604206, 11639.301641901513, 11741.765164696659, 11743.354115523729, 11745.647630571097, 11753.823692644617, 6558.704858281389, 6616.503762217757, 6619.304972977864, 6632.0, 6634.0712300278, 5514.310711161105, 6056.0, 6140.846478838065, 6198.349173336825, 6224.439800417984, 4297.335651895844, 4394.011347446799, 4394.626456751148, 4442.178197033494, 4442.400275962768, 1126.6169502369235, 1269.9238045717286, 1291.8514370847472, 1306.7778477658821, 1321.9226330540578, 709.9609521476265, 770.9884351734728, 780.4185881518305, 807.7657479331045, 815.514768712615, 6193.040078787795, 6266.342034086447, 6298.496120310364, 6368.936310636927, 6405.7117429714845, 573.58988323349, 578.6701580099387, 598.494272339872, 601.883506096194, 601.9285671504729, 3504.7021003215013, 3581.194231297937, 4338.652907767371, 4363.498197707241, 4457.821687132543, 14823.62821948483, 15028.647421731877, 15345.846541534442, 15351.002184857949, 15359.462418815512, 14840.777388339184, 14869.925650371679, 15160.545183644508, 15269.465568705042, 15283.923580382248, 7762.262585054411, 8784.604692788713, 8802.295816334807, 8809.251213646783, 8809.33929955256, 5380.300560927211, 6591.736330546916, 6852.560962197994, 7041.4014808739785, 7216.206217315986, 13091.738165236833, 13358.732825343634, 13404.597862064154, 13743.587692369245, 13764.17414955106, 11242.887571124184, 13664.521741738992, 13962.371693273624, 13980.930095349458, 14215.928920355333, 9275.21160701348, 9435.622575096919, 9436.0, 9462.290328225274, 9470.763230919281, 10577.894221057692, 10922.617708380169, 10923.508442119964, 11057.778844422906, 11062.889371106186, 8063.596820159086, 9009.27925765924, 9080.500572468578, 9213.76965575866, 9282.35023548345, 7421.517601355895, 7505.662245199088, 8790.516521591373, 8919.464832110094, 9050.592723327334, 8166.4283500154415, 8283.08886022525, 8287.0, 8289.378296627656, 8299.0, 6055.636661800306, 6075.696215189135, 6159.938400615936, 6227.9377206227355, 6228.564000520101, 6103.633781973096, 6146.815595532141, 6232.753449310247, 6238.750449982132, 6298.527610429328], "value": 5294.109648641324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.6608549690013, 401.8747506980456, 402.8851777243485, 404.42975404679396, 412.66780241905275, 5820.679862607561, 6001.439625697318, 6417.711202995864, 6569.671516424178, 6591.802245932629, 4953.455119936804, 5839.79560715374, 6007.969960150204, 6153.261608606962, 6409.647469389181, 4844.491328410501, 4848.127337079333, 4852.199387101142, 4875.00062487191, 4886.48691887352, 4758.238681810911, 4882.56056954874, 4893.241547560134, 4895.853124406273, 4899.828506002291, 4786.018866132435, 4798.328234047219, 4803.783829727653, 4804.327394164819, 4814.687045342059, 4067.532233793122, 4790.832320868771, 4834.806607735686, 4842.757862106916, 4878.146324393252, 3739.383001804699, 3951.471058522548, 4287.506936702288, 4305.74165550068, 4306.246406878815, 5490.945090549123, 6094.0, 6150.477209437203, 6186.133941248228, 6193.318734939153, 5459.5359394451125, 5491.807786727445, 5517.062099443105, 5518.392976772552, 5554.0, 6653.7671181508895, 6764.864702705919, 6765.188177418692, 6821.624810635413, 6858.794236172923, 3936.645701886844, 4419.270820314644, 4421.159979603865, 4424.336349547593, 4453.665975051884, 6834.282400347942, 7162.0, 7203.819904502377, 7278.526895751759, 7308.0, 4478.790726503826, 4941.160002799533, 5182.1190397631735, 5182.689038657692, 5229.973850130801, 4118.876433706993, 5197.038547868657, 5326.334208223972, 5380.3274590676165, 5387.434319396485, 5749.25686308797, 5838.5037271831525, 5864.765409383662, 5870.972579798518, 5872.0, 6921.653917304115, 6921.861562768717, 6924.4460443165435, 6939.895901561481, 6966.5471744336455, 1421.4882642248783, 1514.4320879670124, 1528.915909624959, 1751.7810273715786, 1798.5144011116925, 1226.497136174182, 1239.9132060755842, 1243.8258643789877, 1251.7934540800848, 1255.5749224629928, 6541.411272985359, 6590.176227971504, 6603.76886808969, 6609.3060228675295, 6630.9668451657435, 6280.434760871543, 6857.177138743383, 6970.581765094111, 7224.530405523624, 7252.87580425035, 5074.188129899272, 5823.806119745442, 6188.938110618927, 6191.226096737908, 6527.706253218522, 3222.3072039511617, 3525.894223173309, 3706.4069748840056, 3720.925581488409, 3723.72072094594, 6074.271087469532, 7397.593132377771, 7432.364879726451, 7462.626868656545, 7475.289847464483, 6968.686409111602, 7071.363577277967, 7391.963040184764, 7398.815029624302, 7408.333250007525, 7419.406447484188, 7428.925710742931, 7439.367653749276, 7469.365103966115, 7484.251574842581, 6568.408843204134, 6681.29846366144, 7070.929290707027, 7107.7561426751145, 7157.0, 6630.469562434897, 6975.79072627822, 7017.298270172942, 7066.692661857367, 7067.540609860343, 6427.742890284523, 6782.236618479133, 6857.074294970115, 7000.05997481049, 7459.552426854406, 3560.893173204808, 4074.348104303297, 4552.476465206543, 4552.658550608651, 4594.1730488512385, 6472.579282346633, 6694.0, 6883.552569082994, 6888.52246040078, 7196.280371962762, 5828.562857785768, 6184.969075154775, 6567.704453299518, 6586.70359833799, 6963.0, 6296.653684047421, 6844.520883538304, 6909.616182417058, 6922.5154239202775, 6938.27148149437, 5384.480907070284, 6488.740450381932, 6587.9670601646685, 6613.437857782143, 6813.04617353561, 6300.306966233802, 6424.453921416732, 6830.897536537055, 6834.300451321799, 6876.7650513584285, 5517.779288828562, 5854.0048191806645, 5870.9412905872105, 6376.298607153302, 6682.9641405582215], "value": 5037.8124393388325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.7162478258261, 832.3216578488535, 835.6156168162642, 862.7325529085983, 868.7263511993724, 16190.514284571453, 16713.916430417845, 16736.581585460368, 17466.737998929995, 19042.33351832686, 17647.47057588271, 18034.27862885483, 18113.909430452863, 18295.268189272447, 18327.25891040349, 979.9363041402304, 988.7874107066979, 993.1607791416242, 994.1748348870457, 995.144176008632, 989.1888651305932, 1001.7245257554194, 1005.4269066632023, 1008.9293749437543, 1009.5759780892006, 1070.0797314309727, 1072.6674730833427, 1075.0324707763036, 1075.4461452352045, 1075.8816530181673, 1050.9106725928305, 1057.7197042783669, 1067.8505009298703, 1068.5779117248683, 1070.929070929075, 740.7999840043158, 752.2252080357241, 754.7848863073998, 767.098659075584, 767.9692812287502, 16378.83621163785, 16500.83499165005, 16552.75170872438, 16709.498715038568, 16717.49847504577, 17065.14674266282, 17388.391406300845, 17635.382761603283, 17811.0, 17831.286748530045, 13896.513622023282, 14003.579892603137, 14194.0, 14208.076475029193, 14227.0, 3487.163080860575, 3525.964740352589, 3550.3076900004507, 3553.040679016651, 3566.0, 109692.90307096907, 117351.82648173494, 128590.3570482151, 133075.0, 133101.0, 13331.133476324201, 13418.597442076752, 13553.45786168562, 13556.728865422636, 13589.388477518536, 25267.610281434696, 25613.871930640602, 25792.226233213034, 26159.991600966132, 26297.474050518882, 6869.793906182823, 6989.951507273848, 7158.642067896584, 7223.133224013152, 7254.8911766323545, 3360.479125735515, 3421.853679017557, 3979.263836190314, 3992.5208974923194, 4036.0717035081507, 4377.299632058855, 4456.821727130879, 4458.375827384169, 4462.642988560972, 4473.664475164375, 815.8653822119284, 824.5588610093691, 827.606886728799, 931.469062634292, 969.6703120938914, 630.8454428665007, 635.4154178156108, 674.2482132422314, 681.5637991685326, 693.4313862632694, 11201.719957000978, 11539.826902596467, 11835.88164118365, 12002.699932501582, 12166.45250963691, 644.3910504573133, 660.976865809704, 667.9933200667931, 675.1493118670412, 688.1845013658831, 5533.916991245134, 5546.807436401113, 5618.185363122325, 5798.623089499169, 5828.5337173026055, 74840.0, 104045.0, 109795.0, 111105.0, 111232.0, 74119.0, 84025.15974840174, 86468.56765716131, 89708.10291897129, 91326.0, 72436.0, 74585.0, 77255.61372193103, 78129.2187078122, 80562.59718701369, 11762.94118529402, 12581.93709031449, 12644.178128421623, 12649.367531623566, 12657.493700251887, 37852.86441406762, 38639.0, 42146.0, 43042.56957430386, 43482.0, 60101.699491502266, 61445.07832382429, 80614.596927015, 81861.0, 83135.58432207801, 17001.914990424968, 17620.0, 17874.642507150045, 17934.282628694706, 17979.280828766703, 18603.534911626775, 18677.813221867607, 19041.33355332612, 19135.8086419134, 19233.711494327876, 15110.244487775348, 17356.82643173552, 17999.370022049166, 18117.45647630573, 18417.907910460362, 17834.10829458548, 18278.908605456887, 18342.17460214267, 18448.354307599708, 18568.350107746162, 16672.249748761093, 17255.137243137542, 17500.737488937422, 17599.472015839547, 17751.0, 11724.58963936258, 11970.042396608076, 12122.696932576404, 12426.75146497083, 12430.81353779676, 13553.661158471117, 13737.107088039245, 14293.0, 14322.355494003003, 14326.928365358523], "value": 8538.317586451898, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 114.0, 116.0, 112.0, 116.0, 114.0, 115.0, 117.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 96.0, 148.0, 186.0, 146.0, 119.0, 111.0, 114.0, 139.0, 111.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 281.0, 277.0, 217.0, 204.0, 252.0, 256.0, 203.0, 198.0, 200.0], "value": 228.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 83.0, 58.0, 61.0, 58.0, 58.0, 81.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 81.0, 71.0, 72.0, 71.0, 89.0, 72.0, 69.0, 91.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 106.0, 92.0, 93.0, 87.0, 102.0, 105.0, 80.0, 136.0, 132.0], "value": 105.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 105.0, 127.0, 107.0, 129.0, 112.0, 137.0, 99.0, 102.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 94.0, 90.0, 91.0, 89.0, 90.0, 92.0, 112.0, 132.0, 130.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 55.0, 66.0, 60.0, 53.0, 49.0, 49.0, 49.0, 51.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 62.0, 69.0, 65.0, 58.0, 58.0, 55.0, 77.0, 86.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 83.0, 79.0, 103.0, 107.0, 115.0, 106.0, 97.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 142.0, 146.0, 152.0, 160.0, 144.0, 146.0, 158.0, 259.0, 153.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 177.0, 228.0, 184.0, 219.0, 245.0, 187.0, 185.0, 188.0, 184.0], "value": 202.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 67.0, 70.0, 69.0, 70.0, 69.0, 66.0, 76.0, 85.0], "value": 71.3, "unit": "score"}]}]} 07:26:30 INFO - Return code: 0 07:26:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:26:30 INFO - # TBPL SUCCESS # 07:26:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:26:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:26:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:26:30 INFO - 2017-05-10 07:26:30.664 screenresolution[1662:10775] starting screenresolution argv=screenresolution get 07:26:30 INFO - 2017-05-10 07:26:30.668 screenresolution[1662:10775] Display 0: 1600x1200x32@60 07:26:30 INFO - 2017-05-10 07:26:30.674 screenresolution[1663:10778] starting screenresolution argv=screenresolution list 07:26:30 INFO - Available Modes on Display 0 07:26:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:26:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:26:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:26:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:26:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:26:30 INFO - Intel Iris: 07:26:30 INFO - Chipset Model: Intel Iris 07:26:30 INFO - Type: GPU 07:26:30 INFO - Bus: Built-In 07:26:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:26:30 INFO - Vendor: Intel (0x8086) 07:26:30 INFO - Device ID: 0x0a2e 07:26:30 INFO - Revision ID: 0x0009 07:26:30 INFO - Displays: 07:26:30 INFO - Display: 07:26:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:26:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:26:30 INFO - Display Serial Number: 1600x1200 60 07:26:30 INFO - Main Display: Yes 07:26:30 INFO - Mirror: Off 07:26:30 INFO - Online: Yes 07:26:30 INFO - Rotation: Supported 07:26:30 INFO - Return code: 0 07:26:30 INFO - Running post-action listener: _resource_record_post_action 07:26:30 INFO - [mozharness: 2017-05-10 14:26:30.919581Z] Finished run-tests step (success) 07:26:30 INFO - Running post-run listener: _resource_record_post_run 07:26:30 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 40225792; Write bytes: 527813632; Read time: 389; Write time: 1840 07:26:30 INFO - TinderboxPrint: CPU usage
54.2% 07:26:30 INFO - TinderboxPrint: I/O read bytes / time
40,225,792 / 389 07:26:30 INFO - TinderboxPrint: I/O write bytes / time
527,813,632 / 1,840 07:26:30 INFO - TinderboxPrint: CPU idle
2,195.5 (45.8%) 07:26:30 INFO - TinderboxPrint: CPU system
970.3 (20.2%) 07:26:30 INFO - TinderboxPrint: CPU user
1,627.9 (34.0%) 07:26:30 INFO - TinderboxPrint: Swap in / out
253,755,392 / 0 07:26:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212877824; Write bytes: 207808512; Read time: 17089; Write time: 460 07:26:31 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35273728; Write bytes: 309892096; Read time: 354; Write time: 1351 07:26:31 INFO - Running post-run listener: _upload_blobber_files 07:26:31 INFO - Blob upload gear active. 07:26:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:26:31 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 07:26:31 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'] 07:26:31 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 07:26:31 INFO - (blobuploader) - INFO - Open directory for files ... 07:26:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:26:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:26:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:32 INFO - (blobuploader) - INFO - Done attempting. 07:26:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:26:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:26:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:33 INFO - (blobuploader) - INFO - Done attempting. 07:26:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:26:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:26:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:34 INFO - (blobuploader) - INFO - Done attempting. 07:26:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:26:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:26:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:36 INFO - (blobuploader) - INFO - Done attempting. 07:26:36 INFO - (blobuploader) - INFO - Iteration through files over. 07:26:36 INFO - Return code: 0 07:26:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:26:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:26:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5204a5c172750f9bb48fb60df932d7f54bd144f43555ec7b9e7a0074ce4c1da2339e3573daa9c9ad7503c91954c1b93b37119d9eb07b866457e00b9834adc425", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3b549702cef436aee874e05100d12e1188954a9cb508308774394a7c0fe361926ce6a1fd794f58d9addc31de8120940ca2569e04480a54ea153ac284e522021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2db7be84acc6285dcabc309908dca4c711a21be400016caeaa1979fbe78f5efccb37729cb60b5fe467de68729911b38704f24749d36cf56e5ae7cd767a99071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d498c7781d3e95b873187a83828d8f6076cec29987d657ede0bffa9861e93da70437c5c360fe18dc6960d9485aebeb8be810b32f66a92708834e881339556a6"} 07:26:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:26:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:26:36 INFO - Contents: 07:26:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5204a5c172750f9bb48fb60df932d7f54bd144f43555ec7b9e7a0074ce4c1da2339e3573daa9c9ad7503c91954c1b93b37119d9eb07b866457e00b9834adc425", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3b549702cef436aee874e05100d12e1188954a9cb508308774394a7c0fe361926ce6a1fd794f58d9addc31de8120940ca2569e04480a54ea153ac284e522021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2db7be84acc6285dcabc309908dca4c711a21be400016caeaa1979fbe78f5efccb37729cb60b5fe467de68729911b38704f24749d36cf56e5ae7cd767a99071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d498c7781d3e95b873187a83828d8f6076cec29987d657ede0bffa9861e93da70437c5c360fe18dc6960d9485aebeb8be810b32f66a92708834e881339556a6"} 07:26:36 INFO - Running post-run listener: copy_logs_to_upload_dir 07:26:36 INFO - Copying logs to upload dir... 07:26:36 INFO - mkdir: /builds/slave/test/build/upload/logs 07:26:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.634835 ========= master_lag: 3.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-05-10 07:26:40.232910) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 07:26:40.238647) ========= 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.tm8RqZlEZY/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.mtXnJCTC9O/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/5204a5c172750f9bb48fb60df932d7f54bd144f43555ec7b9e7a0074ce4c1da2339e3573daa9c9ad7503c91954c1b93b37119d9eb07b866457e00b9834adc425", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3b549702cef436aee874e05100d12e1188954a9cb508308774394a7c0fe361926ce6a1fd794f58d9addc31de8120940ca2569e04480a54ea153ac284e522021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2db7be84acc6285dcabc309908dca4c711a21be400016caeaa1979fbe78f5efccb37729cb60b5fe467de68729911b38704f24749d36cf56e5ae7cd767a99071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d498c7781d3e95b873187a83828d8f6076cec29987d657ede0bffa9861e93da70437c5c360fe18dc6960d9485aebeb8be810b32f66a92708834e881339556a6"} build_url:https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/MGU9MRTwSzur05Fp4DmOvA/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/5204a5c172750f9bb48fb60df932d7f54bd144f43555ec7b9e7a0074ce4c1da2339e3573daa9c9ad7503c91954c1b93b37119d9eb07b866457e00b9834adc425", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3b549702cef436aee874e05100d12e1188954a9cb508308774394a7c0fe361926ce6a1fd794f58d9addc31de8120940ca2569e04480a54ea153ac284e522021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2db7be84acc6285dcabc309908dca4c711a21be400016caeaa1979fbe78f5efccb37729cb60b5fe467de68729911b38704f24749d36cf56e5ae7cd767a99071", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d498c7781d3e95b873187a83828d8f6076cec29987d657ede0bffa9861e93da70437c5c360fe18dc6960d9485aebeb8be810b32f66a92708834e881339556a6"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 07:26:40.284974) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:26:40.285432) ========= 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.tm8RqZlEZY/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.mtXnJCTC9O/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.004958 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 07:26:40.333981) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 07:26:40.334388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 07:26:40.334699) ========= ========= Total master_lag: 3.52 =========