builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0354 starttime: 1493898292.65 results: success (0) buildid: 20170504030320 builduid: f8ddb320b6584f09823832bba887e606 revision: 33b92d9c40562dab3d7b602368c75619f1d793f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.645504) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.645981) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.646277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.671968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.672363) ========= 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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-04 04:44:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.99M=0.001s 2017-05-04 04:44:53 (7.99 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.825996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.826312) ========= 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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.022654 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:52.875370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 04:44:52.875714) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 33b92d9c40562dab3d7b602368c75619f1d793f7 --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 33b92d9c40562dab3d7b602368c75619f1d793f7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-04 04:44:53,299 truncating revision to first 12 chars 2017-05-04 04:44:53,299 Setting DEBUG logging. 2017-05-04 04:44:53,299 attempt 1/10 2017-05-04 04:44:53,299 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/33b92d9c4056?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 04:44:54,248 unpacking tar archive at: mozilla-central-33b92d9c4056/testing/mozharness/ program finished with exit code 0 elapsedTime=1.229917 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 04:44:54.130866) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:54.131260) ========= script_repo_revision: 33b92d9c40562dab3d7b602368c75619f1d793f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:54.131649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:54.131927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 04:44:54.152150) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-04 04:44:54.152420) ========= /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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:54 INFO - MultiFileLogger online at 20170504 04:44:54 in /builds/slave/test 04:44:54 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:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:54 INFO - {'append_to_log': False, 04:44:54 INFO - 'base_work_dir': '/builds/slave/test', 04:44:54 INFO - 'blob_upload_branch': 'Firefox', 04:44:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:54 INFO - 'branch': 'Firefox', 04:44:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:54 INFO - 'config_files': ('talos/mac_config.py',), 04:44:54 INFO - 'default_actions': ('clobber', 04:44:54 INFO - 'read-buildbot-config', 04:44:54 INFO - 'download-and-extract', 04:44:54 INFO - 'populate-webroot', 04:44:54 INFO - 'create-virtualenv', 04:44:54 INFO - 'install', 04:44:54 INFO - 'run-tests'), 04:44:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:54 INFO - 'download_minidump_stackwalk': True, 04:44:54 INFO - 'download_symbols': 'ondemand', 04:44:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:54 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:54 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:54 INFO - 'gecko_profile': False, 04:44:54 INFO - 'gecko_profile_interval': 0, 04:44:54 INFO - 'installer_path': 'installer.exe', 04:44:54 INFO - 'log_level': 'info', 04:44:54 INFO - 'log_name': 'talos', 04:44:54 INFO - 'log_to_console': True, 04:44:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:54 INFO - 'opt_config_files': (), 04:44:54 INFO - 'pip_index': False, 04:44:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:54 INFO - 'cmd': ('bash', 04:44:54 INFO - '-c', 04:44:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:54 INFO - 'enabled': True, 04:44:54 INFO - 'halt_on_failure': False, 04:44:54 INFO - 'name': 'check_screen_resolution'},), 04:44:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:54 INFO - 'cmd': ('bash', 04:44:54 INFO - '-c', 04:44:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:54 INFO - 'enabled': True, 04:44:54 INFO - 'halt_on_failure': False, 04:44:54 INFO - 'name': 'check_screen_resolution'},), 04:44:54 INFO - 'run_cmd_checks_enabled': True, 04:44:54 INFO - 'suite': 'dromaeojs', 04:44:54 INFO - 'system_bits': '32', 04:44:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:54 INFO - 'title': 't-yosemite-r7-0354', 04:44:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:54 INFO - 'use_talos_json': True, 04:44:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:54 INFO - 'work_dir': 'build'} 04:44:54 INFO - [mozharness: 2017-05-04 11:44:54.698204Z] Running clobber step. 04:44:54 INFO - Running pre-action listener: _resource_record_pre_action 04:44:54 INFO - Running main action method: clobber 04:44:54 INFO - rmtree: /builds/slave/test/build 04:44:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:57 INFO - Running post-action listener: _resource_record_post_action 04:44:57 INFO - [mozharness: 2017-05-04 11:44:57.873301Z] Finished clobber step (success) 04:44:57 INFO - [mozharness: 2017-05-04 11:44:57.873448Z] Running read-buildbot-config step. 04:44:57 INFO - Running pre-action listener: _resource_record_pre_action 04:44:57 INFO - Running main action method: read_buildbot_config 04:44:57 INFO - Using buildbot properties: 04:44:57 INFO - { 04:44:57 INFO - "project": "", 04:44:57 INFO - "product": "firefox", 04:44:57 INFO - "script_repo_revision": "production", 04:44:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:44:57 INFO - "repository": "", 04:44:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:44:57 INFO - "buildid": "20170504030320", 04:44:57 INFO - "stage_platform": "macosx64", 04:44:57 INFO - "basedir": "/builds/slave/test", 04:44:57 INFO - "nightly_build": "True", 04:44:57 INFO - "buildnumber": 113, 04:44:57 INFO - "slavename": "t-yosemite-r7-0354", 04:44:57 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:44:57 INFO - "platform": "yosemite_r7", 04:44:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:57 INFO - "branch": "mozilla-central", 04:44:57 INFO - "repo_path": "mozilla-central", 04:44:57 INFO - "revision": "33b92d9c40562dab3d7b602368c75619f1d793f7", 04:44:57 INFO - "pgo_build": "False", 04:44:57 INFO - "builduid": "f8ddb320b6584f09823832bba887e606", 04:44:57 INFO - "slavebuilddir": "test" 04:44:57 INFO - } 04:44:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:44:57 INFO - Running post-action listener: _resource_record_post_action 04:44:57 INFO - [mozharness: 2017-05-04 11:44:57.876872Z] Finished read-buildbot-config step (success) 04:44:57 INFO - [mozharness: 2017-05-04 11:44:57.877012Z] Running download-and-extract step. 04:44:57 INFO - Running pre-action listener: _resource_record_pre_action 04:44:57 INFO - Running main action method: download_and_extract 04:44:57 INFO - mkdir: /builds/slave/test/build/tests 04:44:57 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:57 INFO - trying https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:57 INFO - Downloading https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/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:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/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:59 INFO - Downloaded 1325 bytes. 04:44:59 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:59 INFO - Using the following test package requirements: 04:44:59 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:44:59 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:59 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:59 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:44:59 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'jsshell-mac.zip'], 04:44:59 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:44:59 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:59 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:44:59 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:44:59 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:44:59 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:59 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:59 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:00 INFO - Content-Length response header: 52700863 04:45:00 INFO - Bytes received: 52700863 04:45:17 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:45:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:45:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:45:17 INFO - Fetch https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:18 INFO - Content-Length response header: 52700863 04:45:18 INFO - Bytes received: 52700863 04:45:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:45:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:45:34 INFO - Fetch https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:45:35 INFO - Content-Length response header: 14408809 04:45:35 INFO - Bytes received: 14408809 04:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:35 INFO - trying https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:35 INFO - Downloading https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:45:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/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:45:37 INFO - Downloaded 70961348 bytes. 04:45:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:37 INFO - mkdir: /builds/slave/test/properties 04:45:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:37 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:37 INFO - Contents: 04:45:37 INFO - build_url:https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:45:38 WARNING - Cannot open symbols url https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip (installer url: https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg): HTTP Error 500: Internal Server Error 04:45:38 WARNING - No symbols_url found. Let minidump_stackwalk query for symbols. 04:45:38 INFO - Running post-action listener: _resource_record_post_action 04:45:38 INFO - Running post-action listener: set_extra_try_arguments 04:45:38 INFO - [mozharness: 2017-05-04 11:45:38.198951Z] Finished download-and-extract step (success) 04:45:38 INFO - [mozharness: 2017-05-04 11:45:38.199057Z] Running populate-webroot step. 04:45:38 INFO - Running pre-action listener: _resource_record_pre_action 04:45:38 INFO - Running main action method: populate_webroot 04:45:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:38 INFO - '--tppagecycles', 04:45:38 INFO - '7'], 04:45:38 INFO - 'tests': ['tsvgm']}}, 04:45:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['damp', 'tps']}, 04:45:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'tests': ['damp', 'tps']}, 04:45:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['dromaeo_dom']}, 04:45:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:45:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:45:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['a11yr', 04:45:38 INFO - 'ts_paint', 04:45:38 INFO - 'tpaint', 04:45:38 INFO - 'sessionrestore', 04:45:38 INFO - 'sessionrestore_no_auto_restore', 04:45:38 INFO - 'tabpaint']}, 04:45:38 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:45:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:45:38 INFO - 'bloom_basic_ref']}, 04:45:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['tsvgx', 04:45:38 INFO - 'tsvgr_opacity', 04:45:38 INFO - 'tart', 04:45:38 INFO - 'tscrollx', 04:45:38 INFO - 'cart', 04:45:38 INFO - 'tsvg_static']}, 04:45:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:38 INFO - 'tsvgr_opacity', 04:45:38 INFO - 'tart', 04:45:38 INFO - 'tscrollx', 04:45:38 INFO - 'cart', 04:45:38 INFO - 'tsvg_static']}, 04:45:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'talos_options': ['--disable-e10s'], 04:45:38 INFO - 'tests': ['tp5o']}, 04:45:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:45:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'talos_options': ['--disable-e10s', 04:45:38 INFO - '--xperf_path', 04:45:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:38 INFO - 'tests': ['tp5n']}, 04:45:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:45:38 INFO - 'talos_options': ['--xperf_path', 04:45:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:38 INFO - 'tests': ['tp5n']}}, 04:45:38 INFO - 'talos.zip': {'path': '', 04:45:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:38 INFO - Running post-action listener: _resource_record_post_action 04:45:38 INFO - [mozharness: 2017-05-04 11:45:38.218295Z] Finished populate-webroot step (success) 04:45:38 INFO - [mozharness: 2017-05-04 11:45:38.218399Z] Running create-virtualenv step. 04:45:38 INFO - Running pre-action listener: _resource_record_pre_action 04:45:38 INFO - Running main action method: create_virtualenv 04:45:38 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:45:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:38 INFO - Using real prefix '/tools/python27' 04:45:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:39 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:42 INFO - Installing pip.................done. 04:45:42 INFO - Return code: 0 04:45:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:42 INFO - Reading from file tmpfile_stdout 04:45:42 INFO - Output received: 04:45:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:45:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:42 INFO - 'HOME': '/Users/cltbld', 04:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:42 INFO - 'LOGNAME': 'cltbld', 04:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:42 INFO - 'MOZ_NO_REMOTE': '1', 04:45:42 INFO - 'NO_EM_RESTART': '1', 04:45:42 INFO - 'PAGER': '/bin/cat', 04:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:42 INFO - 'PWD': '/builds/slave/test', 04:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:42 INFO - 'SHELL': '/bin/bash', 04:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:42 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:42 INFO - 'USER': 'cltbld', 04:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:42 INFO - 'XPC_FLAGS': '0x0', 04:45:42 INFO - 'XPC_SERVICE_NAME': '0', 04:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:42 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:42 INFO - Cleaning up... 04:45:42 INFO - Return code: 0 04:45:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:42 INFO - 'HOME': '/Users/cltbld', 04:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:42 INFO - 'LOGNAME': 'cltbld', 04:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:42 INFO - 'MOZ_NO_REMOTE': '1', 04:45:42 INFO - 'NO_EM_RESTART': '1', 04:45:42 INFO - 'PAGER': '/bin/cat', 04:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:42 INFO - 'PWD': '/builds/slave/test', 04:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:42 INFO - 'SHELL': '/bin/bash', 04:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:42 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:42 INFO - 'USER': 'cltbld', 04:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:42 INFO - 'XPC_FLAGS': '0x0', 04:45:42 INFO - 'XPC_SERVICE_NAME': '0', 04:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:42 INFO - Downloading/unpacking psutil>=3.1.1 04:45:42 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:42 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:42 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:42 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:42 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:42 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:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:45 INFO - Installing collected packages: psutil 04:45:45 INFO - Running setup.py install for psutil 04:45:45 INFO - building 'psutil._psutil_osx' extension 04:45:45 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:45 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:45 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:45 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:45 INFO - building 'psutil._psutil_posix' extension 04:45:45 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:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:45 INFO - ^ 04:45:45 INFO - 1 warning generated. 04:45:45 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:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:45 INFO - Successfully installed psutil 04:45:45 INFO - Cleaning up... 04:45:45 INFO - Return code: 0 04:45:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:45 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:45 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:45 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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/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 - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:45 INFO - 'MOZ_NO_REMOTE': '1', 04:45:45 INFO - 'NO_EM_RESTART': '1', 04:45:45 INFO - 'PAGER': '/bin/cat', 04:45:45 INFO - 'PATH': '/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 - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:45 INFO - 'SHELL': '/bin/bash', 04:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:45 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45:46 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:46 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:46 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:46 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:46 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:46 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:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:47 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:47 INFO - Installing collected packages: mozsystemmonitor 04:45:47 INFO - Running setup.py install for mozsystemmonitor 04:45:47 INFO - Successfully installed mozsystemmonitor 04:45:47 INFO - Cleaning up... 04:45:47 INFO - Return code: 0 04:45:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45:47 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:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:47 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:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:47 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:47 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:47 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:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:47 INFO - 'HOME': '/Users/cltbld', 04:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:47 INFO - 'LOGNAME': 'cltbld', 04:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:47 INFO - 'MOZ_NO_REMOTE': '1', 04:45:47 INFO - 'NO_EM_RESTART': '1', 04:45:47 INFO - 'PAGER': '/bin/cat', 04:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:47 INFO - 'PWD': '/builds/slave/test', 04:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:47 INFO - 'SHELL': '/bin/bash', 04:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:47 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:47 INFO - 'USER': 'cltbld', 04:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:47 INFO - 'XPC_FLAGS': '0x0', 04:45:47 INFO - 'XPC_SERVICE_NAME': '0', 04:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:48 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:49 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:49 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:49 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:49 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:49 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:49 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:49 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:50 INFO - warning: no files found matching '*.txt' 04:45:50 INFO - no previously-included directories found matching 'build' 04:45:50 INFO - no previously-included directories found matching 'dist' 04:45:50 INFO - no previously-included directories found matching '.git*' 04:45:50 INFO - Installing collected packages: jsonschema, functools32 04:45:50 INFO - Running setup.py install for functools32 04:45:50 INFO - warning: no files found matching '*.txt' 04:45:50 INFO - no previously-included directories found matching 'build' 04:45:50 INFO - no previously-included directories found matching 'dist' 04:45:50 INFO - no previously-included directories found matching '.git*' 04:45:50 INFO - Successfully installed jsonschema functools32 04:45:50 INFO - Cleaning up... 04:45:50 INFO - Return code: 0 04:45:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:50 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:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:50 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:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:50 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:50 INFO - 'HOME': '/Users/cltbld', 04:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:50 INFO - 'LOGNAME': 'cltbld', 04:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:50 INFO - 'MOZ_NO_REMOTE': '1', 04:45:50 INFO - 'NO_EM_RESTART': '1', 04:45:50 INFO - 'PAGER': '/bin/cat', 04:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:50 INFO - 'PWD': '/builds/slave/test', 04:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:50 INFO - 'SHELL': '/bin/bash', 04:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:50 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:50 INFO - 'USER': 'cltbld', 04:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:50 INFO - 'XPC_FLAGS': '0x0', 04:45:50 INFO - 'XPC_SERVICE_NAME': '0', 04:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:51 INFO - Cleaning up... 04:45:51 INFO - Return code: 0 04:45:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:45:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:51 INFO - 'HOME': '/Users/cltbld', 04:45:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:51 INFO - 'LOGNAME': 'cltbld', 04:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:51 INFO - 'MOZ_NO_REMOTE': '1', 04:45:51 INFO - 'NO_EM_RESTART': '1', 04:45:51 INFO - 'PAGER': '/bin/cat', 04:45:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:51 INFO - 'PWD': '/builds/slave/test', 04:45:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:51 INFO - 'SHELL': '/bin/bash', 04:45:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:51 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:51 INFO - 'USER': 'cltbld', 04:45:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:51 INFO - 'XPC_FLAGS': '0x0', 04:45:51 INFO - 'XPC_SERVICE_NAME': '0', 04:45:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:51 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:52 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:52 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:52 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:52 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:52 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:52 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:52 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:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:53 INFO - Downloading docopt-0.6.1.tar.gz 04:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:53 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:53 INFO - Running setup.py install for blobuploader 04:45:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:53 INFO - Running setup.py install for requests 04:45:54 INFO - Running setup.py install for docopt 04:45:54 INFO - Successfully installed blobuploader requests docopt 04:45:54 INFO - Cleaning up... 04:45:54 INFO - Return code: 0 04:45:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:54 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:54 INFO - 'HOME': '/Users/cltbld', 04:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:54 INFO - 'LOGNAME': 'cltbld', 04:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:54 INFO - 'MOZ_NO_REMOTE': '1', 04:45:54 INFO - 'NO_EM_RESTART': '1', 04:45:54 INFO - 'PAGER': '/bin/cat', 04:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:54 INFO - 'PWD': '/builds/slave/test', 04:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:54 INFO - 'SHELL': '/bin/bash', 04:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:54 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:54 INFO - 'USER': 'cltbld', 04:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:54 INFO - 'XPC_FLAGS': '0x0', 04:45:54 INFO - 'XPC_SERVICE_NAME': '0', 04:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:54 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-o02Xkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:54 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-a7jAmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:54 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-_3CRss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:54 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-pp5_oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-dgrVya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-RNaBtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-MAuk_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-r_aBAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-R1x44b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-4NdLxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-EmMb54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-yhJWkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:55 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ALKXkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:56 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-n8WW12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:56 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-XhtU5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:56 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-d0OT1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:56 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-SlUXKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:56 INFO - Running setup.py install for manifestparser 04:45:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:56 INFO - Running setup.py install for mozcrash 04:45:56 INFO - Running setup.py install for mozdebug 04:45:56 INFO - Running setup.py install for mozdevice 04:45:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Running setup.py install for mozfile 04:45:57 INFO - Running setup.py install for mozhttpd 04:45:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Running setup.py install for mozinfo 04:45:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Running setup.py install for mozInstall 04:45:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Running setup.py install for mozleak 04:45:57 INFO - Running setup.py install for mozlog 04:45:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:57 INFO - Running setup.py install for moznetwork 04:45:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Running setup.py install for mozprocess 04:45:58 INFO - Running setup.py install for mozprofile 04:45:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Running setup.py install for mozrunner 04:45:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Running setup.py install for mozscreenshot 04:45:58 INFO - Running setup.py install for moztest 04:45:58 INFO - Running setup.py install for mozversion 04:45:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:58 INFO - Cleaning up... 04:45:59 INFO - Return code: 0 04:45:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:59 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:59 INFO - 'HOME': '/Users/cltbld', 04:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:59 INFO - 'LOGNAME': 'cltbld', 04:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:59 INFO - 'MOZ_NO_REMOTE': '1', 04:45:59 INFO - 'NO_EM_RESTART': '1', 04:45:59 INFO - 'PAGER': '/bin/cat', 04:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:59 INFO - 'PWD': '/builds/slave/test', 04:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:59 INFO - 'SHELL': '/bin/bash', 04:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:45:59 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:59 INFO - 'USER': 'cltbld', 04:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:59 INFO - 'XPC_FLAGS': '0x0', 04:45:59 INFO - 'XPC_SERVICE_NAME': '0', 04:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-q95BqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-0dVwXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-6ErYhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-tjWMU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-TSpZd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-TdQFVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:59 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-4LnwaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sZTSKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-1awe_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-duMzS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-vLk5ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-s2FiX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sWu9TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-LR5rsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-1pxLqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-KzWtDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:00 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:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:00 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-R5NHsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:01 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:46:01 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:46:01 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:46:01 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:46:01 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:46:01 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:46:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46:01 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:46:01 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:46:01 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:46:01 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:46:01 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:46:01 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:46:02 INFO - Downloading blessings-1.6.tar.gz 04:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:02 INFO - Installing collected packages: blessings 04:46:02 INFO - Running setup.py install for blessings 04:46:02 INFO - Successfully installed blessings 04:46:02 INFO - Cleaning up... 04:46:02 INFO - Return code: 0 04:46:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:02 INFO - Reading from file tmpfile_stdout 04:46:02 INFO - Current package versions: 04:46:02 INFO - blessings == 1.6 04:46:02 INFO - blobuploader == 1.2.4 04:46:02 INFO - docopt == 0.6.1 04:46:02 INFO - functools32 == 3.2.3-2 04:46:02 INFO - jsonschema == 2.5.1 04:46:02 INFO - manifestparser == 1.1 04:46:02 INFO - mozInstall == 1.12 04:46:02 INFO - mozcrash == 1.0 04:46:02 INFO - mozdebug == 0.1 04:46:02 INFO - mozdevice == 0.50 04:46:02 INFO - mozfile == 1.2 04:46:02 INFO - mozhttpd == 0.7 04:46:02 INFO - mozinfo == 0.9 04:46:02 INFO - mozleak == 0.1 04:46:02 INFO - mozlog == 3.4 04:46:02 INFO - moznetwork == 0.27 04:46:02 INFO - mozprocess == 0.25 04:46:02 INFO - mozprofile == 0.28 04:46:02 INFO - mozrunner == 6.13 04:46:02 INFO - mozscreenshot == 0.1 04:46:02 INFO - mozsystemmonitor == 0.3 04:46:02 INFO - moztest == 0.8 04:46:02 INFO - mozversion == 1.4 04:46:02 INFO - psutil == 3.1.1 04:46:02 INFO - requests == 1.2.3 04:46:02 INFO - wsgiref == 0.1.2 04:46:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:03 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 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:46:03 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:46:03 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:46:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:46:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:03 INFO - 'HOME': '/Users/cltbld', 04:46:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:03 INFO - 'LOGNAME': 'cltbld', 04:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:03 INFO - 'MOZ_NO_REMOTE': '1', 04:46:03 INFO - 'NO_EM_RESTART': '1', 04:46:03 INFO - 'PAGER': '/bin/cat', 04:46:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:03 INFO - 'PWD': '/builds/slave/test', 04:46:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:03 INFO - 'SHELL': '/bin/bash', 04:46:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:46:03 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:46:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:03 INFO - 'USER': 'cltbld', 04:46:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:03 INFO - 'XPC_FLAGS': '0x0', 04:46:03 INFO - 'XPC_SERVICE_NAME': '0', 04:46:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 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:46:03 INFO - Cleaning up... 04:46:03 INFO - Return code: 0 04:46:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46: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:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104651b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104654768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedebd6aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, '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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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:46: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', 'jsonschema'] in /builds/slave/test/build 04:46: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 jsonschema 04:46:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:46:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:03 INFO - 'HOME': '/Users/cltbld', 04:46:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:03 INFO - 'LOGNAME': 'cltbld', 04:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:03 INFO - 'MOZ_NO_REMOTE': '1', 04:46:03 INFO - 'NO_EM_RESTART': '1', 04:46:03 INFO - 'PAGER': '/bin/cat', 04:46:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:03 INFO - 'PWD': '/builds/slave/test', 04:46:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:03 INFO - 'SHELL': '/bin/bash', 04:46:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:46:03 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:46:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:03 INFO - 'USER': 'cltbld', 04:46:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:03 INFO - 'XPC_FLAGS': '0x0', 04:46:03 INFO - 'XPC_SERVICE_NAME': '0', 04:46:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:46:03 INFO - Cleaning up... 04:46:03 INFO - Return code: 0 04:46:03 INFO - Running post-action listener: _resource_record_post_action 04:46:03 INFO - Running post-action listener: _start_resource_monitoring 04:46:03 INFO - Starting resource monitoring. 04:46:03 INFO - [mozharness: 2017-05-04 11:46:03.565390Z] Finished create-virtualenv step (success) 04:46:03 INFO - [mozharness: 2017-05-04 11:46:03.566344Z] Running install step. 04:46:03 INFO - Running pre-action listener: _resource_record_pre_action 04:46:03 INFO - Running main action method: install 04:46:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:03 INFO - Reading from file tmpfile_stdout 04:46:03 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:03 INFO - Reading from file tmpfile_stdout 04:46:03 INFO - Output received: 04:46:03 INFO - Usage: mozinstall [options] installer 04:46:03 INFO - Options: 04:46:03 INFO - -h, --help show this help message and exit 04:46:03 INFO - -d DEST, --destination=DEST 04:46:03 INFO - Directory to install application into. [default: 04:46:03 INFO - "/builds/slave/test"] 04:46:03 INFO - --app=APP Application being installed. [default: firefox] 04:46:03 INFO - mkdir: /builds/slave/test/build/application 04:46:03 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:46:03 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:46:24 INFO - Reading from file tmpfile_stdout 04:46:24 INFO - Output received: 04:46:24 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:46:24 INFO - Running post-action listener: _resource_record_post_action 04:46:24 INFO - [mozharness: 2017-05-04 11:46:24.085698Z] Finished install step (success) 04:46:24 INFO - [mozharness: 2017-05-04 11:46:24.085880Z] Running run-tests step. 04:46:24 INFO - Running pre-action listener: _resource_record_pre_action 04:46:24 INFO - Running main action method: run_tests 04:46:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:24 INFO - 2017-05-04 04:46:24.100 screenresolution[1903:18221] starting screenresolution argv=screenresolution get 04:46:24 INFO - 2017-05-04 04:46:24.103 screenresolution[1903:18221] Display 0: 1600x1200x32@60 04:46:24 INFO - 2017-05-04 04:46:24.108 screenresolution[1904:18225] starting screenresolution argv=screenresolution list 04:46:24 INFO - Available Modes on Display 0 04:46:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:24 INFO - Intel Iris: 04:46:24 INFO - Chipset Model: Intel Iris 04:46:24 INFO - Type: GPU 04:46:24 INFO - Bus: Built-In 04:46:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:24 INFO - Vendor: Intel (0x8086) 04:46:24 INFO - Device ID: 0x0a2e 04:46:24 INFO - Revision ID: 0x0009 04:46:24 INFO - Displays: 04:46:24 INFO - Display: 04:46:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:24 INFO - Display Serial Number: 1600x1200 60 04:46:24 INFO - Main Display: Yes 04:46:24 INFO - Mirror: Off 04:46:24 INFO - Online: Yes 04:46:24 INFO - Rotation: Supported 04:46:24 INFO - Return code: 0 04:46:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:24 INFO - Python 2.7.3 04:46:24 INFO - Return code: 0 04:46:24 INFO - grabbing minidump binary from tooltool 04:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:24 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 0x10442fd30>, '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 0x104355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104424be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:24 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:46:24 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:46:24 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:46:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:24 INFO - Return code: 0 04:46:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:24 INFO - ENV: RUST_BACKTRACE is now 1 04:46:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:24 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', '--title', 't-yosemite-r7-0354', '--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:46:24 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 --title t-yosemite-r7-0354 --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:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlSB4lC8aO/Render', 04:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:24 INFO - 'HOME': '/Users/cltbld', 04:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:24 INFO - 'LOGNAME': 'cltbld', 04:46:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:24 INFO - 'MOZ_NO_REMOTE': '1', 04:46:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:24 INFO - 'NO_EM_RESTART': '1', 04:46:24 INFO - 'PAGER': '/bin/cat', 04:46:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:24 INFO - 'PWD': '/builds/slave/test', 04:46:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:24 INFO - 'RUST_BACKTRACE': '1', 04:46:24 INFO - 'SHELL': '/bin/bash', 04:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LZxkfWP6Eb/Listeners', 04:46:24 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 04:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:24 INFO - 'USER': 'cltbld', 04:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:24 INFO - 'XPC_FLAGS': '0x0', 04:46:24 INFO - 'XPC_SERVICE_NAME': '0', 04:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:24 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', '--title', 't-yosemite-r7-0354', '--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:46:24 INFO - mozversion application_buildid: 20170504030320 04:46:24 INFO - mozversion application_changeset: 33b92d9c40562dab3d7b602368c75619f1d793f7 04:46:24 INFO - mozversion application_display_name: Nightly 04:46:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:24 INFO - mozversion application_name: Firefox 04:46:24 INFO - mozversion application_remotingname: firefox 04:46:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:24 INFO - mozversion application_vendor: Mozilla 04:46:24 INFO - mozversion application_version: 55.0a1 04:46:24 INFO - mozversion platform_buildid: 20170504030320 04:46:24 INFO - mozversion platform_changeset: 33b92d9c40562dab3d7b602368c75619f1d793f7 04:46:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:24 INFO - mozversion platform_version: 55.0a1 04:46:24 INFO - using testdate: 1493898384 04:46:24 INFO - actual date: 1493898384 04:46:24 INFO - starting webserver on 'localhost:56102' 04:46:24 INFO - SUITE-START | Running 2 tests 04:46:24 INFO - TEST-START | dromaeo_css 04:46:24 INFO - operating with platform_type : mac_ 04:46:24 INFO - Initialising browser for dromaeo_css test... 04:46:24 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmprt6xc5/profile http://localhost:56102/getInfo.html) 04:46:27 INFO - PID 1914 | __metrics Screen width/height:1600/1200 04:46:27 INFO - PID 1914 | colorDepth:24 04:46:27 INFO - PID 1914 | Browser inner width/height: 1024/691 04:46:27 INFO - PID 1914 | __metrics 04:46:27 INFO - PID 1914 | 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:46:27 INFO - PID 1914 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:46:28 INFO - TEST-INFO | 1914: exit 0 04:46:28 INFO - Browser initialized. 04:46:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:28 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmprt6xc5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:40 INFO - PID 1917 | RSS: Main: 240783360 04:46:40 INFO - PID 1917 | 04:49:46 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56102/tests/dromaeo/cssquery-ext.html) 04:49:48 INFO - PID 1917 | RSS: Main: 371511296 04:49:48 INFO - PID 1917 | 04:52:50 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-ext.html (next: http://localhost:56102/tests/dromaeo/cssquery-jquery.html) 04:52:51 INFO - PID 1917 | RSS: Main: 369160192 04:52:51 INFO - PID 1917 | 04:55:53 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56102/tests/dromaeo/cssquery-mootools.html) 04:55:54 INFO - PID 1917 | RSS: Main: 328790016 04:55:54 INFO - PID 1917 | 04:58:56 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56102/tests/dromaeo/cssquery-prototype.html) 04:58:57 INFO - PID 1917 | RSS: Main: 324096000 04:58:57 INFO - PID 1917 | 05:02:00 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56102/tests/dromaeo/cssquery-yui.html) 05:02:01 INFO - PID 1917 | RSS: Main: 325361664 05:02:01 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:56102/tests/dromaeo/cssquery-yui.html (next: http://localhost:56102/tests/dromaeo/cssquery-dojo.html) 05:05:03 INFO - PID 1917 | RSS: Main: 367452160 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | __start_tp_report 05:05:03 INFO - PID 1917 | _x_x_mozilla_page_load 05:05:03 INFO - PID 1917 | _x_x_mozilla_page_load_details 05:05:03 INFO - PID 1917 | |i|pagename|runs| 05:05:03 INFO - PID 1917 | |0;dojo.html;3484.547008888844;3511.701505372044;3524;3531.982340088299;3566.1262990567316;6017.729202185901;6119.3268740438625;6145.754169833207;6178.073289006651;6212.037134244188;5100.413452452969;6084.391560843925;6101.762223876893;6127.387261273881;6145.58651510153;5279.551238144743;5311.336082989626;5364.892702145955;5375.7849686012705;5404.486573775485;5423.891522169554;5469.507744303012;5477;5479.726013699299;5482.2873026506495;5273.604479664041;5334.706591137486;5344.812931547416;5407.864803379907;5424.91862622059;5398.838034858961;5405.621606487547;5405.864853378657;5462.371827239878;5474.863128421782;5330.9733451332495;5365.302510673588;5393.541548968343;5406.270153529263;5415.025295446818;6085.208922840003;6143.508519318444;6152.230971128609;6160.7535698572;6165.229346331708;6388.2334119905445;6411.743530258784;6554.14796076507;6594.109795177637;6624;6714.127163468766;6715.865682686368;6719.596824190564;6719.764808231688;6720.932790672079;6134.110553969649;6294.559380843389;6300.653464059521;6300.873982520324;6306;6608.4052435281055;6673.099131617267;6796.4562834973085;6852.588844669336;6870.828229294308;6285.308616052231;6333.176687030748;6346.492280617539;6350.968245158837;6436.195475565554;6459.289478157399;6467.288598254188;6514.478841692748;6520.576162549514;6526.543141980111;6430.196225471816;6491.740330386826;6583.308752581006;6583.835404114935;6589.9341006590275;6572.605643661395;6580.309067547934;6584.539082264291;6587.110740050128;6590.934090659128;5278.287431196816;5284.762185701653;5294.735263236823;5304.602154838402;5347.812826551052;4511.977440112779;4549.727016379028;4582.335561343587;4635.837745678952;4742.762861856962;5473.877855039707;5915.40845915405;6288.9056664150075;6290.716917738713;6300.432961033437;6721.4958878084335;6721.798346049626;6737.562058466184;6744.055832183498;6745.93254067453;6144.665027289601;6237.532185086136;6303.2751233607805;6309.022101574264;6313.368663133741;5764.394738552475;5792.304923409218;5796.3913789052385;5798.768049278066;5800.912986305125;6759.459243260626;6770.8645827084165;6771.28901465339;6773.356531129535;6777.2883847195335;5354.705491198021;5521.641093328921;6568.704408301543;6696.096027036482;6739.831504212434;6019.939800601938;6903;6906.136732908387;6910.896336555056;6912.792616221522;6531.640759758259;6534.248561415498;6539.247986481425;6539.705713243011;6556.737730490918;6298.937010629835;6530.281669016309;6542.018697195268;6549.443297319781;6667.166604174478;6363.809085727436;6378.9362106380195;6379.744810207725;6443;6453.967730161319;6415.582987105823;6435.678216089083;6449.3873082057125;6458.903116453163;6459.838504037437;6184.907226391518;6254.4996400286955;6358.300586935526;6374;6378.394052564999;6401.135846660827;6463.806085817433;6473.158489396348;6474.870502590016;6475.417212450902;6249.500039996698;6354.841128971812;6363.93636063652;6369.426751592379;6376.936230637634 05:05:03 INFO - PID 1917 | |1;ext.html;768.7539987204093;788.6963519045167;802.9638666260016;1103.4758489717385;1107.9446027698614;26071.217863464102;36445.27109457809;36745.26509469809;36831;36837.63162368375;38203;38630.22739545207;38685.22629547407;38733.612663873355;38756.418653720146;5827.562932780037;8149.714759983417;8299.709510167146;8304;8316.41785075045;5904.143899134623;8117.1476994915565;8256.587170641444;8279.83440331193;8283.544405057719;8359.95820020899;8362.080171181165;8370.33037357013;8373.288270497014;8382.790430239233;5765.1928729977835;8254.958725206365;8303.377246706461;8329.250367466897;8338.958305208496;5920;5977.820665380002;5985.25184351956;5989.041753319491;8008.919910800876;36353.63646363529;36726.449103263476;36803;36838.81580592107;36872.63127368745;3487.319972605343;3518.982405087984;3521.8239088045493;3525.294941011782;3525.7355698322726;30401.695983040107;30415.847920760476;30424.54363184554;30430.84784576063;30470.238244043856;3052.755779537654;3113.097201811472;3137.607799025122;3141.3717256548553;3147.291859331655;249098;301031.4948425244;303886.48056759796;303892;305416;47894.80262993453;48048.519514805106;48048.759756201;48133.5186648136;48140;49605.303284056914;49639.7806186471;49695.25457118146;49695.25457118146;49698;13833.031687781759;13927.259092613423;13931.651708707363;13966.371513281927;13978.021538492208;9280.396774209656;9345.345825792265;11360.261582997078;11371.033462155809;11372.54509819598;10026.649067282757;10444;11999;12134.635960921187;12205.108208227739;5306.204069389622;5320.989012087691;5338.386085600128;5347.438518955531;5392.299001129828;983.8622592837008;1007.1640538353149;1021.3616489693941;1063.9468026598638;1079.384750692098;23560.821958901986;24129.879350603136;24304.7569524306;24328.51342973166;24331.513369732507;1025.3079171559168;1108.7449886525983;1110.9888901110885;1145.524607287983;1152.2049785648044;11581.247218930743;11889.133111041361;12395.690107747378;12403.317817520123;12411.503539858304;260480;261466;262057;262612;316845;52566.94866102657;52675.209871851956;52701.68245793809;52730.472695273325;52743.20885186725;1461.590754588717;1489.6648254142947;1499.550134959508;1502.7520459124341;1529.8393668664853;27350.452990939673;28064.43871122607;28444.85777571099;28996.565051523823;29048.854755726086;36552.63447365492;36582.63417365792;36597.63402366049;36612.45081323729;36643.26713465769;222224;222711;223156;223291;223367.88316058315;32030.67969320277;32139.51790723188;32231.35537289288;32235.516467252542;33143;40818.1836363277;41261.79369103135;45334.09331813411;45414.772926135156;45555.316670250635;28427.715722842506;29451.705482944897;30384;31218.375632487678;31488.370232595676;30014;30944.381112377177;31139.53290700596;31288.368093743346;31425.8428707855;36324;36779.81610091932;37148.81425592855;37387;37490;28993.13020609385;29700.554491683073;30567.2358191047;30631;31008.0697579073;22831.972561234452;23050.962706678576;24002.639960400258;24418.02327906864;24456.43548643064 05:05:03 INFO - PID 1917 | |2;jquery.html;387.2951228763649;398.0427072889701;418.73619619639624;430.866431406264;440.32849903896556;13003.479860805568;14980.5505834825;21351.359459216208;21401.892990535063;21463.892680536595;21813.890930545323;21872.671909921362;22713.091476340927;22856.65715014276;23012.88493557534;5874.206982057411;6045.939540604592;6171.228596425447;6207.4723648489935;6223.190985171921;3629.237860049393;3924.4113382992496;6144.6006009609455;6187.288461826903;6194.814155575317;6164.660943648098;6168.537359698018;6213.25440947087;6213.627182369072;6214.632780788219;5901.439363260483;6042.154098426224;6102.20671312731;6189.969050154743;6203.968980155093;5389.164679474688;5404.1083221268445;5405.5135037846785;5415.539679127279;5417.187421886709;20339;21483.785162148335;26684.733152668417;26910.5963410549;26915.461690766075;2202.6695995600626;2243.5064285857084;2244.1808739810017;2244.5623103494827;2250.426141333966;16486.67026659472;16992.83007169925;17430.91284543569;17516.649667006717;17687.38094166698;2074.6991686205565;2120.8091271785615;2132.72274604302;2153.0096155768365;2155.741311042669;165703.34296657;168815;169924;170014.29985700108;171469;11876.287422754489;11877.643670689893;12056.939715301367;12059.939700301442;12237.510499579917;16086.276117574736;16112.952658077187;16117.516474505783;16125;16139.677206455804;7070.964645176741;8917.678383372799;8954.373193876367;9015.549222538846;9101.817963640822;8591.183837535424;8591.398602097917;8622.84071464149;8782.560871956377;8800.251978581893;6418.486521078303;7442.506760235382;7451.254874512505;7601.429892758065;7625.771226863203;1628.332383722668;2062.226665000625;2080.4694802825184;2080.781517940625;2163.891805409723;893.5934149961752;922.1792604581927;943.3113826906397;946.4747065378643;962.6630679262342;19737.703934440993;20321.983900805197;20448.591028179355;20812.895935520224;20866.686999695015;978.6427953796813;988.8714467119372;1010.8685870836745;1015.5531566110888;1019.5412064570972;9912.55393507294;10217.744556386002;10281.53733082013;10326.915673854288;10404.947975260075;525230;553727;556818;560790;563738;108179.45910270399;111451.44274278739;125284;126099.10851337237;131524.3423782875;1147.1052578988401;1152.3028567716578;1161.227783523964;1172.7771723372532;1175.077564112168;15886.205689715236;16888.40890568824;17203.65592688164;17364.21861016232;17834.19746111402;54849.62875927974;56352.43647563472;57148.85702285848;57390.7130464345;57857.710711446176;61438.692806535684;73194.90207646783;75363;78493.60753196197;79815.60092199502;8510.617022234139;8629.87055194185;8962.071676668684;9000.36997410175;9091.181793638605;13810.861891381359;13819.516316928859;13881.375338109607;14069.577912662637;14377;11787.882121178678;11828.76342473163;11968.700782480508;12136.635900922987;12234.816477752664;11254.493547790205;11299.983001529901;11390.430478476208;11464.25482343645;11638.12714046433;11799.410029498662;11979.28124312544;12304.446299916346;12310.199837010565;12401.379931003234;6808.421284190899;8796.956015219883;8853.955730221307;8862;8934.463932164092;11549.307041577533;11787.528498859949;12069.939650301692;12087.879121209027;12177.02583793312 05:05:03 INFO - PID 1917 | |3;mootools.html;1275.4643049919034;1436.2244388030465;1455.8325792533863;1457.0529156048567;1457.4097490516344;11188.552457901684;11669.241499302561;11672.708182295446;11681.59114430995;11690;11067.50196241169;11706.473208705607;11709.941450292737;11715.472803723831;11734.82397764032;2506.310764539748;2556.9360765980905;2557.9872100639473;2562.743725627441;2563.717991020986;2020.7069974853637;2410.2528216253017;2527.0776166699115;2532.898684052645;2538.7207407185197;2156.644153714635;2184.126349460221;2439.85360878347;2516.245126462063;2533.784628306587;2475.3811547113223;2491.838030528019;2503.687039120119;2505.6993160820634;2509.6988361396566;2482.8262021658493;2491.9626205606933;2495.463475352808;2496.588062969608;2498.925032249035;11493.310401375944;11746.647600571996;11752.58865939688;11755.11836612249;11756.764864702744;6560.179977502812;6796.762113326059;6819.24988251297;6820.283870193608;6826.317368263134;3152.558641790128;3922.5489068757174;4184.476940382452;4358.498772641124;4361.847335343247;1259.741752940645;1259.9622011339675;1294.6375014995817;1303.0748168800162;1308.8625694302057;16391.508254752378;16466;16489;16499.340026398928;16501.33994640213;5297.417284098746;5362.436944120889;5454.7818087276855;5581.972090139605;5597.020521408737;9758.804823903482;11783.941080294715;11790.587329443428;11791.29252244868;11816.763664726657;5553.916691249635;6549.508786841006;6550;6554.049662798868;6575.704093315812;5994.220751302303;6197.4732147767945;6259.874802503924;6269.435750782452;6279;3682.4844521766972;4124.597636803501;4271.9786401067795;4288.356746488052;4321.092570560155;1246.7506498700295;1249.0320001998523;1272.0713878868294;1272.3002348708173;1282.910196286251;633.229045981812;777.9260970207821;795.1333046978821;795.2008231727077;845.3575282785005;6561.868762624817;6647.368499992493;6881.621510816954;6916.861662766716;6980;592.8458600763832;605.1739375752155;612.6722203621131;616.8704572039921;723.5984028863926;4109.445224894601;4250.936235956463;4316.438862947797;4369.694121411534;4391.187630288407;14408.063475874244;14430.494932677306;14812.481563145238;15103;15114.773278400833;14679.85320146785;14911.925440372946;14933.477328293675;14949.103053816805;14970.925145374422;8381.245687887997;8583.699570515002;8599.39804213699;8605.569721513773;8617.224449799549;5355.973220133875;6914.239433662193;7117.359437650636;7119.6084215368655;7156.427485801228;13185.934070329587;13454.93272533631;13609.183448993092;13664.590062298146;13887.861121389062;13702.246376449391;13896.513622023585;13906.860931390962;13955.162690238212;14232.573022809333;9171.816563668823;9346.392484488486;9397.436153830791;9397.85903211466;9405.81188376215;10309.536070876979;10751.569937202737;10857.891421086004;10870.945645271722;10879.564817407216;8962.910370896469;8990.86513702308;9080.954595226982;9087.591058402255;9121.042290559395;8729.869051964353;8762.693305734269;8814.603342849716;8908.242799362128;8989.325800564853;7991.680332786624;8094.676212951652;8221.753347399588;8229.588520574067;8246.958765206136;4966.553010229097;5980.8803823924145;6136.570440069152;6143.293521245114;6211.720472578654;6008.54935879802;6161.014237721943;6243.656598887105;6279.623222606659;6394.552381333262 05:05:03 INFO - PID 1917 | |4;prototype.html;392.39766957719894;394.6704501741046;400.61724731438073;404.2763453418381;405.09865549153136;6051.939480605192;6103.572749907508;6107.358407610215;6120.540959428044;6132.2028136342215;4320.783960801955;5674.751554657979;5838.591298609089;5870.471657550819;5940.821775346736;4602.424696912886;4630.21286381316;4646.303054541812;4653.7440440775745;4658.114958157939;4651.162790697673;4658.277966915133;4668.6731928765;4670.836520721776;4676.696014759047;3813.752106113108;4452.888677783065;4624.745638989854;4626.745528995903;4651.7441540715245;4588.151192029469;4589.977050114745;4598.908021839545;4609.723416595015;4626.236670949289;4135.379693046037;4138.117156693697;4149.668026557868;4154.31453810124;4158.293090174648;4782.589136204828;5063.822766203202;5822.621529600605;5828.513728999121;5831.180576722254;4537.705049171794;5043.546080852704;5437.755301011424;5468.699221542814;5526.364468086182;4908;5566.526845218162;5853.85365365865;5985.521158307325;6448.581312111328;3575.641256322581;3984.382420724792;4036.6367026967714;4153.854615088488;4262.190183865056;6446.580972236789;6694.631795251308;6712.194536655633;6741.3258674132685;6814.420774234147;3463.740219483549;3772.4152756322815;4243.42713733642;4304.504981927056;4315.4605674290715;3864.1305706215876;4079.4492743479846;4211.389348544505;4251.383549385322;4365.301551751704;4947.208446648519;5145.099607568661;5145.434002259748;5177.896442071137;5192.9740351298005;5229.320188375486;6222.81331560054;6305.383265944673;6445.742170313229;6461.224653041665;1184.5735535207318;1495.2523738130935;1498.715244103617;1502.5191938579549;1503.7368460519365;840.1556435781999;944.7024187380953;967.8064387122604;974.4056125763278;982.5873133284038;5053.873653158627;5983.282006159288;5989.371116032754;6006.459418652343;6023.819285421444;5949.405059494016;6186.379785238154;6412.839679007969;6563.343665633399;6601.570897891717;4617.630589552828;4896.902061958812;5432.049391356497;5520.779168833202;6094.177286066413;2899.492588796952;3288.109337131159;3357.546731191258;3431.159365955357;3468.254325320018;5248.291480650139;5757.539396848242;6687.732490700415;6833.710706913141;6886.070380498669;6736.932630673728;6741.662916854138;6744.7976560703255;6752.696128674222;6764.594124352555;6438;6561;6745.752035873379;6750.864982700318;6805.795826125323;5368.919466207932;6560.737570497126;6590.594433032004;6591.934080659132;6607.339266073546;6266.749330026748;6433.420992110732;6458.321876202931;6510.47916166715;6518.413342799171;5768.682722450306;6818.18181818185;6818.590884546943;6885.449164066762;6912.585244885323;4266.5946735060115;4280.657547396138;4293.52771195162;4307.169452982533;4344.56554344563;5938.762449502145;6522.743646436404;6544.901826472702;6562;6601.834954125993;6025.578209525291;6306.779262725966;6333.36666333363;6358.0780786784735;6368.490520758421;4710.458297295855;6304.84237894038;6316.305206427198;6417.101605775289;6504.414602685781;6341.302456729848;6352.714127864349;6356.586821856565;6364.172657554488;6373.490120790233;6220.315765265907;6428.325025872403;6457.644829534421;6483.157189565326;6483.42425514067;6318.229736548876;6345.809625711236;6352.587081839665;6353.68231588428;6367.42693157618 05:05:03 INFO - PID 1917 | |5;yui.html;741.9703211871524;819.8442295963763;844.2359664503626;852.4160949749419;864.1315477944664;13866.098703584272;16291.185440727975;16533.834661653378;16691.415800446986;16716.996980181168;17385.217665205066;17892.047812110985;18028.18873150708;18240.361587344447;18252.904825710466;965.9855102173473;977.1547611316231;990.7225976726528;991.7471044883545;992.0327680511516;982.5774916785779;984.6356847966263;984.6750572311156;985.1576901748973;997.1922742578498;1055.4669891704677;1056.060106505211;1056.6618682021717;1057.3338796558157;1062.5696592879858;1052.2003277509148;1054.0566193257068;1055.2771351624162;1057.5928267616946;1061.1828891753346;735.1325435985535;771.7839005078531;772.563501621582;773.1302284929454;773.4740376543948;16685.24916378766;16977.06626135562;17058.573535661708;17245.65508689819;17273.309067637278;14797.482088126972;16828.326866925432;16873.987560746395;17789.19948602316;17881.37415190475;12056.397180140957;13934.024618276724;14216;14359.066660667024;14395.784063238954;3455.4989526518766;3479.9652003480146;3481.425564781808;3482.5298584690995;3487.2328087820893;68828.96756548705;122726.38636806759;123697;126224;126895;10457.581696732197;10629.255952083435;10639.574417023232;10674.679759607225;10695.144388448905;17343.392981245597;18051.27794888216;18877.90561047186;25327;25449.618255726178;7415.629218539051;7457.925420745832;7530.209328020479;7719.073711154698;7719.729809456754;3770.962290377116;3814.275287695329;3831.750936189139;3836.5396152461885;3846.9230615387537;4312.396264522969;4346.022145017346;4391.451068616423;4433.977830110829;4450.488193857748;911.9498427586414;932.6735642525061;933.3280038372361;936.5083331251147;937.161240689579;569.524447086679;654.3914159831403;656.7110471392572;664.1134085995251;692.1624833950975;11118;11693.473793679304;12189.329586872629;13243.602691919257;13291.468341266242;651.6155468273741;662.4203821656051;665.218368417108;669.1802541886151;697;4051;5355.169948657965;5627.184058311604;5645.209670646031;5725.541956643542;79638.20361796308;88576;98567;100365;101939.49030254802;91310.54344728234;91879.29931210974;92726;93693;95009.52495237479;62119.068213976825;80525.19474805177;80628;80935;81252.59373703094;9475.431474111576;10489.47552622381;10632.627858024933;11958.22271552381;12258.080643951918;41420;41788.79105604574;42425.5757442434;42566.93582660458;43260.783696081315;65704;69647.65176174087;71887.64056179686;72305.63847180731;73126.26873731194;13707.286589176352;16055.678886421972;16600.335986560884;16850.241739122015;16883;17982.460526184237;18175.272989080288;18269.81730182681;18383.356582519547;18595.628087437904;15444.30500627452;15823.050616963019;15855.920720396323;17177.398791042255;17270.740938885676;16151.596210094842;16162.595935101717;16805.32778688839;16859.325626974784;16925.576860578585;15854.7621785671;16172.838271617133;16365.181740912669;16384.262708178398;16394.26225819817;10196.847047294445;11692.824607631062;12046.457909393923;12120.575779847662;12256.38718064111;12993.935030324788;13015.609531714064;13036.674083147996;13053.543125990545;13314.86685133175 05:05:03 INFO - PID 1917 | __end_tp_report 05:05:03 INFO - PID 1917 | __start_cc_report 05:05:03 INFO - PID 1917 | _x_x_mozilla_cycle_collect,1373 05:05:03 INFO - PID 1917 | __end_cc_report 05:05:03 INFO - PID 1917 | __startTimestamp1493899503883__endTimestamp 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | ------- Summary: start ------- 05:05:03 INFO - PID 1917 | Number of tests: 6 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#0] dojo.html Cycles:165 Average:6071.56 Median:6313.37 stddev:713.66 (11.3%) stddev-sans-first:686.38 05:05:03 INFO - PID 1917 | Values: 3484.5 3511.7 3524.0 3532.0 3566.1 6017.7 6119.3 6145.8 6178.1 6212.0 5100.4 6084.4 6101.8 6127.4 6145.6 5279.6 5311.3 5364.9 5375.8 5404.5 5423.9 5469.5 5477.0 5479.7 5482.3 5273.6 5334.7 5344.8 5407.9 5424.9 5398.8 5405.6 5405.9 5462.4 5474.9 5331.0 5365.3 5393.5 5406.3 5415.0 6085.2 6143.5 6152.2 6160.8 6165.2 6388.2 6411.7 6554.1 6594.1 6624.0 6714.1 6715.9 6719.6 6719.8 6720.9 6134.1 6294.6 6300.7 6300.9 6306.0 6608.4 6673.1 6796.5 6852.6 6870.8 6285.3 6333.2 6346.5 6351.0 6436.2 6459.3 6467.3 6514.5 6520.6 6526.5 6430.2 6491.7 6583.3 6583.8 6589.9 6572.6 6580.3 6584.5 6587.1 6590.9 5278.3 5284.8 5294.7 5304.6 5347.8 4512.0 4549.7 4582.3 4635.8 4742.8 5473.9 5915.4 6288.9 6290.7 6300.4 6721.5 6721.8 6737.6 6744.1 6745.9 6144.7 6237.5 6303.3 6309.0 6313.4 5764.4 5792.3 5796.4 5798.8 5800.9 6759.5 6770.9 6771.3 6773.4 6777.3 5354.7 5521.6 6568.7 6696.1 6739.8 6019.9 6903.0 6906.1 6910.9 6912.8 6531.6 6534.2 6539.2 6539.7 6556.7 6298.9 6530.3 6542.0 6549.4 6667.2 6363.8 6378.9 6379.7 6443.0 6454.0 6415.6 6435.7 6449.4 6458.9 6459.8 6184.9 6254.5 6358.3 6374.0 6378.4 6401.1 6463.8 6473.2 6474.9 6475.4 6249.5 6354.8 6363.9 6369.4 6376.9 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#1] ext.html Cycles:180 Average:41635.88 Median:32085.10 stddev:69344.27 (216.1%) stddev-sans-first:69470.91 05:05:03 INFO - PID 1917 | Values: 768.8 788.7 803.0 1103.5 1107.9 26071.2 36445.3 36745.3 36831.0 36837.6 38203.0 38630.2 38685.2 38733.6 38756.4 5827.6 8149.7 8299.7 8304.0 8316.4 5904.1 8117.1 8256.6 8279.8 8283.5 8360.0 8362.1 8370.3 8373.3 8382.8 5765.2 8255.0 8303.4 8329.3 8339.0 5920.0 5977.8 5985.3 5989.0 8008.9 36353.6 36726.4 36803.0 36838.8 36872.6 3487.3 3519.0 3521.8 3525.3 3525.7 30401.7 30415.8 30424.5 30430.8 30470.2 3052.8 3113.1 3137.6 3141.4 3147.3 249098.0 301031.5 303886.5 303892.0 305416.0 47894.8 48048.5 48048.8 48133.5 48140.0 49605.3 49639.8 49695.3 49695.3 49698.0 13833.0 13927.3 13931.7 13966.4 13978.0 9280.4 9345.3 11360.3 11371.0 11372.5 10026.6 10444.0 11999.0 12134.6 12205.1 5306.2 5321.0 5338.4 5347.4 5392.3 983.9 1007.2 1021.4 1063.9 1079.4 23560.8 24129.9 24304.8 24328.5 24331.5 1025.3 1108.7 1111.0 1145.5 1152.2 11581.2 11889.1 12395.7 12403.3 12411.5 260480.0 261466.0 262057.0 262612.0 316845.0 52566.9 52675.2 52701.7 52730.5 52743.2 1461.6 1489.7 1499.6 1502.8 1529.8 27350.5 28064.4 28444.9 28996.6 29048.9 36552.6 36582.6 36597.6 36612.5 36643.3 222224.0 222711.0 223156.0 223291.0 223367.9 32030.7 32139.5 32231.4 32235.5 33143.0 40818.2 41261.8 45334.1 45414.8 45555.3 28427.7 29451.7 30384.0 31218.4 31488.4 30014.0 30944.4 31139.5 31288.4 31425.8 36324.0 36779.8 37148.8 37387.0 37490.0 28993.1 29700.6 30567.2 30631.0 31008.1 22832.0 23051.0 24002.6 24418.0 24456.4 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#2] jquery.html Cycles:180 Average:35356.15 Median:12037.67 stddev:93974.90 (780.7%) stddev-sans-first:94201.85 05:05:03 INFO - PID 1917 | Values: 387.3 398.0 418.7 430.9 440.3 13003.5 14980.6 21351.4 21401.9 21463.9 21813.9 21872.7 22713.1 22856.7 23012.9 5874.2 6045.9 6171.2 6207.5 6223.2 3629.2 3924.4 6144.6 6187.3 6194.8 6164.7 6168.5 6213.3 6213.6 6214.6 5901.4 6042.2 6102.2 6190.0 6204.0 5389.2 5404.1 5405.5 5415.5 5417.2 20339.0 21483.8 26684.7 26910.6 26915.5 2202.7 2243.5 2244.2 2244.6 2250.4 16486.7 16992.8 17430.9 17516.6 17687.4 2074.7 2120.8 2132.7 2153.0 2155.7 165703.3 168815.0 169924.0 170014.3 171469.0 11876.3 11877.6 12056.9 12059.9 12237.5 16086.3 16113.0 16117.5 16125.0 16139.7 7071.0 8917.7 8954.4 9015.5 9101.8 8591.2 8591.4 8622.8 8782.6 8800.3 6418.5 7442.5 7451.3 7601.4 7625.8 1628.3 2062.2 2080.5 2080.8 2163.9 893.6 922.2 943.3 946.5 962.7 19737.7 20322.0 20448.6 20812.9 20866.7 978.6 988.9 1010.9 1015.6 1019.5 9912.6 10217.7 10281.5 10326.9 10404.9 525230.0 553727.0 556818.0 560790.0 563738.0 108179.5 111451.4 125284.0 126099.1 131524.3 1147.1 1152.3 1161.2 1172.8 1175.1 15886.2 16888.4 17203.7 17364.2 17834.2 54849.6 56352.4 57148.9 57390.7 57857.7 61438.7 73194.9 75363.0 78493.6 79815.6 8510.6 8629.9 8962.1 9000.4 9091.2 13810.9 13819.5 13881.4 14069.6 14377.0 11787.9 11828.8 11968.7 12136.6 12234.8 11254.5 11300.0 11390.4 11464.3 11638.1 11799.4 11979.3 12304.4 12310.2 12401.4 6808.4 8797.0 8854.0 8862.0 8934.5 11549.3 11787.5 12069.9 12087.9 12177.0 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#3] mootools.html Cycles:180 Average:7085.67 Median:3802.52 stddev:4512.19 (118.7%) stddev-sans-first:4503.73 05:05:03 INFO - PID 1917 | Values: 1275.5 1436.2 1455.8 1457.1 1457.4 11188.6 11669.2 11672.7 11681.6 11690.0 11067.5 11706.5 11709.9 11715.5 11734.8 2506.3 2556.9 2558.0 2562.7 2563.7 2020.7 2410.3 2527.1 2532.9 2538.7 2156.6 2184.1 2439.9 2516.2 2533.8 2475.4 2491.8 2503.7 2505.7 2509.7 2482.8 2492.0 2495.5 2496.6 2498.9 11493.3 11746.6 11752.6 11755.1 11756.8 6560.2 6796.8 6819.2 6820.3 6826.3 3152.6 3922.5 4184.5 4358.5 4361.8 1259.7 1260.0 1294.6 1303.1 1308.9 16391.5 16466.0 16489.0 16499.3 16501.3 5297.4 5362.4 5454.8 5582.0 5597.0 9758.8 11783.9 11790.6 11791.3 11816.8 5553.9 6549.5 6550.0 6554.0 6575.7 5994.2 6197.5 6259.9 6269.4 6279.0 3682.5 4124.6 4272.0 4288.4 4321.1 1246.8 1249.0 1272.1 1272.3 1282.9 633.2 777.9 795.1 795.2 845.4 6561.9 6647.4 6881.6 6916.9 6980.0 592.8 605.2 612.7 616.9 723.6 4109.4 4250.9 4316.4 4369.7 4391.2 14408.1 14430.5 14812.5 15103.0 15114.8 14679.9 14911.9 14933.5 14949.1 14970.9 8381.2 8583.7 8599.4 8605.6 8617.2 5356.0 6914.2 7117.4 7119.6 7156.4 13185.9 13454.9 13609.2 13664.6 13887.9 13702.2 13896.5 13906.9 13955.2 14232.6 9171.8 9346.4 9397.4 9397.9 9405.8 10309.5 10751.6 10857.9 10870.9 10879.6 8962.9 8990.9 9081.0 9087.6 9121.0 8729.9 8762.7 8814.6 8908.2 8989.3 7991.7 8094.7 8221.8 8229.6 8247.0 4966.6 5980.9 6136.6 6143.3 6211.7 6008.5 6161.0 6243.7 6279.6 6394.6 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#4] prototype.html Cycles:180 Average:5152.27 Median:5846.22 stddev:1620.38 (27.7%) stddev-sans-first:1585.05 05:05:03 INFO - PID 1917 | Values: 392.4 394.7 400.6 404.3 405.1 6051.9 6103.6 6107.4 6120.5 6132.2 4320.8 5674.8 5838.6 5870.5 5940.8 4602.4 4630.2 4646.3 4653.7 4658.1 4651.2 4658.3 4668.7 4670.8 4676.7 3813.8 4452.9 4624.7 4626.7 4651.7 4588.2 4590.0 4598.9 4609.7 4626.2 4135.4 4138.1 4149.7 4154.3 4158.3 4782.6 5063.8 5822.6 5828.5 5831.2 4537.7 5043.5 5437.8 5468.7 5526.4 4908.0 5566.5 5853.9 5985.5 6448.6 3575.6 3984.4 4036.6 4153.9 4262.2 6446.6 6694.6 6712.2 6741.3 6814.4 3463.7 3772.4 4243.4 4304.5 4315.5 3864.1 4079.4 4211.4 4251.4 4365.3 4947.2 5145.1 5145.4 5177.9 5193.0 5229.3 6222.8 6305.4 6445.7 6461.2 1184.6 1495.3 1498.7 1502.5 1503.7 840.2 944.7 967.8 974.4 982.6 5053.9 5983.3 5989.4 6006.5 6023.8 5949.4 6186.4 6412.8 6563.3 6601.6 4617.6 4896.9 5432.0 5520.8 6094.2 2899.5 3288.1 3357.5 3431.2 3468.3 5248.3 5757.5 6687.7 6833.7 6886.1 6736.9 6741.7 6744.8 6752.7 6764.6 6438.0 6561.0 6745.8 6750.9 6805.8 5368.9 6560.7 6590.6 6591.9 6607.3 6266.7 6433.4 6458.3 6510.5 6518.4 5768.7 6818.2 6818.6 6885.4 6912.6 4266.6 4280.7 4293.5 4307.2 4344.6 5938.8 6522.7 6544.9 6562.0 6601.8 6025.6 6306.8 6333.4 6358.1 6368.5 4710.5 6304.8 6316.3 6417.1 6504.4 6341.3 6352.7 6356.6 6364.2 6373.5 6220.3 6428.3 6457.6 6483.2 6483.4 6318.2 6345.8 6352.6 6353.7 6367.4 05:05:03 INFO - PID 1917 | 05:05:03 INFO - PID 1917 | [#5] yui.html Cycles:180 Average:21319.90 Median:18489.49 stddev:29604.42 (160.1%) stddev-sans-first:29647.15 05:05:03 INFO - PID 1917 | Values: 742.0 819.8 844.2 852.4 864.1 13866.1 16291.2 16533.8 16691.4 16717.0 17385.2 17892.0 18028.2 18240.4 18252.9 966.0 977.2 990.7 991.7 992.0 982.6 984.6 984.7 985.2 997.2 1055.5 1056.1 1056.7 1057.3 1062.6 1052.2 1054.1 1055.3 1057.6 1061.2 735.1 771.8 772.6 773.1 773.5 16685.2 16977.1 17058.6 17245.7 17273.3 14797.5 16828.3 16874.0 17789.2 17881.4 12056.4 13934.0 14216.0 14359.1 14395.8 3455.5 3480.0 3481.4 3482.5 3487.2 68829.0 122726.4 123697.0 126224.0 126895.0 10457.6 10629.3 10639.6 10674.7 10695.1 17343.4 18051.3 18877.9 25327.0 25449.6 7415.6 7457.9 7530.2 7719.1 7719.7 3771.0 3814.3 3831.8 3836.5 3846.9 4312.4 4346.0 4391.5 4434.0 4450.5 911.9 932.7 933.3 936.5 937.2 569.5 654.4 656.7 664.1 692.2 11118.0 11693.5 12189.3 13243.6 13291.5 651.6 662.4 665.2 669.2 697.0 4051.0 5355.2 5627.2 5645.2 5725.5 79638.2 88576.0 98567.0 100365.0 101939.5 91310.5 91879.3 92726.0 93693.0 95009.5 62119.1 80525.2 80628.0 80935.0 81252.6 9475.4 10489.5 10632.6 11958.2 12258.1 41420.0 41788.8 42425.6 42566.9 43260.8 65704.0 69647.7 71887.6 72305.6 73126.3 13707.3 16055.7 16600.3 16850.2 16883.0 17982.5 18175.3 18269.8 18383.4 18595.6 15444.3 15823.1 15855.9 17177.4 17270.7 16151.6 16162.6 16805.3 16859.3 16925.6 15854.8 16172.8 16365.2 16384.3 16394.3 10196.8 11692.8 12046.5 12120.6 12256.4 12993.9 13015.6 13036.7 13053.5 13314.9 05:05:03 INFO - PID 1917 | -------- Summary: end -------- 05:05:03 INFO - PID 1917 | 05:05:04 INFO - TEST-INFO | 1917: exit 0 05:05:04 INFO - TEST-OK | dromaeo_css | took 1119959ms 05:05:04 INFO - TEST-START | kraken 05:05:04 INFO - operating with platform_type : mac_ 05:05:04 INFO - Initialising browser for kraken test... 05:05:04 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp9OZ0lE/profile http://localhost:56102/getInfo.html) 05:05:07 INFO - PID 1922 | __metrics Screen width/height:1600/1200 05:05:07 INFO - PID 1922 | colorDepth:24 05:05:07 INFO - PID 1922 | Browser inner width/height: 1024/691 05:05:07 INFO - PID 1922 | __metrics 05:05:07 INFO - PID 1922 | 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:05:07 INFO - PID 1922 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:05:08 INFO - TEST-INFO | 1922: exit 0 05:05:08 INFO - Browser initialized. 05:05:08 INFO - Running cycle 1/1 for kraken test... 05:05:08 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp9OZ0lE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:20 INFO - PID 1923 | RSS: Main: 240627712 05:05:20 INFO - PID 1923 | 05:05:23 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56102/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:24 INFO - PID 1923 | RSS: Main: 308625408 05:05:24 INFO - PID 1923 | 05:05:26 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56102/tests/kraken/driver.html?testName=audio-dft) 05:05:27 INFO - PID 1923 | RSS: Main: 291610624 05:05:27 INFO - PID 1923 | 05:05:30 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56102/tests/kraken/driver.html?testName=audio-fft) 05:05:31 INFO - PID 1923 | RSS: Main: 382566400 05:05:31 INFO - PID 1923 | 05:05:33 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56102/tests/kraken/driver.html?testName=audio-oscillator) 05:05:34 INFO - PID 1923 | RSS: Main: 285384704 05:05:34 INFO - PID 1923 | 05:05:36 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56102/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:36 INFO - PID 1923 | RSS: Main: 287834112 05:05:36 INFO - PID 1923 | 05:05:39 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56102/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:40 INFO - PID 1923 | RSS: Main: 352542720 05:05:40 INFO - PID 1923 | 05:05:43 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56102/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:43 INFO - PID 1923 | RSS: Main: 369487872 05:05:43 INFO - PID 1923 | 05:05:46 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56102/tests/kraken/driver.html?testName=json-parse-financial) 05:05:47 INFO - PID 1923 | RSS: Main: 375508992 05:05:47 INFO - PID 1923 | 05:05:48 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56102/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:49 INFO - PID 1923 | RSS: Main: 329068544 05:05:49 INFO - PID 1923 | 05:05:51 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:51 INFO - PID 1923 | RSS: Main: 331206656 05:05:51 INFO - PID 1923 | 05:05:54 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:54 INFO - PID 1923 | RSS: Main: 315203584 05:05:54 INFO - PID 1923 | 05:05:57 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:58 INFO - PID 1923 | RSS: Main: 351453184 05:05:58 INFO - PID 1923 | 05:06:01 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:02 INFO - PID 1923 | RSS: Main: 343384064 05:06:02 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:56102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56102/tests/kraken/driver.html?testName=ai-astar) 05:06:04 INFO - PID 1923 | RSS: Main: 386560000 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | __start_tp_report 05:06:04 INFO - PID 1923 | _x_x_mozilla_page_load 05:06:04 INFO - PID 1923 | _x_x_mozilla_page_load_details 05:06:04 INFO - PID 1923 | |i|pagename|runs| 05:06:04 INFO - PID 1923 | |0;ai-astar;108;109;108;109;109;110;110;108;109;109 05:06:04 INFO - PID 1923 | |1;audio-beat-detection;176;140;110;150;110;107;108;109;145;142 05:06:04 INFO - PID 1923 | |2;audio-dft;186;187;182;187;184;182;182;188;184;185 05:06:04 INFO - PID 1923 | |3;audio-fft;82;59;59;112;87;85;48;55;90;85 05:06:04 INFO - PID 1923 | |4;audio-oscillator;91;70;84;68;69;70;108;103;101;93 05:06:04 INFO - PID 1923 | |5;imaging-gaussian-blur;90;90;90;90;91;91;91;89;91;90 05:06:04 INFO - PID 1923 | |6;imaging-darkroom;97;96;96;97;96;96;96;96;97;97 05:06:04 INFO - PID 1923 | |7;imaging-desaturate;86;85;86;84;85;85;83;85;86;83 05:06:04 INFO - PID 1923 | |8;json-parse-financial;50;48;49;47;48;49;48;48;48;47 05:06:04 INFO - PID 1923 | |9;json-stringify-tinderbox;52;60;76;88;97;59;57;60;59;58 05:06:04 INFO - PID 1923 | |10;stanford-crypto-aes;82;83;94;105;105;84;81;88;92;104 05:06:04 INFO - PID 1923 | |11;stanford-crypto-ccm;137;156;150;155;147;133;136;141;203;148 05:06:04 INFO - PID 1923 | |12;stanford-crypto-pbkdf2;171;183;183;182;183;186;223;232;182;187 05:06:04 INFO - PID 1923 | |13;stanford-crypto-sha256-iterative;67;67;66;67;68;67;67;66;68;72 05:06:04 INFO - PID 1923 | __end_tp_report 05:06:04 INFO - PID 1923 | __start_cc_report 05:06:04 INFO - PID 1923 | _x_x_mozilla_cycle_collect,2286 05:06:04 INFO - PID 1923 | __end_cc_report 05:06:04 INFO - PID 1923 | __startTimestamp1493899564359__endTimestamp 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | ------- Summary: start ------- 05:06:04 INFO - PID 1923 | Number of tests: 14 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#0] ai-astar Cycles:10 Average:108.90 Median:109.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 05:06:04 INFO - PID 1923 | Values: 108.0 109.0 108.0 109.0 109.0 110.0 110.0 108.0 109.0 109.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:141.00 stddev:24.12 (17.1%) stddev-sans-first:18.90 05:06:04 INFO - PID 1923 | Values: 176.0 140.0 110.0 150.0 110.0 107.0 108.0 109.0 145.0 142.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.50 stddev:2.26 (1.2%) stddev-sans-first:2.35 05:06:04 INFO - PID 1923 | Values: 186.0 187.0 182.0 187.0 184.0 182.0 182.0 188.0 184.0 185.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#3] audio-fft Cycles:10 Average:76.20 Median:83.50 stddev:20.04 (24.0%) stddev-sans-first:21.14 05:06:04 INFO - PID 1923 | Values: 82.0 59.0 59.0 112.0 87.0 85.0 48.0 55.0 90.0 85.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:70.00 stddev:15.64 (22.3%) stddev-sans-first:16.47 05:06:04 INFO - PID 1923 | Values: 91.0 70.0 84.0 68.0 69.0 70.0 108.0 103.0 101.0 93.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:90.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:06:04 INFO - PID 1923 | Values: 90.0 90.0 90.0 90.0 91.0 91.0 91.0 89.0 91.0 90.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:06:04 INFO - PID 1923 | Values: 97.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 97.0 97.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 05:06:04 INFO - PID 1923 | Values: 86.0 85.0 86.0 84.0 85.0 85.0 83.0 85.0 86.0 83.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#8] json-parse-financial Cycles:10 Average:48.20 Median:48.00 stddev:0.92 (1.9%) stddev-sans-first:0.71 05:06:04 INFO - PID 1923 | Values: 50.0 48.0 49.0 47.0 48.0 49.0 48.0 48.0 48.0 47.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:60.00 stddev:15.10 (25.2%) stddev-sans-first:15.06 05:06:04 INFO - PID 1923 | Values: 52.0 60.0 76.0 88.0 97.0 59.0 57.0 60.0 59.0 58.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:83.50 stddev:9.82 (11.8%) stddev-sans-first:9.75 05:06:04 INFO - PID 1923 | Values: 82.0 83.0 94.0 105.0 105.0 84.0 81.0 88.0 92.0 104.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:149.00 stddev:20.04 (13.4%) stddev-sans-first:20.64 05:06:04 INFO - PID 1923 | Values: 137.0 156.0 150.0 155.0 147.0 133.0 136.0 141.0 203.0 148.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:184.50 stddev:19.72 (10.7%) stddev-sans-first:19.51 05:06:04 INFO - PID 1923 | Values: 171.0 183.0 183.0 182.0 183.0 186.0 223.0 232.0 182.0 187.0 05:06:04 INFO - PID 1923 | 05:06:04 INFO - PID 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:1.72 (2.6%) stddev-sans-first:1.81 05:06:04 INFO - PID 1923 | Values: 67.0 67.0 66.0 67.0 68.0 67.0 67.0 66.0 68.0 72.0 05:06:04 INFO - PID 1923 | -------- Summary: end -------- 05:06:04 INFO - PID 1923 | 05:06:05 INFO - TEST-INFO | 1923: exit 0 05:06:05 INFO - TEST-OK | kraken | took 60981ms 05:06:05 INFO - SUITE-END | took 1180s 05:06:06 INFO - Completed test suite (00:19:41) 05:06:06 INFO - javascript benchmark 05:06:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7706.488717744763, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3484.547008888844, 3511.701505372044, 3524.0, 3531.982340088299, 3566.1262990567316, 6017.729202185901, 6119.3268740438625, 6145.754169833207, 6178.073289006651, 6212.037134244188, 5100.413452452969, 6084.391560843925, 6101.762223876893, 6127.387261273881, 6145.58651510153, 5279.551238144743, 5311.336082989626, 5364.892702145955, 5375.7849686012705, 5404.486573775485, 5423.891522169554, 5469.507744303012, 5477.0, 5479.726013699299, 5482.2873026506495, 5273.604479664041, 5334.706591137486, 5344.812931547416, 5407.864803379907, 5424.91862622059, 5398.838034858961, 5405.621606487547, 5405.864853378657, 5462.371827239878, 5474.863128421782, 5330.9733451332495, 5365.302510673588, 5393.541548968343, 5406.270153529263, 5415.025295446818, 6085.208922840003, 6143.508519318444, 6152.230971128609, 6160.7535698572, 6165.229346331708, 6388.2334119905445, 6411.743530258784, 6554.14796076507, 6594.109795177637, 6624.0, 6714.127163468766, 6715.865682686368, 6719.596824190564, 6719.764808231688, 6720.932790672079, 6134.110553969649, 6294.559380843389, 6300.653464059521, 6300.873982520324, 6306.0, 6608.4052435281055, 6673.099131617267, 6796.4562834973085, 6852.588844669336, 6870.828229294308, 6285.308616052231, 6333.176687030748, 6346.492280617539, 6350.968245158837, 6436.195475565554, 6459.289478157399, 6467.288598254188, 6514.478841692748, 6520.576162549514, 6526.543141980111, 6430.196225471816, 6491.740330386826, 6583.308752581006, 6583.835404114935, 6589.9341006590275, 6572.605643661395, 6580.309067547934, 6584.539082264291, 6587.110740050128, 6590.934090659128, 5278.287431196816, 5284.762185701653, 5294.735263236823, 5304.602154838402, 5347.812826551052, 4511.977440112779, 4549.727016379028, 4582.335561343587, 4635.837745678952, 4742.762861856962, 5473.877855039707, 5915.40845915405, 6288.9056664150075, 6290.716917738713, 6300.432961033437, 6721.4958878084335, 6721.798346049626, 6737.562058466184, 6744.055832183498, 6745.93254067453, 6144.665027289601, 6237.532185086136, 6303.2751233607805, 6309.022101574264, 6313.368663133741, 5764.394738552475, 5792.304923409218, 5796.3913789052385, 5798.768049278066, 5800.912986305125, 6759.459243260626, 6770.8645827084165, 6771.28901465339, 6773.356531129535, 6777.2883847195335, 5354.705491198021, 5521.641093328921, 6568.704408301543, 6696.096027036482, 6739.831504212434, 6019.939800601938, 6903.0, 6906.136732908387, 6910.896336555056, 6912.792616221522, 6531.640759758259, 6534.248561415498, 6539.247986481425, 6539.705713243011, 6556.737730490918, 6298.937010629835, 6530.281669016309, 6542.018697195268, 6549.443297319781, 6667.166604174478, 6363.809085727436, 6378.9362106380195, 6379.744810207725, 6443.0, 6453.967730161319, 6415.582987105823, 6435.678216089083, 6449.3873082057125, 6458.903116453163, 6459.838504037437, 6184.907226391518, 6254.4996400286955, 6358.300586935526, 6374.0, 6378.394052564999, 6401.135846660827, 6463.806085817433, 6473.158489396348, 6474.870502590016, 6475.417212450902, 6249.500039996698, 6354.841128971812, 6363.93636063652, 6369.426751592379, 6376.936230637634], "value": 6024.398502500077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.7539987204093, 788.6963519045167, 802.9638666260016, 1103.4758489717385, 1107.9446027698614, 26071.217863464102, 36445.27109457809, 36745.26509469809, 36831.0, 36837.63162368375, 38203.0, 38630.22739545207, 38685.22629547407, 38733.612663873355, 38756.418653720146, 5827.562932780037, 8149.714759983417, 8299.709510167146, 8304.0, 8316.41785075045, 5904.143899134623, 8117.1476994915565, 8256.587170641444, 8279.83440331193, 8283.544405057719, 8359.95820020899, 8362.080171181165, 8370.33037357013, 8373.288270497014, 8382.790430239233, 5765.1928729977835, 8254.958725206365, 8303.377246706461, 8329.250367466897, 8338.958305208496, 5920.0, 5977.820665380002, 5985.25184351956, 5989.041753319491, 8008.919910800876, 36353.63646363529, 36726.449103263476, 36803.0, 36838.81580592107, 36872.63127368745, 3487.319972605343, 3518.982405087984, 3521.8239088045493, 3525.294941011782, 3525.7355698322726, 30401.695983040107, 30415.847920760476, 30424.54363184554, 30430.84784576063, 30470.238244043856, 3052.755779537654, 3113.097201811472, 3137.607799025122, 3141.3717256548553, 3147.291859331655, 249098.0, 301031.4948425244, 303886.48056759796, 303892.0, 305416.0, 47894.80262993453, 48048.519514805106, 48048.759756201, 48133.5186648136, 48140.0, 49605.303284056914, 49639.7806186471, 49695.25457118146, 49695.25457118146, 49698.0, 13833.031687781759, 13927.259092613423, 13931.651708707363, 13966.371513281927, 13978.021538492208, 9280.396774209656, 9345.345825792265, 11360.261582997078, 11371.033462155809, 11372.54509819598, 10026.649067282757, 10444.0, 11999.0, 12134.635960921187, 12205.108208227739, 5306.204069389622, 5320.989012087691, 5338.386085600128, 5347.438518955531, 5392.299001129828, 983.8622592837008, 1007.1640538353149, 1021.3616489693941, 1063.9468026598638, 1079.384750692098, 23560.821958901986, 24129.879350603136, 24304.7569524306, 24328.51342973166, 24331.513369732507, 1025.3079171559168, 1108.7449886525983, 1110.9888901110885, 1145.524607287983, 1152.2049785648044, 11581.247218930743, 11889.133111041361, 12395.690107747378, 12403.317817520123, 12411.503539858304, 260480.0, 261466.0, 262057.0, 262612.0, 316845.0, 52566.94866102657, 52675.209871851956, 52701.68245793809, 52730.472695273325, 52743.20885186725, 1461.590754588717, 1489.6648254142947, 1499.550134959508, 1502.7520459124341, 1529.8393668664853, 27350.452990939673, 28064.43871122607, 28444.85777571099, 28996.565051523823, 29048.854755726086, 36552.63447365492, 36582.63417365792, 36597.63402366049, 36612.45081323729, 36643.26713465769, 222224.0, 222711.0, 223156.0, 223291.0, 223367.88316058315, 32030.67969320277, 32139.51790723188, 32231.35537289288, 32235.516467252542, 33143.0, 40818.1836363277, 41261.79369103135, 45334.09331813411, 45414.772926135156, 45555.316670250635, 28427.715722842506, 29451.705482944897, 30384.0, 31218.375632487678, 31488.370232595676, 30014.0, 30944.381112377177, 31139.53290700596, 31288.368093743346, 31425.8428707855, 36324.0, 36779.81610091932, 37148.81425592855, 37387.0, 37490.0, 28993.13020609385, 29700.554491683073, 30567.2358191047, 30631.0, 31008.0697579073, 22831.972561234452, 23050.962706678576, 24002.639960400258, 24418.02327906864, 24456.43548643064], "value": 16828.822854539856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.2951228763649, 398.0427072889701, 418.73619619639624, 430.866431406264, 440.32849903896556, 13003.479860805568, 14980.5505834825, 21351.359459216208, 21401.892990535063, 21463.892680536595, 21813.890930545323, 21872.671909921362, 22713.091476340927, 22856.65715014276, 23012.88493557534, 5874.206982057411, 6045.939540604592, 6171.228596425447, 6207.4723648489935, 6223.190985171921, 3629.237860049393, 3924.4113382992496, 6144.6006009609455, 6187.288461826903, 6194.814155575317, 6164.660943648098, 6168.537359698018, 6213.25440947087, 6213.627182369072, 6214.632780788219, 5901.439363260483, 6042.154098426224, 6102.20671312731, 6189.969050154743, 6203.968980155093, 5389.164679474688, 5404.1083221268445, 5405.5135037846785, 5415.539679127279, 5417.187421886709, 20339.0, 21483.785162148335, 26684.733152668417, 26910.5963410549, 26915.461690766075, 2202.6695995600626, 2243.5064285857084, 2244.1808739810017, 2244.5623103494827, 2250.426141333966, 16486.67026659472, 16992.83007169925, 17430.91284543569, 17516.649667006717, 17687.38094166698, 2074.6991686205565, 2120.8091271785615, 2132.72274604302, 2153.0096155768365, 2155.741311042669, 165703.34296657, 168815.0, 169924.0, 170014.29985700108, 171469.0, 11876.287422754489, 11877.643670689893, 12056.939715301367, 12059.939700301442, 12237.510499579917, 16086.276117574736, 16112.952658077187, 16117.516474505783, 16125.0, 16139.677206455804, 7070.964645176741, 8917.678383372799, 8954.373193876367, 9015.549222538846, 9101.817963640822, 8591.183837535424, 8591.398602097917, 8622.84071464149, 8782.560871956377, 8800.251978581893, 6418.486521078303, 7442.506760235382, 7451.254874512505, 7601.429892758065, 7625.771226863203, 1628.332383722668, 2062.226665000625, 2080.4694802825184, 2080.781517940625, 2163.891805409723, 893.5934149961752, 922.1792604581927, 943.3113826906397, 946.4747065378643, 962.6630679262342, 19737.703934440993, 20321.983900805197, 20448.591028179355, 20812.895935520224, 20866.686999695015, 978.6427953796813, 988.8714467119372, 1010.8685870836745, 1015.5531566110888, 1019.5412064570972, 9912.55393507294, 10217.744556386002, 10281.53733082013, 10326.915673854288, 10404.947975260075, 525230.0, 553727.0, 556818.0, 560790.0, 563738.0, 108179.45910270399, 111451.44274278739, 125284.0, 126099.10851337237, 131524.3423782875, 1147.1052578988401, 1152.3028567716578, 1161.227783523964, 1172.7771723372532, 1175.077564112168, 15886.205689715236, 16888.40890568824, 17203.65592688164, 17364.21861016232, 17834.19746111402, 54849.62875927974, 56352.43647563472, 57148.85702285848, 57390.7130464345, 57857.710711446176, 61438.692806535684, 73194.90207646783, 75363.0, 78493.60753196197, 79815.60092199502, 8510.617022234139, 8629.87055194185, 8962.071676668684, 9000.36997410175, 9091.181793638605, 13810.861891381359, 13819.516316928859, 13881.375338109607, 14069.577912662637, 14377.0, 11787.882121178678, 11828.76342473163, 11968.700782480508, 12136.635900922987, 12234.816477752664, 11254.493547790205, 11299.983001529901, 11390.430478476208, 11464.25482343645, 11638.12714046433, 11799.410029498662, 11979.28124312544, 12304.446299916346, 12310.199837010565, 12401.379931003234, 6808.421284190899, 8796.956015219883, 8853.955730221307, 8862.0, 8934.463932164092, 11549.307041577533, 11787.528498859949, 12069.939650301692, 12087.879121209027, 12177.02583793312], "value": 10165.000433836985, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.4643049919034, 1436.2244388030465, 1455.8325792533863, 1457.0529156048567, 1457.4097490516344, 11188.552457901684, 11669.241499302561, 11672.708182295446, 11681.59114430995, 11690.0, 11067.50196241169, 11706.473208705607, 11709.941450292737, 11715.472803723831, 11734.82397764032, 2506.310764539748, 2556.9360765980905, 2557.9872100639473, 2562.743725627441, 2563.717991020986, 2020.7069974853637, 2410.2528216253017, 2527.0776166699115, 2532.898684052645, 2538.7207407185197, 2156.644153714635, 2184.126349460221, 2439.85360878347, 2516.245126462063, 2533.784628306587, 2475.3811547113223, 2491.838030528019, 2503.687039120119, 2505.6993160820634, 2509.6988361396566, 2482.8262021658493, 2491.9626205606933, 2495.463475352808, 2496.588062969608, 2498.925032249035, 11493.310401375944, 11746.647600571996, 11752.58865939688, 11755.11836612249, 11756.764864702744, 6560.179977502812, 6796.762113326059, 6819.24988251297, 6820.283870193608, 6826.317368263134, 3152.558641790128, 3922.5489068757174, 4184.476940382452, 4358.498772641124, 4361.847335343247, 1259.741752940645, 1259.9622011339675, 1294.6375014995817, 1303.0748168800162, 1308.8625694302057, 16391.508254752378, 16466.0, 16489.0, 16499.340026398928, 16501.33994640213, 5297.417284098746, 5362.436944120889, 5454.7818087276855, 5581.972090139605, 5597.020521408737, 9758.804823903482, 11783.941080294715, 11790.587329443428, 11791.29252244868, 11816.763664726657, 5553.916691249635, 6549.508786841006, 6550.0, 6554.049662798868, 6575.704093315812, 5994.220751302303, 6197.4732147767945, 6259.874802503924, 6269.435750782452, 6279.0, 3682.4844521766972, 4124.597636803501, 4271.9786401067795, 4288.356746488052, 4321.092570560155, 1246.7506498700295, 1249.0320001998523, 1272.0713878868294, 1272.3002348708173, 1282.910196286251, 633.229045981812, 777.9260970207821, 795.1333046978821, 795.2008231727077, 845.3575282785005, 6561.868762624817, 6647.368499992493, 6881.621510816954, 6916.861662766716, 6980.0, 592.8458600763832, 605.1739375752155, 612.6722203621131, 616.8704572039921, 723.5984028863926, 4109.445224894601, 4250.936235956463, 4316.438862947797, 4369.694121411534, 4391.187630288407, 14408.063475874244, 14430.494932677306, 14812.481563145238, 15103.0, 15114.773278400833, 14679.85320146785, 14911.925440372946, 14933.477328293675, 14949.103053816805, 14970.925145374422, 8381.245687887997, 8583.699570515002, 8599.39804213699, 8605.569721513773, 8617.224449799549, 5355.973220133875, 6914.239433662193, 7117.359437650636, 7119.6084215368655, 7156.427485801228, 13185.934070329587, 13454.93272533631, 13609.183448993092, 13664.590062298146, 13887.861121389062, 13702.246376449391, 13896.513622023585, 13906.860931390962, 13955.162690238212, 14232.573022809333, 9171.816563668823, 9346.392484488486, 9397.436153830791, 9397.85903211466, 9405.81188376215, 10309.536070876979, 10751.569937202737, 10857.891421086004, 10870.945645271722, 10879.564817407216, 8962.910370896469, 8990.86513702308, 9080.954595226982, 9087.591058402255, 9121.042290559395, 8729.869051964353, 8762.693305734269, 8814.603342849716, 8908.242799362128, 8989.325800564853, 7991.680332786624, 8094.676212951652, 8221.753347399588, 8229.588520574067, 8246.958765206136, 4966.553010229097, 5980.8803823924145, 6136.570440069152, 6143.293521245114, 6211.720472578654, 6008.54935879802, 6161.014237721943, 6243.656598887105, 6279.623222606659, 6394.552381333262], "value": 5301.780597624659, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.39766957719894, 394.6704501741046, 400.61724731438073, 404.2763453418381, 405.09865549153136, 6051.939480605192, 6103.572749907508, 6107.358407610215, 6120.540959428044, 6132.2028136342215, 4320.783960801955, 5674.751554657979, 5838.591298609089, 5870.471657550819, 5940.821775346736, 4602.424696912886, 4630.21286381316, 4646.303054541812, 4653.7440440775745, 4658.114958157939, 4651.162790697673, 4658.277966915133, 4668.6731928765, 4670.836520721776, 4676.696014759047, 3813.752106113108, 4452.888677783065, 4624.745638989854, 4626.745528995903, 4651.7441540715245, 4588.151192029469, 4589.977050114745, 4598.908021839545, 4609.723416595015, 4626.236670949289, 4135.379693046037, 4138.117156693697, 4149.668026557868, 4154.31453810124, 4158.293090174648, 4782.589136204828, 5063.822766203202, 5822.621529600605, 5828.513728999121, 5831.180576722254, 4537.705049171794, 5043.546080852704, 5437.755301011424, 5468.699221542814, 5526.364468086182, 4908.0, 5566.526845218162, 5853.85365365865, 5985.521158307325, 6448.581312111328, 3575.641256322581, 3984.382420724792, 4036.6367026967714, 4153.854615088488, 4262.190183865056, 6446.580972236789, 6694.631795251308, 6712.194536655633, 6741.3258674132685, 6814.420774234147, 3463.740219483549, 3772.4152756322815, 4243.42713733642, 4304.504981927056, 4315.4605674290715, 3864.1305706215876, 4079.4492743479846, 4211.389348544505, 4251.383549385322, 4365.301551751704, 4947.208446648519, 5145.099607568661, 5145.434002259748, 5177.896442071137, 5192.9740351298005, 5229.320188375486, 6222.81331560054, 6305.383265944673, 6445.742170313229, 6461.224653041665, 1184.5735535207318, 1495.2523738130935, 1498.715244103617, 1502.5191938579549, 1503.7368460519365, 840.1556435781999, 944.7024187380953, 967.8064387122604, 974.4056125763278, 982.5873133284038, 5053.873653158627, 5983.282006159288, 5989.371116032754, 6006.459418652343, 6023.819285421444, 5949.405059494016, 6186.379785238154, 6412.839679007969, 6563.343665633399, 6601.570897891717, 4617.630589552828, 4896.902061958812, 5432.049391356497, 5520.779168833202, 6094.177286066413, 2899.492588796952, 3288.109337131159, 3357.546731191258, 3431.159365955357, 3468.254325320018, 5248.291480650139, 5757.539396848242, 6687.732490700415, 6833.710706913141, 6886.070380498669, 6736.932630673728, 6741.662916854138, 6744.7976560703255, 6752.696128674222, 6764.594124352555, 6438.0, 6561.0, 6745.752035873379, 6750.864982700318, 6805.795826125323, 5368.919466207932, 6560.737570497126, 6590.594433032004, 6591.934080659132, 6607.339266073546, 6266.749330026748, 6433.420992110732, 6458.321876202931, 6510.47916166715, 6518.413342799171, 5768.682722450306, 6818.18181818185, 6818.590884546943, 6885.449164066762, 6912.585244885323, 4266.5946735060115, 4280.657547396138, 4293.52771195162, 4307.169452982533, 4344.56554344563, 5938.762449502145, 6522.743646436404, 6544.901826472702, 6562.0, 6601.834954125993, 6025.578209525291, 6306.779262725966, 6333.36666333363, 6358.0780786784735, 6368.490520758421, 4710.458297295855, 6304.84237894038, 6316.305206427198, 6417.101605775289, 6504.414602685781, 6341.302456729848, 6352.714127864349, 6356.586821856565, 6364.172657554488, 6373.490120790233, 6220.315765265907, 6428.325025872403, 6457.644829534421, 6483.157189565326, 6483.42425514067, 6318.229736548876, 6345.809625711236, 6352.587081839665, 6353.68231588428, 6367.42693157618], "value": 4645.54169340612, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.9703211871524, 819.8442295963763, 844.2359664503626, 852.4160949749419, 864.1315477944664, 13866.098703584272, 16291.185440727975, 16533.834661653378, 16691.415800446986, 16716.996980181168, 17385.217665205066, 17892.047812110985, 18028.18873150708, 18240.361587344447, 18252.904825710466, 965.9855102173473, 977.1547611316231, 990.7225976726528, 991.7471044883545, 992.0327680511516, 982.5774916785779, 984.6356847966263, 984.6750572311156, 985.1576901748973, 997.1922742578498, 1055.4669891704677, 1056.060106505211, 1056.6618682021717, 1057.3338796558157, 1062.5696592879858, 1052.2003277509148, 1054.0566193257068, 1055.2771351624162, 1057.5928267616946, 1061.1828891753346, 735.1325435985535, 771.7839005078531, 772.563501621582, 773.1302284929454, 773.4740376543948, 16685.24916378766, 16977.06626135562, 17058.573535661708, 17245.65508689819, 17273.309067637278, 14797.482088126972, 16828.326866925432, 16873.987560746395, 17789.19948602316, 17881.37415190475, 12056.397180140957, 13934.024618276724, 14216.0, 14359.066660667024, 14395.784063238954, 3455.4989526518766, 3479.9652003480146, 3481.425564781808, 3482.5298584690995, 3487.2328087820893, 68828.96756548705, 122726.38636806759, 123697.0, 126224.0, 126895.0, 10457.581696732197, 10629.255952083435, 10639.574417023232, 10674.679759607225, 10695.144388448905, 17343.392981245597, 18051.27794888216, 18877.90561047186, 25327.0, 25449.618255726178, 7415.629218539051, 7457.925420745832, 7530.209328020479, 7719.073711154698, 7719.729809456754, 3770.962290377116, 3814.275287695329, 3831.750936189139, 3836.5396152461885, 3846.9230615387537, 4312.396264522969, 4346.022145017346, 4391.451068616423, 4433.977830110829, 4450.488193857748, 911.9498427586414, 932.6735642525061, 933.3280038372361, 936.5083331251147, 937.161240689579, 569.524447086679, 654.3914159831403, 656.7110471392572, 664.1134085995251, 692.1624833950975, 11118.0, 11693.473793679304, 12189.329586872629, 13243.602691919257, 13291.468341266242, 651.6155468273741, 662.4203821656051, 665.218368417108, 669.1802541886151, 697.0, 4051.0, 5355.169948657965, 5627.184058311604, 5645.209670646031, 5725.541956643542, 79638.20361796308, 88576.0, 98567.0, 100365.0, 101939.49030254802, 91310.54344728234, 91879.29931210974, 92726.0, 93693.0, 95009.52495237479, 62119.068213976825, 80525.19474805177, 80628.0, 80935.0, 81252.59373703094, 9475.431474111576, 10489.47552622381, 10632.627858024933, 11958.22271552381, 12258.080643951918, 41420.0, 41788.79105604574, 42425.5757442434, 42566.93582660458, 43260.783696081315, 65704.0, 69647.65176174087, 71887.64056179686, 72305.63847180731, 73126.26873731194, 13707.286589176352, 16055.678886421972, 16600.335986560884, 16850.241739122015, 16883.0, 17982.460526184237, 18175.272989080288, 18269.81730182681, 18383.356582519547, 18595.628087437904, 15444.30500627452, 15823.050616963019, 15855.920720396323, 17177.398791042255, 17270.740938885676, 16151.596210094842, 16162.595935101717, 16805.32778688839, 16859.325626974784, 16925.576860578585, 15854.7621785671, 16172.838271617133, 16365.181740912669, 16384.262708178398, 16394.26225819817, 10196.847047294445, 11692.824607631062, 12046.457909393923, 12120.575779847662, 12256.38718064111, 12993.935030324788, 13015.609531714064, 13036.674083147996, 13053.543125990545, 13314.86685133175], "value": 8252.315462009652, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 108.0, 109.0, 109.0, 110.0, 110.0, 108.0, 109.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 140.0, 110.0, 150.0, 110.0, 107.0, 108.0, 109.0, 145.0, 142.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 182.0, 187.0, 184.0, 182.0, 182.0, 188.0, 184.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 112.0, 87.0, 85.0, 48.0, 55.0, 90.0, 85.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 84.0, 68.0, 69.0, 70.0, 108.0, 103.0, 101.0, 93.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 91.0, 91.0, 91.0, 89.0, 91.0, 90.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 85.0, 85.0, 83.0, 85.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 49.0, 47.0, 48.0, 49.0, 48.0, 48.0, 48.0, 47.0], "value": 48.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 60.0, 76.0, 88.0, 97.0, 59.0, 57.0, 60.0, 59.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 94.0, 105.0, 105.0, 84.0, 81.0, 88.0, 92.0, 104.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 156.0, 150.0, 155.0, 147.0, 133.0, 136.0, 141.0, 203.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 183.0, 183.0, 182.0, 183.0, 186.0, 223.0, 232.0, 182.0, 187.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 68.0, 67.0, 67.0, 66.0, 68.0, 72.0], "value": 67.5, "unit": "score"}]}]} 05:06:06 INFO - Return code: 0 05:06:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:06:06 INFO - # TBPL SUCCESS # 05:06:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:06 INFO - 2017-05-04 05:06:06.195 screenresolution[1928:20535] starting screenresolution argv=screenresolution get 05:06:06 INFO - 2017-05-04 05:06:06.197 screenresolution[1928:20535] Display 0: 1600x1200x32@60 05:06:06 INFO - 2017-05-04 05:06:06.203 screenresolution[1929:20539] starting screenresolution argv=screenresolution list 05:06:06 INFO - Available Modes on Display 0 05:06:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:06 INFO - Intel Iris: 05:06:06 INFO - Chipset Model: Intel Iris 05:06:06 INFO - Type: GPU 05:06:06 INFO - Bus: Built-In 05:06:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:06 INFO - Vendor: Intel (0x8086) 05:06:06 INFO - Device ID: 0x0a2e 05:06:06 INFO - Revision ID: 0x0009 05:06:06 INFO - Displays: 05:06:06 INFO - Display: 05:06:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:06 INFO - Display Serial Number: 1600x1200 60 05:06:06 INFO - Main Display: Yes 05:06:06 INFO - Mirror: Off 05:06:06 INFO - Online: Yes 05:06:06 INFO - Rotation: Supported 05:06:06 INFO - Return code: 0 05:06:06 INFO - Running post-action listener: _resource_record_post_action 05:06:06 INFO - [mozharness: 2017-05-04 12:06:06.438659Z] Finished run-tests step (success) 05:06:06 INFO - Running post-run listener: _resource_record_post_run 05:06:06 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 94208; Write bytes: 508768256; Read time: 1; Write time: 1593 05:06:06 INFO - TinderboxPrint: CPU usage
53.2% 05:06:06 INFO - TinderboxPrint: I/O read bytes / time
94,208 / 1 05:06:06 INFO - TinderboxPrint: I/O write bytes / time
508,768,256 / 1,593 05:06:06 INFO - TinderboxPrint: CPU idle
2,250.6 (46.8%) 05:06:06 INFO - TinderboxPrint: CPU system
958.0 (19.9%) 05:06:06 INFO - TinderboxPrint: CPU user
1,601.0 (33.3%) 05:06:06 INFO - TinderboxPrint: Swap in / out
207,695,872 / 0 05:06:06 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 203897344; Write bytes: 203337728; Read time: 16023; Write time: 439 05:06:06 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 94208; Write bytes: 295856128; Read time: 1; Write time: 1113 05:06:06 INFO - Running post-run listener: _upload_blobber_files 05:06:06 INFO - Blob upload gear active. 05:06:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:06 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:06:06 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:06:06 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:06:07 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:06:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:08 INFO - (blobuploader) - INFO - Done attempting. 05:06:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:06:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:06:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:09 INFO - (blobuploader) - INFO - Done attempting. 05:06:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:06:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:06:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:10 INFO - (blobuploader) - INFO - Done attempting. 05:06:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:12 INFO - (blobuploader) - INFO - Done attempting. 05:06:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:12 INFO - Return code: 0 05:06:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38b51eb86a2df0fa659a599d41d07a9541a8e2b77f5fa6fd8a538723949cdd568350471ee911f5cdd2e2866539025ddb5d63b41f947b9062fbd0b152e3fa6170", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e79ec4b558ecef7f9f3d505fd1ea2748cc92c5acd15758da8acceb83d660a530775d9c45559ddfc0dc60b005bc81f93fe68b6c33e61515d8dde361abbd460d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/016ce85f7d34b855615b2800c91628459b92d4b54656609aa3344de6e0193ba9e31cfbd8655267cca3c94edd4ad82e5efda2c48492b7060faeb78414c515323e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bfe1e1f0d07cb096f511d90214af0363c51f48a373de30f9934ce21f5f766c2c826d29cf79afdb04e0c51ee3a04b21a8a8b1b5bf52fbb180fce97fbd8f1a49f"} 05:06:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:12 INFO - Contents: 05:06:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38b51eb86a2df0fa659a599d41d07a9541a8e2b77f5fa6fd8a538723949cdd568350471ee911f5cdd2e2866539025ddb5d63b41f947b9062fbd0b152e3fa6170", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e79ec4b558ecef7f9f3d505fd1ea2748cc92c5acd15758da8acceb83d660a530775d9c45559ddfc0dc60b005bc81f93fe68b6c33e61515d8dde361abbd460d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/016ce85f7d34b855615b2800c91628459b92d4b54656609aa3344de6e0193ba9e31cfbd8655267cca3c94edd4ad82e5efda2c48492b7060faeb78414c515323e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bfe1e1f0d07cb096f511d90214af0363c51f48a373de30f9934ce21f5f766c2c826d29cf79afdb04e0c51ee3a04b21a8a8b1b5bf52fbb180fce97fbd8f1a49f"} 05:06:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:12 INFO - Copying logs to upload dir... 05:06:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.860677 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-04 05:06:12.409640) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 05:06:12.413516) ========= 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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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/38b51eb86a2df0fa659a599d41d07a9541a8e2b77f5fa6fd8a538723949cdd568350471ee911f5cdd2e2866539025ddb5d63b41f947b9062fbd0b152e3fa6170", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e79ec4b558ecef7f9f3d505fd1ea2748cc92c5acd15758da8acceb83d660a530775d9c45559ddfc0dc60b005bc81f93fe68b6c33e61515d8dde361abbd460d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/016ce85f7d34b855615b2800c91628459b92d4b54656609aa3344de6e0193ba9e31cfbd8655267cca3c94edd4ad82e5efda2c48492b7060faeb78414c515323e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bfe1e1f0d07cb096f511d90214af0363c51f48a373de30f9934ce21f5f766c2c826d29cf79afdb04e0c51ee3a04b21a8a8b1b5bf52fbb180fce97fbd8f1a49f"} build_url:https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/a3xjx-0rSQO3SRK0QXEI0Q/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/38b51eb86a2df0fa659a599d41d07a9541a8e2b77f5fa6fd8a538723949cdd568350471ee911f5cdd2e2866539025ddb5d63b41f947b9062fbd0b152e3fa6170", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e79ec4b558ecef7f9f3d505fd1ea2748cc92c5acd15758da8acceb83d660a530775d9c45559ddfc0dc60b005bc81f93fe68b6c33e61515d8dde361abbd460d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/016ce85f7d34b855615b2800c91628459b92d4b54656609aa3344de6e0193ba9e31cfbd8655267cca3c94edd4ad82e5efda2c48492b7060faeb78414c515323e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bfe1e1f0d07cb096f511d90214af0363c51f48a373de30f9934ce21f5f766c2c826d29cf79afdb04e0c51ee3a04b21a8a8b1b5bf52fbb180fce97fbd8f1a49f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 05:06:12.450432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 05:06:12.450874) ========= 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.DlSB4lC8aO/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.LZxkfWP6Eb/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.005424 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 05:06:12.500591) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 05:06:12.501126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 05:06:12.501532) ========= ========= Total master_lag: 0.54 =========