builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0219 starttime: 1485879337.96 results: success (0) buildid: 20170131065409 builduid: fc82d122069b494498e8f5f4a44e020b revision: 1d5f138d4af7822f724903b4e4676662d12d8b4b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:37.961266) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:37.961807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:37.962082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:37.987856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:37.988314) ========= 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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-01-31 08:15:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-01-31 08:15:38 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.527168 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:38.544557) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:38.544865) ========= 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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.025204 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:38.594599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 08:15:38.595166) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d5f138d4af7822f724903b4e4676662d12d8b4b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1d5f138d4af7822f724903b4e4676662d12d8b4b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-01-31 08:15:38,704 truncating revision to first 12 chars 2017-01-31 08:15:38,704 Setting DEBUG logging. 2017-01-31 08:15:38,704 attempt 1/10 2017-01-31 08:15:38,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d5f138d4af7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 08:15:40,417 unpacking tar archive at: mozilla-central-1d5f138d4af7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.974423 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 08:15:40.594387) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:40.594744) ========= script_repo_revision: 1d5f138d4af7822f724903b4e4676662d12d8b4b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:40.595202) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:40.598713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 08:15:40.619068) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-31 08:15:40.619475) ========= /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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:15:40 INFO - MultiFileLogger online at 20170131 08:15:40 in /builds/slave/test 08:15:40 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 08:15:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:15:40 INFO - {'append_to_log': False, 08:15:40 INFO - 'base_work_dir': '/builds/slave/test', 08:15:40 INFO - 'blob_upload_branch': 'Firefox', 08:15:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:15:40 INFO - 'branch': 'Firefox', 08:15:40 INFO - 'buildbot_json_path': 'buildprops.json', 08:15:40 INFO - 'config_files': ('talos/mac_config.py',), 08:15:40 INFO - 'default_actions': ('clobber', 08:15:40 INFO - 'read-buildbot-config', 08:15:40 INFO - 'download-and-extract', 08:15:40 INFO - 'populate-webroot', 08:15:40 INFO - 'create-virtualenv', 08:15:40 INFO - 'install', 08:15:40 INFO - 'run-tests'), 08:15:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:15:40 INFO - 'download_minidump_stackwalk': True, 08:15:40 INFO - 'download_symbols': 'ondemand', 08:15:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:15:40 INFO - 'tooltool.py': '/tools/tooltool.py', 08:15:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:15:40 INFO - '/tools/misc-python/virtualenv.py')}, 08:15:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:15:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:15:40 INFO - 'installer_path': 'installer.exe', 08:15:40 INFO - 'log_level': 'info', 08:15:40 INFO - 'log_name': 'talos', 08:15:40 INFO - 'log_to_console': True, 08:15:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:15:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:15:40 INFO - 'opt_config_files': (), 08:15:40 INFO - 'pip_index': False, 08:15:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:40 INFO - 'cmd': ('bash', 08:15:40 INFO - '-c', 08:15:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:40 INFO - 'enabled': True, 08:15:40 INFO - 'halt_on_failure': False, 08:15:40 INFO - 'name': 'check_screen_resolution'},), 08:15:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:40 INFO - 'cmd': ('bash', 08:15:40 INFO - '-c', 08:15:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:40 INFO - 'enabled': True, 08:15:40 INFO - 'halt_on_failure': False, 08:15:40 INFO - 'name': 'check_screen_resolution'},), 08:15:40 INFO - 'run_cmd_checks_enabled': True, 08:15:40 INFO - 'sps_profile': False, 08:15:40 INFO - 'sps_profile_interval': 0, 08:15:40 INFO - 'suite': 'dromaeojs-e10s', 08:15:40 INFO - 'system_bits': '32', 08:15:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:15:40 INFO - 'title': 't-yosemite-r7-0219', 08:15:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:15:40 INFO - 'use_talos_json': True, 08:15:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:15:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:15:40 INFO - 'work_dir': 'build'} 08:15:40 INFO - [mozharness: 2017-01-31 16:15:40.830761Z] Running clobber step. 08:15:40 INFO - Running pre-action listener: _resource_record_pre_action 08:15:40 INFO - Running main action method: clobber 08:15:40 INFO - rmtree: /builds/slave/test/build 08:15:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:15:43 INFO - Running post-action listener: _resource_record_post_action 08:15:43 INFO - [mozharness: 2017-01-31 16:15:43.376576Z] Finished clobber step (success) 08:15:43 INFO - [mozharness: 2017-01-31 16:15:43.376866Z] Running read-buildbot-config step. 08:15:43 INFO - Running pre-action listener: _resource_record_pre_action 08:15:43 INFO - Running main action method: read_buildbot_config 08:15:43 INFO - Using buildbot properties: 08:15:43 INFO - { 08:15:43 INFO - "project": "", 08:15:43 INFO - "product": "firefox", 08:15:43 INFO - "script_repo_revision": "production", 08:15:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:15:43 INFO - "repository": "", 08:15:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:15:43 INFO - "buildid": "20170131065409", 08:15:43 INFO - "pgo_build": "False", 08:15:43 INFO - "basedir": "/builds/slave/test", 08:15:43 INFO - "buildnumber": 71, 08:15:43 INFO - "slavename": "t-yosemite-r7-0219", 08:15:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:15:43 INFO - "platform": "yosemite_r7", 08:15:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:15:43 INFO - "branch": "mozilla-central", 08:15:43 INFO - "repo_path": "mozilla-central", 08:15:43 INFO - "revision": "1d5f138d4af7822f724903b4e4676662d12d8b4b", 08:15:43 INFO - "stage_platform": "macosx64", 08:15:43 INFO - "builduid": "fc82d122069b494498e8f5f4a44e020b", 08:15:43 INFO - "slavebuilddir": "test" 08:15:43 INFO - } 08:15:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:15:43 INFO - Running post-action listener: _resource_record_post_action 08:15:43 INFO - [mozharness: 2017-01-31 16:15:43.382506Z] Finished read-buildbot-config step (success) 08:15:43 INFO - [mozharness: 2017-01-31 16:15:43.382708Z] Running download-and-extract step. 08:15:43 INFO - Running pre-action listener: _resource_record_pre_action 08:15:43 INFO - Running main action method: download_and_extract 08:15:43 INFO - mkdir: /builds/slave/test/build/tests 08:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:15:43 INFO - trying https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:15:43 INFO - Downloading https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:15:44 INFO - Downloaded 1199 bytes. 08:15:44 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:15:44 INFO - Using the following test package requirements: 08:15:44 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:15:44 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:15:44 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:15:44 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'jsshell-mac.zip'], 08:15:44 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:15:44 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:15:44 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:15:44 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:15:44 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:15:44 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:15:44 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:15:44 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:15:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:15:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:15:44 INFO - Fetch https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:15:45 INFO - Expected file size: 16367065 08:15:45 INFO - Obtained file size: 16367065 08:15:52 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:15:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:15:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:15:52 INFO - Fetch https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:15:53 INFO - Expected file size: 16367065 08:15:53 INFO - Obtained file size: 16367065 08:15:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:15:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:15:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:16:00 INFO - Expected file size: 14403454 08:16:00 INFO - Obtained file size: 14403454 08:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:00 INFO - trying https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:16:02 INFO - Downloaded 70153222 bytes. 08:16:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:16:02 INFO - mkdir: /builds/slave/test/properties 08:16:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:02 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:02 INFO - Contents: 08:16:02 INFO - build_url:https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:16:03 INFO - Running post-action listener: _resource_record_post_action 08:16:03 INFO - Running post-action listener: set_extra_try_arguments 08:16:03 INFO - [mozharness: 2017-01-31 16:16:03.495617Z] Finished download-and-extract step (success) 08:16:03 INFO - [mozharness: 2017-01-31 16:16:03.495768Z] Running populate-webroot step. 08:16:03 INFO - Running pre-action listener: _resource_record_pre_action 08:16:03 INFO - Running main action method: populate_webroot 08:16:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:03 INFO - '--tppagecycles', 08:16:03 INFO - '7'], 08:16:03 INFO - 'tests': ['tsvgm']}}, 08:16:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['damp', 'tps']}, 08:16:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'tests': ['damp', 'tps']}, 08:16:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['dromaeo_dom']}, 08:16:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['basic_compositor_video']}, 08:16:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:16:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['a11yr', 08:16:03 INFO - 'ts_paint', 08:16:03 INFO - 'tpaint', 08:16:03 INFO - 'sessionrestore', 08:16:03 INFO - 'sessionrestore_no_auto_restore', 08:16:03 INFO - 'tabpaint']}, 08:16:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['tsvgx', 08:16:03 INFO - 'tsvgr_opacity', 08:16:03 INFO - 'tart', 08:16:03 INFO - 'tscrollx', 08:16:03 INFO - 'cart', 08:16:03 INFO - 'tsvg_static']}, 08:16:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:03 INFO - 'tsvgr_opacity', 08:16:03 INFO - 'tart', 08:16:03 INFO - 'tscrollx', 08:16:03 INFO - 'cart', 08:16:03 INFO - 'tsvg_static']}, 08:16:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'talos_options': ['--disable-e10s'], 08:16:03 INFO - 'tests': ['tp5o']}, 08:16:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:16:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'talos_options': ['--disable-e10s', 08:16:03 INFO - '--xperf_path', 08:16:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:03 INFO - 'tests': ['tp5n']}, 08:16:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:16:03 INFO - 'talos_options': ['--xperf_path', 08:16:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:03 INFO - 'tests': ['tp5n']}}, 08:16:03 INFO - 'talos.zip': {'path': '', 08:16:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:03 INFO - Running post-action listener: _resource_record_post_action 08:16:03 INFO - [mozharness: 2017-01-31 16:16:03.514416Z] Finished populate-webroot step (success) 08:16:03 INFO - [mozharness: 2017-01-31 16:16:03.514538Z] Running create-virtualenv step. 08:16:03 INFO - Running pre-action listener: _resource_record_pre_action 08:16:03 INFO - Running main action method: create_virtualenv 08:16:03 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:03 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 08:16:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:03 INFO - Using real prefix '/tools/python27' 08:16:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:07 INFO - Installing pip.................done. 08:16:07 INFO - Return code: 0 08:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:16:07 INFO - Reading from file tmpfile_stdout 08:16:07 INFO - Output received: 08:16:07 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) 08:16:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:07 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:07 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 08:16:07 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 08:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:07 INFO - 'HOME': '/Users/cltbld', 08:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:07 INFO - 'LOGNAME': 'cltbld', 08:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:07 INFO - 'MOZ_NO_REMOTE': '1', 08:16:07 INFO - 'NO_EM_RESTART': '1', 08:16:07 INFO - 'PAGER': '/bin/cat', 08:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:07 INFO - 'PWD': '/builds/slave/test', 08:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:07 INFO - 'SHELL': '/bin/bash', 08:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:07 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:07 INFO - 'USER': 'cltbld', 08:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:07 INFO - 'XPC_FLAGS': '0x0', 08:16:07 INFO - 'XPC_SERVICE_NAME': '0', 08:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:07 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 08:16:07 INFO - Cleaning up... 08:16:07 INFO - Return code: 0 08:16:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:07 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:07 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 08:16:07 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 08:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:07 INFO - 'HOME': '/Users/cltbld', 08:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:07 INFO - 'LOGNAME': 'cltbld', 08:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:07 INFO - 'MOZ_NO_REMOTE': '1', 08:16:07 INFO - 'NO_EM_RESTART': '1', 08:16:07 INFO - 'PAGER': '/bin/cat', 08:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:07 INFO - 'PWD': '/builds/slave/test', 08:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:07 INFO - 'SHELL': '/bin/bash', 08:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:07 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:07 INFO - 'USER': 'cltbld', 08:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:07 INFO - 'XPC_FLAGS': '0x0', 08:16:07 INFO - 'XPC_SERVICE_NAME': '0', 08:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:08 INFO - Downloading/unpacking psutil>=3.1.1 08:16:08 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 08:16:08 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 08:16:08 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 08:16:08 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 08:16:08 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 08:16:08 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 08:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:11 INFO - Installing collected packages: psutil 08:16:11 INFO - Running setup.py install for psutil 08:16:11 INFO - building 'psutil._psutil_osx' extension 08:16:11 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 08:16: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 08:16: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 08:16: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 08:16:12 INFO - building 'psutil._psutil_posix' extension 08:16: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 08:16:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:12 INFO - ^ 08:16:12 INFO - 1 warning generated. 08:16:12 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 08:16:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:12 INFO - Successfully installed psutil 08:16:12 INFO - Cleaning up... 08:16:12 INFO - Return code: 0 08:16:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:12 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')]} 08:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:12 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')]} 08:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:12 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:12 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 08:16:12 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 08:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:12 INFO - 'HOME': '/Users/cltbld', 08:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:12 INFO - 'LOGNAME': 'cltbld', 08:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:12 INFO - 'MOZ_NO_REMOTE': '1', 08:16:12 INFO - 'NO_EM_RESTART': '1', 08:16:12 INFO - 'PAGER': '/bin/cat', 08:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:12 INFO - 'PWD': '/builds/slave/test', 08:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:12 INFO - 'SHELL': '/bin/bash', 08:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:12 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:12 INFO - 'USER': 'cltbld', 08:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:12 INFO - 'XPC_FLAGS': '0x0', 08:16:12 INFO - 'XPC_SERVICE_NAME': '0', 08:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:16:15 INFO - Installing collected packages: mozsystemmonitor 08:16:15 INFO - Running setup.py install for mozsystemmonitor 08:16:16 INFO - Successfully installed mozsystemmonitor 08:16:16 INFO - Cleaning up... 08:16:16 INFO - Return code: 0 08:16:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:16:16 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')]} 08:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 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')]} 08:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:16 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 08:16:16 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 08:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:16 INFO - 'HOME': '/Users/cltbld', 08:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:16 INFO - 'LOGNAME': 'cltbld', 08:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:16 INFO - 'MOZ_NO_REMOTE': '1', 08:16:16 INFO - 'NO_EM_RESTART': '1', 08:16:16 INFO - 'PAGER': '/bin/cat', 08:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:16 INFO - 'PWD': '/builds/slave/test', 08:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:16 INFO - 'SHELL': '/bin/bash', 08:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:16 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:16 INFO - 'USER': 'cltbld', 08:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:16 INFO - 'XPC_FLAGS': '0x0', 08:16:16 INFO - 'XPC_SERVICE_NAME': '0', 08:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:16:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:19 INFO - Downloading functools32-3.2.3-2.tar.gz 08:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:16:19 INFO - warning: no files found matching '*.txt' 08:16:19 INFO - no previously-included directories found matching 'build' 08:16:19 INFO - no previously-included directories found matching 'dist' 08:16:19 INFO - no previously-included directories found matching '.git*' 08:16:19 INFO - Installing collected packages: jsonschema, functools32 08:16:19 INFO - Running setup.py install for functools32 08:16:19 INFO - warning: no files found matching '*.txt' 08:16:19 INFO - no previously-included directories found matching 'build' 08:16:19 INFO - no previously-included directories found matching 'dist' 08:16:19 INFO - no previously-included directories found matching '.git*' 08:16:19 INFO - Successfully installed jsonschema functools32 08:16:19 INFO - Cleaning up... 08:16:19 INFO - Return code: 0 08:16:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:16:19 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')]} 08:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:19 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')]} 08:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:19 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:19 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 08:16:19 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 08:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:19 INFO - 'HOME': '/Users/cltbld', 08:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:19 INFO - 'LOGNAME': 'cltbld', 08:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:19 INFO - 'MOZ_NO_REMOTE': '1', 08:16:19 INFO - 'NO_EM_RESTART': '1', 08:16:19 INFO - 'PAGER': '/bin/cat', 08:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:19 INFO - 'PWD': '/builds/slave/test', 08:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:19 INFO - 'SHELL': '/bin/bash', 08:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:19 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:19 INFO - 'USER': 'cltbld', 08:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:19 INFO - 'XPC_FLAGS': '0x0', 08:16:19 INFO - 'XPC_SERVICE_NAME': '0', 08:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:16:19 INFO - Cleaning up... 08:16:19 INFO - Return code: 0 08:16:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16:19 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')]} 08:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:20 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')]} 08:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:20 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:20 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 08:16:20 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 08:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:20 INFO - 'HOME': '/Users/cltbld', 08:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:20 INFO - 'LOGNAME': 'cltbld', 08:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:20 INFO - 'MOZ_NO_REMOTE': '1', 08:16:20 INFO - 'NO_EM_RESTART': '1', 08:16:20 INFO - 'PAGER': '/bin/cat', 08:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:20 INFO - 'PWD': '/builds/slave/test', 08:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:20 INFO - 'SHELL': '/bin/bash', 08:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:20 INFO - 'USER': 'cltbld', 08:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:20 INFO - 'XPC_FLAGS': '0x0', 08:16:20 INFO - 'XPC_SERVICE_NAME': '0', 08:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:20 INFO - Downloading/unpacking blobuploader==1.2.4 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:23 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 08:16:23 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 08:16:23 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 08:16:23 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 08:16:23 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 08:16:23 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 08:16:24 INFO - Downloading docopt-0.6.1.tar.gz 08:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:24 INFO - Running setup.py install for blobuploader 08:16:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:24 INFO - Running setup.py install for requests 08:16:24 INFO - Running setup.py install for docopt 08:16:24 INFO - Successfully installed blobuploader requests docopt 08:16:24 INFO - Cleaning up... 08:16:25 INFO - Return code: 0 08:16:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:25 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')]} 08:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 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')]} 08:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:25 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 08:16:25 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 08:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:25 INFO - 'HOME': '/Users/cltbld', 08:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:25 INFO - 'LOGNAME': 'cltbld', 08:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:25 INFO - 'MOZ_NO_REMOTE': '1', 08:16:25 INFO - 'NO_EM_RESTART': '1', 08:16:25 INFO - 'PAGER': '/bin/cat', 08:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:25 INFO - 'PWD': '/builds/slave/test', 08:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:25 INFO - 'SHELL': '/bin/bash', 08:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:25 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:25 INFO - 'USER': 'cltbld', 08:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:25 INFO - 'XPC_FLAGS': '0x0', 08:16:25 INFO - 'XPC_SERVICE_NAME': '0', 08:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ahzBgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jQHTjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-FigdO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jdXpZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-EN0XwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5lXtw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-UKhiWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5rlilB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WfFegY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-iti8UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ye512I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VjPdAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JnIplB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-azqHL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1uXzWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-6H5fV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-klZWFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:27 INFO - Running setup.py install for manifestparser 08:16:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:27 INFO - Running setup.py install for mozcrash 08:16:27 INFO - Running setup.py install for mozdebug 08:16:27 INFO - Running setup.py install for mozdevice 08:16:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:16:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:27 INFO - Running setup.py install for mozfile 08:16:27 INFO - Running setup.py install for mozhttpd 08:16:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:27 INFO - Running setup.py install for mozinfo 08:16:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Running setup.py install for mozInstall 08:16:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Running setup.py install for mozleak 08:16:28 INFO - Running setup.py install for mozlog 08:16:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Running setup.py install for moznetwork 08:16:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Running setup.py install for mozprocess 08:16:28 INFO - Running setup.py install for mozprofile 08:16:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:28 INFO - Running setup.py install for mozrunner 08:16:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:29 INFO - Running setup.py install for mozscreenshot 08:16:29 INFO - Running setup.py install for moztest 08:16:29 INFO - Running setup.py install for mozversion 08:16:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:29 INFO - Cleaning up... 08:16:29 INFO - Return code: 0 08:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:29 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')]} 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 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')]} 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:29 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 08:16:29 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 08:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:29 INFO - 'HOME': '/Users/cltbld', 08:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:29 INFO - 'LOGNAME': 'cltbld', 08:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:29 INFO - 'MOZ_NO_REMOTE': '1', 08:16:29 INFO - 'NO_EM_RESTART': '1', 08:16:29 INFO - 'PAGER': '/bin/cat', 08:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:29 INFO - 'PWD': '/builds/slave/test', 08:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:29 INFO - 'SHELL': '/bin/bash', 08:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:29 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:29 INFO - 'USER': 'cltbld', 08:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:29 INFO - 'XPC_FLAGS': '0x0', 08:16:29 INFO - 'XPC_SERVICE_NAME': '0', 08:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-GQdxcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:29 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)) 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-fyrq0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dzuZpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dOgn9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-c00G1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-okMDaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-BKnTZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-4YNCvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-eJp2bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-8cmGMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:30 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)) 08:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-G7eyDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-L95spE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dOXncn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-zHYtiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VkuC9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-wfVsL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-LUBROC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:31 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)) 08:16:31 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)) 08:16:31 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)) 08:16:31 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)) 08:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:16:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16:31 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 08:16:31 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 08:16:31 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 08:16:31 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 08:16:31 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 08:16:31 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 08:16:34 INFO - Downloading blessings-1.6.tar.gz 08:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:16:34 INFO - Installing collected packages: blessings 08:16:34 INFO - Running setup.py install for blessings 08:16:34 INFO - Successfully installed blessings 08:16:34 INFO - Cleaning up... 08:16:34 INFO - Return code: 0 08:16:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:34 INFO - Reading from file tmpfile_stdout 08:16:34 INFO - Current package versions: 08:16:34 INFO - blessings == 1.6 08:16:34 INFO - blobuploader == 1.2.4 08:16:34 INFO - docopt == 0.6.1 08:16:34 INFO - functools32 == 3.2.3-2 08:16:34 INFO - jsonschema == 2.5.1 08:16:34 INFO - manifestparser == 1.1 08:16:34 INFO - mozInstall == 1.12 08:16:34 INFO - mozcrash == 1.0 08:16:34 INFO - mozdebug == 0.1 08:16:34 INFO - mozdevice == 0.49 08:16:34 INFO - mozfile == 1.2 08:16:34 INFO - mozhttpd == 0.7 08:16:34 INFO - mozinfo == 0.9 08:16:34 INFO - mozleak == 0.1 08:16:34 INFO - mozlog == 3.4 08:16:34 INFO - moznetwork == 0.27 08:16:34 INFO - mozprocess == 0.24 08:16:34 INFO - mozprofile == 0.28 08:16:34 INFO - mozrunner == 6.13 08:16:34 INFO - mozscreenshot == 0.1 08:16:34 INFO - mozsystemmonitor == 0.3 08:16:34 INFO - moztest == 0.8 08:16:34 INFO - mozversion == 1.4 08:16:34 INFO - psutil == 3.1.1 08:16:34 INFO - requests == 1.2.3 08:16:34 INFO - wsgiref == 0.1.2 08:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:34 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')]} 08:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:34 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')]} 08:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:34 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:34 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 08:16:34 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 08:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:34 INFO - 'HOME': '/Users/cltbld', 08:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:34 INFO - 'LOGNAME': 'cltbld', 08:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:34 INFO - 'MOZ_NO_REMOTE': '1', 08:16:34 INFO - 'NO_EM_RESTART': '1', 08:16:34 INFO - 'PAGER': '/bin/cat', 08:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:34 INFO - 'PWD': '/builds/slave/test', 08:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:34 INFO - 'SHELL': '/bin/bash', 08:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:34 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:34 INFO - 'USER': 'cltbld', 08:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:34 INFO - 'XPC_FLAGS': '0x0', 08:16:34 INFO - 'XPC_SERVICE_NAME': '0', 08:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 INFO - Cleaning up... 08:16:35 INFO - Return code: 0 08:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 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 0x108886b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe589495300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, '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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 08:16:35 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 08:16:35 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 08:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:35 INFO - 'HOME': '/Users/cltbld', 08:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:35 INFO - 'LOGNAME': 'cltbld', 08:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:35 INFO - 'MOZ_NO_REMOTE': '1', 08:16:35 INFO - 'NO_EM_RESTART': '1', 08:16:35 INFO - 'PAGER': '/bin/cat', 08:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:35 INFO - 'PWD': '/builds/slave/test', 08:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:35 INFO - 'SHELL': '/bin/bash', 08:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:35 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:35 INFO - 'USER': 'cltbld', 08:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:35 INFO - 'XPC_FLAGS': '0x0', 08:16:35 INFO - 'XPC_SERVICE_NAME': '0', 08:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:16:35 INFO - Cleaning up... 08:16:35 INFO - Return code: 0 08:16:35 INFO - Running post-action listener: _resource_record_post_action 08:16:35 INFO - Running post-action listener: _start_resource_monitoring 08:16:35 INFO - Starting resource monitoring. 08:16:35 INFO - [mozharness: 2017-01-31 16:16:35.474166Z] Finished create-virtualenv step (success) 08:16:35 INFO - [mozharness: 2017-01-31 16:16:35.475110Z] Running install step. 08:16:35 INFO - Running pre-action listener: _resource_record_pre_action 08:16:35 INFO - Running main action method: install 08:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:35 INFO - Reading from file tmpfile_stdout 08:16:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:16:35 INFO - Reading from file tmpfile_stdout 08:16:35 INFO - Output received: 08:16:35 INFO - Usage: mozinstall [options] installer 08:16:35 INFO - Options: 08:16:35 INFO - -h, --help show this help message and exit 08:16:35 INFO - -d DEST, --destination=DEST 08:16:35 INFO - Directory to install application into. [default: 08:16:35 INFO - "/builds/slave/test"] 08:16:35 INFO - --app=APP Application being installed. [default: firefox] 08:16:35 INFO - mkdir: /builds/slave/test/build/application 08:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:16:57 INFO - Reading from file tmpfile_stdout 08:16:57 INFO - Output received: 08:16:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:16:57 INFO - Running post-action listener: _resource_record_post_action 08:16:57 INFO - [mozharness: 2017-01-31 16:16:57.899642Z] Finished install step (success) 08:16:57 INFO - [mozharness: 2017-01-31 16:16:57.899845Z] Running run-tests step. 08:16:57 INFO - Running pre-action listener: _resource_record_pre_action 08:16:57 INFO - Running main action method: run_tests 08:16:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:16:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:16:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:16:57 INFO - 2017-01-31 08:16:57.914 screenresolution[1634:8159] starting screenresolution argv=screenresolution get 08:16:57 INFO - 2017-01-31 08:16:57.917 screenresolution[1634:8159] Display 0: 1600x1200x32@60 08:16:57 INFO - 2017-01-31 08:16:57.923 screenresolution[1635:8162] starting screenresolution argv=screenresolution list 08:16:57 INFO - Available Modes on Display 0 08:16:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:16:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:16:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:16:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:16:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:16:58 INFO - Intel Iris: 08:16:58 INFO - Chipset Model: Intel Iris 08:16:58 INFO - Type: GPU 08:16:58 INFO - Bus: Built-In 08:16:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:16:58 INFO - Vendor: Intel (0x8086) 08:16:58 INFO - Device ID: 0x0a2e 08:16:58 INFO - Revision ID: 0x0009 08:16:58 INFO - Displays: 08:16:58 INFO - Display: 08:16:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:16:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:16:58 INFO - Display Serial Number: 1600x1200 60 08:16:58 INFO - Main Display: Yes 08:16:58 INFO - Mirror: Off 08:16:58 INFO - Online: Yes 08:16:58 INFO - Rotation: Supported 08:16:58 INFO - Return code: 0 08:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:16:58 INFO - Python 2.7.3 08:16:58 INFO - Return code: 0 08:16:58 INFO - grabbing minidump binary from tooltool 08:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108664d30>, '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 0x10858bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108659be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:16:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:16:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:16:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:16:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:16:58 INFO - Return code: 0 08:16:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:16:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:16:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:16:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:16:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 08:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --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 08:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uhtJyrb7Jg/Render', 08:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:58 INFO - 'HOME': '/Users/cltbld', 08:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:58 INFO - 'LOGNAME': 'cltbld', 08:16:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:16:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:58 INFO - 'MOZ_NO_REMOTE': '1', 08:16:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:16:58 INFO - 'NO_EM_RESTART': '1', 08:16:58 INFO - 'PAGER': '/bin/cat', 08:16:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:58 INFO - 'PWD': '/builds/slave/test', 08:16:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:58 INFO - 'SHELL': '/bin/bash', 08:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oZV2BCWze6/Listeners', 08:16:58 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 08:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:58 INFO - 'USER': 'cltbld', 08:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:58 INFO - 'XPC_FLAGS': '0x0', 08:16:58 INFO - 'XPC_SERVICE_NAME': '0', 08:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 08:16:58 INFO - mozversion application_buildid: 20170131065409 08:16:58 INFO - mozversion application_changeset: 1d5f138d4af7822f724903b4e4676662d12d8b4b 08:16:58 INFO - mozversion application_display_name: Nightly 08:16:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:16:58 INFO - mozversion application_name: Firefox 08:16:58 INFO - mozversion application_remotingname: firefox 08:16:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:16:58 INFO - mozversion application_vendor: Mozilla 08:16:58 INFO - mozversion application_version: 54.0a1 08:16:58 INFO - mozversion platform_buildid: 20170131065409 08:16:58 INFO - mozversion platform_changeset: 1d5f138d4af7822f724903b4e4676662d12d8b4b 08:16:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:16:58 INFO - mozversion platform_version: 54.0a1 08:16:58 INFO - using testdate: 1485879418 08:16:58 INFO - actual date: 1485879418 08:16:58 INFO - starting webserver on 'localhost:49240' 08:16:58 INFO - SUITE-START | Running 2 tests 08:16:58 INFO - TEST-START | dromaeo_css 08:16:58 INFO - operating with platform_type : mac_ 08:16:58 INFO - Initialising browser for dromaeo_css test... 08:16:58 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile http://localhost:49240/getInfo.html) 08:17:01 INFO - PROCESS | 1645 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:17:01 INFO - PROCESS | 1645 | 2017-01-31 08:17:01.733 plugin-container[1647:8329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 08:17:01 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:01 INFO - PROCESS | 1645 | 2017-01-31 08:17:01.736 plugin-container[1647:8329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1b, name = 'com.apple.CFPasteboardClient' 08:17:01 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:01 INFO - PROCESS | 1645 | 2017-01-31 08:17:01.736 plugin-container[1647:8329] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:01 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 08:17:01 INFO - PROCESS | 1645 | colorDepth:24 08:17:01 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 08:17:01 INFO - PROCESS | 1645 | __metrics 08:17:01 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 08:17:01 INFO - PROCESS | 1645 | 1485879421885 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:17:02 INFO - TEST-INFO | 1645: exit 0 08:17:02 INFO - Browser initialized. 08:17:02 INFO - Running cycle 1/1 for dromaeo_css test... 08:17:02 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpDQ7Vl0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:05 INFO - PROCESS | 1649 | 2017-01-31 08:17:05.207 plugin-container[1650:8454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8757, name = 'com.apple.tsm.portname' 08:17:05 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:05 INFO - PROCESS | 1649 | 2017-01-31 08:17:05.207 plugin-container[1650:8454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:17:05 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:05 INFO - PROCESS | 1649 | 2017-01-31 08:17:05.207 plugin-container[1650:8454] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:14 INFO - PROCESS | 1649 | RSS: Main: 233754624 08:17:14 INFO - PROCESS | 1649 | 08:20:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:20:19 INFO - PROCESS | 1649 | RSS: Main: 263712768 08:20:19 INFO - PROCESS | 1649 | 08:22:02 INFO - PROCESS | 1649 | ************************* 08:22:02 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 08:22:02 INFO - PROCESS | 1649 | 08:22:02 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:22:02 INFO - PROCESS | 1649 | Full stack: 08:22:02 INFO - PROCESS | 1649 | ************************* 08:23:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:23:22 INFO - PROCESS | 1649 | RSS: Main: 269279232 08:23:22 INFO - PROCESS | 1649 | 08:26:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:26:24 INFO - PROCESS | 1649 | RSS: Main: 280174592 08:26:24 INFO - PROCESS | 1649 | 08:29:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:29:27 INFO - PROCESS | 1649 | RSS: Main: 274235392 08:29:27 INFO - PROCESS | 1649 | 08:32:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:32:30 INFO - PROCESS | 1649 | RSS: Main: 279134208 08:32:30 INFO - PROCESS | 1649 | 08:35:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:35:34 INFO - PROCESS | 1649 | RSS: Main: 282128384 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | __start_tp_report 08:35:34 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 08:35:34 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 08:35:34 INFO - PROCESS | 1649 | |i|pagename|runs| 08:35:34 INFO - PROCESS | 1649 | |0;dojo.html;3281.34373125375;3288.3587700398425;3334.5164951082093;3335.5997280326355;3349.0790032741006;5485.314335708032;5505.091659876116;5512.1456174293;5512.91730624041;5632.155176723493;4602.631789456844;5498.972505137479;5595.524380427669;5669.177969194475;5676.687782171979;4272.636825869805;5026.371703537058;5106.948930510703;5221.530062294392;5254.185601231796;5073.644844860862;5219.582433405338;5223.529882310591;5241;5261.736913154327;5044.873878153039;5098.821541246039;5173.793048278064;5206.401263854629;5274.340707411574;5060.493950604947;5084.161113416282;5122.974385128088;5185.974070129663;5212.895742085175;5068.467810879842;5107.438181799999;5107.463716309771;5121.28302037715;5126.794928202868;4907.607391408679;4944.9258261126115;5495.120780675113;5654.519365853907;5674.262345895048;6035.758569657209;6104.337893874282;6149.631022138686;6163.907541386883;6224.09750586167;6256.624602523863;6260.373962603749;6296.275928268216;6305.558610897239;6319;5747.540196784247;5774.39368866263;5802.12968054791;5808.157817116579;5836.708164591796;6165.290991535941;6187.721552530147;6197.25632924052;6225.408586184305;6250.531210159256;5041.7227052511735;6120.355709576947;6132.356102609251;6137.775380989496;6144.678894037804;6169.660668663267;6254.968725156436;6268.153799237045;6268.4671802896355;6276.995680691068;6033.728482218311;6081.027035674271;6090.725917333731;6116.8776624468155;6133.51829027926;5468.7539060742365;5523.613347065667;6147.770445910835;6206.503479721611;6213.968930155411;4980.526849949249;4993.2010878260035;4998.2002879539095;5010.549050585392;5035.647504674711;4322.092360604245;4375.7155784873885;4462.464504259509;4479.686421950498;4494.955050449519;5755.194272801811;5767.192593036978;5800.564957628195;5822.941770582324;5916.585838991229;5282.709450980156;6136.6011209271255;6266.154069200691;6282.591631544026;6301.684915754194;5692.31692196939;5821.592488525847;5824.068149096124;5832.650040997553;5836.358000619928;5294.92057619136;5370;5382.165764306539;5391.514763671288;5488.91766623501;6296.590721603081;6348.4286414222015;6356.523260755369;6362.172917520693;6364.586301890362;6270.341614130451;6290.842728931813;6299.464545513682;6304.432601065926;6325.240971083499;6191.4118158774845;6200.689965501617;6200.844978875564;6229.283632382334;6235.937640623536;6134.492701656909;6190.690465476799;6202.627842329474;6214.875702486015;6219.160413344322;6232.345603711545;6239.656818875005;6281.057841323838;6295.96852015737;6301.40136687014;4827.793051737066;5745.770169193185;5819.94180058194;5987.6108052976415;5990.9700451497465;6013.609115407485;6023.518118550418;6024.638521688713;6062.90905636407;6119.418655227746;4774.928376074431;5716.314042314949;5824.708764561839;5896.557758168069;5917.437843404869;5850.414958504116;5972.25346831646;5987.311459182076;6046.27444706638;6106.389361064036;5877.676727780014;5886.117082437669;5887.116932460165;5902.1146827976145;5903.350631430453 08:35:34 INFO - PROCESS | 1649 | |1;ext.html;450.39871771185466;459.71267957526544;497.39318032000944;500.6120256800979;528.9947100528993;40552.98617534563;41254.58745412549;41317.3802392964;41464;41543;42839.78580107095;43328;43364.349534757006;43408.56591434085;43452.78273608635;8451;9198.402103863244;9528.09483099108;9544.713658590254;9551.665691700791;8429.789255268606;11258.54965801367;11521.19351645381;11550.422478876022;11743.647690569296;11466.656000320003;11533.96194342509;11536.826947595751;11544.711382215428;11552.53789848405;9644.373115747489;11584.362860042736;11598.536058557647;11605.76788464226;11628.302301861831;11363.715907102305;11442.256253343507;11446.14153938458;11471.827922581168;11478.139139564566;39706.80146599249;40728.38907416391;41008.38487422691;41149.97125071897;41212.175756485005;4053.412255222976;4060.675145988314;4086.5504794472586;4113.403556484293;4237.4915010198665;22269.22057727988;30011.549826752616;30123;30176.34030128342;31594.526082108787;3134.435801555719;3173.3811906678206;3194.0577529628818;3225.2743132795167;3285.65500622436;356779;358288;359124;359394;361127.9999999974;51084.48915510797;60542.78914421687;60673.69663151656;60700.69649651724;60802.695986519786;62465.687671562264;62663.36029757344;62762.0585691215;62822;63127.36872631307;15919.363225471083;16159.192040397933;16179.757303640456;16216.513504594644;16239.02565846053;11179.329240245612;11463.082953363712;12178.269303841798;12563.994880409546;12647.304398258;14970.700585988438;15334.923325383525;15634.68730625381;15738.291776870068;15820.367185312458;5887.61730487517;7023.8595228095155;7024.2273349931465;7057.788266352018;7077.504574679723;843.9451435656663;856.1181982557976;867.6876324523166;869.4044579463088;874.5802015032692;26209;27154.728452715615;29592.408151836844;30118.84940575327;30723.38553228923;898.5686870302292;1017.8473229015707;1052.9736756580994;1068.417712346773;1173.6479056283251;12637.557685480964;12666;15163.620909477351;15167.696646067017;16079.196040198178;235767;236235;236287;236391.81804091213;236837;59697.403025969186;59863.102053469236;60025.699871500365;60061.699691501264;60175.699121504986;1354.3566805767182;1390.6314826570967;1407.774756039029;1411.964700882486;1421.3390773290264;25042.874785625954;28953;29613.927025108896;30138.54792178163;30423;49956;50366.748166258934;50375.24437133519;50392;50443.747781260856;550675.2466237643;553314.2334288303;554445;554691;554830;32593.674063259063;34660;39577.406338904366;39915;39996.60003399929;45121.09757804891;46761;47405.05189896252;47624.28563571619;48130;32641.836790815894;36995.815020924725;39002.804985974886;39355;40243.597564023985;38857;39002.80498597602;39742.60900868456;39877.2024559513;40382.99042523843;37052.44421333628;37528;37677.681281155026;38330.23339533249;38747;27349.45301094007;31116.533252000787;33328.666853325594;34719.47920781241;34824.47763283603;18786.060696964825;23164;23583;25600.3996003996;25609.871950640874 08:35:34 INFO - PROCESS | 1649 | |2;jquery.html;375.7764130342447;376.8059449383568;387.6549870615153;389.65126212040207;411.6871177904793;15501.457448989288;20892.8955355223;20977.895110524427;21431.99999999998;21481.57036859264;21023.68464473034;21038.89480552599;21238.57522849542;21431.035603397846;21870.78129218707;5577.776888924438;5596.664200147994;5729.369769325392;5772.422757724236;5779.133130030508;4092.815823287944;4311.6335111515555;5697.487226149646;5705.971470142644;6155.230596175478;6467.644279564622;6484.837879053014;6500.902486462707;6505.0892874997535;6512.153420055386;5432.158015507583;6398.096006293525;6454.160959075337;6461.030845373185;6478.060681201199;5512.834614961557;5578.944210557924;5639.182318563785;5650.519705824969;5658.547316214693;26502.469950601073;26754.46491070187;26765;26807.061752838752;26931.73068269312;2756;2759.7930155238437;2762.944741105187;2764.405652784641;2788.6793018802896;13076.304902126136;15246;15377.077375357403;16020.519384418487;16073.910391649128;1818.8817726847844;1827.0955876840917;1836.4857839804877;1844.880082794614;1845.6308738252403;125939;129748;130110;130950.42892279482;131745;11397.316161030365;11401.20191586581;11567.826482602768;11645.12661550387;11811.822822657667;14166.220857852917;14281.78577321341;14328.928355358157;14332.355044023045;14373.56879293602;7968.884356190138;8438.240558349778;8568.314534837198;8620;8750.737477875547;7707.4604777666145;8379.114699192754;8447.577621118919;8452.070272270046;8624.18070283322;7073.92926070743;7146.03528523653;7190.787757051245;7197.352238298578;7272.854542909112;1627.3897288516807;1709.6666150100612;1854.5178253653878;1884.2651365967283;1898.8480921526245;389.70382509292574;947.4788866123605;1012.5595366015807;1027.669168426889;1051.794899994509;8670.696525621574;9305.953470232605;10828.891711082946;11743.354115523729;11994.700132496582;413.7765606572477;428.7791787229579;436.1973967899081;438.51763060633687;443.0408166319946;5697.145428185722;5917.01779903737;6020.909686354708;6033.698315084316;6050.031994880798;712582;727939;732941;744088.0852063404;751499;160821.1958940198;161212;161457;163485.3651463494;164543;427.79679652165595;427.87377723571;433.3261777935278;436.64195359804654;437.71767210149915;13733.587992359846;16817.41139060127;18157.54606134857;20968.378952630923;21221.681674774583;63296.683516583966;63314;63451;63780.68109659422;64483.355166447735;86762;86894.56552717196;87743;88281.55859220662;89143.10856891607;8147.226013528705;8194.001658987894;8211.507309561446;8493.87259191125;8568.785780355542;9260;10129.949350253202;10661.57353705874;12495.875041249472;12980.545680901123;11350.489227984595;11539.711507212387;11610.709732256424;11908.915939710689;11986.160968732105;11242.437878106226;11302.604408845651;11503.309801411942;11801.055915526566;11926.642200733992;11256.887431125584;11282.04102651251;11878.584249551224;11990.800919907939;12167.695807604881;8254.174582541938;8347.402175758978;8367.832643347221;8403.453775504573;8469.237768600855;11262.83105753398;11325.490352934303;11640.94179529097;11671;11733.765324693288 08:35:34 INFO - PROCESS | 1649 | |3;mootools.html;1241.3172754984757;1276.4575055601379;1391.4781956766212;1423.6939058102505;1441.517091774256;7325.560466372008;7522.285382888617;7804.219578042193;8271.91728082719;8284.585770711463;7104.609246491454;7635.312821846032;7700.1914798946145;8030.357571394289;8159.428839981204;2202.8127609153203;2246.86518808872;2252.324302709189;2254.413852398372;2262.9434264143447;2005.9899700501478;2217.390217690132;2243.2372993182316;2279.9886000569973;2285.90856365746;2031.2484380779133;2193.9890300548477;2270.8069814065825;2279.2136712834044;2288.565172617206;2246.4945387287894;2260.8982595783227;2261.1407665087327;2267.557826223887;2271;2219.7225346831647;2266.1388672304583;2281.97718022821;2283.1552325639454;2291.862488250694;8010.759677209693;8219.86283566271;8221.465604735733;8221.917780822236;8231.794205144859;4942.654014219006;5093.2105523643895;5107.1828507439;5110.565601923804;5112.36095488064;3163.0510846745883;3210.807351558891;3218.951715724266;3257.3485302939507;3398.456247000468;1182.9704257393548;1199.6461043992044;1206.9818952715716;1214.5081242096876;1242.5899453180518;10331.431771252579;10391.168706503537;10445.47772611374;10450.947745261301;10477.214208934283;3916.8629097981434;4256.936145957813;4393.165298593257;4534.478534968455;4573.63410927125;7414.6292685365515;7561.848763024819;9099.363044586948;9120.680776172801;9135.95432022849;4310.870673879788;4974.328465657162;5022;5052.267421223975;5137.306463627438;4520.593146616755;4653.092646933883;4653.371794807726;4681.836135735233;4712.410948631421;3003.663369800418;3608.45873119029;3615.620359862229;3677.3748462761396;3684.871029513954;1053.8946105389552;1128.2666266926562;1134.8978591926766;1135.4493070860626;1137.6018393562188;743.9293267139722;783.5455435847194;854.9102344253888;864.7319331007408;892.3842548641417;5270.868228294246;5474.91787623186;5514.200440936042;5570;5582.665040097607;926.972190834276;935.4480856294683;936.639393833379;948.8908775490769;976.4287891583504;3581.4806853006166;3634.236810269873;3749.6812770914776;3751.9437208441896;3790;9329.72010839676;9684.225261979025;9701.466419346863;9703.708888733348;9716.222702183808;7490.812729681802;9523.190528804991;9591.472469014132;9669.371490853073;9675.758106047406;7358.632068396666;7480.513766605153;7673.923260767432;7706.460547761603;7718.228177182237;5134.28120063184;5262.789488420421;5335.279737235579;5439.238506608999;5460.31746031746;7457.47797654159;7986;8189.508629482012;8979.191872731486;8987.281017518451;8801.207891289814;9108.863367049633;9146.908530914605;9184.724458266262;9195.448273103635;6360.173177486897;6421.614703117828;6472.935270647234;6526.836829079311;6532.608043517404;7550.509216900884;7651.846963060596;7652.426068045031;7658;7667.3099421052375;6697.296783837627;6880.380765731108;6904.171499419901;6941.2364639888565;7009.684564194521;6549.214094308713;6720.932790672031;6765.661716914036;6805.455563554805;6835.93164068353;5985.551083668829;6170.413810687977;6205.6586887721605;6290.748370065146;6293.685315734287;4841;4879.707217566957;4887.486813884633;4943.332650092191;4955.40535135786;4592.7014744041535;4634.582887540138;4705.6000239979985;4775.044991001883;4849.612031037579 08:35:34 INFO - PROCESS | 1649 | |4;prototype.html;335.84047577400736;353.15771884056534;362.711644242827;365.55402409060935;369.941965977305;4826.927596086061;4960.156773348531;5164.9856555943215;5374.3013408256875;5606;4407.360932664769;5285.841424757264;5443.564514838813;5473.917891231635;5482.5613950883935;3786.924261514768;4281.828726850922;4297.677674174433;4301.311790113583;4321.783910804463;3455;3884.844606215748;3914.902127446808;4146.325619975114;4212.073343864345;4099.7745124018165;4123.422720819088;4137.77242251676;4138.730982486144;4153.875383738493;3953.2641653637324;4132.380142978547;4142.875713728593;4146.253674338618;4147.937780933288;3144.842757862121;3577.302426026926;3589.982050089759;3599.748017638766;3600.641579765258;4736.016309724886;5079.619028572835;5209.322788037531;5338.973305133488;5405.783768649249;4196.454460920061;4727.652619003602;4729.62163026957;4741.193997020515;4774.976125119352;5653.462921022497;5843.415658434165;5848.660535785677;5867.970660146672;5885.322178408814;3704.944425833615;3773.924521509582;3826.1199924017633;3927.3897701942064;3930.744501607386;4858.805647774085;6109.969450152766;6155.815325540241;6191.752329906798;6271.435570798604;3990.2618015666612;3999.6200360965663;4006.7395619284653;4010.2981978153707;4010.939835902464;3276.6559511251453;3342.4986252062386;3423;4017.1764788218347;4043.130726893673;3749.7000239980734;4267.7225980311805;4313.374560688682;4450.687047321048;4759.976200118977;4879.780409881564;5881.2942446906645;5898.203742494794;5911.812629327176;5950.732217050243;1248.8501379834297;1276.074845736852;1283.4930202569933;1293.1723696834033;1322.9933850330879;857.8541647919868;876.8114855306012;885.6501681835639;890.398980688033;890.9353322779347;4561.475430325555;4949.084419382426;5189.065968125698;5258.5267325940085;5290.761915713802;5216.765245563883;5353.866153346197;5553.250311208016;5589.413111623302;5748.223989761329;4496.887577810581;4707.728128950346;5230.398504172069;5254.264402983586;5275.683458992473;2474.9481470375017;2769.030839206302;2826.4488424757387;2857.3428111534463;2897.246715853851;5099.5410413063;5671;6020.969895150496;6034.818955431344;6064.393560643988;5129.897402052013;5307.3896501902;5392.973035134878;5870.295564532284;5937.229859039541;5159.58723302135;5917.911231331534;5932.317783454958;5961.7615295387695;5961.823934264049;4838.371011768448;5046.369203849519;5368.677879327252;5828.854278642898;5837.5038121758425;4846.642939976812;5092.567131793839;5136.563392111712;5326.520613144836;5516.889662206653;4981.950180498294;5663.292088488939;5690.886182276248;6046.939530604637;6073.635581865102;3069.5088785794164;3204.279037216608;3463.0564020582306;3788.734788564884;3869.9806500967316;5232.790688372423;5424.267723857229;5717.799262154994;6005.609635373687;6078.240219972504;4643.141018911512;4856;5752.568557358299;5766.740496677743;5923.348431672599;4764.904701906012;5311.468853114658;5880.26496687914;5888.175655408332;5932.317783454958;4789.928151077806;5299.443558426464;5306.6550674206055;5539.418361072029;5789.421057894177;4480.417545719036;5890.440408161217;5893.705314734332;5900.616459930091;5901.645901245939;5759.568032397503;5779.826605201851;5783.652980821164;5786.826395208151;5791.47876691083 08:35:34 INFO - PROCESS | 1649 | |5;yui.html;705.2348202200616;707.6780065070393;731.3819822250198;787.3937068457288;847.8812966184734;14945.850541494581;15944.521664350059;15951.122688252142;16025.198740062993;16049.67900641988;14591.854081459182;14690.926545367258;14722.484713035046;14753.11481311122;14769.335379907903;600.2826622186489;668.3216535216759;852.6674596907211;868.2272777228272;876.4434584039149;867.7396780965717;871.2507243770352;883.2404132446092;883.3463237204454;885.9734207973771;848.6591185926221;860.5568132411781;936.6440752514035;939.6992962252082;947.6304241345881;932.7714709896085;932.8134373125368;935.4247138010143;936.0405584276103;937.2689302344182;684.948628852838;695.3498478922224;697.476892330752;697.5326531224041;697.7836870570139;15132.092074475566;15441.845581544154;15563.533094007085;15634.765478517718;15732.213389330487;13725.862741372559;13780.173189608655;14812.70374592502;14855.034422762594;14935.327910244065;11798.52805887772;11859.288442693465;11893.643190704206;11957.52169913211;12349.93825030878;3700.3709369407266;3756.4365345198166;3808.333541630231;3922.666573341269;3923.0780766519847;110551.44724276407;111997.32004019947;114106.42946785213;114627;115564.42217788857;11243.100551955824;11346.546138154546;11416.372099534605;11497.712557186138;11611;23926.760732392453;24280;24412.755872441405;24438.14466493664;24448.633270500955;7830.608469576612;7869.370450363957;7872.527648341118;7927.563983980821;7935.722249721348;4182.372644103409;4201;4221.240176768211;4249.053637959964;4376.015396535754;3578.785272883635;3620.6198349173483;3650.470681751131;3658.63413658632;3669.229461813049;638.8083574927504;639.1563136659657;645.2225068792094;649.1658219188321;662.5229834519139;665.6072916979006;667.4660271782554;692.8267933016746;710.5949608722979;738.7119023580808;9807.656732014346;10159;10324.690259292232;10598.841017384746;10609.787804244026;666.6566718140161;693.8126705789408;698.3470455124475;706.7632343165097;732.3555271361168;4732.432108146975;5053.494650534991;5056.469070747592;5069.619778516626;5077.416097148801;93162;106746.46626766816;108295;110377;112603.87396125933;83789.74315385275;94221.5288923551;96260;96922;98331;85451.14548854556;86191;87047.12952870518;88473;89394;9514.666986655433;9838;9841.015898410102;9862.852057219005;9877.160441362565;45619.77190114161;55879.044233451634;56531;56643;57098;73453;74052.14869628302;74373.51252974803;74965.62517187379;76238.61880690561;9169.954150229207;10012.899871001197;11652;11680.766384672428;11854.466549005143;11419;11857.5849845255;12904.870951290366;12953.287569183409;12968.35158242103;11961.342126183143;12006.279623222634;12044.939775301067;12133.69665758363;12147.75704485923;11411.54353825871;12013;12232;12382.195157314745;12384.56654017105;13172.209667419986;13376.124133311385;14124.576262712135;14140.646483837574;14158.78761818553;11180.608678696206;11200.943995280297;11383.032442242502;11498.482568284615;11535.36555489456;8670.956645216986;10531.21015923824;10893.673189804318;11227.831582526105;11240.662780116609 08:35:34 INFO - PROCESS | 1649 | __end_tp_report 08:35:34 INFO - PROCESS | 1649 | __start_cc_report 08:35:34 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,423 08:35:34 INFO - PROCESS | 1649 | __end_cc_report 08:35:34 INFO - PROCESS | 1649 | __startTimestamp1485880534146__endTimestamp 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | ------- Summary: start ------- 08:35:34 INFO - PROCESS | 1649 | Number of tests: 6 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5660.42 Median:5836.36 stddev:663.86 (11.4%) stddev-sans-first:639.13 08:35:34 INFO - PROCESS | 1649 | Values: 3281.3 3288.4 3334.5 3335.6 3349.1 5485.3 5505.1 5512.1 5512.9 5632.2 4602.6 5499.0 5595.5 5669.2 5676.7 4272.6 5026.4 5106.9 5221.5 5254.2 5073.6 5219.6 5223.5 5241.0 5261.7 5044.9 5098.8 5173.8 5206.4 5274.3 5060.5 5084.2 5123.0 5186.0 5212.9 5068.5 5107.4 5107.5 5121.3 5126.8 4907.6 4944.9 5495.1 5654.5 5674.3 6035.8 6104.3 6149.6 6163.9 6224.1 6256.6 6260.4 6296.3 6305.6 6319.0 5747.5 5774.4 5802.1 5808.2 5836.7 6165.3 6187.7 6197.3 6225.4 6250.5 5041.7 6120.4 6132.4 6137.8 6144.7 6169.7 6255.0 6268.2 6268.5 6277.0 6033.7 6081.0 6090.7 6116.9 6133.5 5468.8 5523.6 6147.8 6206.5 6214.0 4980.5 4993.2 4998.2 5010.5 5035.6 4322.1 4375.7 4462.5 4479.7 4495.0 5755.2 5767.2 5800.6 5822.9 5916.6 5282.7 6136.6 6266.2 6282.6 6301.7 5692.3 5821.6 5824.1 5832.7 5836.4 5294.9 5370.0 5382.2 5391.5 5488.9 6296.6 6348.4 6356.5 6362.2 6364.6 6270.3 6290.8 6299.5 6304.4 6325.2 6191.4 6200.7 6200.8 6229.3 6235.9 6134.5 6190.7 6202.6 6214.9 6219.2 6232.3 6239.7 6281.1 6296.0 6301.4 4827.8 5745.8 5819.9 5987.6 5991.0 6013.6 6023.5 6024.6 6062.9 6119.4 4774.9 5716.3 5824.7 5896.6 5917.4 5850.4 5972.3 5987.3 6046.3 6106.4 5877.7 5886.1 5887.1 5902.1 5903.4 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:54934.35 Median:37024.13 stddev:107660.74 (290.8%) stddev-sans-first:107885.04 08:35:34 INFO - PROCESS | 1649 | Values: 450.4 459.7 497.4 500.6 529.0 40553.0 41254.6 41317.4 41464.0 41543.0 42839.8 43328.0 43364.3 43408.6 43452.8 8451.0 9198.4 9528.1 9544.7 9551.7 8429.8 11258.5 11521.2 11550.4 11743.6 11466.7 11534.0 11536.8 11544.7 11552.5 9644.4 11584.4 11598.5 11605.8 11628.3 11363.7 11442.3 11446.1 11471.8 11478.1 39706.8 40728.4 41008.4 41150.0 41212.2 4053.4 4060.7 4086.6 4113.4 4237.5 22269.2 30011.5 30123.0 30176.3 31594.5 3134.4 3173.4 3194.1 3225.3 3285.7 356779.0 358288.0 359124.0 359394.0 361128.0 51084.5 60542.8 60673.7 60700.7 60802.7 62465.7 62663.4 62762.1 62822.0 63127.4 15919.4 16159.2 16179.8 16216.5 16239.0 11179.3 11463.1 12178.3 12564.0 12647.3 14970.7 15334.9 15634.7 15738.3 15820.4 5887.6 7023.9 7024.2 7057.8 7077.5 843.9 856.1 867.7 869.4 874.6 26209.0 27154.7 29592.4 30118.8 30723.4 898.6 1017.8 1053.0 1068.4 1173.6 12637.6 12666.0 15163.6 15167.7 16079.2 235767.0 236235.0 236287.0 236391.8 236837.0 59697.4 59863.1 60025.7 60061.7 60175.7 1354.4 1390.6 1407.8 1412.0 1421.3 25042.9 28953.0 29613.9 30138.5 30423.0 49956.0 50366.7 50375.2 50392.0 50443.7 550675.2 553314.2 554445.0 554691.0 554830.0 32593.7 34660.0 39577.4 39915.0 39996.6 45121.1 46761.0 47405.1 47624.3 48130.0 32641.8 36995.8 39002.8 39355.0 40243.6 38857.0 39002.8 39742.6 39877.2 40383.0 37052.4 37528.0 37677.7 38330.2 38747.0 27349.5 31116.5 33328.7 34719.5 34824.5 18786.1 23164.0 23583.0 25600.4 25609.9 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:40473.07 Median:376.29 stddev:122637.83 (32591.2%) stddev-sans-first:122944.91 08:35:34 INFO - PROCESS | 1649 | Values: 375.8 376.8 387.7 389.7 411.7 15501.5 20892.9 20977.9 21432.0 21481.6 21023.7 21038.9 21238.6 21431.0 21870.8 5577.8 5596.7 5729.4 5772.4 5779.1 4092.8 4311.6 5697.5 5706.0 6155.2 6467.6 6484.8 6500.9 6505.1 6512.2 5432.2 6398.1 6454.2 6461.0 6478.1 5512.8 5578.9 5639.2 5650.5 5658.5 26502.5 26754.5 26765.0 26807.1 26931.7 2756.0 2759.8 2762.9 2764.4 2788.7 13076.3 15246.0 15377.1 16020.5 16073.9 1818.9 1827.1 1836.5 1844.9 1845.6 125939.0 129748.0 130110.0 130950.4 131745.0 11397.3 11401.2 11567.8 11645.1 11811.8 14166.2 14281.8 14328.9 14332.4 14373.6 7968.9 8438.2 8568.3 8620.0 8750.7 7707.5 8379.1 8447.6 8452.1 8624.2 7073.9 7146.0 7190.8 7197.4 7272.9 1627.4 1709.7 1854.5 1884.3 1898.8 389.7 947.5 1012.6 1027.7 1051.8 8670.7 9306.0 10828.9 11743.4 11994.7 413.8 428.8 436.2 438.5 443.0 5697.1 5917.0 6020.9 6033.7 6050.0 712582.0 727939.0 732941.0 744088.1 751499.0 160821.2 161212.0 161457.0 163485.4 164543.0 427.8 427.9 433.3 436.6 437.7 13733.6 16817.4 18157.5 20968.4 21221.7 63296.7 63314.0 63451.0 63780.7 64483.4 86762.0 86894.6 87743.0 88281.6 89143.1 8147.2 8194.0 8211.5 8493.9 8568.8 9260.0 10129.9 10661.6 12495.9 12980.5 11350.5 11539.7 11610.7 11908.9 11986.2 11242.4 11302.6 11503.3 11801.1 11926.6 11256.9 11282.0 11878.6 11990.8 12167.7 8254.2 8347.4 8367.8 8403.5 8469.2 11262.8 11325.5 11640.9 11671.0 11733.8 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5190.21 Median:5200.05 stddev:2802.93 (53.9%) stddev-sans-first:2795.08 08:35:34 INFO - PROCESS | 1649 | Values: 1241.3 1276.5 1391.5 1423.7 1441.5 7325.6 7522.3 7804.2 8271.9 8284.6 7104.6 7635.3 7700.2 8030.4 8159.4 2202.8 2246.9 2252.3 2254.4 2262.9 2006.0 2217.4 2243.2 2280.0 2285.9 2031.2 2194.0 2270.8 2279.2 2288.6 2246.5 2260.9 2261.1 2267.6 2271.0 2219.7 2266.1 2282.0 2283.2 2291.9 8010.8 8219.9 8221.5 8221.9 8231.8 4942.7 5093.2 5107.2 5110.6 5112.4 3163.1 3210.8 3219.0 3257.3 3398.5 1183.0 1199.6 1207.0 1214.5 1242.6 10331.4 10391.2 10445.5 10450.9 10477.2 3916.9 4256.9 4393.2 4534.5 4573.6 7414.6 7561.8 9099.4 9120.7 9136.0 4310.9 4974.3 5022.0 5052.3 5137.3 4520.6 4653.1 4653.4 4681.8 4712.4 3003.7 3608.5 3615.6 3677.4 3684.9 1053.9 1128.3 1134.9 1135.4 1137.6 743.9 783.5 854.9 864.7 892.4 5270.9 5474.9 5514.2 5570.0 5582.7 927.0 935.4 936.6 948.9 976.4 3581.5 3634.2 3749.7 3751.9 3790.0 9329.7 9684.2 9701.5 9703.7 9716.2 7490.8 9523.2 9591.5 9669.4 9675.8 7358.6 7480.5 7673.9 7706.5 7718.2 5134.3 5262.8 5335.3 5439.2 5460.3 7457.5 7986.0 8189.5 8979.2 8987.3 8801.2 9108.9 9146.9 9184.7 9195.4 6360.2 6421.6 6472.9 6526.8 6532.6 7550.5 7651.8 7652.4 7658.0 7667.3 6697.3 6880.4 6904.2 6941.2 7009.7 6549.2 6720.9 6765.7 6805.5 6835.9 5985.6 6170.4 6205.7 6290.7 6293.7 4841.0 4879.7 4887.5 4943.3 4955.4 4592.7 4634.6 4705.6 4775.0 4849.6 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4563.49 Median:5096.05 stddev:1428.73 (28.0%) stddev-sans-first:1397.06 08:35:34 INFO - PROCESS | 1649 | Values: 335.8 353.2 362.7 365.6 369.9 4826.9 4960.2 5165.0 5374.3 5606.0 4407.4 5285.8 5443.6 5473.9 5482.6 3786.9 4281.8 4297.7 4301.3 4321.8 3455.0 3884.8 3914.9 4146.3 4212.1 4099.8 4123.4 4137.8 4138.7 4153.9 3953.3 4132.4 4142.9 4146.3 4147.9 3144.8 3577.3 3590.0 3599.7 3600.6 4736.0 5079.6 5209.3 5339.0 5405.8 4196.5 4727.7 4729.6 4741.2 4775.0 5653.5 5843.4 5848.7 5868.0 5885.3 3704.9 3773.9 3826.1 3927.4 3930.7 4858.8 6110.0 6155.8 6191.8 6271.4 3990.3 3999.6 4006.7 4010.3 4010.9 3276.7 3342.5 3423.0 4017.2 4043.1 3749.7 4267.7 4313.4 4450.7 4760.0 4879.8 5881.3 5898.2 5911.8 5950.7 1248.9 1276.1 1283.5 1293.2 1323.0 857.9 876.8 885.7 890.4 890.9 4561.5 4949.1 5189.1 5258.5 5290.8 5216.8 5353.9 5553.3 5589.4 5748.2 4496.9 4707.7 5230.4 5254.3 5275.7 2474.9 2769.0 2826.4 2857.3 2897.2 5099.5 5671.0 6021.0 6034.8 6064.4 5129.9 5307.4 5393.0 5870.3 5937.2 5159.6 5917.9 5932.3 5961.8 5961.8 4838.4 5046.4 5368.7 5828.9 5837.5 4846.6 5092.6 5136.6 5326.5 5516.9 4982.0 5663.3 5690.9 6046.9 6073.6 3069.5 3204.3 3463.1 3788.7 3870.0 5232.8 5424.3 5717.8 6005.6 6078.2 4643.1 4856.0 5752.6 5766.7 5923.3 4764.9 5311.5 5880.3 5888.2 5932.3 4789.9 5299.4 5306.7 5539.4 5789.4 4480.4 5890.4 5893.7 5900.6 5901.6 5759.6 5779.8 5783.7 5786.8 5791.5 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:21462.95 Median:4052.73 stddev:31593.59 (779.6%) stddev-sans-first:31643.78 08:35:34 INFO - PROCESS | 1649 | Values: 705.2 707.7 731.4 787.4 847.9 14945.9 15944.5 15951.1 16025.2 16049.7 14591.9 14690.9 14722.5 14753.1 14769.3 600.3 668.3 852.7 868.2 876.4 867.7 871.3 883.2 883.3 886.0 848.7 860.6 936.6 939.7 947.6 932.8 932.8 935.4 936.0 937.3 684.9 695.3 697.5 697.5 697.8 15132.1 15441.8 15563.5 15634.8 15732.2 13725.9 13780.2 14812.7 14855.0 14935.3 11798.5 11859.3 11893.6 11957.5 12349.9 3700.4 3756.4 3808.3 3922.7 3923.1 110551.4 111997.3 114106.4 114627.0 115564.4 11243.1 11346.5 11416.4 11497.7 11611.0 23926.8 24280.0 24412.8 24438.1 24448.6 7830.6 7869.4 7872.5 7927.6 7935.7 4182.4 4201.0 4221.2 4249.1 4376.0 3578.8 3620.6 3650.5 3658.6 3669.2 638.8 639.2 645.2 649.2 662.5 665.6 667.5 692.8 710.6 738.7 9807.7 10159.0 10324.7 10598.8 10609.8 666.7 693.8 698.3 706.8 732.4 4732.4 5053.5 5056.5 5069.6 5077.4 93162.0 106746.5 108295.0 110377.0 112603.9 83789.7 94221.5 96260.0 96922.0 98331.0 85451.1 86191.0 87047.1 88473.0 89394.0 9514.7 9838.0 9841.0 9862.9 9877.2 45619.8 55879.0 56531.0 56643.0 57098.0 73453.0 74052.1 74373.5 74965.6 76238.6 9170.0 10012.9 11652.0 11680.8 11854.5 11419.0 11857.6 12904.9 12953.3 12968.4 11961.3 12006.3 12044.9 12133.7 12147.8 11411.5 12013.0 12232.0 12382.2 12384.6 13172.2 13376.1 14124.6 14140.6 14158.8 11180.6 11200.9 11383.0 11498.5 11535.4 8671.0 10531.2 10893.7 11227.8 11240.7 08:35:34 INFO - PROCESS | 1649 | -------- Summary: end -------- 08:35:34 INFO - PROCESS | 1649 | 08:35:34 INFO - TEST-INFO | 1649: exit 0 08:35:34 INFO - TEST-OK | dromaeo_css | took 1116311ms 08:35:34 INFO - TEST-START | kraken 08:35:34 INFO - operating with platform_type : mac_ 08:35:34 INFO - Initialising browser for kraken test... 08:35:34 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile http://localhost:49240/getInfo.html) 08:35:37 INFO - PROCESS | 1658 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:35:37 INFO - PROCESS | 1658 | 2017-01-31 08:35:37.586 plugin-container[1659:10343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 08:35:37 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:37 INFO - PROCESS | 1658 | 2017-01-31 08:35:37.589 plugin-container[1659:10343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 08:35:37 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:37 INFO - PROCESS | 1658 | 2017-01-31 08:35:37.589 plugin-container[1659:10343] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:37 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 08:35:37 INFO - PROCESS | 1658 | colorDepth:24 08:35:37 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 08:35:37 INFO - PROCESS | 1658 | __metrics 08:35:37 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 08:35:37 INFO - PROCESS | 1658 | 1485880537738 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:35:38 INFO - TEST-INFO | 1658: exit 0 08:35:38 INFO - Browser initialized. 08:35:38 INFO - Running cycle 1/1 for kraken test... 08:35:38 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpP2WYXg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:40 INFO - PROCESS | 1660 | 2017-01-31 08:35:40.862 plugin-container[1661:10455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 08:35:40 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:40 INFO - PROCESS | 1660 | 2017-01-31 08:35:40.863 plugin-container[1661:10455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:35:40 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:40 INFO - PROCESS | 1660 | 2017-01-31 08:35:40.863 plugin-container[1661:10455] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:50 INFO - PROCESS | 1660 | RSS: Main: 233668608 08:35:50 INFO - PROCESS | 1660 | 08:35:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:35:54 INFO - PROCESS | 1660 | RSS: Main: 247193600 08:35:54 INFO - PROCESS | 1660 | 08:35:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:35:57 INFO - PROCESS | 1660 | RSS: Main: 255102976 08:35:57 INFO - PROCESS | 1660 | 08:36:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:36:01 INFO - PROCESS | 1660 | RSS: Main: 257765376 08:36:01 INFO - PROCESS | 1660 | 08:36:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:36:04 INFO - PROCESS | 1660 | RSS: Main: 260059136 08:36:04 INFO - PROCESS | 1660 | 08:36:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:36:06 INFO - PROCESS | 1660 | RSS: Main: 260407296 08:36:06 INFO - PROCESS | 1660 | 08:36:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:36:10 INFO - PROCESS | 1660 | RSS: Main: 265945088 08:36:10 INFO - PROCESS | 1660 | 08:36:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:14 INFO - PROCESS | 1660 | RSS: Main: 265568256 08:36:14 INFO - PROCESS | 1660 | 08:36:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:36:17 INFO - PROCESS | 1660 | RSS: Main: 265129984 08:36:17 INFO - PROCESS | 1660 | 08:36:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:20 INFO - PROCESS | 1660 | RSS: Main: 264957952 08:36:20 INFO - PROCESS | 1660 | 08:36:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:22 INFO - PROCESS | 1660 | RSS: Main: 264990720 08:36:22 INFO - PROCESS | 1660 | 08:36:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:36:25 INFO - PROCESS | 1660 | RSS: Main: 264953856 08:36:25 INFO - PROCESS | 1660 | 08:36:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:36:29 INFO - PROCESS | 1660 | RSS: Main: 265306112 08:36:29 INFO - PROCESS | 1660 | 08:36:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:36:32 INFO - PROCESS | 1660 | RSS: Main: 265383936 08:36:32 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:36:34 INFO - PROCESS | 1660 | RSS: Main: 267096064 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | __start_tp_report 08:36:34 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 08:36:34 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 08:36:34 INFO - PROCESS | 1660 | |i|pagename|runs| 08:36:34 INFO - PROCESS | 1660 | |0;ai-astar;129;175;149;119;124;102;169;178;118;119 08:36:34 INFO - PROCESS | 1660 | |1;audio-beat-detection;155;121;110;152;112;115;112;131;175;101 08:36:34 INFO - PROCESS | 1660 | |2;audio-dft;241;203;229;200;193;235;249;197;202;195 08:36:34 INFO - PROCESS | 1660 | |3;audio-fft;79;58;60;84;62;57;60;59;73;59 08:36:34 INFO - PROCESS | 1660 | |4;audio-oscillator;85;71;83;74;69;72;82;69;72;87 08:36:34 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;89;85;89;129;136;86;78;132;138;85 08:36:34 INFO - PROCESS | 1660 | |6;imaging-darkroom;146;138;108;111;152;149;94;105;96;95 08:36:34 INFO - PROCESS | 1660 | |7;imaging-desaturate;90;96;91;92;92;94;92;96;92;91 08:36:34 INFO - PROCESS | 1660 | |8;json-parse-financial;64;63;62;65;63;64;65;62;63;63 08:36:34 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;68;75;71;86;77;74;69;87;105;71 08:36:34 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;87;86;88;88;93;111;111;89;88;88 08:36:34 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;140;137;140;139;140;141;144;142;185;149 08:36:34 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;175;173;175;173;204;242;198;175;174;175 08:36:34 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;70;67;68;67;72;82;92;91;89;76 08:36:34 INFO - PROCESS | 1660 | __end_tp_report 08:36:34 INFO - PROCESS | 1660 | __start_cc_report 08:36:34 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,948 08:36:34 INFO - PROCESS | 1660 | __end_cc_report 08:36:34 INFO - PROCESS | 1660 | __startTimestamp1485880594986__endTimestamp 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | ------- Summary: start ------- 08:36:34 INFO - PROCESS | 1660 | Number of tests: 14 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:138.20 Median:139.00 stddev:27.38 (19.7%) stddev-sans-first:28.83 08:36:34 INFO - PROCESS | 1660 | Values: 129.0 175.0 149.0 119.0 124.0 102.0 169.0 178.0 118.0 119.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:126.00 stddev:24.28 (19.3%) stddev-sans-first:23.77 08:36:34 INFO - PROCESS | 1660 | Values: 155.0 121.0 110.0 152.0 112.0 115.0 112.0 131.0 175.0 101.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:214.40 Median:216.00 stddev:21.53 (10.0%) stddev-sans-first:20.57 08:36:34 INFO - PROCESS | 1660 | Values: 241.0 203.0 229.0 200.0 193.0 235.0 249.0 197.0 202.0 195.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:65.10 Median:61.00 stddev:9.80 (16.1%) stddev-sans-first:9.02 08:36:34 INFO - PROCESS | 1660 | Values: 79.0 58.0 60.0 84.0 62.0 57.0 60.0 59.0 73.0 59.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:78.00 stddev:7.03 (9.0%) stddev-sans-first:6.73 08:36:34 INFO - PROCESS | 1660 | Values: 85.0 71.0 83.0 74.0 69.0 72.0 82.0 69.0 72.0 87.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:85.00 stddev:25.29 (29.8%) stddev-sans-first:26.18 08:36:34 INFO - PROCESS | 1660 | Values: 89.0 85.0 89.0 129.0 136.0 86.0 78.0 132.0 138.0 85.0 08:36:34 INFO - PROCESS | 1660 | 08:36:34 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:150.50 stddev:24.01 (16.0%) stddev-sans-first:23.46 08:36:35 INFO - PROCESS | 1660 | Values: 146.0 138.0 108.0 111.0 152.0 149.0 94.0 105.0 96.0 95.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:92.00 stddev:2.07 (2.2%) stddev-sans-first:1.96 08:36:35 INFO - PROCESS | 1660 | Values: 90.0 96.0 91.0 92.0 92.0 94.0 92.0 96.0 92.0 91.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.50 stddev:1.07 (1.7%) stddev-sans-first:1.12 08:36:35 INFO - PROCESS | 1660 | Values: 64.0 63.0 62.0 65.0 63.0 64.0 65.0 62.0 63.0 63.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:74.50 stddev:11.44 (15.4%) stddev-sans-first:11.51 08:36:35 INFO - PROCESS | 1660 | Values: 68.0 75.0 71.0 86.0 77.0 74.0 69.0 87.0 105.0 71.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:88.00 stddev:9.71 (11.0%) stddev-sans-first:10.06 08:36:35 INFO - PROCESS | 1660 | Values: 87.0 86.0 88.0 88.0 93.0 111.0 111.0 89.0 88.0 88.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:141.50 stddev:14.19 (10.0%) stddev-sans-first:14.90 08:36:35 INFO - PROCESS | 1660 | Values: 140.0 137.0 140.0 139.0 140.0 141.0 144.0 142.0 185.0 149.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:175.00 stddev:22.53 (12.9%) stddev-sans-first:23.52 08:36:35 INFO - PROCESS | 1660 | Values: 175.0 173.0 175.0 173.0 204.0 242.0 198.0 175.0 174.0 175.0 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:79.00 stddev:10.24 (13.0%) stddev-sans-first:10.51 08:36:35 INFO - PROCESS | 1660 | Values: 70.0 67.0 68.0 67.0 72.0 82.0 92.0 91.0 89.0 76.0 08:36:35 INFO - PROCESS | 1660 | -------- Summary: end -------- 08:36:35 INFO - PROCESS | 1660 | 08:36:35 INFO - TEST-INFO | 1660: exit 0 08:36:35 INFO - TEST-OK | kraken | took 61043ms 08:36:35 INFO - SUITE-END | took 1177s 08:36:36 INFO - Completed test suite (00:19:37) 08:36:36 INFO - javascript benchmark 08:36:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7161.174426635369, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3281.34373125375, 3288.3587700398425, 3334.5164951082093, 3335.5997280326355, 3349.0790032741006, 5485.314335708032, 5505.091659876116, 5512.1456174293, 5512.91730624041, 5632.155176723493, 4602.631789456844, 5498.972505137479, 5595.524380427669, 5669.177969194475, 5676.687782171979, 4272.636825869805, 5026.371703537058, 5106.948930510703, 5221.530062294392, 5254.185601231796, 5073.644844860862, 5219.582433405338, 5223.529882310591, 5241.0, 5261.736913154327, 5044.873878153039, 5098.821541246039, 5173.793048278064, 5206.401263854629, 5274.340707411574, 5060.493950604947, 5084.161113416282, 5122.974385128088, 5185.974070129663, 5212.895742085175, 5068.467810879842, 5107.438181799999, 5107.463716309771, 5121.28302037715, 5126.794928202868, 4907.607391408679, 4944.9258261126115, 5495.120780675113, 5654.519365853907, 5674.262345895048, 6035.758569657209, 6104.337893874282, 6149.631022138686, 6163.907541386883, 6224.09750586167, 6256.624602523863, 6260.373962603749, 6296.275928268216, 6305.558610897239, 6319.0, 5747.540196784247, 5774.39368866263, 5802.12968054791, 5808.157817116579, 5836.708164591796, 6165.290991535941, 6187.721552530147, 6197.25632924052, 6225.408586184305, 6250.531210159256, 5041.7227052511735, 6120.355709576947, 6132.356102609251, 6137.775380989496, 6144.678894037804, 6169.660668663267, 6254.968725156436, 6268.153799237045, 6268.4671802896355, 6276.995680691068, 6033.728482218311, 6081.027035674271, 6090.725917333731, 6116.8776624468155, 6133.51829027926, 5468.7539060742365, 5523.613347065667, 6147.770445910835, 6206.503479721611, 6213.968930155411, 4980.526849949249, 4993.2010878260035, 4998.2002879539095, 5010.549050585392, 5035.647504674711, 4322.092360604245, 4375.7155784873885, 4462.464504259509, 4479.686421950498, 4494.955050449519, 5755.194272801811, 5767.192593036978, 5800.564957628195, 5822.941770582324, 5916.585838991229, 5282.709450980156, 6136.6011209271255, 6266.154069200691, 6282.591631544026, 6301.684915754194, 5692.31692196939, 5821.592488525847, 5824.068149096124, 5832.650040997553, 5836.358000619928, 5294.92057619136, 5370.0, 5382.165764306539, 5391.514763671288, 5488.91766623501, 6296.590721603081, 6348.4286414222015, 6356.523260755369, 6362.172917520693, 6364.586301890362, 6270.341614130451, 6290.842728931813, 6299.464545513682, 6304.432601065926, 6325.240971083499, 6191.4118158774845, 6200.689965501617, 6200.844978875564, 6229.283632382334, 6235.937640623536, 6134.492701656909, 6190.690465476799, 6202.627842329474, 6214.875702486015, 6219.160413344322, 6232.345603711545, 6239.656818875005, 6281.057841323838, 6295.96852015737, 6301.40136687014, 4827.793051737066, 5745.770169193185, 5819.94180058194, 5987.6108052976415, 5990.9700451497465, 6013.609115407485, 6023.518118550418, 6024.638521688713, 6062.90905636407, 6119.418655227746, 4774.928376074431, 5716.314042314949, 5824.708764561839, 5896.557758168069, 5917.437843404869, 5850.414958504116, 5972.25346831646, 5987.311459182076, 6046.27444706638, 6106.389361064036, 5877.676727780014, 5886.117082437669, 5887.116932460165, 5902.1146827976145, 5903.350631430453], "value": 5618.920361018954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.39871771185466, 459.71267957526544, 497.39318032000944, 500.6120256800979, 528.9947100528993, 40552.98617534563, 41254.58745412549, 41317.3802392964, 41464.0, 41543.0, 42839.78580107095, 43328.0, 43364.349534757006, 43408.56591434085, 43452.78273608635, 8451.0, 9198.402103863244, 9528.09483099108, 9544.713658590254, 9551.665691700791, 8429.789255268606, 11258.54965801367, 11521.19351645381, 11550.422478876022, 11743.647690569296, 11466.656000320003, 11533.96194342509, 11536.826947595751, 11544.711382215428, 11552.53789848405, 9644.373115747489, 11584.362860042736, 11598.536058557647, 11605.76788464226, 11628.302301861831, 11363.715907102305, 11442.256253343507, 11446.14153938458, 11471.827922581168, 11478.139139564566, 39706.80146599249, 40728.38907416391, 41008.38487422691, 41149.97125071897, 41212.175756485005, 4053.412255222976, 4060.675145988314, 4086.5504794472586, 4113.403556484293, 4237.4915010198665, 22269.22057727988, 30011.549826752616, 30123.0, 30176.34030128342, 31594.526082108787, 3134.435801555719, 3173.3811906678206, 3194.0577529628818, 3225.2743132795167, 3285.65500622436, 356779.0, 358288.0, 359124.0, 359394.0, 361127.9999999974, 51084.48915510797, 60542.78914421687, 60673.69663151656, 60700.69649651724, 60802.695986519786, 62465.687671562264, 62663.36029757344, 62762.0585691215, 62822.0, 63127.36872631307, 15919.363225471083, 16159.192040397933, 16179.757303640456, 16216.513504594644, 16239.02565846053, 11179.329240245612, 11463.082953363712, 12178.269303841798, 12563.994880409546, 12647.304398258, 14970.700585988438, 15334.923325383525, 15634.68730625381, 15738.291776870068, 15820.367185312458, 5887.61730487517, 7023.8595228095155, 7024.2273349931465, 7057.788266352018, 7077.504574679723, 843.9451435656663, 856.1181982557976, 867.6876324523166, 869.4044579463088, 874.5802015032692, 26209.0, 27154.728452715615, 29592.408151836844, 30118.84940575327, 30723.38553228923, 898.5686870302292, 1017.8473229015707, 1052.9736756580994, 1068.417712346773, 1173.6479056283251, 12637.557685480964, 12666.0, 15163.620909477351, 15167.696646067017, 16079.196040198178, 235767.0, 236235.0, 236287.0, 236391.81804091213, 236837.0, 59697.403025969186, 59863.102053469236, 60025.699871500365, 60061.699691501264, 60175.699121504986, 1354.3566805767182, 1390.6314826570967, 1407.774756039029, 1411.964700882486, 1421.3390773290264, 25042.874785625954, 28953.0, 29613.927025108896, 30138.54792178163, 30423.0, 49956.0, 50366.748166258934, 50375.24437133519, 50392.0, 50443.747781260856, 550675.2466237643, 553314.2334288303, 554445.0, 554691.0, 554830.0, 32593.674063259063, 34660.0, 39577.406338904366, 39915.0, 39996.60003399929, 45121.09757804891, 46761.0, 47405.05189896252, 47624.28563571619, 48130.0, 32641.836790815894, 36995.815020924725, 39002.804985974886, 39355.0, 40243.597564023985, 38857.0, 39002.80498597602, 39742.60900868456, 39877.2024559513, 40382.99042523843, 37052.44421333628, 37528.0, 37677.681281155026, 38330.23339533249, 38747.0, 27349.45301094007, 31116.533252000787, 33328.666853325594, 34719.47920781241, 34824.47763283603, 18786.060696964825, 23164.0, 23583.0, 25600.3996003996, 25609.871950640874], "value": 19425.00685584678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.7764130342447, 376.8059449383568, 387.6549870615153, 389.65126212040207, 411.6871177904793, 15501.457448989288, 20892.8955355223, 20977.895110524427, 21431.99999999998, 21481.57036859264, 21023.68464473034, 21038.89480552599, 21238.57522849542, 21431.035603397846, 21870.78129218707, 5577.776888924438, 5596.664200147994, 5729.369769325392, 5772.422757724236, 5779.133130030508, 4092.815823287944, 4311.6335111515555, 5697.487226149646, 5705.971470142644, 6155.230596175478, 6467.644279564622, 6484.837879053014, 6500.902486462707, 6505.0892874997535, 6512.153420055386, 5432.158015507583, 6398.096006293525, 6454.160959075337, 6461.030845373185, 6478.060681201199, 5512.834614961557, 5578.944210557924, 5639.182318563785, 5650.519705824969, 5658.547316214693, 26502.469950601073, 26754.46491070187, 26765.0, 26807.061752838752, 26931.73068269312, 2756.0, 2759.7930155238437, 2762.944741105187, 2764.405652784641, 2788.6793018802896, 13076.304902126136, 15246.0, 15377.077375357403, 16020.519384418487, 16073.910391649128, 1818.8817726847844, 1827.0955876840917, 1836.4857839804877, 1844.880082794614, 1845.6308738252403, 125939.0, 129748.0, 130110.0, 130950.42892279482, 131745.0, 11397.316161030365, 11401.20191586581, 11567.826482602768, 11645.12661550387, 11811.822822657667, 14166.220857852917, 14281.78577321341, 14328.928355358157, 14332.355044023045, 14373.56879293602, 7968.884356190138, 8438.240558349778, 8568.314534837198, 8620.0, 8750.737477875547, 7707.4604777666145, 8379.114699192754, 8447.577621118919, 8452.070272270046, 8624.18070283322, 7073.92926070743, 7146.03528523653, 7190.787757051245, 7197.352238298578, 7272.854542909112, 1627.3897288516807, 1709.6666150100612, 1854.5178253653878, 1884.2651365967283, 1898.8480921526245, 389.70382509292574, 947.4788866123605, 1012.5595366015807, 1027.669168426889, 1051.794899994509, 8670.696525621574, 9305.953470232605, 10828.891711082946, 11743.354115523729, 11994.700132496582, 413.7765606572477, 428.7791787229579, 436.1973967899081, 438.51763060633687, 443.0408166319946, 5697.145428185722, 5917.01779903737, 6020.909686354708, 6033.698315084316, 6050.031994880798, 712582.0, 727939.0, 732941.0, 744088.0852063404, 751499.0, 160821.1958940198, 161212.0, 161457.0, 163485.3651463494, 164543.0, 427.79679652165595, 427.87377723571, 433.3261777935278, 436.64195359804654, 437.71767210149915, 13733.587992359846, 16817.41139060127, 18157.54606134857, 20968.378952630923, 21221.681674774583, 63296.683516583966, 63314.0, 63451.0, 63780.68109659422, 64483.355166447735, 86762.0, 86894.56552717196, 87743.0, 88281.55859220662, 89143.10856891607, 8147.226013528705, 8194.001658987894, 8211.507309561446, 8493.87259191125, 8568.785780355542, 9260.0, 10129.949350253202, 10661.57353705874, 12495.875041249472, 12980.545680901123, 11350.489227984595, 11539.711507212387, 11610.709732256424, 11908.915939710689, 11986.160968732105, 11242.437878106226, 11302.604408845651, 11503.309801411942, 11801.055915526566, 11926.642200733992, 11256.887431125584, 11282.04102651251, 11878.584249551224, 11990.800919907939, 12167.695807604881, 8254.174582541938, 8347.402175758978, 8367.832643347221, 8403.453775504573, 8469.237768600855, 11262.83105753398, 11325.490352934303, 11640.94179529097, 11671.0, 11733.765324693288], "value": 9321.02471886311, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.3172754984757, 1276.4575055601379, 1391.4781956766212, 1423.6939058102505, 1441.517091774256, 7325.560466372008, 7522.285382888617, 7804.219578042193, 8271.91728082719, 8284.585770711463, 7104.609246491454, 7635.312821846032, 7700.1914798946145, 8030.357571394289, 8159.428839981204, 2202.8127609153203, 2246.86518808872, 2252.324302709189, 2254.413852398372, 2262.9434264143447, 2005.9899700501478, 2217.390217690132, 2243.2372993182316, 2279.9886000569973, 2285.90856365746, 2031.2484380779133, 2193.9890300548477, 2270.8069814065825, 2279.2136712834044, 2288.565172617206, 2246.4945387287894, 2260.8982595783227, 2261.1407665087327, 2267.557826223887, 2271.0, 2219.7225346831647, 2266.1388672304583, 2281.97718022821, 2283.1552325639454, 2291.862488250694, 8010.759677209693, 8219.86283566271, 8221.465604735733, 8221.917780822236, 8231.794205144859, 4942.654014219006, 5093.2105523643895, 5107.1828507439, 5110.565601923804, 5112.36095488064, 3163.0510846745883, 3210.807351558891, 3218.951715724266, 3257.3485302939507, 3398.456247000468, 1182.9704257393548, 1199.6461043992044, 1206.9818952715716, 1214.5081242096876, 1242.5899453180518, 10331.431771252579, 10391.168706503537, 10445.47772611374, 10450.947745261301, 10477.214208934283, 3916.8629097981434, 4256.936145957813, 4393.165298593257, 4534.478534968455, 4573.63410927125, 7414.6292685365515, 7561.848763024819, 9099.363044586948, 9120.680776172801, 9135.95432022849, 4310.870673879788, 4974.328465657162, 5022.0, 5052.267421223975, 5137.306463627438, 4520.593146616755, 4653.092646933883, 4653.371794807726, 4681.836135735233, 4712.410948631421, 3003.663369800418, 3608.45873119029, 3615.620359862229, 3677.3748462761396, 3684.871029513954, 1053.8946105389552, 1128.2666266926562, 1134.8978591926766, 1135.4493070860626, 1137.6018393562188, 743.9293267139722, 783.5455435847194, 854.9102344253888, 864.7319331007408, 892.3842548641417, 5270.868228294246, 5474.91787623186, 5514.200440936042, 5570.0, 5582.665040097607, 926.972190834276, 935.4480856294683, 936.639393833379, 948.8908775490769, 976.4287891583504, 3581.4806853006166, 3634.236810269873, 3749.6812770914776, 3751.9437208441896, 3790.0, 9329.72010839676, 9684.225261979025, 9701.466419346863, 9703.708888733348, 9716.222702183808, 7490.812729681802, 9523.190528804991, 9591.472469014132, 9669.371490853073, 9675.758106047406, 7358.632068396666, 7480.513766605153, 7673.923260767432, 7706.460547761603, 7718.228177182237, 5134.28120063184, 5262.789488420421, 5335.279737235579, 5439.238506608999, 5460.31746031746, 7457.47797654159, 7986.0, 8189.508629482012, 8979.191872731486, 8987.281017518451, 8801.207891289814, 9108.863367049633, 9146.908530914605, 9184.724458266262, 9195.448273103635, 6360.173177486897, 6421.614703117828, 6472.935270647234, 6526.836829079311, 6532.608043517404, 7550.509216900884, 7651.846963060596, 7652.426068045031, 7658.0, 7667.3099421052375, 6697.296783837627, 6880.380765731108, 6904.171499419901, 6941.2364639888565, 7009.684564194521, 6549.214094308713, 6720.932790672031, 6765.661716914036, 6805.455563554805, 6835.93164068353, 5985.551083668829, 6170.413810687977, 6205.6586887721605, 6290.748370065146, 6293.685315734287, 4841.0, 4879.707217566957, 4887.486813884633, 4943.332650092191, 4955.40535135786, 4592.7014744041535, 4634.582887540138, 4705.6000239979985, 4775.044991001883, 4849.612031037579], "value": 4233.041919229961, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.84047577400736, 353.15771884056534, 362.711644242827, 365.55402409060935, 369.941965977305, 4826.927596086061, 4960.156773348531, 5164.9856555943215, 5374.3013408256875, 5606.0, 4407.360932664769, 5285.841424757264, 5443.564514838813, 5473.917891231635, 5482.5613950883935, 3786.924261514768, 4281.828726850922, 4297.677674174433, 4301.311790113583, 4321.783910804463, 3455.0, 3884.844606215748, 3914.902127446808, 4146.325619975114, 4212.073343864345, 4099.7745124018165, 4123.422720819088, 4137.77242251676, 4138.730982486144, 4153.875383738493, 3953.2641653637324, 4132.380142978547, 4142.875713728593, 4146.253674338618, 4147.937780933288, 3144.842757862121, 3577.302426026926, 3589.982050089759, 3599.748017638766, 3600.641579765258, 4736.016309724886, 5079.619028572835, 5209.322788037531, 5338.973305133488, 5405.783768649249, 4196.454460920061, 4727.652619003602, 4729.62163026957, 4741.193997020515, 4774.976125119352, 5653.462921022497, 5843.415658434165, 5848.660535785677, 5867.970660146672, 5885.322178408814, 3704.944425833615, 3773.924521509582, 3826.1199924017633, 3927.3897701942064, 3930.744501607386, 4858.805647774085, 6109.969450152766, 6155.815325540241, 6191.752329906798, 6271.435570798604, 3990.2618015666612, 3999.6200360965663, 4006.7395619284653, 4010.2981978153707, 4010.939835902464, 3276.6559511251453, 3342.4986252062386, 3423.0, 4017.1764788218347, 4043.130726893673, 3749.7000239980734, 4267.7225980311805, 4313.374560688682, 4450.687047321048, 4759.976200118977, 4879.780409881564, 5881.2942446906645, 5898.203742494794, 5911.812629327176, 5950.732217050243, 1248.8501379834297, 1276.074845736852, 1283.4930202569933, 1293.1723696834033, 1322.9933850330879, 857.8541647919868, 876.8114855306012, 885.6501681835639, 890.398980688033, 890.9353322779347, 4561.475430325555, 4949.084419382426, 5189.065968125698, 5258.5267325940085, 5290.761915713802, 5216.765245563883, 5353.866153346197, 5553.250311208016, 5589.413111623302, 5748.223989761329, 4496.887577810581, 4707.728128950346, 5230.398504172069, 5254.264402983586, 5275.683458992473, 2474.9481470375017, 2769.030839206302, 2826.4488424757387, 2857.3428111534463, 2897.246715853851, 5099.5410413063, 5671.0, 6020.969895150496, 6034.818955431344, 6064.393560643988, 5129.897402052013, 5307.3896501902, 5392.973035134878, 5870.295564532284, 5937.229859039541, 5159.58723302135, 5917.911231331534, 5932.317783454958, 5961.7615295387695, 5961.823934264049, 4838.371011768448, 5046.369203849519, 5368.677879327252, 5828.854278642898, 5837.5038121758425, 4846.642939976812, 5092.567131793839, 5136.563392111712, 5326.520613144836, 5516.889662206653, 4981.950180498294, 5663.292088488939, 5690.886182276248, 6046.939530604637, 6073.635581865102, 3069.5088785794164, 3204.279037216608, 3463.0564020582306, 3788.734788564884, 3869.9806500967316, 5232.790688372423, 5424.267723857229, 5717.799262154994, 6005.609635373687, 6078.240219972504, 4643.141018911512, 4856.0, 5752.568557358299, 5766.740496677743, 5923.348431672599, 4764.904701906012, 5311.468853114658, 5880.26496687914, 5888.175655408332, 5932.317783454958, 4789.928151077806, 5299.443558426464, 5306.6550674206055, 5539.418361072029, 5789.421057894177, 4480.417545719036, 5890.440408161217, 5893.705314734332, 5900.616459930091, 5901.645901245939, 5759.568032397503, 5779.826605201851, 5783.652980821164, 5786.826395208151, 5791.47876691083], "value": 4122.000855982767, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2348202200616, 707.6780065070393, 731.3819822250198, 787.3937068457288, 847.8812966184734, 14945.850541494581, 15944.521664350059, 15951.122688252142, 16025.198740062993, 16049.67900641988, 14591.854081459182, 14690.926545367258, 14722.484713035046, 14753.11481311122, 14769.335379907903, 600.2826622186489, 668.3216535216759, 852.6674596907211, 868.2272777228272, 876.4434584039149, 867.7396780965717, 871.2507243770352, 883.2404132446092, 883.3463237204454, 885.9734207973771, 848.6591185926221, 860.5568132411781, 936.6440752514035, 939.6992962252082, 947.6304241345881, 932.7714709896085, 932.8134373125368, 935.4247138010143, 936.0405584276103, 937.2689302344182, 684.948628852838, 695.3498478922224, 697.476892330752, 697.5326531224041, 697.7836870570139, 15132.092074475566, 15441.845581544154, 15563.533094007085, 15634.765478517718, 15732.213389330487, 13725.862741372559, 13780.173189608655, 14812.70374592502, 14855.034422762594, 14935.327910244065, 11798.52805887772, 11859.288442693465, 11893.643190704206, 11957.52169913211, 12349.93825030878, 3700.3709369407266, 3756.4365345198166, 3808.333541630231, 3922.666573341269, 3923.0780766519847, 110551.44724276407, 111997.32004019947, 114106.42946785213, 114627.0, 115564.42217788857, 11243.100551955824, 11346.546138154546, 11416.372099534605, 11497.712557186138, 11611.0, 23926.760732392453, 24280.0, 24412.755872441405, 24438.14466493664, 24448.633270500955, 7830.608469576612, 7869.370450363957, 7872.527648341118, 7927.563983980821, 7935.722249721348, 4182.372644103409, 4201.0, 4221.240176768211, 4249.053637959964, 4376.015396535754, 3578.785272883635, 3620.6198349173483, 3650.470681751131, 3658.63413658632, 3669.229461813049, 638.8083574927504, 639.1563136659657, 645.2225068792094, 649.1658219188321, 662.5229834519139, 665.6072916979006, 667.4660271782554, 692.8267933016746, 710.5949608722979, 738.7119023580808, 9807.656732014346, 10159.0, 10324.690259292232, 10598.841017384746, 10609.787804244026, 666.6566718140161, 693.8126705789408, 698.3470455124475, 706.7632343165097, 732.3555271361168, 4732.432108146975, 5053.494650534991, 5056.469070747592, 5069.619778516626, 5077.416097148801, 93162.0, 106746.46626766816, 108295.0, 110377.0, 112603.87396125933, 83789.74315385275, 94221.5288923551, 96260.0, 96922.0, 98331.0, 85451.14548854556, 86191.0, 87047.12952870518, 88473.0, 89394.0, 9514.666986655433, 9838.0, 9841.015898410102, 9862.852057219005, 9877.160441362565, 45619.77190114161, 55879.044233451634, 56531.0, 56643.0, 57098.0, 73453.0, 74052.14869628302, 74373.51252974803, 74965.62517187379, 76238.61880690561, 9169.954150229207, 10012.899871001197, 11652.0, 11680.766384672428, 11854.466549005143, 11419.0, 11857.5849845255, 12904.870951290366, 12953.287569183409, 12968.35158242103, 11961.342126183143, 12006.279623222634, 12044.939775301067, 12133.69665758363, 12147.75704485923, 11411.54353825871, 12013.0, 12232.0, 12382.195157314745, 12384.56654017105, 13172.209667419986, 13376.124133311385, 14124.576262712135, 14140.646483837574, 14158.78761818553, 11180.608678696206, 11200.943995280297, 11383.032442242502, 11498.482568284615, 11535.36555489456, 8670.956645216986, 10531.21015923824, 10893.673189804318, 11227.831582526105, 11240.662780116609], "value": 7596.607150700955, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.3000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 175.0, 149.0, 119.0, 124.0, 102.0, 169.0, 178.0, 118.0, 119.0], "value": 138.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 110.0, 152.0, 112.0, 115.0, 112.0, 131.0, 175.0, 101.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [241.0, 203.0, 229.0, 200.0, 193.0, 235.0, 249.0, 197.0, 202.0, 195.0], "value": 214.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 84.0, 62.0, 57.0, 60.0, 59.0, 73.0, 59.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 83.0, 74.0, 69.0, 72.0, 82.0, 69.0, 72.0, 87.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 129.0, 136.0, 86.0, 78.0, 132.0, 138.0, 85.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 138.0, 108.0, 111.0, 152.0, 149.0, 94.0, 105.0, 96.0, 95.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 91.0, 92.0, 92.0, 94.0, 92.0, 96.0, 92.0, 91.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 65.0, 63.0, 64.0, 65.0, 62.0, 63.0, 63.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 71.0, 86.0, 77.0, 74.0, 69.0, 87.0, 105.0, 71.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 88.0, 93.0, 111.0, 111.0, 89.0, 88.0, 88.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 140.0, 139.0, 140.0, 141.0, 144.0, 142.0, 185.0, 149.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 175.0, 173.0, 204.0, 242.0, 198.0, 175.0, 174.0, 175.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 67.0, 72.0, 82.0, 92.0, 91.0, 89.0, 76.0], "value": 77.4, "unit": "score"}]}]} 08:36:36 INFO - Return code: 0 08:36:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:36:36 ERROR - # TBPL SUCCESS # 08:36:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:36 INFO - 2017-01-31 08:36:36.289 screenresolution[1665:10847] starting screenresolution argv=screenresolution get 08:36:36 INFO - 2017-01-31 08:36:36.291 screenresolution[1665:10847] Display 0: 1600x1200x32@60 08:36:36 INFO - 2017-01-31 08:36:36.298 screenresolution[1666:10850] starting screenresolution argv=screenresolution list 08:36:36 INFO - Available Modes on Display 0 08:36:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:36 INFO - Intel Iris: 08:36:36 INFO - Chipset Model: Intel Iris 08:36:36 INFO - Type: GPU 08:36:36 INFO - Bus: Built-In 08:36:36 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:36 INFO - Vendor: Intel (0x8086) 08:36:36 INFO - Device ID: 0x0a2e 08:36:36 INFO - Revision ID: 0x0009 08:36:36 INFO - Displays: 08:36:36 INFO - Display: 08:36:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:36 INFO - Display Serial Number: 1600x1200 60 08:36:36 INFO - Main Display: Yes 08:36:36 INFO - Mirror: Off 08:36:36 INFO - Online: Yes 08:36:36 INFO - Rotation: Supported 08:36:36 INFO - Return code: 0 08:36:36 INFO - Running post-action listener: _resource_record_post_action 08:36:36 INFO - [mozharness: 2017-01-31 16:36:36.541219Z] Finished run-tests step (success) 08:36:36 INFO - Running post-run listener: _resource_record_post_run 08:36:36 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 33587712; Write bytes: 558682112; Read time: 202; Write time: 1706 08:36:36 INFO - TinderboxPrint: CPU usage
54.3% 08:36:36 INFO - TinderboxPrint: I/O read bytes / time
33,587,712 / 202 08:36:36 INFO - TinderboxPrint: I/O write bytes / time
558,682,112 / 1,706 08:36:36 INFO - TinderboxPrint: CPU idle
2,194.1 (45.7%) 08:36:36 INFO - TinderboxPrint: CPU system
960.9 (20.0%) 08:36:36 INFO - TinderboxPrint: CPU user
1,646.8 (34.3%) 08:36:36 INFO - TinderboxPrint: Swap in / out
282,578,944 / 0 08:36:36 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244505088; Write bytes: 236830720; Read time: 17724; Write time: 527 08:36:36 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31511040; Write bytes: 304537600; Read time: 183; Write time: 1136 08:36:37 INFO - Running post-run listener: _upload_blobber_files 08:36:37 INFO - Blob upload gear active. 08:36:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:36:37 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 08:36:37 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'] 08:36:37 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 08:36:37 INFO - (blobuploader) - INFO - Open directory for files ... 08:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:38 INFO - (blobuploader) - INFO - Done attempting. 08:36:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:36:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:39 INFO - (blobuploader) - INFO - Done attempting. 08:36:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:36:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:36:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:40 INFO - (blobuploader) - INFO - Done attempting. 08:36:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:36:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:43 INFO - (blobuploader) - INFO - Done attempting. 08:36:43 INFO - (blobuploader) - INFO - Iteration through files over. 08:36:43 INFO - Return code: 0 08:36:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:36:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:36:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a0d9a01b8337f7e5ff847b2dc4735c4752938098e9995623e7a42602d94ae3dde9f1ee45598dbff6680fc829f482a07b549a46da515e6c6a71d10c4a0b7404", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6d13bae628c7bac8089521f81ed51fda7c34083eabbef5a211f4e7a3ea525eddea2d6dbe7aec30d1dbef5645efb75b9a5dc3760a156950cd91ad58ea1eb854", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e66c73aca0a267b7f58bea1fc8b4e494f8351582e0125b92d82a2f856df8288e4f5aacb44aea721161519290748650b9be0fa7ce68fe80243d38430cf594876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9463d4213af98f2b603fd3e90e948ccee0e9c9cf16368db531fc2174317e88995a715f747ede111f40c5b502f38c6073154069cb114c613485b9379b0c8d7c66"} 08:36:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:36:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:36:43 INFO - Contents: 08:36:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a0d9a01b8337f7e5ff847b2dc4735c4752938098e9995623e7a42602d94ae3dde9f1ee45598dbff6680fc829f482a07b549a46da515e6c6a71d10c4a0b7404", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6d13bae628c7bac8089521f81ed51fda7c34083eabbef5a211f4e7a3ea525eddea2d6dbe7aec30d1dbef5645efb75b9a5dc3760a156950cd91ad58ea1eb854", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e66c73aca0a267b7f58bea1fc8b4e494f8351582e0125b92d82a2f856df8288e4f5aacb44aea721161519290748650b9be0fa7ce68fe80243d38430cf594876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9463d4213af98f2b603fd3e90e948ccee0e9c9cf16368db531fc2174317e88995a715f747ede111f40c5b502f38c6073154069cb114c613485b9379b0c8d7c66"} 08:36:43 INFO - Running post-run listener: copy_logs_to_upload_dir 08:36:43 INFO - Copying logs to upload dir... 08:36:43 INFO - mkdir: /builds/slave/test/build/upload/logs 08:36:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.959950 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-31 08:36:43.618561) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 08:36:43.621762) ========= 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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/56a0d9a01b8337f7e5ff847b2dc4735c4752938098e9995623e7a42602d94ae3dde9f1ee45598dbff6680fc829f482a07b549a46da515e6c6a71d10c4a0b7404", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6d13bae628c7bac8089521f81ed51fda7c34083eabbef5a211f4e7a3ea525eddea2d6dbe7aec30d1dbef5645efb75b9a5dc3760a156950cd91ad58ea1eb854", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e66c73aca0a267b7f58bea1fc8b4e494f8351582e0125b92d82a2f856df8288e4f5aacb44aea721161519290748650b9be0fa7ce68fe80243d38430cf594876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9463d4213af98f2b603fd3e90e948ccee0e9c9cf16368db531fc2174317e88995a715f747ede111f40c5b502f38c6073154069cb114c613485b9379b0c8d7c66"} build_url:https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/XePhcCTrRXm6PoBodGvU1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a0d9a01b8337f7e5ff847b2dc4735c4752938098e9995623e7a42602d94ae3dde9f1ee45598dbff6680fc829f482a07b549a46da515e6c6a71d10c4a0b7404", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6d13bae628c7bac8089521f81ed51fda7c34083eabbef5a211f4e7a3ea525eddea2d6dbe7aec30d1dbef5645efb75b9a5dc3760a156950cd91ad58ea1eb854", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e66c73aca0a267b7f58bea1fc8b4e494f8351582e0125b92d82a2f856df8288e4f5aacb44aea721161519290748650b9be0fa7ce68fe80243d38430cf594876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9463d4213af98f2b603fd3e90e948ccee0e9c9cf16368db531fc2174317e88995a715f747ede111f40c5b502f38c6073154069cb114c613485b9379b0c8d7c66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 08:36:43.655733) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:36:43.656071) ========= 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.uhtJyrb7Jg/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.oZV2BCWze6/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 08:36:43.704904) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 08:36:43.705214) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 08:36:43.705542) ========= ========= Total master_lag: 0.18 =========