builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0283 starttime: 1493811854.58 results: success (0) buildid: 20170503030212 builduid: 3d6bc241d5a6429b877fc9b13c38f6b1 revision: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.579137) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.579689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.580009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.607617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.608052) ========= 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.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-03 04:44:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2017-05-03 04:44:14 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095797 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.837070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.837648) ========= 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.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.026883 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:14.889247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 04:44:14.889617) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 --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 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-03 04:44:14,994 truncating revision to first 12 chars 2017-05-03 04:44:14,994 Setting DEBUG logging. 2017-05-03 04:44:14,994 attempt 1/10 2017-05-03 04:44:14,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/82c2d17e74ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 04:44:15,963 unpacking tar archive at: mozilla-central-82c2d17e74ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.263606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 04:44:16.177850) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:16.178244) ========= script_repo_revision: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:16.178683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:16.178968) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 04:44:16.200218) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-03 04:44:16.200897) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:44:16 INFO - MultiFileLogger online at 20170503 04:44:16 in /builds/slave/test 04:44:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:44:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:16 INFO - {'append_to_log': False, 04:44:16 INFO - 'base_work_dir': '/builds/slave/test', 04:44:16 INFO - 'blob_upload_branch': 'Firefox', 04:44:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:16 INFO - 'branch': 'Firefox', 04:44:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:16 INFO - 'config_files': ('talos/mac_config.py',), 04:44:16 INFO - 'default_actions': ('clobber', 04:44:16 INFO - 'read-buildbot-config', 04:44:16 INFO - 'download-and-extract', 04:44:16 INFO - 'populate-webroot', 04:44:16 INFO - 'create-virtualenv', 04:44:16 INFO - 'install', 04:44:16 INFO - 'run-tests'), 04:44:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:16 INFO - 'download_minidump_stackwalk': True, 04:44:16 INFO - 'download_symbols': 'ondemand', 04:44:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:16 INFO - 'gecko_profile': False, 04:44:16 INFO - 'gecko_profile_interval': 0, 04:44:16 INFO - 'installer_path': 'installer.exe', 04:44:16 INFO - 'log_level': 'info', 04:44:16 INFO - 'log_name': 'talos', 04:44:16 INFO - 'log_to_console': True, 04:44:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:16 INFO - 'opt_config_files': (), 04:44:16 INFO - 'pip_index': False, 04:44:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:16 INFO - 'cmd': ('bash', 04:44:16 INFO - '-c', 04:44:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:16 INFO - 'enabled': True, 04:44:16 INFO - 'halt_on_failure': False, 04:44:16 INFO - 'name': 'check_screen_resolution'},), 04:44:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:16 INFO - 'cmd': ('bash', 04:44:16 INFO - '-c', 04:44:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:16 INFO - 'enabled': True, 04:44:16 INFO - 'halt_on_failure': False, 04:44:16 INFO - 'name': 'check_screen_resolution'},), 04:44:16 INFO - 'run_cmd_checks_enabled': True, 04:44:16 INFO - 'suite': 'dromaeojs', 04:44:16 INFO - 'system_bits': '32', 04:44:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:16 INFO - 'title': 't-yosemite-r7-0283', 04:44:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:16 INFO - 'use_talos_json': True, 04:44:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:16 INFO - 'work_dir': 'build'} 04:44:16 INFO - [mozharness: 2017-05-03 11:44:16.409526Z] Running clobber step. 04:44:16 INFO - Running pre-action listener: _resource_record_pre_action 04:44:16 INFO - Running main action method: clobber 04:44:16 INFO - rmtree: /builds/slave/test/build 04:44:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:18 INFO - Running post-action listener: _resource_record_post_action 04:44:18 INFO - [mozharness: 2017-05-03 11:44:18.771237Z] Finished clobber step (success) 04:44:18 INFO - [mozharness: 2017-05-03 11:44:18.771389Z] Running read-buildbot-config step. 04:44:18 INFO - Running pre-action listener: _resource_record_pre_action 04:44:18 INFO - Running main action method: read_buildbot_config 04:44:18 INFO - Using buildbot properties: 04:44:18 INFO - { 04:44:18 INFO - "project": "", 04:44:18 INFO - "product": "firefox", 04:44:18 INFO - "script_repo_revision": "production", 04:44:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:44:18 INFO - "repository": "", 04:44:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:44:18 INFO - "buildid": "20170503030212", 04:44:18 INFO - "stage_platform": "macosx64", 04:44:18 INFO - "basedir": "/builds/slave/test", 04:44:18 INFO - "nightly_build": "True", 04:44:18 INFO - "buildnumber": 46, 04:44:18 INFO - "slavename": "t-yosemite-r7-0283", 04:44:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:44:18 INFO - "platform": "yosemite_r7", 04:44:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:18 INFO - "branch": "mozilla-central", 04:44:18 INFO - "repo_path": "mozilla-central", 04:44:18 INFO - "revision": "82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4", 04:44:18 INFO - "pgo_build": "False", 04:44:18 INFO - "builduid": "3d6bc241d5a6429b877fc9b13c38f6b1", 04:44:18 INFO - "slavebuilddir": "test" 04:44:18 INFO - } 04:44:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:44:18 INFO - Running post-action listener: _resource_record_post_action 04:44:18 INFO - [mozharness: 2017-05-03 11:44:18.774715Z] Finished read-buildbot-config step (success) 04:44:18 INFO - [mozharness: 2017-05-03 11:44:18.774835Z] Running download-and-extract step. 04:44:18 INFO - Running pre-action listener: _resource_record_pre_action 04:44:18 INFO - Running main action method: download_and_extract 04:44:18 INFO - mkdir: /builds/slave/test/build/tests 04:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:18 INFO - trying https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:18 INFO - Downloading https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:44:20 INFO - Downloaded 1325 bytes. 04:44:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:20 INFO - Using the following test package requirements: 04:44:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:44:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:44:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'jsshell-mac.zip'], 04:44:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:44:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:44:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:44:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:44:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:20 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:21 INFO - Content-Length response header: 52700035 04:44:21 INFO - Bytes received: 52700035 04:44:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:44:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:38 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:39 INFO - Content-Length response header: 52700035 04:44:39 INFO - Bytes received: 52700035 04:44:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:44:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:44:55 INFO - Fetch https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:44:56 INFO - Content-Length response header: 14408811 04:44:56 INFO - Bytes received: 14408811 04:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:56 INFO - trying https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:56 INFO - Downloading https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:44:58 INFO - Downloaded 71273714 bytes. 04:44:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:58 INFO - mkdir: /builds/slave/test/properties 04:44:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:58 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:58 INFO - Contents: 04:44:58 INFO - build_url:https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:59 INFO - Running post-action listener: _resource_record_post_action 04:44:59 INFO - Running post-action listener: set_extra_try_arguments 04:44:59 INFO - [mozharness: 2017-05-03 11:44:59.642681Z] Finished download-and-extract step (success) 04:44:59 INFO - [mozharness: 2017-05-03 11:44:59.642812Z] Running populate-webroot step. 04:44:59 INFO - Running pre-action listener: _resource_record_pre_action 04:44:59 INFO - Running main action method: populate_webroot 04:44:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:59 INFO - '--tppagecycles', 04:44:59 INFO - '7'], 04:44:59 INFO - 'tests': ['tsvgm']}}, 04:44:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['damp', 'tps']}, 04:44:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'tests': ['damp', 'tps']}, 04:44:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['dromaeo_dom']}, 04:44:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:44:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:44:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['a11yr', 04:44:59 INFO - 'ts_paint', 04:44:59 INFO - 'tpaint', 04:44:59 INFO - 'sessionrestore', 04:44:59 INFO - 'sessionrestore_no_auto_restore', 04:44:59 INFO - 'tabpaint']}, 04:44:59 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:44:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:44:59 INFO - 'bloom_basic_ref']}, 04:44:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['tsvgx', 04:44:59 INFO - 'tsvgr_opacity', 04:44:59 INFO - 'tart', 04:44:59 INFO - 'tscrollx', 04:44:59 INFO - 'cart', 04:44:59 INFO - 'tsvg_static']}, 04:44:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:59 INFO - 'tsvgr_opacity', 04:44:59 INFO - 'tart', 04:44:59 INFO - 'tscrollx', 04:44:59 INFO - 'cart', 04:44:59 INFO - 'tsvg_static']}, 04:44:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'talos_options': ['--disable-e10s'], 04:44:59 INFO - 'tests': ['tp5o']}, 04:44:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:44:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'talos_options': ['--disable-e10s', 04:44:59 INFO - '--xperf_path', 04:44:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:59 INFO - 'tests': ['tp5n']}, 04:44:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:44:59 INFO - 'talos_options': ['--xperf_path', 04:44:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:59 INFO - 'tests': ['tp5n']}}, 04:44:59 INFO - 'talos.zip': {'path': '', 04:44:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:59 INFO - Running post-action listener: _resource_record_post_action 04:44:59 INFO - [mozharness: 2017-05-03 11:44:59.662090Z] Finished populate-webroot step (success) 04:44:59 INFO - [mozharness: 2017-05-03 11:44:59.662231Z] Running create-virtualenv step. 04:44:59 INFO - Running pre-action listener: _resource_record_pre_action 04:44:59 INFO - Running main action method: create_virtualenv 04:44:59 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:44:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:59 INFO - Using real prefix '/tools/python27' 04:44:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:00 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:03 INFO - Installing pip.................done. 04:45:03 INFO - Return code: 0 04:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:03 INFO - Reading from file tmpfile_stdout 04:45:03 INFO - Output received: 04:45:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:45:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:03 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:03 INFO - 'HOME': '/Users/cltbld', 04:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:03 INFO - 'LOGNAME': 'cltbld', 04:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:03 INFO - 'MOZ_NO_REMOTE': '1', 04:45:03 INFO - 'NO_EM_RESTART': '1', 04:45:03 INFO - 'PAGER': '/bin/cat', 04:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:03 INFO - 'PWD': '/builds/slave/test', 04:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:03 INFO - 'SHELL': '/bin/bash', 04:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:03 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:03 INFO - 'USER': 'cltbld', 04:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:03 INFO - 'XPC_FLAGS': '0x0', 04:45:03 INFO - 'XPC_SERVICE_NAME': '0', 04:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:45:03 INFO - Cleaning up... 04:45:03 INFO - Return code: 0 04:45:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:03 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:03 INFO - 'HOME': '/Users/cltbld', 04:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:03 INFO - 'LOGNAME': 'cltbld', 04:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:03 INFO - 'MOZ_NO_REMOTE': '1', 04:45:03 INFO - 'NO_EM_RESTART': '1', 04:45:03 INFO - 'PAGER': '/bin/cat', 04:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:03 INFO - 'PWD': '/builds/slave/test', 04:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:03 INFO - 'SHELL': '/bin/bash', 04:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:03 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:03 INFO - 'USER': 'cltbld', 04:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:03 INFO - 'XPC_FLAGS': '0x0', 04:45:03 INFO - 'XPC_SERVICE_NAME': '0', 04:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:04 INFO - Downloading/unpacking psutil>=3.1.1 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:06 INFO - Installing collected packages: psutil 04:45:06 INFO - Running setup.py install for psutil 04:45:06 INFO - building 'psutil._psutil_osx' extension 04:45:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:45:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:45:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:45:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:45:07 INFO - building 'psutil._psutil_posix' extension 04:45:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:45:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:07 INFO - ^ 04:45:07 INFO - 1 warning generated. 04:45:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:45:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:07 INFO - Successfully installed psutil 04:45:07 INFO - Cleaning up... 04:45:07 INFO - Return code: 0 04:45:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:45: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 mozsystemmonitor==0.3 04:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:07 INFO - 'HOME': '/Users/cltbld', 04:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:07 INFO - 'LOGNAME': 'cltbld', 04:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:07 INFO - 'MOZ_NO_REMOTE': '1', 04:45:07 INFO - 'NO_EM_RESTART': '1', 04:45:07 INFO - 'PAGER': '/bin/cat', 04:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:07 INFO - 'PWD': '/builds/slave/test', 04:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:07 INFO - 'SHELL': '/bin/bash', 04:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:07 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:07 INFO - 'USER': 'cltbld', 04:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:07 INFO - 'XPC_FLAGS': '0x0', 04:45:07 INFO - 'XPC_SERVICE_NAME': '0', 04:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:45:09 INFO - Installing collected packages: mozsystemmonitor 04:45:09 INFO - Running setup.py install for mozsystemmonitor 04:45:09 INFO - Successfully installed mozsystemmonitor 04:45:09 INFO - Cleaning up... 04:45:09 INFO - Return code: 0 04:45:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:09 INFO - 'HOME': '/Users/cltbld', 04:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:09 INFO - 'LOGNAME': 'cltbld', 04:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:09 INFO - 'MOZ_NO_REMOTE': '1', 04:45:09 INFO - 'NO_EM_RESTART': '1', 04:45:09 INFO - 'PAGER': '/bin/cat', 04:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:09 INFO - 'PWD': '/builds/slave/test', 04:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:09 INFO - 'SHELL': '/bin/bash', 04:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:09 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:09 INFO - 'USER': 'cltbld', 04:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:09 INFO - 'XPC_FLAGS': '0x0', 04:45:09 INFO - 'XPC_SERVICE_NAME': '0', 04:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:09 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:11 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:11 INFO - warning: no files found matching '*.txt' 04:45:11 INFO - no previously-included directories found matching 'build' 04:45:11 INFO - no previously-included directories found matching 'dist' 04:45:11 INFO - no previously-included directories found matching '.git*' 04:45:11 INFO - Installing collected packages: jsonschema, functools32 04:45:11 INFO - Running setup.py install for functools32 04:45:11 INFO - warning: no files found matching '*.txt' 04:45:11 INFO - no previously-included directories found matching 'build' 04:45:11 INFO - no previously-included directories found matching 'dist' 04:45:11 INFO - no previously-included directories found matching '.git*' 04:45:11 INFO - Successfully installed jsonschema functools32 04:45:11 INFO - Cleaning up... 04:45:11 INFO - Return code: 0 04:45:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:45: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 functools32==3.2.3-2 04:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:12 INFO - 'HOME': '/Users/cltbld', 04:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:12 INFO - 'LOGNAME': 'cltbld', 04:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:12 INFO - 'MOZ_NO_REMOTE': '1', 04:45:12 INFO - 'NO_EM_RESTART': '1', 04:45:12 INFO - 'PAGER': '/bin/cat', 04:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:12 INFO - 'PWD': '/builds/slave/test', 04:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:12 INFO - 'SHELL': '/bin/bash', 04:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:12 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:12 INFO - 'USER': 'cltbld', 04:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:12 INFO - 'XPC_FLAGS': '0x0', 04:45:12 INFO - 'XPC_SERVICE_NAME': '0', 04:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:12 INFO - Cleaning up... 04:45:12 INFO - Return code: 0 04:45:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45: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 blobuploader==1.2.4 04:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:12 INFO - 'HOME': '/Users/cltbld', 04:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:12 INFO - 'LOGNAME': 'cltbld', 04:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:12 INFO - 'MOZ_NO_REMOTE': '1', 04:45:12 INFO - 'NO_EM_RESTART': '1', 04:45:12 INFO - 'PAGER': '/bin/cat', 04:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:12 INFO - 'PWD': '/builds/slave/test', 04:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:12 INFO - 'SHELL': '/bin/bash', 04:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:12 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:12 INFO - 'USER': 'cltbld', 04:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:12 INFO - 'XPC_FLAGS': '0x0', 04:45:12 INFO - 'XPC_SERVICE_NAME': '0', 04:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:13 INFO - Downloading/unpacking blobuploader==1.2.4 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:15 INFO - Downloading docopt-0.6.1.tar.gz 04:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:15 INFO - Running setup.py install for blobuploader 04:45:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:15 INFO - Running setup.py install for requests 04:45:16 INFO - Running setup.py install for docopt 04:45:16 INFO - Successfully installed blobuploader requests docopt 04:45:16 INFO - Cleaning up... 04:45:16 INFO - Return code: 0 04:45:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:16 INFO - 'HOME': '/Users/cltbld', 04:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:16 INFO - 'LOGNAME': 'cltbld', 04:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:16 INFO - 'MOZ_NO_REMOTE': '1', 04:45:16 INFO - 'NO_EM_RESTART': '1', 04:45:16 INFO - 'PAGER': '/bin/cat', 04:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:16 INFO - 'PWD': '/builds/slave/test', 04:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:16 INFO - 'SHELL': '/bin/bash', 04:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:16 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:16 INFO - 'USER': 'cltbld', 04:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:16 INFO - 'XPC_FLAGS': '0x0', 04:45:16 INFO - 'XPC_SERVICE_NAME': '0', 04:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:16 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-OvcTgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:16 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-0p9jI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:16 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-FILq3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-3NSv9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-JC3yq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-nvw0xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-SM1pLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-e9fjda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-tHPitI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Y2CUhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-oLuVnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:17 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-rpJgcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:18 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-1Ibnci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:18 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-WjstMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:18 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-IH9xQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:18 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-SfsNEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:18 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-xhx1Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:18 INFO - Running setup.py install for manifestparser 04:45:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:18 INFO - Running setup.py install for mozcrash 04:45:18 INFO - Running setup.py install for mozdebug 04:45:18 INFO - Running setup.py install for mozdevice 04:45:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Running setup.py install for mozfile 04:45:19 INFO - Running setup.py install for mozhttpd 04:45:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Running setup.py install for mozinfo 04:45:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Running setup.py install for mozInstall 04:45:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:19 INFO - Running setup.py install for mozleak 04:45:19 INFO - Running setup.py install for mozlog 04:45:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for moznetwork 04:45:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozprocess 04:45:20 INFO - Running setup.py install for mozprofile 04:45:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozrunner 04:45:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozscreenshot 04:45:20 INFO - Running setup.py install for moztest 04:45:20 INFO - Running setup.py install for mozversion 04:45:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:21 INFO - Cleaning up... 04:45:21 INFO - Return code: 0 04:45:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:21 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:21 INFO - 'HOME': '/Users/cltbld', 04:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:21 INFO - 'LOGNAME': 'cltbld', 04:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:21 INFO - 'MOZ_NO_REMOTE': '1', 04:45:21 INFO - 'NO_EM_RESTART': '1', 04:45:21 INFO - 'PAGER': '/bin/cat', 04:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:21 INFO - 'PWD': '/builds/slave/test', 04:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:21 INFO - 'SHELL': '/bin/bash', 04:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:21 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:21 INFO - 'USER': 'cltbld', 04:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:21 INFO - 'XPC_FLAGS': '0x0', 04:45:21 INFO - 'XPC_SERVICE_NAME': '0', 04:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-zgK2h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-goLUqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:21 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)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-9jU3FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-stiYop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-g45PoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-zmESda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:21 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-AVA8mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-AtmyCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-H9goqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-hODKoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-LbjVh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Ads5qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-nsqwC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-mN15zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:22 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)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-yIQWs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:22 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Bp31sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:23 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-WWYZ1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:45:23 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)) 04:45:23 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)) 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:24 INFO - Downloading blessings-1.6.tar.gz 04:45:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:24 INFO - Installing collected packages: blessings 04:45:24 INFO - Running setup.py install for blessings 04:45:24 INFO - Successfully installed blessings 04:45:24 INFO - Cleaning up... 04:45:24 INFO - Return code: 0 04:45:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:25 INFO - Reading from file tmpfile_stdout 04:45:25 INFO - Current package versions: 04:45:25 INFO - blessings == 1.6 04:45:25 INFO - blobuploader == 1.2.4 04:45:25 INFO - docopt == 0.6.1 04:45:25 INFO - functools32 == 3.2.3-2 04:45:25 INFO - jsonschema == 2.5.1 04:45:25 INFO - manifestparser == 1.1 04:45:25 INFO - mozInstall == 1.12 04:45:25 INFO - mozcrash == 1.0 04:45:25 INFO - mozdebug == 0.1 04:45:25 INFO - mozdevice == 0.50 04:45:25 INFO - mozfile == 1.2 04:45:25 INFO - mozhttpd == 0.7 04:45:25 INFO - mozinfo == 0.9 04:45:25 INFO - mozleak == 0.1 04:45:25 INFO - mozlog == 3.4 04:45:25 INFO - moznetwork == 0.27 04:45:25 INFO - mozprocess == 0.25 04:45:25 INFO - mozprofile == 0.28 04:45:25 INFO - mozrunner == 6.13 04:45:25 INFO - mozscreenshot == 0.1 04:45:25 INFO - mozsystemmonitor == 0.3 04:45:25 INFO - moztest == 0.8 04:45:25 INFO - mozversion == 1.4 04:45:25 INFO - psutil == 3.1.1 04:45:25 INFO - requests == 1.2.3 04:45:25 INFO - wsgiref == 0.1.2 04:45:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:25 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:25 INFO - 'HOME': '/Users/cltbld', 04:45:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:25 INFO - 'LOGNAME': 'cltbld', 04:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:25 INFO - 'MOZ_NO_REMOTE': '1', 04:45:25 INFO - 'NO_EM_RESTART': '1', 04:45:25 INFO - 'PAGER': '/bin/cat', 04:45:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:25 INFO - 'PWD': '/builds/slave/test', 04:45:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:25 INFO - 'SHELL': '/bin/bash', 04:45:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:25 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:25 INFO - 'USER': 'cltbld', 04:45:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:25 INFO - 'XPC_FLAGS': '0x0', 04:45:25 INFO - 'XPC_SERVICE_NAME': '0', 04:45:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:45:25 INFO - Cleaning up... 04:45:25 INFO - Return code: 0 04:45:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:25 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 0x101d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc0de0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, '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.smdwrYUcbB/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.KgBd2o3RQH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:45:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:45:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:25 INFO - 'HOME': '/Users/cltbld', 04:45:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:25 INFO - 'LOGNAME': 'cltbld', 04:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:25 INFO - 'MOZ_NO_REMOTE': '1', 04:45:25 INFO - 'NO_EM_RESTART': '1', 04:45:25 INFO - 'PAGER': '/bin/cat', 04:45:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:25 INFO - 'PWD': '/builds/slave/test', 04:45:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:25 INFO - 'SHELL': '/bin/bash', 04:45:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:25 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:25 INFO - 'USER': 'cltbld', 04:45:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:25 INFO - 'XPC_FLAGS': '0x0', 04:45:25 INFO - 'XPC_SERVICE_NAME': '0', 04:45:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:25 INFO - Cleaning up... 04:45:25 INFO - Return code: 0 04:45:25 INFO - Running post-action listener: _resource_record_post_action 04:45:25 INFO - Running post-action listener: _start_resource_monitoring 04:45:25 INFO - Starting resource monitoring. 04:45:25 INFO - [mozharness: 2017-05-03 11:45:25.720675Z] Finished create-virtualenv step (success) 04:45:25 INFO - [mozharness: 2017-05-03 11:45:25.721666Z] Running install step. 04:45:25 INFO - Running pre-action listener: _resource_record_pre_action 04:45:25 INFO - Running main action method: install 04:45:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:25 INFO - Reading from file tmpfile_stdout 04:45:25 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:26 INFO - Reading from file tmpfile_stdout 04:45:26 INFO - Output received: 04:45:26 INFO - Usage: mozinstall [options] installer 04:45:26 INFO - Options: 04:45:26 INFO - -h, --help show this help message and exit 04:45:26 INFO - -d DEST, --destination=DEST 04:45:26 INFO - Directory to install application into. [default: 04:45:26 INFO - "/builds/slave/test"] 04:45:26 INFO - --app=APP Application being installed. [default: firefox] 04:45:26 INFO - mkdir: /builds/slave/test/build/application 04:45:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:45:45 INFO - Reading from file tmpfile_stdout 04:45:45 INFO - Output received: 04:45:45 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:45:45 INFO - Running post-action listener: _resource_record_post_action 04:45:45 INFO - [mozharness: 2017-05-03 11:45:45.517232Z] Finished install step (success) 04:45:45 INFO - [mozharness: 2017-05-03 11:45:45.517369Z] Running run-tests step. 04:45:45 INFO - Running pre-action listener: _resource_record_pre_action 04:45:45 INFO - Running main action method: run_tests 04:45:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:45 INFO - 2017-05-03 04:45:45.531 screenresolution[1622:7461] starting screenresolution argv=screenresolution get 04:45:45 INFO - 2017-05-03 04:45:45.534 screenresolution[1622:7461] Display 0: 1600x1200x32@60 04:45:45 INFO - 2017-05-03 04:45:45.540 screenresolution[1623:7464] starting screenresolution argv=screenresolution list 04:45:45 INFO - Available Modes on Display 0 04:45:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:45 INFO - Intel Iris: 04:45:45 INFO - Chipset Model: Intel Iris 04:45:45 INFO - Type: GPU 04:45:45 INFO - Bus: Built-In 04:45:45 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:45 INFO - Vendor: Intel (0x8086) 04:45:45 INFO - Device ID: 0x0a2e 04:45:45 INFO - Revision ID: 0x0009 04:45:45 INFO - Displays: 04:45:45 INFO - Display: 04:45:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:45 INFO - Display Serial Number: 1600x1200 60 04:45:45 INFO - Main Display: Yes 04:45:45 INFO - Mirror: Off 04:45:45 INFO - Online: Yes 04:45:45 INFO - Rotation: Supported 04:45:45 INFO - Return code: 0 04:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:45 INFO - Python 2.7.3 04:45:45 INFO - Return code: 0 04:45:45 INFO - grabbing minidump binary from tooltool 04:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b43d30>, '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 0x101a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b38be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:45:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:45:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:45:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:45 INFO - Return code: 0 04:45:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:45:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:45 INFO - ENV: RUST_BACKTRACE is now 1 04:45:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:45:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0283 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.smdwrYUcbB/Render', 04:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:45 INFO - 'HOME': '/Users/cltbld', 04:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:45 INFO - 'LOGNAME': 'cltbld', 04:45:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:45 INFO - 'MOZ_NO_REMOTE': '1', 04:45:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:45 INFO - 'NO_EM_RESTART': '1', 04:45:45 INFO - 'PAGER': '/bin/cat', 04:45:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:45 INFO - 'PWD': '/builds/slave/test', 04:45:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:45 INFO - 'RUST_BACKTRACE': '1', 04:45:45 INFO - 'SHELL': '/bin/bash', 04:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KgBd2o3RQH/Listeners', 04:45:45 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 04:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:45 INFO - 'USER': 'cltbld', 04:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:45 INFO - 'XPC_FLAGS': '0x0', 04:45:45 INFO - 'XPC_SERVICE_NAME': '0', 04:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:45:46 INFO - mozversion application_buildid: 20170503030212 04:45:46 INFO - mozversion application_changeset: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 04:45:46 INFO - mozversion application_display_name: Nightly 04:45:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:46 INFO - mozversion application_name: Firefox 04:45:46 INFO - mozversion application_remotingname: firefox 04:45:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:45:46 INFO - mozversion application_vendor: Mozilla 04:45:46 INFO - mozversion application_version: 55.0a1 04:45:46 INFO - mozversion platform_buildid: 20170503030212 04:45:46 INFO - mozversion platform_changeset: 82c2d17e74ef9cdf38a5d5ac4eb3ae846ec30ba4 04:45:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:45:46 INFO - mozversion platform_version: 55.0a1 04:45:46 INFO - using testdate: 1493811946 04:45:46 INFO - actual date: 1493811946 04:45:46 INFO - starting webserver on 'localhost:49240' 04:45:46 INFO - SUITE-START | Running 2 tests 04:45:46 INFO - TEST-START | dromaeo_css 04:45:46 INFO - operating with platform_type : mac_ 04:45:46 INFO - Initialising browser for dromaeo_css test... 04:45:46 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpvjISF1/profile http://localhost:49240/getInfo.html) 04:45:48 INFO - PID 1633 | __metrics Screen width/height:1600/1200 04:45:48 INFO - PID 1633 | colorDepth:24 04:45:48 INFO - PID 1633 | Browser inner width/height: 1024/691 04:45:48 INFO - PID 1633 | __metrics 04:45:48 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:45:48 INFO - PID 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:49 INFO - TEST-INFO | 1633: exit 0 04:45:49 INFO - Browser initialized. 04:45:49 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:49 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpvjISF1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:01 INFO - PID 1636 | RSS: Main: 240750592 04:46:01 INFO - PID 1636 | 04:49:06 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:49:08 INFO - PID 1636 | RSS: Main: 363896832 04:49:08 INFO - PID 1636 | 04:52:10 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:52:11 INFO - PID 1636 | RSS: Main: 364511232 04:52:11 INFO - PID 1636 | 04:55:13 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:55:14 INFO - PID 1636 | RSS: Main: 331644928 04:55:14 INFO - PID 1636 | 04:58:16 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:58:16 INFO - PID 1636 | RSS: Main: 358084608 04:58:16 INFO - PID 1636 | 05:01:20 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:01:21 INFO - PID 1636 | RSS: Main: 321277952 05:01:21 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:04:23 INFO - PID 1636 | RSS: Main: 371810304 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | __start_tp_report 05:04:23 INFO - PID 1636 | _x_x_mozilla_page_load 05:04:23 INFO - PID 1636 | _x_x_mozilla_page_load_details 05:04:23 INFO - PID 1636 | |i|pagename|runs| 05:04:23 INFO - PID 1636 | |0;dojo.html;2850.2731803390134;3516.8241587920616;3525.3654342218424;3550.952469021639;3596.7482276240657;5956.13636022777;6051.12258722485;6063.969680151593;6130.734471166796;6195;6120.751898419022;6148.569600127982;6177.258728952532;6179.505639548836;6188.907166392508;5237.109691352479;5297.629165958385;5383;5408.594355423349;5455.018096742585;5494;5496.587755918302;5505.944940550583;5510.862228444301;5512.724363781815;5318.5213330800025;5367.973160134175;5393.892122157535;5447.210154527611;5496.725163741797;5422.132458806612;5429.592780541475;5457.126859702428;5461.044317244506;5478.643888147258;5367.973160134175;5416.4312747161375;5424.159255315432;5425.728713564306;5462;5594.188842617838;6104.481119104881;6160;6209.87580248397;6211.130441738161;6449.54853160279;6493.577917435352;6568.80293591193;6587.2095348558005;6587.341265873422;6666.799996000128;6672.232693240242;6677.098591690108;6680.0981867448245;6687.699053542554;6141.877162456771;6187.102870083812;6220.160278362454;6231.158793562811;6240.063990401431;6638.269790323061;6689.832754181184;6790.524663273523;6847;6855.211650660138;6522.804315870531;6530.118434011442;6534.8366290843105;6539.673016349258;6541.28045914949;6608.2400523939405;6629.370209830058;6656.5340426169705;6680.265170831205;6705.765298214539;6367.681615919186;6456.0961465394885;6610.305917878553;6619.801405957829;6625.17185351831;6433.967830160913;6456.483481321483;6462.773802916875;6463.095166676669;6472.870542589122;5195.532402083755;5204.739763011834;5235.47645235481;5236.031334203185;5242.423333433319;4186.727862688916;4298.247806633826;4667.62658987287;4679.9532004680195;4692.6245900327895;6169.722362493699;6229.56393052482;6236.469900058455;6246.15676883624;6247.812565623039;6723.159605049369;6724.9327506725285;6728.394444500019;6732.788098142283;6750.864982700318;6122.184701511125;6219.968900155471;6247.500199983991;6251.593646412968;6255.749770009149;4826.7827947742435;5208.140656791663;5720.599558030981;5755.079187330007;5762.164486149485;6828.556143850733;6873.072135261776;6898.758543450955;6906.309369063154;6918.93081069203;6440.935590644033;6539.509536784665;6544.934550654432;6719.529632925648;6739.831504212434;6575.210974683068;6824.215215250111;6873.243943166348;6885.862282754417;6915.412189963909;6529.67351632426;6572.8356791080605;6573.737050518117;6578.8355291118105;6588.505862060269;6022.939770602238;6640.83397915056;6651.268360480247;6673.165854268216;6706.932930670826;6277.403646653561;6329.367063293634;6376.52176086786;6474.902876456763;6512.706928188148;5419.972900135474;6011;6469.967650161719;6480.513961452815;6492.64290464029;5878.500327472042;6340.144080549251;6385.489160867212;6386.457151142205;6391.680415979089;6260.655663938345;6350.1744773181035;6379.744810207725;6411.96794016027;6457.483401327789;6350.793349263625;6368.713407896748;6371.458426033839;6378.840528986812;6379.298277189413 05:04:23 INFO - PID 1636 | |1;ext.html;774.303127185533;786.940979426543;802.9558374289413;1076.074575864756;1087.6410784441134;25604.97580096796;26049.609255861153;32540.67459325406;35817.92546223611;35889.820550897275;37012.629873701255;37289.627103728955;37303.694370697034;37383;37426.4386034209;8037.91962080379;8200.83598328033;8201.917980820204;8204.589770511451;8208.876866846973;5894.646321220741;6013.128096426038;8090.514569125871;8239.546824924657;8253.09215986244;8206.958965205196;8310.335173186131;8313.916860831374;8328.375371847105;8337.83324333513;8199.877001844978;8241.423100382975;8264.545450000247;8267.793305167359;8274.586270686501;5804.129380592903;5912.3496415394275;7930.9603451982375;8046.879296810553;8060.758177254692;35879.28241435157;36154.819225903964;36167.81916090429;36175.27649446997;36216.45675314873;3486.703630191437;3519.384107781128;3522.330757156132;3523.8061906595126;3529.4882242074755;29470;29550;29555.261118471994;29592.8520357399;29594.66823992904;3032.4996375598;3114.1903105192587;3139.3250451152885;3142.308692087737;3146.8426578671206;286997;294225.99999999785;294824;294896;295154;47373.5262647376;47497.28754068692;47518.76240618775;47530.76234618874;47656.5234347659;49665.50334496609;49671.25493117606;49679.25481117786;49687.75156124196;49722;13710.45158193681;13780.586582402344;13791.448342066406;13806.033577649468;13816.516421925386;9593.328467007243;11144.777104458028;11146.944265278622;11147.72130696739;11161.665150045512;10836.945815270874;10935.671929842118;12429.254244745163;12459.813102803466;12659.987201023896;5165.468920229136;5214.895702085937;5232.502912223333;5236.214567814782;5246.265522826808;957.9473128977974;976.511744127936;1006.959721611142;1007.5163921317809;1029.763154474475;22175.00212490442;23704;23708.525829483315;23803.523929521314;23853.642195367083;1090.2477290669412;1090.487470888681;1094.63876920616;1127.2503784983057;1134.28540019787;10746.623868164577;11606.535738570363;11979.341136237415;12017.579384721494;12018.4591693374;288064;288281;288812;288874.55562722473;288998;50716;50782.23826642604;50926.49073509217;50960.745196274525;50988.49011509837;1457.8250609926665;1482.4885414531968;1522.9695406092037;1526.4428483603626;1537.031670047885;23271.53456930818;23388.94749736232;23427.414314642272;23465;23469.178578749663;37011.44482832706;37019.44470832886;37074.443883341235;37114.70098546538;37125.6287437133;266970;267039;267170;267858;268000.9799852965;32967.34065318632;33045.339093218485;33727.49408758822;33820.661793381754;33868.830655847545;42697.78651106724;42712.78643606762;42763.57236427596;42850.57149428466;43049.569504304556;27757.444851103268;28543.2874027559;29227;29607;30148.84925575446;28218.435631286848;29086.56370154407;29148;29443;29826.55260171056;35978.64021359753;36101.638983609824;36172.73395431147;36209;36329.27341453103;25041.373965651004;28233;29780.404391912474;30203.697963020088;30422.391552169276;18803.529911752317;23516;23819.236312317516;24015;24223.63664544998 05:04:23 INFO - PID 1636 | |2;jquery.html;382.8266363595581;424.2607905104117;430.96121349078584;437.28285611596976;454.03517525258803;16768;20791;21560;22223.555528889414;22489.875506224686;21705.67441488375;22105.66841497379;22362.440938976535;22546.09815607374;22614.77385226147;5948.821535353924;6031.607945483541;6115.3884611538715;6151.354107818659;6197.69011549423;6041.335453100155;6135.478484328837;6154.50763938886;6155.661438620874;6156.415140561639;3625.43805710114;3703.4074548072313;3803.5055442792536;3838;6196.349383314755;6153.446189842891;6161.353057928898;6162.352952939944;6166.352532984017;6180.6291622502795;5387;5401.378841433247;5460.699661518614;5463.3717122530625;5468.152436372368;26260.474790504082;26308.473830523475;26913.86543067292;27112.728872711217;27388.315292117855;2157;2216.9334919952425;2220.877851718155;2223.455253462912;2224.799768020886;16754.74867876983;17054.914725426293;17098.487045388658;17170.39903603368;17327.65344693099;2071.927482538104;2082.073477302601;2130.808227259554;2135;2135.754388245356;164425;165376;166596.835079124;166840;168276;11311.547538098548;11602.07183425324;12047.951848185829;12254.81617775734;12459.06557008228;15070.397184112513;15087;15918.68162636764;16362.018278903304;16382.344706211617;8609.440386374865;8654.264387527004;8767.386282960131;8893.510856902803;8941.865872011926;8142.633581488847;8164.510129392137;8166.387520935954;8336.666533338599;8338.916610833936;7413;7565.524722679024;7577.962110189524;7586.696532138763;7605.239476052461;2134.487722946482;2149.623815832223;2151.7095192149172;2156.8598041127275;2308.8614683119067;912.5346073502561;922.5341202692597;926.9212116970133;942.1144124522926;949.803909843372;18718.71921921172;20417.387478375374;20463.8976805115;20550.794492054887;20892.791072089083;843.2874221283006;901.1079031758643;955.1117460761419;991.2565575818137;996.3673067602125;8490.278326342206;9972.750681233027;10452.902445243291;10482.475876206312;10648.840267395995;487368;514140;544121;551144;562220;131314;131786.34106829404;133241;133586.33206834097;135566.3221683905;1082.680609220282;1105.7180418993105;1126.8366086917351;1126.8422420861086;1130.0733398613222;16841;17155.7426638598;17312;17683.027433491283;17712.734308985633;55668.16497752456;55761.28813424025;56616.15075773784;56701.86596268134;58216.4178358211;77151.61424193067;77959.4408111846;78084.31494110372;78547;80859.6366690327;7999.680012799423;8702.042775294585;8770.517621530877;8888.6000129993;8889.377743557889;11698.883011169779;13134.146280491737;13805.585832425044;13902.791458128338;14299.21354325522;11355;11707.297562145957;11988.920997110303;12138.211016283913;12164.513419463123;10646.467676615983;11089.390083545482;11094.112471002461;11219.495122719334;11470.942645286721;11485.598004069818;11614.593489227836;11927.82108268394;11955.581554645894;12117.757644846877;7990.961175047207;8367.58162091905;8921.598528066123;8926.196642302224;9036.45781253127;11071.557137714723;11491;12012.219205751599;12013.339266340436;12114.454849531969 05:04:23 INFO - PID 1636 | |3;mootools.html;1227.5335372558427;1394.5537428023038;1405.1498843199863;1425.2731107135357;1433.3836450326355;11002.779944401118;11498.137639677037;11505.4822532986;11519.827202591969;11524.942375288123;10918.454077296143;11247.775044499125;11635;11656.184067115306;11674.824877626843;2505.561526732828;2549.7322781107996;2549.859757713325;2551.4995304601503;2552.1577879299784;2472.332470233036;2511.3847107458605;2511.547921374161;2516.836405633628;2523.179966510882;2446.4006318452;2532.7847132993625;2537.112010796225;2537.936551586207;2543.9618405723927;2496.0639760089966;2510.297116807297;2523.4196134888894;2523.987380063088;2533.5819589767666;2481.416867036245;2496.7253602103756;2513.6229565565127;2516.207394670673;2516.30801529579;11283.323000619988;11401.37292448915;11409.771804563863;11412.087033037336;11427.48576314068;5638.71806409682;5941.554383421218;6439.613623182624;6708.731650733965;6734.0572319875255;3965.643092121723;4254.31930891056;4322.913541729148;4402.779861006937;4405.779711014468;1250.4373032135484;1261.6215135459418;1315.8617795606826;1316.210273835691;1319.2612137203198;15157.469488567847;15606.219689015505;16105.27526261321;16268.26792794327;16288.429904953387;4941.6293777966075;5415.864603384947;5575.804846830341;5587.078132108238;5601.215829783833;11616.767664646659;11629.76740465203;11643.825342619868;11645.708857278467;11661.766764664659;5398.271233383522;6488.513361497907;6490.643014634146;6500.6424646643945;6501.057346684799;5181.274621552986;6184.350643182401;6185.567010309325;6189.81430557084;6191.721372538246;4228.619424251832;4279.40088387626;4284.407933200431;4317.848875289413;4336.783160841946;1239.9938000309942;1240.9131360804656;1244.2099266965542;1259.106034715344;1259.9685007874878;776.1423626892202;785.4384115357547;793.1156760212322;794.8291117409784;797.0674311056079;6545.661712526646;6762.425193858478;6897.689603967833;6944.651426187007;6973.19808222061;880.933929955256;903.3631289940707;908.5366463103865;912.4844462878585;917.2295271971576;3827.445020472016;3843.8078096095082;3887.9502534315575;3943.526776786804;3969.0672691917375;14490.85509144916;15263.542093737206;15325.846741532665;15331.003484773453;15356;15032.549023529311;15223.543293701206;15238.238088095552;15239.314230859638;15251.084934903942;8004.83990320202;8442.493450392873;8488;8597.742067738103;8635.222829945335;6471.902921456088;6532.248791389051;6623.304553022056;6730.629815360202;6763.222229443677;12994;13108.868911310765;13128.146670466389;13321.467141314239;13805.378757955716;12971.935140324238;13068.477260909458;13524.459021639024;13688.520901768788;13785.931070344584;8776.561171941505;10502.527386267484;10529.631462898762;10538.89461105379;10544.05103540685;10287.588496460057;10469.581216751245;10542.789144217226;10545.050945414949;10545.57817687284;8894.199522043047;8901.910980890108;9086.591103400495;9120.315976301936;9163.725088247364;8713.956430217808;8763.780905477413;8803.515806630698;8861.291096712119;8950.283977281671;7988.081370642451;8137.267645911897;8143.063547692092;8150.307223886036;8160.5103693778565;5808.6224395414165;5827.329857066322;5936;5957.880842383215;6040.456358927542;5983.940160598512;6002.129691194839;6115.755369785159;6149.2620885494025;6288.213973253343 05:04:23 INFO - PID 1636 | |4;prototype.html;378.64596602176965;390.1300100775271;400.74152171849147;405.37167390544687;405.61061381074165;5422.810201642938;5520.834374968752;5963.642181469115;5978.73095710693;6010.6093103948215;5612.607117501777;5752.309722833266;5816.4407660629295;5828.912566311498;5838.854028649286;4241.388272456477;4305.461817272833;4306.956930430702;4325.935110973338;4326.632236259922;4309.913801723964;4318.654507639397;4322.956770432287;4325.783710814462;4347.978260108695;4019.819108140126;4128.678822776713;4231.957680423203;4264.505264420318;4308.504521979966;3560.9821950890077;4242.091059023421;4255.212785634637;4260.510041345254;4270.957290427087;3063.2648164440598;3079.5842561254394;3669.981650091759;3691.920427927129;3694.5935947046073;5130.897382052375;5259.053370393328;5586.497215250647;5746.683932383718;5749.1663708762;3953.940690889639;4962.6526143169995;4969.503049695038;4974.303597496353;4996.376177742249;5868.119782032687;6137.478314343286;6176.3514830943;6190.473809726179;6196.659183744892;3355.580552430946;3625.365561026836;3662.901129661091;3769.378052621342;4123.752574845519;4763.190257656207;5662.631928924607;6415.262244841903;6479.870402591969;6579.868402631969;3743.9251214975548;3864.516935383077;3961.207758448264;4183.611274867367;4187.267228235108;3609.6029436761937;4040.696947728932;4232.661387089025;4248.171606536695;4248.171606536757;4193.622573968358;4323.113761678848;4811.975940120347;4849.660523763303;4943.6786608871025;5449.836504904859;6184.6598437086395;6226.132160351876;6226.906596401058;6227.346128656516;1243.7077286837587;1385.2519639394816;1418.7871819227198;1435.1604311477693;1442.37977669601;874.4272501511521;933.2767105493161;933.659214386744;946.8627049077845;950.4249928793118;5819.447152520504;5831.737571809279;5859.560532960045;5877.353491115974;5888.558358123158;5026.597872170218;5636.914341693599;6224.875502489925;6373.203349581302;6392.7123279452535;5042.697438153723;5172.5344718975475;5515.338159420815;5863.677497737665;5901.557383196277;3277.557529733503;3321.485169798685;3322.302316513511;3344.0469466202017;3352.513885486591;5461.4811592899405;5802.586249937607;5980.5514586405325;6582.341765823379;6640.701168447335;5362.943122420183;6144.8771024579255;6469.935300647027;6554.803355899236;6582.713508599276;5542.501174894279;5570.777168913198;6573.605583664996;6609.966950165314;6615.272320044791;5216.113260745758;6203.31763506023;6403.839904002437;6464.09502669636;6553.2136143663065;6062.363451837494;6235.844103897439;6240.719167637377;6303.1175635412;6360.4911607072245;5454.618176727749;6123.387661233841;6321.52588555851;6498.512611554058;6579.736810527525;3523.5419395478425;3582.1760994971823;4100.91798164041;4105.973506623344;4138.482689663792;5449.618526703092;5490.725463726718;6200.286966998853;6245.033609035671;6479.287278399278;5762.510186634183;5903.439173278532;6187.504999599932;6216.337746689983;6226.844328891814;5318;5878.2064421302575;6120.234970628671;6235.657038862906;6240.344763799736;5558.44415558441;5700;5933.495652869555;6140.631562106288;6188.628682279078;5463.9180412294645;5659.971700141473;5883.235179426647;5987.880242395215;6239.908016097074;6059.909101363572;6104.38956104386;6112.1748563945075;6114.633122012693;6115.816525504242 05:04:23 INFO - PID 1636 | |5;yui.html;809.1584751858068;819.6680344460493;828.5525816059328;840.2395831772249;849.983000339993;15977.84022159778;16400.17999100045;16430;16431.507054788344;16441.177941102927;12302.507899684024;16472.752908706378;16706;16752.664946701057;16760.41338553151;963.6579014449875;964.0841200859194;965.5558443116175;966.8888077871029;972.7616733900204;858.5750053723416;952.4523399045542;972.6838777397339;978.7797745507239;995.258532393368;1016.7864748402807;1019.2966852871494;1020.367372229219;1023.4524529376785;1024.3802499487815;1013.2906965124369;1017.1811691588232;1017.4353233955152;1020.566259339785;1032.473438546343;755.833716582351;757.0763668324635;759.851828893366;766.2605585609913;779.8752199648029;16443.93114447557;16778.74831877523;16856.241469133918;16979.660406791794;17117.657646847118;16268.186590670419;16358.83641163585;16482.75275870851;16686.49940501787;16746.162691865356;13557.25435101069;13660;13759.656008599764;13867.653308667263;13887.722245555033;3437.432823584106;3465.7227421806197;3467.133216695826;3467.9133021674406;3472.166679996808;92558.53720731354;129311;133130;134811.32594336965;137818;10152;11012.669619911416;11043.723906902393;11082.335059896432;11267;24069.759302407103;24293.757062429504;24378.390540236636;24418.389540261636;24587;7840.882386764089;7904.920950790533;7914.802129946797;7919.485233459806;7928.8810667840025;3867.207222519377;3956.0307724607655;4011.13760541485;4011.4183443400543;4016.979915100406;4073.816678249486;4119.402686610485;4150.688698347636;4156.044109854751;4172.937405938914;917.5091326140483;919.1911118215927;937.095702646949;937.1986951156663;938.4556956964944;525.5506541906691;643.9195100612424;660.4881217056819;665.2283351312454;691.4952084977995;10986.176036797273;11623.476943537562;11731.120165987584;11819.527218911147;11841.703957400961;636.4908073541148;650.3788881618043;652.1912828093131;652.9738810447624;672.1195234243157;5103.387593488805;5312.548433383128;5409.134538473825;5532.861678458071;5898.705064746746;104109;107549.25437302914;108089;108671;112570;94110.52944735369;99919.00080999243;100110;101160.41296902615;101665.49167254116;86012.13987860167;87562.24875502454;88313.11686883049;89674;90306.09693903108;11806.645800625995;12447.502099916264;12485.93757031209;12545.686357841127;12614.432350544064;31270.53094203634;41126.681698422435;41831.954201145214;42594.148117036864;42698.35952460654;35481.93554193378;55013.72493137509;55634.72182639061;56602.43397566136;56627.71686141543;15888.364465421253;16146.273417695997;16152.273147708147;16159.515214543582;16162.111083890028;15972.20138993069;16070.67858642844;16380.83619163793;16537.421190258283;16739.07935063583;16049.518514444586;16055.357785688442;16372.099534525716;16607.41874034403;16649.334026639284;15642.34090693408;16230;16315.918420408298;16322.918385407997;16428.75356869624;15754.527364179094;16491.09298988567;16864.156792160098;16957.66084678275;17148;11532.769344613229;11674.824877627012;11832.940835295769;12261.87738122643;12349.567765128177;12116.878831211927;12547.74904501923;13436;13526.39131239077;13559.254241016444 05:04:23 INFO - PID 1636 | __end_tp_report 05:04:23 INFO - PID 1636 | __start_cc_report 05:04:23 INFO - PID 1636 | _x_x_mozilla_cycle_collect,1658 05:04:23 INFO - PID 1636 | __end_cc_report 05:04:23 INFO - PID 1636 | __startTimestamp1493813063481__endTimestamp 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | ------- Summary: start ------- 05:04:23 INFO - PID 1636 | Number of tests: 6 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6077.51 Median:6340.14 stddev:745.22 (11.8%) stddev-sans-first:703.19 05:04:23 INFO - PID 1636 | Values: 2850.3 3516.8 3525.4 3551.0 3596.7 5956.1 6051.1 6064.0 6130.7 6195.0 6120.8 6148.6 6177.3 6179.5 6188.9 5237.1 5297.6 5383.0 5408.6 5455.0 5494.0 5496.6 5505.9 5510.9 5512.7 5318.5 5368.0 5393.9 5447.2 5496.7 5422.1 5429.6 5457.1 5461.0 5478.6 5368.0 5416.4 5424.2 5425.7 5462.0 5594.2 6104.5 6160.0 6209.9 6211.1 6449.5 6493.6 6568.8 6587.2 6587.3 6666.8 6672.2 6677.1 6680.1 6687.7 6141.9 6187.1 6220.2 6231.2 6240.1 6638.3 6689.8 6790.5 6847.0 6855.2 6522.8 6530.1 6534.8 6539.7 6541.3 6608.2 6629.4 6656.5 6680.3 6705.8 6367.7 6456.1 6610.3 6619.8 6625.2 6434.0 6456.5 6462.8 6463.1 6472.9 5195.5 5204.7 5235.5 5236.0 5242.4 4186.7 4298.2 4667.6 4680.0 4692.6 6169.7 6229.6 6236.5 6246.2 6247.8 6723.2 6724.9 6728.4 6732.8 6750.9 6122.2 6220.0 6247.5 6251.6 6255.7 4826.8 5208.1 5720.6 5755.1 5762.2 6828.6 6873.1 6898.8 6906.3 6918.9 6440.9 6539.5 6544.9 6719.5 6739.8 6575.2 6824.2 6873.2 6885.9 6915.4 6529.7 6572.8 6573.7 6578.8 6588.5 6022.9 6640.8 6651.3 6673.2 6706.9 6277.4 6329.4 6376.5 6474.9 6512.7 5420.0 6011.0 6470.0 6480.5 6492.6 5878.5 6340.1 6385.5 6386.5 6391.7 6260.7 6350.2 6379.7 6412.0 6457.5 6350.8 6368.7 6371.5 6378.8 6379.3 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:42814.70 Median:17843.76 stddev:74257.71 (416.2%) stddev-sans-first:74398.94 05:04:23 INFO - PID 1636 | Values: 774.3 786.9 803.0 1076.1 1087.6 25605.0 26049.6 32540.7 35817.9 35889.8 37012.6 37289.6 37303.7 37383.0 37426.4 8037.9 8200.8 8201.9 8204.6 8208.9 5894.6 6013.1 8090.5 8239.5 8253.1 8207.0 8310.3 8313.9 8328.4 8337.8 8199.9 8241.4 8264.5 8267.8 8274.6 5804.1 5912.3 7931.0 8046.9 8060.8 35879.3 36154.8 36167.8 36175.3 36216.5 3486.7 3519.4 3522.3 3523.8 3529.5 29470.0 29550.0 29555.3 29592.9 29594.7 3032.5 3114.2 3139.3 3142.3 3146.8 286997.0 294226.0 294824.0 294896.0 295154.0 47373.5 47497.3 47518.8 47530.8 47656.5 49665.5 49671.3 49679.3 49687.8 49722.0 13710.5 13780.6 13791.4 13806.0 13816.5 9593.3 11144.8 11146.9 11147.7 11161.7 10836.9 10935.7 12429.3 12459.8 12660.0 5165.5 5214.9 5232.5 5236.2 5246.3 957.9 976.5 1007.0 1007.5 1029.8 22175.0 23704.0 23708.5 23803.5 23853.6 1090.2 1090.5 1094.6 1127.3 1134.3 10746.6 11606.5 11979.3 12017.6 12018.5 288064.0 288281.0 288812.0 288874.6 288998.0 50716.0 50782.2 50926.5 50960.7 50988.5 1457.8 1482.5 1523.0 1526.4 1537.0 23271.5 23388.9 23427.4 23465.0 23469.2 37011.4 37019.4 37074.4 37114.7 37125.6 266970.0 267039.0 267170.0 267858.0 268001.0 32967.3 33045.3 33727.5 33820.7 33868.8 42697.8 42712.8 42763.6 42850.6 43049.6 27757.4 28543.3 29227.0 29607.0 30148.8 28218.4 29086.6 29148.0 29443.0 29826.6 35978.6 36101.6 36172.7 36209.0 36329.3 25041.4 28233.0 29780.4 30203.7 30422.4 18803.5 23516.0 23819.2 24015.0 24223.6 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:35227.74 Median:22580.44 stddev:91239.65 (404.1%) stddev-sans-first:91458.09 05:04:23 INFO - PID 1636 | Values: 382.8 424.3 431.0 437.3 454.0 16768.0 20791.0 21560.0 22223.6 22489.9 21705.7 22105.7 22362.4 22546.1 22614.8 5948.8 6031.6 6115.4 6151.4 6197.7 6041.3 6135.5 6154.5 6155.7 6156.4 3625.4 3703.4 3803.5 3838.0 6196.3 6153.4 6161.4 6162.4 6166.4 6180.6 5387.0 5401.4 5460.7 5463.4 5468.2 26260.5 26308.5 26913.9 27112.7 27388.3 2157.0 2216.9 2220.9 2223.5 2224.8 16754.7 17054.9 17098.5 17170.4 17327.7 2071.9 2082.1 2130.8 2135.0 2135.8 164425.0 165376.0 166596.8 166840.0 168276.0 11311.5 11602.1 12048.0 12254.8 12459.1 15070.4 15087.0 15918.7 16362.0 16382.3 8609.4 8654.3 8767.4 8893.5 8941.9 8142.6 8164.5 8166.4 8336.7 8338.9 7413.0 7565.5 7578.0 7586.7 7605.2 2134.5 2149.6 2151.7 2156.9 2308.9 912.5 922.5 926.9 942.1 949.8 18718.7 20417.4 20463.9 20550.8 20892.8 843.3 901.1 955.1 991.3 996.4 8490.3 9972.8 10452.9 10482.5 10648.8 487368.0 514140.0 544121.0 551144.0 562220.0 131314.0 131786.3 133241.0 133586.3 135566.3 1082.7 1105.7 1126.8 1126.8 1130.1 16841.0 17155.7 17312.0 17683.0 17712.7 55668.2 55761.3 56616.2 56701.9 58216.4 77151.6 77959.4 78084.3 78547.0 80859.6 7999.7 8702.0 8770.5 8888.6 8889.4 11698.9 13134.1 13805.6 13902.8 14299.2 11355.0 11707.3 11988.9 12138.2 12164.5 10646.5 11089.4 11094.1 11219.5 11470.9 11485.6 11614.6 11927.8 11955.6 12117.8 7991.0 8367.6 8921.6 8926.2 9036.5 11071.6 11491.0 12012.2 12013.3 12114.5 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7053.98 Median:2549.80 stddev:4460.02 (174.9%) stddev-sans-first:4451.04 05:04:23 INFO - PID 1636 | Values: 1227.5 1394.6 1405.1 1425.3 1433.4 11002.8 11498.1 11505.5 11519.8 11524.9 10918.5 11247.8 11635.0 11656.2 11674.8 2505.6 2549.7 2549.9 2551.5 2552.2 2472.3 2511.4 2511.5 2516.8 2523.2 2446.4 2532.8 2537.1 2537.9 2544.0 2496.1 2510.3 2523.4 2524.0 2533.6 2481.4 2496.7 2513.6 2516.2 2516.3 11283.3 11401.4 11409.8 11412.1 11427.5 5638.7 5941.6 6439.6 6708.7 6734.1 3965.6 4254.3 4322.9 4402.8 4405.8 1250.4 1261.6 1315.9 1316.2 1319.3 15157.5 15606.2 16105.3 16268.3 16288.4 4941.6 5415.9 5575.8 5587.1 5601.2 11616.8 11629.8 11643.8 11645.7 11661.8 5398.3 6488.5 6490.6 6500.6 6501.1 5181.3 6184.4 6185.6 6189.8 6191.7 4228.6 4279.4 4284.4 4317.8 4336.8 1240.0 1240.9 1244.2 1259.1 1260.0 776.1 785.4 793.1 794.8 797.1 6545.7 6762.4 6897.7 6944.7 6973.2 880.9 903.4 908.5 912.5 917.2 3827.4 3843.8 3888.0 3943.5 3969.1 14490.9 15263.5 15325.8 15331.0 15356.0 15032.5 15223.5 15238.2 15239.3 15251.1 8004.8 8442.5 8488.0 8597.7 8635.2 6471.9 6532.2 6623.3 6730.6 6763.2 12994.0 13108.9 13128.1 13321.5 13805.4 12971.9 13068.5 13524.5 13688.5 13785.9 8776.6 10502.5 10529.6 10538.9 10544.1 10287.6 10469.6 10542.8 10545.1 10545.6 8894.2 8901.9 9086.6 9120.3 9163.7 8714.0 8763.8 8803.5 8861.3 8950.3 7988.1 8137.3 8143.1 8150.3 8160.5 5808.6 5827.3 5936.0 5957.9 6040.5 5983.9 6002.1 6115.8 6149.3 6288.2 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:4913.81 Median:5578.64 stddev:1561.69 (28.0%) stddev-sans-first:1528.52 05:04:23 INFO - PID 1636 | Values: 378.6 390.1 400.7 405.4 405.6 5422.8 5520.8 5963.6 5978.7 6010.6 5612.6 5752.3 5816.4 5828.9 5838.9 4241.4 4305.5 4307.0 4325.9 4326.6 4309.9 4318.7 4323.0 4325.8 4348.0 4019.8 4128.7 4232.0 4264.5 4308.5 3561.0 4242.1 4255.2 4260.5 4271.0 3063.3 3079.6 3670.0 3691.9 3694.6 5130.9 5259.1 5586.5 5746.7 5749.2 3953.9 4962.7 4969.5 4974.3 4996.4 5868.1 6137.5 6176.4 6190.5 6196.7 3355.6 3625.4 3662.9 3769.4 4123.8 4763.2 5662.6 6415.3 6479.9 6579.9 3743.9 3864.5 3961.2 4183.6 4187.3 3609.6 4040.7 4232.7 4248.2 4248.2 4193.6 4323.1 4812.0 4849.7 4943.7 5449.8 6184.7 6226.1 6226.9 6227.3 1243.7 1385.3 1418.8 1435.2 1442.4 874.4 933.3 933.7 946.9 950.4 5819.4 5831.7 5859.6 5877.4 5888.6 5026.6 5636.9 6224.9 6373.2 6392.7 5042.7 5172.5 5515.3 5863.7 5901.6 3277.6 3321.5 3322.3 3344.0 3352.5 5461.5 5802.6 5980.6 6582.3 6640.7 5362.9 6144.9 6469.9 6554.8 6582.7 5542.5 5570.8 6573.6 6610.0 6615.3 5216.1 6203.3 6403.8 6464.1 6553.2 6062.4 6235.8 6240.7 6303.1 6360.5 5454.6 6123.4 6321.5 6498.5 6579.7 3523.5 3582.2 4100.9 4106.0 4138.5 5449.6 5490.7 6200.3 6245.0 6479.3 5762.5 5903.4 6187.5 6216.3 6226.8 5318.0 5878.2 6120.2 6235.7 6240.3 5558.4 5700.0 5933.5 6140.6 6188.6 5463.9 5660.0 5883.2 5987.9 6239.9 6059.9 6104.4 6112.2 6114.6 6115.8 05:04:23 INFO - PID 1636 | 05:04:23 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21972.64 Median:16817.49 stddev:31926.93 (189.8%) stddev-sans-first:31976.95 05:04:23 INFO - PID 1636 | Values: 809.2 819.7 828.6 840.2 850.0 15977.8 16400.2 16430.0 16431.5 16441.2 12302.5 16472.8 16706.0 16752.7 16760.4 963.7 964.1 965.6 966.9 972.8 858.6 952.5 972.7 978.8 995.3 1016.8 1019.3 1020.4 1023.5 1024.4 1013.3 1017.2 1017.4 1020.6 1032.5 755.8 757.1 759.9 766.3 779.9 16443.9 16778.7 16856.2 16979.7 17117.7 16268.2 16358.8 16482.8 16686.5 16746.2 13557.3 13660.0 13759.7 13867.7 13887.7 3437.4 3465.7 3467.1 3467.9 3472.2 92558.5 129311.0 133130.0 134811.3 137818.0 10152.0 11012.7 11043.7 11082.3 11267.0 24069.8 24293.8 24378.4 24418.4 24587.0 7840.9 7904.9 7914.8 7919.5 7928.9 3867.2 3956.0 4011.1 4011.4 4017.0 4073.8 4119.4 4150.7 4156.0 4172.9 917.5 919.2 937.1 937.2 938.5 525.6 643.9 660.5 665.2 691.5 10986.2 11623.5 11731.1 11819.5 11841.7 636.5 650.4 652.2 653.0 672.1 5103.4 5312.5 5409.1 5532.9 5898.7 104109.0 107549.3 108089.0 108671.0 112570.0 94110.5 99919.0 100110.0 101160.4 101665.5 86012.1 87562.2 88313.1 89674.0 90306.1 11806.6 12447.5 12485.9 12545.7 12614.4 31270.5 41126.7 41832.0 42594.1 42698.4 35481.9 55013.7 55634.7 56602.4 56627.7 15888.4 16146.3 16152.3 16159.5 16162.1 15972.2 16070.7 16380.8 16537.4 16739.1 16049.5 16055.4 16372.1 16607.4 16649.3 15642.3 16230.0 16315.9 16322.9 16428.8 15754.5 16491.1 16864.2 16957.7 17148.0 11532.8 11674.8 11832.9 12261.9 12349.6 12116.9 12547.7 13436.0 13526.4 13559.3 05:04:23 INFO - PID 1636 | -------- Summary: end -------- 05:04:23 INFO - PID 1636 | 05:04:24 INFO - TEST-INFO | 1636: exit 0 05:04:24 INFO - TEST-OK | dromaeo_css | took 1118028ms 05:04:24 INFO - TEST-START | kraken 05:04:24 INFO - operating with platform_type : mac_ 05:04:24 INFO - Initialising browser for kraken test... 05:04:24 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp2ShYk3/profile http://localhost:49240/getInfo.html) 05:04:26 INFO - PID 1647 | __metrics Screen width/height:1600/1200 05:04:26 INFO - PID 1647 | colorDepth:24 05:04:26 INFO - PID 1647 | Browser inner width/height: 1024/691 05:04:26 INFO - PID 1647 | __metrics 05:04:26 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:04:26 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:04:27 INFO - TEST-INFO | 1647: exit 0 05:04:27 INFO - Browser initialized. 05:04:27 INFO - Running cycle 1/1 for kraken test... 05:04:27 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp2ShYk3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:39 INFO - PID 1648 | RSS: Main: 241668096 05:04:39 INFO - PID 1648 | 05:04:42 INFO - PID 1648 | 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) 05:04:43 INFO - PID 1648 | RSS: Main: 308174848 05:04:43 INFO - PID 1648 | 05:04:45 INFO - PID 1648 | 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) 05:04:46 INFO - PID 1648 | RSS: Main: 291803136 05:04:46 INFO - PID 1648 | 05:04:49 INFO - PID 1648 | 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) 05:04:50 INFO - PID 1648 | RSS: Main: 383643648 05:04:50 INFO - PID 1648 | 05:04:52 INFO - PID 1648 | 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) 05:04:52 INFO - PID 1648 | RSS: Main: 285765632 05:04:52 INFO - PID 1648 | 05:04:54 INFO - PID 1648 | 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) 05:04:55 INFO - PID 1648 | RSS: Main: 289386496 05:04:55 INFO - PID 1648 | 05:04:58 INFO - PID 1648 | 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) 05:04:58 INFO - PID 1648 | RSS: Main: 353034240 05:04:58 INFO - PID 1648 | 05:05:01 INFO - PID 1648 | 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) 05:05:02 INFO - PID 1648 | RSS: Main: 375955456 05:05:02 INFO - PID 1648 | 05:05:05 INFO - PID 1648 | 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) 05:05:05 INFO - PID 1648 | RSS: Main: 390025216 05:05:05 INFO - PID 1648 | 05:05:07 INFO - PID 1648 | 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) 05:05:07 INFO - PID 1648 | RSS: Main: 331235328 05:05:07 INFO - PID 1648 | 05:05:09 INFO - PID 1648 | 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) 05:05:10 INFO - PID 1648 | RSS: Main: 330375168 05:05:10 INFO - PID 1648 | 05:05:12 INFO - PID 1648 | 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) 05:05:12 INFO - PID 1648 | RSS: Main: 332677120 05:05:12 INFO - PID 1648 | 05:05:15 INFO - PID 1648 | 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) 05:05:16 INFO - PID 1648 | RSS: Main: 351834112 05:05:16 INFO - PID 1648 | 05:05:19 INFO - PID 1648 | 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) 05:05:20 INFO - PID 1648 | RSS: Main: 344977408 05:05:20 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | 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) 05:05:22 INFO - PID 1648 | RSS: Main: 387657728 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | __start_tp_report 05:05:22 INFO - PID 1648 | _x_x_mozilla_page_load 05:05:22 INFO - PID 1648 | _x_x_mozilla_page_load_details 05:05:22 INFO - PID 1648 | |i|pagename|runs| 05:05:22 INFO - PID 1648 | |0;ai-astar;111;110;110;110;110;109;110;110;109;109 05:05:22 INFO - PID 1648 | |1;audio-beat-detection;142;110;108;149;108;109;108;107;136;104 05:05:22 INFO - PID 1648 | |2;audio-dft;185;182;183;184;184;182;181;184;181;180 05:05:22 INFO - PID 1648 | |3;audio-fft;77;57;58;85;58;58;58;58;79;58 05:05:22 INFO - PID 1648 | |4;audio-oscillator;93;68;85;68;69;67;89;69;69;91 05:05:22 INFO - PID 1648 | |5;imaging-gaussian-blur;86;86;87;86;86;86;88;87;87;87 05:05:22 INFO - PID 1648 | |6;imaging-darkroom;94;95;94;95;102;94;95;95;94;93 05:05:22 INFO - PID 1648 | |7;imaging-desaturate;86;83;83;85;85;83;84;83;84;82 05:05:22 INFO - PID 1648 | |8;json-parse-financial;49;48;48;50;47;50;50;49;51;49 05:05:22 INFO - PID 1648 | |9;json-stringify-tinderbox;53;52;59;64;66;61;56;55;56;55 05:05:22 INFO - PID 1648 | |10;stanford-crypto-aes;83;85;106;103;81;83;82;80;81;82 05:05:22 INFO - PID 1648 | |11;stanford-crypto-ccm;140;139;141;138;142;145;140;146;175;153 05:05:22 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;184;196;194;187;186;238;185;186;185;182 05:05:22 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;70;68;67;67;68;68;68;70;68;68 05:05:22 INFO - PID 1648 | __end_tp_report 05:05:22 INFO - PID 1648 | __start_cc_report 05:05:22 INFO - PID 1648 | _x_x_mozilla_cycle_collect,2173 05:05:22 INFO - PID 1648 | __end_cc_report 05:05:22 INFO - PID 1648 | __startTimestamp1493813122355__endTimestamp 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | ------- Summary: start ------- 05:05:22 INFO - PID 1648 | Number of tests: 14 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.50 05:05:22 INFO - PID 1648 | Values: 111.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 109.0 109.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:109.50 stddev:17.07 (15.6%) stddev-sans-first:15.76 05:05:22 INFO - PID 1648 | Values: 142.0 110.0 108.0 149.0 108.0 109.0 108.0 107.0 136.0 104.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.65 (0.9%) stddev-sans-first:1.50 05:05:22 INFO - PID 1648 | Values: 185.0 182.0 183.0 184.0 184.0 182.0 181.0 184.0 181.0 180.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:64.60 Median:58.00 stddev:11.04 (19.0%) stddev-sans-first:10.76 05:05:22 INFO - PID 1648 | Values: 77.0 57.0 58.0 85.0 58.0 58.0 58.0 58.0 79.0 58.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:11.12 (14.4%) stddev-sans-first:10.14 05:05:22 INFO - PID 1648 | Values: 93.0 68.0 85.0 68.0 69.0 67.0 89.0 69.0 69.0 91.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 05:05:22 INFO - PID 1648 | Values: 86.0 86.0 87.0 86.0 86.0 86.0 88.0 87.0 87.0 87.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:94.50 stddev:2.51 (2.7%) stddev-sans-first:2.64 05:05:22 INFO - PID 1648 | Values: 94.0 95.0 94.0 95.0 102.0 94.0 95.0 95.0 94.0 93.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.01 05:05:22 INFO - PID 1648 | Values: 86.0 83.0 83.0 85.0 85.0 83.0 84.0 83.0 84.0 82.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.50 stddev:1.20 (2.4%) stddev-sans-first:1.27 05:05:22 INFO - PID 1648 | Values: 49.0 48.0 48.0 50.0 47.0 50.0 50.0 49.0 51.0 49.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:57.50 stddev:4.67 (8.1%) stddev-sans-first:4.63 05:05:22 INFO - PID 1648 | Values: 53.0 52.0 59.0 64.0 66.0 61.0 56.0 55.0 56.0 55.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:82.00 stddev:9.56 (11.7%) stddev-sans-first:10.05 05:05:22 INFO - PID 1648 | Values: 83.0 85.0 106.0 103.0 81.0 83.0 82.0 80.0 81.0 82.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:143.50 stddev:11.14 (7.8%) stddev-sans-first:11.61 05:05:22 INFO - PID 1648 | Values: 140.0 139.0 141.0 138.0 142.0 145.0 140.0 146.0 175.0 153.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:186.50 stddev:16.65 (8.9%) stddev-sans-first:17.38 05:05:22 INFO - PID 1648 | Values: 184.0 196.0 194.0 187.0 186.0 238.0 185.0 186.0 185.0 182.0 05:05:22 INFO - PID 1648 | 05:05:22 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:0.87 05:05:22 INFO - PID 1648 | Values: 70.0 68.0 67.0 67.0 68.0 68.0 68.0 70.0 68.0 68.0 05:05:22 INFO - PID 1648 | -------- Summary: end -------- 05:05:22 INFO - PID 1648 | 05:05:23 INFO - TEST-INFO | 1648: exit 0 05:05:23 INFO - TEST-OK | kraken | took 59285ms 05:05:23 INFO - SUITE-END | took 1177s 05:05:23 INFO - Completed test suite (00:19:37) 05:05:23 INFO - javascript benchmark 05:05:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7641.893539887, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2850.2731803390134, 3516.8241587920616, 3525.3654342218424, 3550.952469021639, 3596.7482276240657, 5956.13636022777, 6051.12258722485, 6063.969680151593, 6130.734471166796, 6195.0, 6120.751898419022, 6148.569600127982, 6177.258728952532, 6179.505639548836, 6188.907166392508, 5237.109691352479, 5297.629165958385, 5383.0, 5408.594355423349, 5455.018096742585, 5494.0, 5496.587755918302, 5505.944940550583, 5510.862228444301, 5512.724363781815, 5318.5213330800025, 5367.973160134175, 5393.892122157535, 5447.210154527611, 5496.725163741797, 5422.132458806612, 5429.592780541475, 5457.126859702428, 5461.044317244506, 5478.643888147258, 5367.973160134175, 5416.4312747161375, 5424.159255315432, 5425.728713564306, 5462.0, 5594.188842617838, 6104.481119104881, 6160.0, 6209.87580248397, 6211.130441738161, 6449.54853160279, 6493.577917435352, 6568.80293591193, 6587.2095348558005, 6587.341265873422, 6666.799996000128, 6672.232693240242, 6677.098591690108, 6680.0981867448245, 6687.699053542554, 6141.877162456771, 6187.102870083812, 6220.160278362454, 6231.158793562811, 6240.063990401431, 6638.269790323061, 6689.832754181184, 6790.524663273523, 6847.0, 6855.211650660138, 6522.804315870531, 6530.118434011442, 6534.8366290843105, 6539.673016349258, 6541.28045914949, 6608.2400523939405, 6629.370209830058, 6656.5340426169705, 6680.265170831205, 6705.765298214539, 6367.681615919186, 6456.0961465394885, 6610.305917878553, 6619.801405957829, 6625.17185351831, 6433.967830160913, 6456.483481321483, 6462.773802916875, 6463.095166676669, 6472.870542589122, 5195.532402083755, 5204.739763011834, 5235.47645235481, 5236.031334203185, 5242.423333433319, 4186.727862688916, 4298.247806633826, 4667.62658987287, 4679.9532004680195, 4692.6245900327895, 6169.722362493699, 6229.56393052482, 6236.469900058455, 6246.15676883624, 6247.812565623039, 6723.159605049369, 6724.9327506725285, 6728.394444500019, 6732.788098142283, 6750.864982700318, 6122.184701511125, 6219.968900155471, 6247.500199983991, 6251.593646412968, 6255.749770009149, 4826.7827947742435, 5208.140656791663, 5720.599558030981, 5755.079187330007, 5762.164486149485, 6828.556143850733, 6873.072135261776, 6898.758543450955, 6906.309369063154, 6918.93081069203, 6440.935590644033, 6539.509536784665, 6544.934550654432, 6719.529632925648, 6739.831504212434, 6575.210974683068, 6824.215215250111, 6873.243943166348, 6885.862282754417, 6915.412189963909, 6529.67351632426, 6572.8356791080605, 6573.737050518117, 6578.8355291118105, 6588.505862060269, 6022.939770602238, 6640.83397915056, 6651.268360480247, 6673.165854268216, 6706.932930670826, 6277.403646653561, 6329.367063293634, 6376.52176086786, 6474.902876456763, 6512.706928188148, 5419.972900135474, 6011.0, 6469.967650161719, 6480.513961452815, 6492.64290464029, 5878.500327472042, 6340.144080549251, 6385.489160867212, 6386.457151142205, 6391.680415979089, 6260.655663938345, 6350.1744773181035, 6379.744810207725, 6411.96794016027, 6457.483401327789, 6350.793349263625, 6368.713407896748, 6371.458426033839, 6378.840528986812, 6379.298277189413], "value": 6024.8881829698075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.303127185533, 786.940979426543, 802.9558374289413, 1076.074575864756, 1087.6410784441134, 25604.97580096796, 26049.609255861153, 32540.67459325406, 35817.92546223611, 35889.820550897275, 37012.629873701255, 37289.627103728955, 37303.694370697034, 37383.0, 37426.4386034209, 8037.91962080379, 8200.83598328033, 8201.917980820204, 8204.589770511451, 8208.876866846973, 5894.646321220741, 6013.128096426038, 8090.514569125871, 8239.546824924657, 8253.09215986244, 8206.958965205196, 8310.335173186131, 8313.916860831374, 8328.375371847105, 8337.83324333513, 8199.877001844978, 8241.423100382975, 8264.545450000247, 8267.793305167359, 8274.586270686501, 5804.129380592903, 5912.3496415394275, 7930.9603451982375, 8046.879296810553, 8060.758177254692, 35879.28241435157, 36154.819225903964, 36167.81916090429, 36175.27649446997, 36216.45675314873, 3486.703630191437, 3519.384107781128, 3522.330757156132, 3523.8061906595126, 3529.4882242074755, 29470.0, 29550.0, 29555.261118471994, 29592.8520357399, 29594.66823992904, 3032.4996375598, 3114.1903105192587, 3139.3250451152885, 3142.308692087737, 3146.8426578671206, 286997.0, 294225.99999999785, 294824.0, 294896.0, 295154.0, 47373.5262647376, 47497.28754068692, 47518.76240618775, 47530.76234618874, 47656.5234347659, 49665.50334496609, 49671.25493117606, 49679.25481117786, 49687.75156124196, 49722.0, 13710.45158193681, 13780.586582402344, 13791.448342066406, 13806.033577649468, 13816.516421925386, 9593.328467007243, 11144.777104458028, 11146.944265278622, 11147.72130696739, 11161.665150045512, 10836.945815270874, 10935.671929842118, 12429.254244745163, 12459.813102803466, 12659.987201023896, 5165.468920229136, 5214.895702085937, 5232.502912223333, 5236.214567814782, 5246.265522826808, 957.9473128977974, 976.511744127936, 1006.959721611142, 1007.5163921317809, 1029.763154474475, 22175.00212490442, 23704.0, 23708.525829483315, 23803.523929521314, 23853.642195367083, 1090.2477290669412, 1090.487470888681, 1094.63876920616, 1127.2503784983057, 1134.28540019787, 10746.623868164577, 11606.535738570363, 11979.341136237415, 12017.579384721494, 12018.4591693374, 288064.0, 288281.0, 288812.0, 288874.55562722473, 288998.0, 50716.0, 50782.23826642604, 50926.49073509217, 50960.745196274525, 50988.49011509837, 1457.8250609926665, 1482.4885414531968, 1522.9695406092037, 1526.4428483603626, 1537.031670047885, 23271.53456930818, 23388.94749736232, 23427.414314642272, 23465.0, 23469.178578749663, 37011.44482832706, 37019.44470832886, 37074.443883341235, 37114.70098546538, 37125.6287437133, 266970.0, 267039.0, 267170.0, 267858.0, 268000.9799852965, 32967.34065318632, 33045.339093218485, 33727.49408758822, 33820.661793381754, 33868.830655847545, 42697.78651106724, 42712.78643606762, 42763.57236427596, 42850.57149428466, 43049.569504304556, 27757.444851103268, 28543.2874027559, 29227.0, 29607.0, 30148.84925575446, 28218.435631286848, 29086.56370154407, 29148.0, 29443.0, 29826.55260171056, 35978.64021359753, 36101.638983609824, 36172.73395431147, 36209.0, 36329.27341453103, 25041.373965651004, 28233.0, 29780.404391912474, 30203.697963020088, 30422.391552169276, 18803.529911752317, 23516.0, 23819.236312317516, 24015.0, 24223.63664544998], "value": 16695.44577824879, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8266363595581, 424.2607905104117, 430.96121349078584, 437.28285611596976, 454.03517525258803, 16768.0, 20791.0, 21560.0, 22223.555528889414, 22489.875506224686, 21705.67441488375, 22105.66841497379, 22362.440938976535, 22546.09815607374, 22614.77385226147, 5948.821535353924, 6031.607945483541, 6115.3884611538715, 6151.354107818659, 6197.69011549423, 6041.335453100155, 6135.478484328837, 6154.50763938886, 6155.661438620874, 6156.415140561639, 3625.43805710114, 3703.4074548072313, 3803.5055442792536, 3838.0, 6196.349383314755, 6153.446189842891, 6161.353057928898, 6162.352952939944, 6166.352532984017, 6180.6291622502795, 5387.0, 5401.378841433247, 5460.699661518614, 5463.3717122530625, 5468.152436372368, 26260.474790504082, 26308.473830523475, 26913.86543067292, 27112.728872711217, 27388.315292117855, 2157.0, 2216.9334919952425, 2220.877851718155, 2223.455253462912, 2224.799768020886, 16754.74867876983, 17054.914725426293, 17098.487045388658, 17170.39903603368, 17327.65344693099, 2071.927482538104, 2082.073477302601, 2130.808227259554, 2135.0, 2135.754388245356, 164425.0, 165376.0, 166596.835079124, 166840.0, 168276.0, 11311.547538098548, 11602.07183425324, 12047.951848185829, 12254.81617775734, 12459.06557008228, 15070.397184112513, 15087.0, 15918.68162636764, 16362.018278903304, 16382.344706211617, 8609.440386374865, 8654.264387527004, 8767.386282960131, 8893.510856902803, 8941.865872011926, 8142.633581488847, 8164.510129392137, 8166.387520935954, 8336.666533338599, 8338.916610833936, 7413.0, 7565.524722679024, 7577.962110189524, 7586.696532138763, 7605.239476052461, 2134.487722946482, 2149.623815832223, 2151.7095192149172, 2156.8598041127275, 2308.8614683119067, 912.5346073502561, 922.5341202692597, 926.9212116970133, 942.1144124522926, 949.803909843372, 18718.71921921172, 20417.387478375374, 20463.8976805115, 20550.794492054887, 20892.791072089083, 843.2874221283006, 901.1079031758643, 955.1117460761419, 991.2565575818137, 996.3673067602125, 8490.278326342206, 9972.750681233027, 10452.902445243291, 10482.475876206312, 10648.840267395995, 487368.0, 514140.0, 544121.0, 551144.0, 562220.0, 131314.0, 131786.34106829404, 133241.0, 133586.33206834097, 135566.3221683905, 1082.680609220282, 1105.7180418993105, 1126.8366086917351, 1126.8422420861086, 1130.0733398613222, 16841.0, 17155.7426638598, 17312.0, 17683.027433491283, 17712.734308985633, 55668.16497752456, 55761.28813424025, 56616.15075773784, 56701.86596268134, 58216.4178358211, 77151.61424193067, 77959.4408111846, 78084.31494110372, 78547.0, 80859.6366690327, 7999.680012799423, 8702.042775294585, 8770.517621530877, 8888.6000129993, 8889.377743557889, 11698.883011169779, 13134.146280491737, 13805.585832425044, 13902.791458128338, 14299.21354325522, 11355.0, 11707.297562145957, 11988.920997110303, 12138.211016283913, 12164.513419463123, 10646.467676615983, 11089.390083545482, 11094.112471002461, 11219.495122719334, 11470.942645286721, 11485.598004069818, 11614.593489227836, 11927.82108268394, 11955.581554645894, 12117.757644846877, 7990.961175047207, 8367.58162091905, 8921.598528066123, 8926.196642302224, 9036.45781253127, 11071.557137714723, 11491.0, 12012.219205751599, 12013.339266340436, 12114.454849531969], "value": 10137.205452288998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.5335372558427, 1394.5537428023038, 1405.1498843199863, 1425.2731107135357, 1433.3836450326355, 11002.779944401118, 11498.137639677037, 11505.4822532986, 11519.827202591969, 11524.942375288123, 10918.454077296143, 11247.775044499125, 11635.0, 11656.184067115306, 11674.824877626843, 2505.561526732828, 2549.7322781107996, 2549.859757713325, 2551.4995304601503, 2552.1577879299784, 2472.332470233036, 2511.3847107458605, 2511.547921374161, 2516.836405633628, 2523.179966510882, 2446.4006318452, 2532.7847132993625, 2537.112010796225, 2537.936551586207, 2543.9618405723927, 2496.0639760089966, 2510.297116807297, 2523.4196134888894, 2523.987380063088, 2533.5819589767666, 2481.416867036245, 2496.7253602103756, 2513.6229565565127, 2516.207394670673, 2516.30801529579, 11283.323000619988, 11401.37292448915, 11409.771804563863, 11412.087033037336, 11427.48576314068, 5638.71806409682, 5941.554383421218, 6439.613623182624, 6708.731650733965, 6734.0572319875255, 3965.643092121723, 4254.31930891056, 4322.913541729148, 4402.779861006937, 4405.779711014468, 1250.4373032135484, 1261.6215135459418, 1315.8617795606826, 1316.210273835691, 1319.2612137203198, 15157.469488567847, 15606.219689015505, 16105.27526261321, 16268.26792794327, 16288.429904953387, 4941.6293777966075, 5415.864603384947, 5575.804846830341, 5587.078132108238, 5601.215829783833, 11616.767664646659, 11629.76740465203, 11643.825342619868, 11645.708857278467, 11661.766764664659, 5398.271233383522, 6488.513361497907, 6490.643014634146, 6500.6424646643945, 6501.057346684799, 5181.274621552986, 6184.350643182401, 6185.567010309325, 6189.81430557084, 6191.721372538246, 4228.619424251832, 4279.40088387626, 4284.407933200431, 4317.848875289413, 4336.783160841946, 1239.9938000309942, 1240.9131360804656, 1244.2099266965542, 1259.106034715344, 1259.9685007874878, 776.1423626892202, 785.4384115357547, 793.1156760212322, 794.8291117409784, 797.0674311056079, 6545.661712526646, 6762.425193858478, 6897.689603967833, 6944.651426187007, 6973.19808222061, 880.933929955256, 903.3631289940707, 908.5366463103865, 912.4844462878585, 917.2295271971576, 3827.445020472016, 3843.8078096095082, 3887.9502534315575, 3943.526776786804, 3969.0672691917375, 14490.85509144916, 15263.542093737206, 15325.846741532665, 15331.003484773453, 15356.0, 15032.549023529311, 15223.543293701206, 15238.238088095552, 15239.314230859638, 15251.084934903942, 8004.83990320202, 8442.493450392873, 8488.0, 8597.742067738103, 8635.222829945335, 6471.902921456088, 6532.248791389051, 6623.304553022056, 6730.629815360202, 6763.222229443677, 12994.0, 13108.868911310765, 13128.146670466389, 13321.467141314239, 13805.378757955716, 12971.935140324238, 13068.477260909458, 13524.459021639024, 13688.520901768788, 13785.931070344584, 8776.561171941505, 10502.527386267484, 10529.631462898762, 10538.89461105379, 10544.05103540685, 10287.588496460057, 10469.581216751245, 10542.789144217226, 10545.050945414949, 10545.57817687284, 8894.199522043047, 8901.910980890108, 9086.591103400495, 9120.315976301936, 9163.725088247364, 8713.956430217808, 8763.780905477413, 8803.515806630698, 8861.291096712119, 8950.283977281671, 7988.081370642451, 8137.267645911897, 8143.063547692092, 8150.307223886036, 8160.5103693778565, 5808.6224395414165, 5827.329857066322, 5936.0, 5957.880842383215, 6040.456358927542, 5983.940160598512, 6002.129691194839, 6115.755369785159, 6149.2620885494025, 6288.213973253343], "value": 5342.025913356798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.64596602176965, 390.1300100775271, 400.74152171849147, 405.37167390544687, 405.61061381074165, 5422.810201642938, 5520.834374968752, 5963.642181469115, 5978.73095710693, 6010.6093103948215, 5612.607117501777, 5752.309722833266, 5816.4407660629295, 5828.912566311498, 5838.854028649286, 4241.388272456477, 4305.461817272833, 4306.956930430702, 4325.935110973338, 4326.632236259922, 4309.913801723964, 4318.654507639397, 4322.956770432287, 4325.783710814462, 4347.978260108695, 4019.819108140126, 4128.678822776713, 4231.957680423203, 4264.505264420318, 4308.504521979966, 3560.9821950890077, 4242.091059023421, 4255.212785634637, 4260.510041345254, 4270.957290427087, 3063.2648164440598, 3079.5842561254394, 3669.981650091759, 3691.920427927129, 3694.5935947046073, 5130.897382052375, 5259.053370393328, 5586.497215250647, 5746.683932383718, 5749.1663708762, 3953.940690889639, 4962.6526143169995, 4969.503049695038, 4974.303597496353, 4996.376177742249, 5868.119782032687, 6137.478314343286, 6176.3514830943, 6190.473809726179, 6196.659183744892, 3355.580552430946, 3625.365561026836, 3662.901129661091, 3769.378052621342, 4123.752574845519, 4763.190257656207, 5662.631928924607, 6415.262244841903, 6479.870402591969, 6579.868402631969, 3743.9251214975548, 3864.516935383077, 3961.207758448264, 4183.611274867367, 4187.267228235108, 3609.6029436761937, 4040.696947728932, 4232.661387089025, 4248.171606536695, 4248.171606536757, 4193.622573968358, 4323.113761678848, 4811.975940120347, 4849.660523763303, 4943.6786608871025, 5449.836504904859, 6184.6598437086395, 6226.132160351876, 6226.906596401058, 6227.346128656516, 1243.7077286837587, 1385.2519639394816, 1418.7871819227198, 1435.1604311477693, 1442.37977669601, 874.4272501511521, 933.2767105493161, 933.659214386744, 946.8627049077845, 950.4249928793118, 5819.447152520504, 5831.737571809279, 5859.560532960045, 5877.353491115974, 5888.558358123158, 5026.597872170218, 5636.914341693599, 6224.875502489925, 6373.203349581302, 6392.7123279452535, 5042.697438153723, 5172.5344718975475, 5515.338159420815, 5863.677497737665, 5901.557383196277, 3277.557529733503, 3321.485169798685, 3322.302316513511, 3344.0469466202017, 3352.513885486591, 5461.4811592899405, 5802.586249937607, 5980.5514586405325, 6582.341765823379, 6640.701168447335, 5362.943122420183, 6144.8771024579255, 6469.935300647027, 6554.803355899236, 6582.713508599276, 5542.501174894279, 5570.777168913198, 6573.605583664996, 6609.966950165314, 6615.272320044791, 5216.113260745758, 6203.31763506023, 6403.839904002437, 6464.09502669636, 6553.2136143663065, 6062.363451837494, 6235.844103897439, 6240.719167637377, 6303.1175635412, 6360.4911607072245, 5454.618176727749, 6123.387661233841, 6321.52588555851, 6498.512611554058, 6579.736810527525, 3523.5419395478425, 3582.1760994971823, 4100.91798164041, 4105.973506623344, 4138.482689663792, 5449.618526703092, 5490.725463726718, 6200.286966998853, 6245.033609035671, 6479.287278399278, 5762.510186634183, 5903.439173278532, 6187.504999599932, 6216.337746689983, 6226.844328891814, 5318.0, 5878.2064421302575, 6120.234970628671, 6235.657038862906, 6240.344763799736, 5558.44415558441, 5700.0, 5933.495652869555, 6140.631562106288, 6188.628682279078, 5463.9180412294645, 5659.971700141473, 5883.235179426647, 5987.880242395215, 6239.908016097074, 6059.909101363572, 6104.38956104386, 6112.1748563945075, 6114.633122012693, 6115.816525504242], "value": 4432.882257777155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.1584751858068, 819.6680344460493, 828.5525816059328, 840.2395831772249, 849.983000339993, 15977.84022159778, 16400.17999100045, 16430.0, 16431.507054788344, 16441.177941102927, 12302.507899684024, 16472.752908706378, 16706.0, 16752.664946701057, 16760.41338553151, 963.6579014449875, 964.0841200859194, 965.5558443116175, 966.8888077871029, 972.7616733900204, 858.5750053723416, 952.4523399045542, 972.6838777397339, 978.7797745507239, 995.258532393368, 1016.7864748402807, 1019.2966852871494, 1020.367372229219, 1023.4524529376785, 1024.3802499487815, 1013.2906965124369, 1017.1811691588232, 1017.4353233955152, 1020.566259339785, 1032.473438546343, 755.833716582351, 757.0763668324635, 759.851828893366, 766.2605585609913, 779.8752199648029, 16443.93114447557, 16778.74831877523, 16856.241469133918, 16979.660406791794, 17117.657646847118, 16268.186590670419, 16358.83641163585, 16482.75275870851, 16686.49940501787, 16746.162691865356, 13557.25435101069, 13660.0, 13759.656008599764, 13867.653308667263, 13887.722245555033, 3437.432823584106, 3465.7227421806197, 3467.133216695826, 3467.9133021674406, 3472.166679996808, 92558.53720731354, 129311.0, 133130.0, 134811.32594336965, 137818.0, 10152.0, 11012.669619911416, 11043.723906902393, 11082.335059896432, 11267.0, 24069.759302407103, 24293.757062429504, 24378.390540236636, 24418.389540261636, 24587.0, 7840.882386764089, 7904.920950790533, 7914.802129946797, 7919.485233459806, 7928.8810667840025, 3867.207222519377, 3956.0307724607655, 4011.13760541485, 4011.4183443400543, 4016.979915100406, 4073.816678249486, 4119.402686610485, 4150.688698347636, 4156.044109854751, 4172.937405938914, 917.5091326140483, 919.1911118215927, 937.095702646949, 937.1986951156663, 938.4556956964944, 525.5506541906691, 643.9195100612424, 660.4881217056819, 665.2283351312454, 691.4952084977995, 10986.176036797273, 11623.476943537562, 11731.120165987584, 11819.527218911147, 11841.703957400961, 636.4908073541148, 650.3788881618043, 652.1912828093131, 652.9738810447624, 672.1195234243157, 5103.387593488805, 5312.548433383128, 5409.134538473825, 5532.861678458071, 5898.705064746746, 104109.0, 107549.25437302914, 108089.0, 108671.0, 112570.0, 94110.52944735369, 99919.00080999243, 100110.0, 101160.41296902615, 101665.49167254116, 86012.13987860167, 87562.24875502454, 88313.11686883049, 89674.0, 90306.09693903108, 11806.645800625995, 12447.502099916264, 12485.93757031209, 12545.686357841127, 12614.432350544064, 31270.53094203634, 41126.681698422435, 41831.954201145214, 42594.148117036864, 42698.35952460654, 35481.93554193378, 55013.72493137509, 55634.72182639061, 56602.43397566136, 56627.71686141543, 15888.364465421253, 16146.273417695997, 16152.273147708147, 16159.515214543582, 16162.111083890028, 15972.20138993069, 16070.67858642844, 16380.83619163793, 16537.421190258283, 16739.07935063583, 16049.518514444586, 16055.357785688442, 16372.099534525716, 16607.41874034403, 16649.334026639284, 15642.34090693408, 16230.0, 16315.918420408298, 16322.918385407997, 16428.75356869624, 15754.527364179094, 16491.09298988567, 16864.156792160098, 16957.66084678275, 17148.0, 11532.769344613229, 11674.824877627012, 11832.940835295769, 12261.87738122643, 12349.567765128177, 12116.878831211927, 12547.74904501923, 13436.0, 13526.39131239077, 13559.254241016444], "value": 8247.390939693776, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1417.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 108.0, 149.0, 108.0, 109.0, 108.0, 107.0, 136.0, 104.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 183.0, 184.0, 184.0, 182.0, 181.0, 184.0, 181.0, 180.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 58.0, 85.0, 58.0, 58.0, 58.0, 58.0, 79.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 68.0, 85.0, 68.0, 69.0, 67.0, 89.0, 69.0, 69.0, 91.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 86.0, 86.0, 88.0, 87.0, 87.0, 87.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 102.0, 94.0, 95.0, 95.0, 94.0, 93.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 83.0, 85.0, 85.0, 83.0, 84.0, 83.0, 84.0, 82.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 48.0, 50.0, 47.0, 50.0, 50.0, 49.0, 51.0, 49.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 59.0, 64.0, 66.0, 61.0, 56.0, 55.0, 56.0, 55.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 106.0, 103.0, 81.0, 83.0, 82.0, 80.0, 81.0, 82.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 141.0, 138.0, 142.0, 145.0, 140.0, 146.0, 175.0, 153.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 196.0, 194.0, 187.0, 186.0, 238.0, 185.0, 186.0, 185.0, 182.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 70.0, 68.0, 68.0], "value": 68.2, "unit": "score"}]}]} 05:05:23 INFO - Return code: 0 05:05:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:23 INFO - # TBPL SUCCESS # 05:05:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:23 INFO - 2017-05-03 05:05:23.693 screenresolution[1653:9955] starting screenresolution argv=screenresolution get 05:05:23 INFO - 2017-05-03 05:05:23.696 screenresolution[1653:9955] Display 0: 1600x1200x32@60 05:05:23 INFO - 2017-05-03 05:05:23.702 screenresolution[1654:9958] starting screenresolution argv=screenresolution list 05:05:23 INFO - Available Modes on Display 0 05:05:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:23 INFO - Intel Iris: 05:05:23 INFO - Chipset Model: Intel Iris 05:05:23 INFO - Type: GPU 05:05:23 INFO - Bus: Built-In 05:05:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:23 INFO - Vendor: Intel (0x8086) 05:05:23 INFO - Device ID: 0x0a2e 05:05:23 INFO - Revision ID: 0x0009 05:05:23 INFO - Displays: 05:05:23 INFO - Display: 05:05:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:23 INFO - Display Serial Number: 1600x1200 60 05:05:23 INFO - Main Display: Yes 05:05:23 INFO - Mirror: Off 05:05:23 INFO - Online: Yes 05:05:23 INFO - Rotation: Supported 05:05:23 INFO - Return code: 0 05:05:23 INFO - Running post-action listener: _resource_record_post_action 05:05:23 INFO - [mozharness: 2017-05-03 12:05:23.947240Z] Finished run-tests step (success) 05:05:23 INFO - Running post-run listener: _resource_record_post_run 05:05:24 INFO - Total resource usage - Wall time: 1197s; CPU: 53.0%; Read bytes: 38774272; Write bytes: 507329536; Read time: 252; Write time: 1561 05:05:24 INFO - TinderboxPrint: CPU usage
53.1% 05:05:24 INFO - TinderboxPrint: I/O read bytes / time
38,774,272 / 252 05:05:24 INFO - TinderboxPrint: I/O write bytes / time
507,329,536 / 1,561 05:05:24 INFO - TinderboxPrint: CPU idle
2,246.0 (46.9%) 05:05:24 INFO - TinderboxPrint: CPU system
944.7 (19.7%) 05:05:24 INFO - TinderboxPrint: CPU user
1,598.9 (33.4%) 05:05:24 INFO - TinderboxPrint: Swap in / out
246,534,144 / 0 05:05:24 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 197198336; Write bytes: 195790848; Read time: 15004; Write time: 408 05:05:24 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37819904; Write bytes: 294695936; Read time: 240; Write time: 1107 05:05:24 INFO - Running post-run listener: _upload_blobber_files 05:05:24 INFO - Blob upload gear active. 05:05:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:25 INFO - (blobuploader) - INFO - Done attempting. 05:05:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:26 INFO - (blobuploader) - INFO - Done attempting. 05:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:05:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:27 INFO - (blobuploader) - INFO - Done attempting. 05:05:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:29 INFO - (blobuploader) - INFO - Done attempting. 05:05:29 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:29 INFO - Return code: 0 05:05:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5af44b7949a64463e9a2f627be767b9cd6ab0a616367bb46e56f2228971d9df9e605189c65ee90a7e252697d2f197a3554178b9768e3c54d11c19df8d0c92211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad819485c838c63fd9dd387f449c91983bee2a9daaf7ca4cb3e15bc4c52a967ad4cd10834a20ac766ce6fd4414ee050abdc967c2da24e2fd1026638023b03b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54d63a43c6f5390f0940d6432564892a04d35e15de822fb98a63a318af144d19b17e71b8a10f68ed9206d35f4e4ebd72f1ba1c727793e31df8ca86c32f3bcfbc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63807b41ed6668f1d8706ffb00c4f6101f4b543a390a9be8d340a87edd54f3c8279bf4114414617d5f786ba2fa0eba98dd169fe620d87280d9e4f18cd95a580f"} 05:05:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:29 INFO - Contents: 05:05:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5af44b7949a64463e9a2f627be767b9cd6ab0a616367bb46e56f2228971d9df9e605189c65ee90a7e252697d2f197a3554178b9768e3c54d11c19df8d0c92211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad819485c838c63fd9dd387f449c91983bee2a9daaf7ca4cb3e15bc4c52a967ad4cd10834a20ac766ce6fd4414ee050abdc967c2da24e2fd1026638023b03b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54d63a43c6f5390f0940d6432564892a04d35e15de822fb98a63a318af144d19b17e71b8a10f68ed9206d35f4e4ebd72f1ba1c727793e31df8ca86c32f3bcfbc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63807b41ed6668f1d8706ffb00c4f6101f4b543a390a9be8d340a87edd54f3c8279bf4114414617d5f786ba2fa0eba98dd169fe620d87280d9e4f18cd95a580f"} 05:05:29 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:29 INFO - Copying logs to upload dir... 05:05:29 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.797805 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-03 05:05:30.046816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:30.050334) ========= 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.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5af44b7949a64463e9a2f627be767b9cd6ab0a616367bb46e56f2228971d9df9e605189c65ee90a7e252697d2f197a3554178b9768e3c54d11c19df8d0c92211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad819485c838c63fd9dd387f449c91983bee2a9daaf7ca4cb3e15bc4c52a967ad4cd10834a20ac766ce6fd4414ee050abdc967c2da24e2fd1026638023b03b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54d63a43c6f5390f0940d6432564892a04d35e15de822fb98a63a318af144d19b17e71b8a10f68ed9206d35f4e4ebd72f1ba1c727793e31df8ca86c32f3bcfbc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63807b41ed6668f1d8706ffb00c4f6101f4b543a390a9be8d340a87edd54f3c8279bf4114414617d5f786ba2fa0eba98dd169fe620d87280d9e4f18cd95a580f"} build_url:https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011012 build_url: 'https://queue.taskcluster.net/v1/task/f5BMAJ-CQluYsA5hQ1fwEw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5af44b7949a64463e9a2f627be767b9cd6ab0a616367bb46e56f2228971d9df9e605189c65ee90a7e252697d2f197a3554178b9768e3c54d11c19df8d0c92211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad819485c838c63fd9dd387f449c91983bee2a9daaf7ca4cb3e15bc4c52a967ad4cd10834a20ac766ce6fd4414ee050abdc967c2da24e2fd1026638023b03b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54d63a43c6f5390f0940d6432564892a04d35e15de822fb98a63a318af144d19b17e71b8a10f68ed9206d35f4e4ebd72f1ba1c727793e31df8ca86c32f3bcfbc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63807b41ed6668f1d8706ffb00c4f6101f4b543a390a9be8d340a87edd54f3c8279bf4114414617d5f786ba2fa0eba98dd169fe620d87280d9e4f18cd95a580f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:30.089509) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:30.089947) ========= 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.smdwrYUcbB/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.KgBd2o3RQH/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.005186 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 05:05:30.138931) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 05:05:30.139279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 05:05:30.139608) ========= ========= Total master_lag: 0.30 =========