builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0369 starttime: 1492650739.16 results: success (0) buildid: 20170419165729 builduid: ca66bf3ef0f441c5b218053b0a49975e revision: e9a5d4f62461ee0db07a41f59b73163ec106bc3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.159368) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.159837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.160112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.184791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.185134) ========= 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.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-19 18:12:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2017-04-19 18:12:19 (13.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.186862 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.395158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.395481) ========= 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.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.022678 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:19.444974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-19 18:12:19.445765) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e9a5d4f62461ee0db07a41f59b73163ec106bc3e --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 e9a5d4f62461ee0db07a41f59b73163ec106bc3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-19 18:12:19,538 truncating revision to first 12 chars 2017-04-19 18:12:19,538 Setting DEBUG logging. 2017-04-19 18:12:19,538 attempt 1/10 2017-04-19 18:12:19,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e9a5d4f62461?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-19 18:12:21,063 unpacking tar archive at: mozilla-central-e9a5d4f62461/testing/mozharness/ program finished with exit code 0 elapsedTime=1.793637 ========= master_lag: 2.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-19 18:12:23.577050) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:23.577364) ========= script_repo_revision: e9a5d4f62461ee0db07a41f59b73163ec106bc3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:23.577770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:23.578032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 18:12:23.599011) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2017-04-19 18:12:23.599284) ========= /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.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:12:23 INFO - MultiFileLogger online at 20170419 18:12:23 in /builds/slave/test 18:12:23 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 18:12:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:12:23 INFO - {'append_to_log': False, 18:12:23 INFO - 'base_work_dir': '/builds/slave/test', 18:12:23 INFO - 'blob_upload_branch': 'Firefox', 18:12:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:12:23 INFO - 'branch': 'Firefox', 18:12:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:12:23 INFO - 'config_files': ('talos/mac_config.py',), 18:12:23 INFO - 'default_actions': ('clobber', 18:12:23 INFO - 'read-buildbot-config', 18:12:23 INFO - 'download-and-extract', 18:12:23 INFO - 'populate-webroot', 18:12:23 INFO - 'create-virtualenv', 18:12:23 INFO - 'install', 18:12:23 INFO - 'run-tests'), 18:12:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:12:23 INFO - 'download_minidump_stackwalk': True, 18:12:23 INFO - 'download_symbols': 'ondemand', 18:12:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:12:23 INFO - 'tooltool.py': '/tools/tooltool.py', 18:12:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:12:23 INFO - '/tools/misc-python/virtualenv.py')}, 18:12:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:12:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:12:23 INFO - 'gecko_profile': False, 18:12:23 INFO - 'gecko_profile_interval': 0, 18:12:23 INFO - 'installer_path': 'installer.exe', 18:12:23 INFO - 'log_level': 'info', 18:12:23 INFO - 'log_name': 'talos', 18:12:23 INFO - 'log_to_console': True, 18:12:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:12:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:12:23 INFO - 'opt_config_files': (), 18:12:23 INFO - 'pip_index': False, 18:12:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:23 INFO - 'cmd': ('bash', 18:12:23 INFO - '-c', 18:12:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:23 INFO - 'enabled': True, 18:12:23 INFO - 'halt_on_failure': False, 18:12:23 INFO - 'name': 'check_screen_resolution'},), 18:12:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:23 INFO - 'cmd': ('bash', 18:12:23 INFO - '-c', 18:12:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:23 INFO - 'enabled': True, 18:12:23 INFO - 'halt_on_failure': False, 18:12:23 INFO - 'name': 'check_screen_resolution'},), 18:12:23 INFO - 'run_cmd_checks_enabled': True, 18:12:23 INFO - 'suite': 'dromaeojs', 18:12:23 INFO - 'system_bits': '32', 18:12:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:12:23 INFO - 'title': 't-yosemite-r7-0369', 18:12:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:12:23 INFO - 'use_talos_json': True, 18:12:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:12:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:12:23 INFO - 'work_dir': 'build'} 18:12:23 INFO - [mozharness: 2017-04-20 01:12:23.807313Z] Running clobber step. 18:12:23 INFO - Running pre-action listener: _resource_record_pre_action 18:12:23 INFO - Running main action method: clobber 18:12:23 INFO - rmtree: /builds/slave/test/build 18:12:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:12:27 INFO - Running post-action listener: _resource_record_post_action 18:12:27 INFO - [mozharness: 2017-04-20 01:12:27.083982Z] Finished clobber step (success) 18:12:27 INFO - [mozharness: 2017-04-20 01:12:27.084150Z] Running read-buildbot-config step. 18:12:27 INFO - Running pre-action listener: _resource_record_pre_action 18:12:27 INFO - Running main action method: read_buildbot_config 18:12:27 INFO - Using buildbot properties: 18:12:27 INFO - { 18:12:27 INFO - "project": "", 18:12:27 INFO - "product": "firefox", 18:12:27 INFO - "script_repo_revision": "production", 18:12:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:12:27 INFO - "repository": "", 18:12:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:12:27 INFO - "buildid": "20170419165729", 18:12:27 INFO - "pgo_build": "False", 18:12:27 INFO - "basedir": "/builds/slave/test", 18:12:27 INFO - "buildnumber": 13, 18:12:27 INFO - "slavename": "t-yosemite-r7-0369", 18:12:27 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:12:27 INFO - "platform": "yosemite_r7", 18:12:27 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:12:27 INFO - "branch": "mozilla-central", 18:12:27 INFO - "repo_path": "mozilla-central", 18:12:27 INFO - "revision": "e9a5d4f62461ee0db07a41f59b73163ec106bc3e", 18:12:27 INFO - "stage_platform": "macosx64", 18:12:27 INFO - "builduid": "ca66bf3ef0f441c5b218053b0a49975e", 18:12:27 INFO - "slavebuilddir": "test" 18:12:27 INFO - } 18:12:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:12:27 INFO - Running post-action listener: _resource_record_post_action 18:12:27 INFO - [mozharness: 2017-04-20 01:12:27.089876Z] Finished read-buildbot-config step (success) 18:12:27 INFO - [mozharness: 2017-04-20 01:12:27.090272Z] Running download-and-extract step. 18:12:27 INFO - Running pre-action listener: _resource_record_pre_action 18:12:27 INFO - Running main action method: download_and_extract 18:12:27 INFO - mkdir: /builds/slave/test/build/tests 18:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:27 INFO - trying https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:12:27 INFO - Downloading https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/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 18:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/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 18:12:28 INFO - Downloaded 1325 bytes. 18:12:28 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:12:28 INFO - Using the following test package requirements: 18:12:28 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:12:28 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:12:28 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:12:28 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:12:28 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'jsshell-mac.zip'], 18:12:28 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:12:28 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:12:28 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:12:28 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:12:28 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:12:28 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:28 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:12:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:12:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:12:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:12:28 INFO - Fetch https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:13:00 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 18:13:00 INFO - retry: Failed, sleeping 30 seconds before retrying 18:13:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #2 18:13:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:13:32 INFO - Expected file size: 52514761 18:13:32 INFO - Obtained file size: 52514761 18:13:49 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 18:13:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:13:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:13:49 INFO - Fetch https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:13:50 INFO - Expected file size: 52514761 18:13:50 INFO - Obtained file size: 52514761 18:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:14:07 INFO - Expected file size: 14408811 18:14:07 INFO - Obtained file size: 14408811 18:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:07 INFO - trying https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:14:07 INFO - Downloading https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:14:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/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 18:14:09 INFO - Downloaded 71004098 bytes. 18:14:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:14:09 INFO - mkdir: /builds/slave/test/properties 18:14:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:14:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:14:09 INFO - Contents: 18:14:09 INFO - build_url:https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:14:10 INFO - Running post-action listener: _resource_record_post_action 18:14:10 INFO - Running post-action listener: set_extra_try_arguments 18:14:10 INFO - [mozharness: 2017-04-20 01:14:10.521493Z] Finished download-and-extract step (success) 18:14:10 INFO - [mozharness: 2017-04-20 01:14:10.521608Z] Running populate-webroot step. 18:14:10 INFO - Running pre-action listener: _resource_record_pre_action 18:14:10 INFO - Running main action method: populate_webroot 18:14:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:14:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:14:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:14:10 INFO - '--tppagecycles', 18:14:10 INFO - '7'], 18:14:10 INFO - 'tests': ['tsvgm']}}, 18:14:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:14:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:14:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:14:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:14:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['damp', 'tps']}, 18:14:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'tests': ['damp', 'tps']}, 18:14:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['dromaeo_dom']}, 18:14:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:14:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:14:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:14:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['a11yr', 18:14:10 INFO - 'ts_paint', 18:14:10 INFO - 'tpaint', 18:14:10 INFO - 'sessionrestore', 18:14:10 INFO - 'sessionrestore_no_auto_restore', 18:14:10 INFO - 'tabpaint']}, 18:14:10 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:14:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:14:10 INFO - 'bloom_basic_ref']}, 18:14:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['tsvgx', 18:14:10 INFO - 'tsvgr_opacity', 18:14:10 INFO - 'tart', 18:14:10 INFO - 'tscrollx', 18:14:10 INFO - 'cart', 18:14:10 INFO - 'tsvg_static']}, 18:14:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:14:10 INFO - 'tsvgr_opacity', 18:14:10 INFO - 'tart', 18:14:10 INFO - 'tscrollx', 18:14:10 INFO - 'cart', 18:14:10 INFO - 'tsvg_static']}, 18:14:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'talos_options': ['--disable-e10s'], 18:14:10 INFO - 'tests': ['tp5o']}, 18:14:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:14:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'talos_options': ['--disable-e10s', 18:14:10 INFO - '--xperf_path', 18:14:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:10 INFO - 'tests': ['tp5n']}, 18:14:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:14:10 INFO - 'talos_options': ['--xperf_path', 18:14:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:10 INFO - 'tests': ['tp5n']}}, 18:14:10 INFO - 'talos.zip': {'path': '', 18:14:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:14:10 INFO - Running post-action listener: _resource_record_post_action 18:14:10 INFO - [mozharness: 2017-04-20 01:14:10.540919Z] Finished populate-webroot step (success) 18:14:10 INFO - [mozharness: 2017-04-20 01:14:10.541057Z] Running create-virtualenv step. 18:14:10 INFO - Running pre-action listener: _resource_record_pre_action 18:14:10 INFO - Running main action method: create_virtualenv 18:14:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:14:10 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 18:14:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:14:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:14:10 INFO - Using real prefix '/tools/python27' 18:14:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:14:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:14:14 INFO - Installing pip.................done. 18:14:14 INFO - Return code: 0 18:14:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:14:14 INFO - Reading from file tmpfile_stdout 18:14:14 INFO - Output received: 18:14:14 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) 18:14:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:14 INFO - 'HOME': '/Users/cltbld', 18:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:14 INFO - 'LOGNAME': 'cltbld', 18:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:14 INFO - 'MOZ_NO_REMOTE': '1', 18:14:14 INFO - 'NO_EM_RESTART': '1', 18:14:14 INFO - 'PAGER': '/bin/cat', 18:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:14 INFO - 'PWD': '/builds/slave/test', 18:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:14 INFO - 'SHELL': '/bin/bash', 18:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:14 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:14 INFO - 'USER': 'cltbld', 18:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:14 INFO - 'XPC_FLAGS': '0x0', 18:14:14 INFO - 'XPC_SERVICE_NAME': '0', 18:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:14 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 18:14:14 INFO - Cleaning up... 18:14:14 INFO - Return code: 0 18:14:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:14 INFO - 'HOME': '/Users/cltbld', 18:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:14 INFO - 'LOGNAME': 'cltbld', 18:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:14 INFO - 'MOZ_NO_REMOTE': '1', 18:14:14 INFO - 'NO_EM_RESTART': '1', 18:14:14 INFO - 'PAGER': '/bin/cat', 18:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:14 INFO - 'PWD': '/builds/slave/test', 18:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:14 INFO - 'SHELL': '/bin/bash', 18:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:14 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:14 INFO - 'USER': 'cltbld', 18:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:14 INFO - 'XPC_FLAGS': '0x0', 18:14:14 INFO - 'XPC_SERVICE_NAME': '0', 18:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:15 INFO - Downloading/unpacking psutil>=3.1.1 18:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:14:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:18 INFO - Installing collected packages: psutil 18:14:18 INFO - Running setup.py install for psutil 18:14:19 INFO - building 'psutil._psutil_osx' extension 18:14:19 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 18:14:19 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 18:14:19 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 18:14:19 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 18:14:19 INFO - building 'psutil._psutil_posix' extension 18:14:19 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 18:14:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:14:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:14:19 INFO - ^ 18:14:19 INFO - 1 warning generated. 18:14:19 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 18:14:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:19 INFO - Successfully installed psutil 18:14:19 INFO - Cleaning up... 18:14:19 INFO - Return code: 0 18:14:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:19 INFO - 'HOME': '/Users/cltbld', 18:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:19 INFO - 'LOGNAME': 'cltbld', 18:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:19 INFO - 'MOZ_NO_REMOTE': '1', 18:14:19 INFO - 'NO_EM_RESTART': '1', 18:14:19 INFO - 'PAGER': '/bin/cat', 18:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:19 INFO - 'PWD': '/builds/slave/test', 18:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:19 INFO - 'SHELL': '/bin/bash', 18:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:19 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:19 INFO - 'USER': 'cltbld', 18:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:19 INFO - 'XPC_FLAGS': '0x0', 18:14:19 INFO - 'XPC_SERVICE_NAME': '0', 18:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:14:22 INFO - Installing collected packages: mozsystemmonitor 18:14:22 INFO - Running setup.py install for mozsystemmonitor 18:14:23 INFO - Successfully installed mozsystemmonitor 18:14:23 INFO - Cleaning up... 18:14:23 INFO - Return code: 0 18:14:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:14:23 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')]} 18:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:23 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')]} 18:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:23 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:23 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 18:14:23 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 18:14:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:23 INFO - 'HOME': '/Users/cltbld', 18:14:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:23 INFO - 'LOGNAME': 'cltbld', 18:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:23 INFO - 'MOZ_NO_REMOTE': '1', 18:14:23 INFO - 'NO_EM_RESTART': '1', 18:14:23 INFO - 'PAGER': '/bin/cat', 18:14:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:23 INFO - 'PWD': '/builds/slave/test', 18:14:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:23 INFO - 'SHELL': '/bin/bash', 18:14:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:23 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:23 INFO - 'USER': 'cltbld', 18:14:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:23 INFO - 'XPC_FLAGS': '0x0', 18:14:23 INFO - 'XPC_SERVICE_NAME': '0', 18:14:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:26 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:26 INFO - warning: no files found matching '*.txt' 18:14:26 INFO - no previously-included directories found matching 'build' 18:14:26 INFO - no previously-included directories found matching 'dist' 18:14:26 INFO - no previously-included directories found matching '.git*' 18:14:26 INFO - Installing collected packages: jsonschema, functools32 18:14:26 INFO - Running setup.py install for functools32 18:14:26 INFO - warning: no files found matching '*.txt' 18:14:26 INFO - no previously-included directories found matching 'build' 18:14:26 INFO - no previously-included directories found matching 'dist' 18:14:26 INFO - no previously-included directories found matching '.git*' 18:14:26 INFO - Successfully installed jsonschema functools32 18:14:26 INFO - Cleaning up... 18:14:26 INFO - Return code: 0 18:14:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:26 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:26 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 18:14:26 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 18:14:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:26 INFO - 'HOME': '/Users/cltbld', 18:14:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:26 INFO - 'LOGNAME': 'cltbld', 18:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:26 INFO - 'MOZ_NO_REMOTE': '1', 18:14:26 INFO - 'NO_EM_RESTART': '1', 18:14:26 INFO - 'PAGER': '/bin/cat', 18:14:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:26 INFO - 'PWD': '/builds/slave/test', 18:14:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:26 INFO - 'SHELL': '/bin/bash', 18:14:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:26 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:26 INFO - 'USER': 'cltbld', 18:14:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:26 INFO - 'XPC_FLAGS': '0x0', 18:14:26 INFO - 'XPC_SERVICE_NAME': '0', 18:14:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:14:27 INFO - Cleaning up... 18:14:27 INFO - Return code: 0 18:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:27 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:27 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 18:14:27 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 18:14:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:27 INFO - 'HOME': '/Users/cltbld', 18:14:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:27 INFO - 'LOGNAME': 'cltbld', 18:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:27 INFO - 'MOZ_NO_REMOTE': '1', 18:14:27 INFO - 'NO_EM_RESTART': '1', 18:14:27 INFO - 'PAGER': '/bin/cat', 18:14:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:27 INFO - 'PWD': '/builds/slave/test', 18:14:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:27 INFO - 'SHELL': '/bin/bash', 18:14:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:27 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:27 INFO - 'USER': 'cltbld', 18:14:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:27 INFO - 'XPC_FLAGS': '0x0', 18:14:27 INFO - 'XPC_SERVICE_NAME': '0', 18:14:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:14:27 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 18:14:27 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 18:14:27 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 18:14:27 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 18:14:27 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 18:14:27 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 18:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:30 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 18:14:31 INFO - Downloading docopt-0.6.1.tar.gz 18:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:31 INFO - Running setup.py install for blobuploader 18:14:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:31 INFO - Running setup.py install for requests 18:14:31 INFO - Running setup.py install for docopt 18:14:31 INFO - Successfully installed blobuploader requests docopt 18:14:31 INFO - Cleaning up... 18:14:32 INFO - Return code: 0 18:14:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:32 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 18:14:32 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 18:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:32 INFO - 'HOME': '/Users/cltbld', 18:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:32 INFO - 'LOGNAME': 'cltbld', 18:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:32 INFO - 'MOZ_NO_REMOTE': '1', 18:14:32 INFO - 'NO_EM_RESTART': '1', 18:14:32 INFO - 'PAGER': '/bin/cat', 18:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:32 INFO - 'PWD': '/builds/slave/test', 18:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:32 INFO - 'SHELL': '/bin/bash', 18:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:32 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:32 INFO - 'USER': 'cltbld', 18:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:32 INFO - 'XPC_FLAGS': '0x0', 18:14:32 INFO - 'XPC_SERVICE_NAME': '0', 18:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ESAbrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-jJjaxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-h7iKOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-b7kT4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-lpCekO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ZF3LDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:32 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-60SBVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-YMYjw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Ea6KEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Augk4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-XWjJg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-1j9tMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-QB1zYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-YMOidG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-p1QOZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:33 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-fm4KZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:34 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-kG5hII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:34 INFO - Running setup.py install for manifestparser 18:14:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:34 INFO - Running setup.py install for mozcrash 18:14:34 INFO - Running setup.py install for mozdebug 18:14:34 INFO - Running setup.py install for mozdevice 18:14:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:34 INFO - Running setup.py install for mozfile 18:14:34 INFO - Running setup.py install for mozhttpd 18:14:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:34 INFO - Running setup.py install for mozinfo 18:14:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Running setup.py install for mozInstall 18:14:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Running setup.py install for mozleak 18:14:35 INFO - Running setup.py install for mozlog 18:14:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Running setup.py install for moznetwork 18:14:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:35 INFO - Running setup.py install for mozprocess 18:14:35 INFO - Running setup.py install for mozprofile 18:14:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:36 INFO - Running setup.py install for mozrunner 18:14:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:36 INFO - Running setup.py install for mozscreenshot 18:14:36 INFO - Running setup.py install for moztest 18:14:36 INFO - Running setup.py install for mozversion 18:14:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:36 INFO - Cleaning up... 18:14:36 INFO - Return code: 0 18:14:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:36 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')]} 18:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:36 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')]} 18:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:36 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:36 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 18:14:36 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 18:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:36 INFO - 'HOME': '/Users/cltbld', 18:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:36 INFO - 'LOGNAME': 'cltbld', 18:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:36 INFO - 'MOZ_NO_REMOTE': '1', 18:14:36 INFO - 'NO_EM_RESTART': '1', 18:14:36 INFO - 'PAGER': '/bin/cat', 18:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:36 INFO - 'PWD': '/builds/slave/test', 18:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:36 INFO - 'SHELL': '/bin/bash', 18:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:36 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:36 INFO - 'USER': 'cltbld', 18:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:36 INFO - 'XPC_FLAGS': '0x0', 18:14:36 INFO - 'XPC_SERVICE_NAME': '0', 18:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-SY3oPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qhDotA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ppLyJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Fv33fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-HzH4OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-zBqjqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-iyaJ7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-rZX4pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-RYoKm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:37 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)) 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:37 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-8Vie7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-7zaVZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qAGRsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-xbJNdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-yeoYsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-_RGsFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-5BWZoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:38 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)) 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:38 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-7SAz8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:38 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)) 18:14:38 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)) 18:14:38 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)) 18:14:38 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)) 18:14:38 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)) 18:14:38 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)) 18:14:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:38 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 18:14:38 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 18:14:38 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 18:14:38 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 18:14:38 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 18:14:38 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 18:14:41 INFO - Downloading blessings-1.6.tar.gz 18:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:41 INFO - Installing collected packages: blessings 18:14:41 INFO - Running setup.py install for blessings 18:14:41 INFO - Successfully installed blessings 18:14:41 INFO - Cleaning up... 18:14:41 INFO - Return code: 0 18:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:42 INFO - Reading from file tmpfile_stdout 18:14:42 INFO - Current package versions: 18:14:42 INFO - blessings == 1.6 18:14:42 INFO - blobuploader == 1.2.4 18:14:42 INFO - docopt == 0.6.1 18:14:42 INFO - functools32 == 3.2.3-2 18:14:42 INFO - jsonschema == 2.5.1 18:14:42 INFO - manifestparser == 1.1 18:14:42 INFO - mozInstall == 1.12 18:14:42 INFO - mozcrash == 1.0 18:14:42 INFO - mozdebug == 0.1 18:14:42 INFO - mozdevice == 0.50 18:14:42 INFO - mozfile == 1.2 18:14:42 INFO - mozhttpd == 0.7 18:14:42 INFO - mozinfo == 0.9 18:14:42 INFO - mozleak == 0.1 18:14:42 INFO - mozlog == 3.4 18:14:42 INFO - moznetwork == 0.27 18:14:42 INFO - mozprocess == 0.25 18:14:42 INFO - mozprofile == 0.28 18:14:42 INFO - mozrunner == 6.13 18:14:42 INFO - mozscreenshot == 0.1 18:14:42 INFO - mozsystemmonitor == 0.3 18:14:42 INFO - moztest == 0.8 18:14:42 INFO - mozversion == 1.4 18:14:42 INFO - psutil == 3.1.1 18:14:42 INFO - requests == 1.2.3 18:14:42 INFO - wsgiref == 0.1.2 18:14:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:42 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 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14:42 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 18:14:42 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 18:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:42 INFO - 'HOME': '/Users/cltbld', 18:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:42 INFO - 'LOGNAME': 'cltbld', 18:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:42 INFO - 'MOZ_NO_REMOTE': '1', 18:14:42 INFO - 'NO_EM_RESTART': '1', 18:14:42 INFO - 'PAGER': '/bin/cat', 18:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:42 INFO - 'PWD': '/builds/slave/test', 18:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:42 INFO - 'SHELL': '/bin/bash', 18:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:42 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:42 INFO - 'USER': 'cltbld', 18:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:42 INFO - 'XPC_FLAGS': '0x0', 18:14:42 INFO - 'XPC_SERVICE_NAME': '0', 18:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 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)) 18:14:42 INFO - Cleaning up... 18:14:42 INFO - Return code: 0 18:14:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106419b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10641c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc73551710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, '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.yviXuZeS2k/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.CT1dYRwif4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 18:14: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', 'jsonschema'] in /builds/slave/test/build 18:14: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 jsonschema 18:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:42 INFO - 'HOME': '/Users/cltbld', 18:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:42 INFO - 'LOGNAME': 'cltbld', 18:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:42 INFO - 'MOZ_NO_REMOTE': '1', 18:14:42 INFO - 'NO_EM_RESTART': '1', 18:14:42 INFO - 'PAGER': '/bin/cat', 18:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:42 INFO - 'PWD': '/builds/slave/test', 18:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:42 INFO - 'SHELL': '/bin/bash', 18:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:14:42 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:42 INFO - 'USER': 'cltbld', 18:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:42 INFO - 'XPC_FLAGS': '0x0', 18:14:42 INFO - 'XPC_SERVICE_NAME': '0', 18:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:14:42 INFO - Cleaning up... 18:14:42 INFO - Return code: 0 18:14:42 INFO - Running post-action listener: _resource_record_post_action 18:14:42 INFO - Running post-action listener: _start_resource_monitoring 18:14:42 INFO - Starting resource monitoring. 18:14:42 INFO - [mozharness: 2017-04-20 01:14:42.684570Z] Finished create-virtualenv step (success) 18:14:42 INFO - [mozharness: 2017-04-20 01:14:42.685558Z] Running install step. 18:14:42 INFO - Running pre-action listener: _resource_record_pre_action 18:14:42 INFO - Running main action method: install 18:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:42 INFO - Reading from file tmpfile_stdout 18:14:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:43 INFO - Reading from file tmpfile_stdout 18:14:43 INFO - Output received: 18:14:43 INFO - Usage: mozinstall [options] installer 18:14:43 INFO - Options: 18:14:43 INFO - -h, --help show this help message and exit 18:14:43 INFO - -d DEST, --destination=DEST 18:14:43 INFO - Directory to install application into. [default: 18:14:43 INFO - "/builds/slave/test"] 18:14:43 INFO - --app=APP Application being installed. [default: firefox] 18:14:43 INFO - mkdir: /builds/slave/test/build/application 18:14:43 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'] 18:14:43 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 18:15:02 INFO - Reading from file tmpfile_stdout 18:15:02 INFO - Output received: 18:15:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:15:02 INFO - Running post-action listener: _resource_record_post_action 18:15:02 INFO - [mozharness: 2017-04-20 01:15:02.791305Z] Finished install step (success) 18:15:02 INFO - [mozharness: 2017-04-20 01:15:02.791471Z] Running run-tests step. 18:15:02 INFO - Running pre-action listener: _resource_record_pre_action 18:15:02 INFO - Running main action method: run_tests 18:15:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:15:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:15:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:15:02 INFO - 2017-04-19 18:15:02.806 screenresolution[2697:38818] starting screenresolution argv=screenresolution get 18:15:02 INFO - 2017-04-19 18:15:02.809 screenresolution[2697:38818] Display 0: 1600x1200x32@60 18:15:02 INFO - 2017-04-19 18:15:02.815 screenresolution[2698:38821] starting screenresolution argv=screenresolution list 18:15:02 INFO - Available Modes on Display 0 18:15:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:15:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:15:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:15:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:15:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:15:03 INFO - Intel Iris: 18:15:03 INFO - Chipset Model: Intel Iris 18:15:03 INFO - Type: GPU 18:15:03 INFO - Bus: Built-In 18:15:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:15:03 INFO - Vendor: Intel (0x8086) 18:15:03 INFO - Device ID: 0x0a2e 18:15:03 INFO - Revision ID: 0x0009 18:15:03 INFO - Displays: 18:15:03 INFO - Display: 18:15:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:15:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:15:03 INFO - Display Serial Number: 1600x1200 60 18:15:03 INFO - Main Display: Yes 18:15:03 INFO - Mirror: Off 18:15:03 INFO - Online: Yes 18:15:03 INFO - Rotation: Supported 18:15:03 INFO - Return code: 0 18:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:03 INFO - Python 2.7.3 18:15:03 INFO - Return code: 0 18:15:03 INFO - grabbing minidump binary from tooltool 18:15: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')]} 18:15:03 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 0x1061f7d30>, '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 0x10611ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061ecbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:03 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 18:15:03 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 18:15:03 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 18:15:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:03 INFO - Return code: 0 18:15:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:15:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:03 INFO - ENV: RUST_BACKTRACE is now 1 18:15:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:15:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:03 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 18:15:03 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0369 --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 18:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yviXuZeS2k/Render', 18:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:03 INFO - 'HOME': '/Users/cltbld', 18:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:03 INFO - 'LOGNAME': 'cltbld', 18:15:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:03 INFO - 'MOZ_NO_REMOTE': '1', 18:15:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:03 INFO - 'NO_EM_RESTART': '1', 18:15:03 INFO - 'PAGER': '/bin/cat', 18:15:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:03 INFO - 'PWD': '/builds/slave/test', 18:15:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:03 INFO - 'RUST_BACKTRACE': '1', 18:15:03 INFO - 'SHELL': '/bin/bash', 18:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CT1dYRwif4/Listeners', 18:15:03 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 18:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:03 INFO - 'USER': 'cltbld', 18:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:03 INFO - 'XPC_FLAGS': '0x0', 18:15:03 INFO - 'XPC_SERVICE_NAME': '0', 18:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:03 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 18:15:03 INFO - mozversion application_buildid: 20170419165729 18:15:03 INFO - mozversion application_changeset: e9a5d4f62461ee0db07a41f59b73163ec106bc3e 18:15:03 INFO - mozversion application_display_name: Nightly 18:15:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:03 INFO - mozversion application_name: Firefox 18:15:03 INFO - mozversion application_remotingname: firefox 18:15:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:15:03 INFO - mozversion application_vendor: Mozilla 18:15:03 INFO - mozversion application_version: 55.0a1 18:15:03 INFO - mozversion platform_buildid: 20170419165729 18:15:03 INFO - mozversion platform_changeset: e9a5d4f62461ee0db07a41f59b73163ec106bc3e 18:15:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:15:03 INFO - mozversion platform_version: 55.0a1 18:15:03 INFO - using testdate: 1492650903 18:15:03 INFO - actual date: 1492650903 18:15:03 INFO - starting webserver on 'localhost:64288' 18:15:03 INFO - SUITE-START | Running 2 tests 18:15:03 INFO - TEST-START | dromaeo_css 18:15:03 INFO - operating with platform_type : mac_ 18:15:03 INFO - Initialising browser for dromaeo_css test... 18:15:03 INFO - TEST-INFO | started process 2708 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpnh5Fvp/profile http://localhost:64288/getInfo.html) 18:15:06 INFO - PID 2708 | __metrics Screen width/height:1600/1200 18:15:06 INFO - PID 2708 | colorDepth:24 18:15:06 INFO - PID 2708 | Browser inner width/height: 1024/689 18:15:06 INFO - PID 2708 | __metrics 18:15:06 INFO - PID 2708 | 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" 18:15:06 INFO - PID 2708 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:07 INFO - TEST-INFO | 2708: exit 0 18:15:07 INFO - Browser initialized. 18:15:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:07 INFO - TEST-INFO | started process 2710 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpnh5Fvp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:19 INFO - PID 2710 | RSS: Main: 242552832 18:15:19 INFO - PID 2710 | 18:18:24 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64288/tests/dromaeo/cssquery-ext.html) 18:18:26 INFO - PID 2710 | RSS: Main: 371265536 18:18:26 INFO - PID 2710 | 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | A coding exception was thrown and uncaught in a Task. 18:20:09 INFO - PID 2710 | 18:20:09 ERROR - PID 2710 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:20:09 INFO - PID 2710 | Full stack: 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | A coding exception was thrown and uncaught in a Task. 18:20:09 INFO - PID 2710 | 18:20:09 ERROR - PID 2710 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:20:09 INFO - PID 2710 | Full stack: 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | A coding exception was thrown and uncaught in a Task. 18:20:09 INFO - PID 2710 | 18:20:09 ERROR - PID 2710 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:20:09 INFO - PID 2710 | Full stack: 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | ************************* 18:20:09 INFO - PID 2710 | A coding exception was thrown and uncaught in a Task. 18:20:09 INFO - PID 2710 | 18:20:09 ERROR - PID 2710 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:20:09 INFO - PID 2710 | Full stack: 18:20:09 INFO - PID 2710 | ************************* 18:21:29 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-ext.html (next: http://localhost:64288/tests/dromaeo/cssquery-jquery.html) 18:21:30 INFO - PID 2710 | RSS: Main: 370614272 18:21:30 INFO - PID 2710 | 18:24:32 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64288/tests/dromaeo/cssquery-mootools.html) 18:24:33 INFO - PID 2710 | RSS: Main: 327090176 18:24:33 INFO - PID 2710 | 18:27:35 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64288/tests/dromaeo/cssquery-prototype.html) 18:27:35 INFO - PID 2710 | RSS: Main: 324567040 18:27:35 INFO - PID 2710 | 18:30:39 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64288/tests/dromaeo/cssquery-yui.html) 18:30:40 INFO - PID 2710 | RSS: Main: 329486336 18:30:40 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | Cycle 1(1): loaded http://localhost:64288/tests/dromaeo/cssquery-yui.html (next: http://localhost:64288/tests/dromaeo/cssquery-dojo.html) 18:33:42 INFO - PID 2710 | RSS: Main: 372711424 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | __start_tp_report 18:33:42 INFO - PID 2710 | _x_x_mozilla_page_load 18:33:42 INFO - PID 2710 | _x_x_mozilla_page_load_details 18:33:42 INFO - PID 2710 | |i|pagename|runs| 18:33:42 INFO - PID 2710 | |0;dojo.html;3459.031471188065;3471.5660542432197;3478.391281525733;3506.088417011577;3525.294941011798;5882.852928676785;6032.939670603293;6148.938510614898;6171.598846075008;6188.876222475547;6097.847553811146;6103.034636161429;6149.661768602725;6185.103160041791;6207.5033997280225;5128.358955130609;5299.125644268691;5323.387810401795;5331.333583302077;5366.195070739382;4438.289873620221;5412.729363531827;5447.237386765856;5447.264619276387;5496.477834605706;5080.949190508103;5142.537171654531;5146.228065790124;5295.126304159809;5430.70131142787;4346.565343465628;4437.955620443787;5338.039152952467;5351.892962140735;5418.864528386822;5265.341832270966;5309.4425085365865;5320.654157479751;5347.331583552056;5348.304720386363;5941.316748573883;6004.129401236796;6113.144159817629;6131.14164017038;6148.169997050386;6354.5551811373225;6396.712147953353;6463.48292136628;6484.124643173159;6499.675016249169;6559.540832141752;6560.901586476207;6567.376099270562;6570.408663220285;6580.5722628029025;6146.139540464339;6218.471429928416;6228.408301211332;6246.312905580383;6252.5935814171935;5402.459754024645;6514.5114116441455;6546.738130474823;6672.466202703772;6759.256481787098;6346.174997250328;6347.206599175103;6364.904526432108;6371.458426033839;6385.5210859185745;6391.616503009835;6482.773102941374;6484.221893372825;6493.123428337209;6495.7076931538195;6307.590006649553;6393.264774550893;6397.360263973565;6404.71178796946;6426.678666066678;6394.488440924715;6420.165378500765;6421.711023003976;6437.034444833313;6444.7744328948265;5244.5279924806955;5245.029669511153;5254.868628284324;5288.629795914249;5294.205869119586;4654.743989080633;4673.976630116896;4674.182018146811;4704.88237794051;4730.5032971538185;5983.132445795335;6001;6145.631262124287;6148.016317389286;6180.721867515973;6500.284968653445;6568.113304703899;6573.868522629521;6578.111954886125;6583.374579414948;6175.44421002103;6177.752889884354;6178.474829639531;6190.102435146879;6190.1952746142715;5506.229127922094;5657.51911087554;5666.433356664383;5742.196092547047;5767.394423585547;6646.8005959821285;6685.197776266685;6697.397234248941;6699.799006029827;6710.429613482908;6304.401081897213;6348.365163483615;6355.809325720235;6466.870662586816;6533.052707357501;6679.899801503078;6760.053592497153;6767.35710107539;6767.830804229934;6784.830379240361;6428.710708018244;6428.874946884183;6437.935620643921;6441.452476539546;6452.516061295515;5412.350517937873;6355.3326900674765;6404.775832845828;6495.220573531206;6543.214814222324;6335.3981371769605;6337.809865704036;6353.14232578596;6358.109864618865;6364.936350636434;6195.349488303844;6342.39747224013;6344.429001389897;6348.714307856065;6355.618662880242;6134.631922084689;6240.157578726996;6252.531060170414;6298.464630506275;6341.651209183539;6293.496520278458;6326.335734747785;6352.142460767922;6377;6379.298277189599;6311.463525600376;6312.495000399865;6330;6340.7463701451425;6341.682915854281 18:33:42 INFO - PID 2710 | |1;ext.html;786.256987147146;1101.2896681640339;1119.0320372877463;1127.0194930410544;1253.7241806802501;35613.82193089034;36521.26957460852;36600.08499787506;36633.63366366336;36665.26669466613;37429.81285093578;37824.24351512968;37873.621263787354;37888.81055594718;37927.431088533696;5993.760249590033;6157.261128664567;7797.103333116694;7937.087234967995;7938;6062.181605483247;6145.170401995718;6151.077338399231;8300.626471808782;8502.404831661786;8534.274586660142;8586.828263434727;8593.183647553504;8597.785055373603;8600.913990860105;8494.490330580154;8554.35842311829;8563.400561960665;8564.100769419183;8568.914310856873;5962.3739507351975;6115.602485838406;8235.50586964784;8340.124286949904;8436.493810371398;37340.253194936224;37648.43527347092;37718;37728.81135594332;37794;3560.127768696686;3602.35157671619;3606.1886075633033;3607.4228123500375;3615.276944611062;30548.54177187344;30561.930332438256;30593.847030764704;30606;30644.84677576598;3106.0371284901753;3108.4249413858283;3162.288485090859;3162.5730526378875;3163.920901977469;280616;289544;290165;290330.548347259;291001;39536.802315988236;39658.80170599128;48393.54819355425;48425.7893552664;48466.757666211444;41481.1703765923;47847;47941.5205847937;48033.03933921302;48299.27551086737;13414.597562073153;13519.391627376792;13535.39090740919;13549.932250338685;13554.59336219915;10984.615538456115;10990.230683852213;10993.505292261867;10998.450077496253;11033.889661103447;12291;12291.446884890202;12332.81500777489;12370.6288811334;12423.254604723745;5040.269160971712;5169.198774190007;5554.444555544494;5575.804846830422;5583.274174357308;848.3806821020567;857.498363457372;993.7764003099389;1018.9949050254701;1030.994845025785;24165.51668966646;24301.513969720505;24329.148479803123;24333.75666243315;24375.390615234763;1084.2952081147316;1096.4956120184645;1131.6718151735904;1137.0903277378065;1137.732632831284;11863.940680296715;12440.688982775322;12446.81329780054;12463.37976063106;12508.562200322946;307947;308176;308595.7421277365;309421.9057809393;309467;52503.687407814345;52579.73710131425;52602;52679;52724.945501089765;1488.3525666334963;1533.9769903451681;1541.7841502189704;1543.6526781474079;1555.922203889801;19793.60412791765;23808.880955595112;23958.76041239565;24044.75955240425;24105.397365066012;37584.812075939444;37628.623713763605;37630.43554346632;37654.870353889426;37662;277527.6123619369;277731;278109;278622.2137778677;279249.6037519881;29390.853045734635;29619.85190074036;33847.66152338445;33914.66085339214;33931.15172120717;42702.359464607434;43409.34885976776;43438.782806085765;43439.701243320174;43577;28423.43153136967;29028.12915612535;29480.852595737742;30296.54555181718;30506.542401864433;23342.883285583463;23921;24614.50770984606;30119;30536.541951871182;36315.09212269714;36432;36434.8178259107;36531;36874.631253688196;25315.74684253134;29965;30965.535516967713;31121.68878311188;31317.373652527276;22642.43393915165;23667.171648992204;23674.763252367255;23800.523989519766;24082.638760418256 18:33:42 INFO - PID 2710 | |2;jquery.html;387.89720724008123;424.19826527862324;427.83100675233305;431.2496256513666;433.0753840550425;19153.904230478845;20139.29512467064;20872.37382878516;20897.477563060893;20972.16111355546;21389.251376201835;21542.784572154273;22211;22496.32511024668;22624.8868755656;5897.351291357958;6211.192544969148;6219.720112594921;6221.471174950135;6225.688715564226;5836;5840.328362238333;6029.1257767623665;6169.784057557987;6178.19683441152;6131.8773624527485;6135.570510064297;6140.232470941132;6142.907856382158;6158.6920653967345;5822.563307751914;6017.939820601804;6105.938940610581;6115.052166914135;6123.020316749344;5416.620836541444;5417.972910135424;5428.212909128194;5428.348598168205;6413.198350206224;28357.574636380472;28399.57400638992;28567.142985710256;29020.564691529646;29349.41301173986;2254.0645632062674;2314.351981445198;2315.710536182977;2316.6756654068445;2322.2800931711054;17917.283308667636;18101.275948962026;18721.344752933583;18894.905525472423;18994.52513687155;2014.7810574602302;2071.554615757604;2131.6056529542084;2252.155441709359;2258.7741225877444;121158.18262726066;139370.3031484846;148004.2599787005;150706;151799;17582.648347032988;21273.574528509344;22241.999110040088;22532.887335563217;22597.638873616866;39299.80350098288;39362.60637393647;39372.40941385881;39509;39554.62672239617;8626.482411055356;8687.305015598737;8975;9081;10744.570217191225;8348.373871959628;8363.818090954523;8485.321174306164;8508.532030738244;8540.53027083504;7549.3583045440655;7686.269804368688;7691.269329413697;7705.190954949761;7716.6527506262355;2106.5892151030707;2107.7681455039933;2111.0711287033964;2113.2286715348787;2250.1449449209194;890.3678388344218;909.4406939732017;924.7641851327868;942.7360339105062;966.246327864267;17352.47942561725;20093.74424060664;20132.798672013385;20662.96685165736;20694.586108277752;866.8947092457167;998.6654470752234;1005.4269066631949;1007.9344842585355;1018.8267994440963;9475.384100033614;10707.839382409271;10790.136789056856;10834.855826515106;10879.075278601249;526015;534496;536487;549155.2542237263;551604;83383;119727.07565358876;123448;128280.35859820641;129215;1093.9260677496102;1147.9598214062469;1188.1247381337184;1193.5524178433088;1196.5034510678145;20718.505412780687;20908.895455523234;20997.685034724796;21220.151193952068;21255.893720531298;54803.45196548143;54968.45031549793;55871.72064139653;56451;56887.431125688214;48075.75962120167;73937.6303118481;76664.61667691625;76997.46005079756;77612.8358074618;8172.468789528662;8476.32189424857;8525.989177629881;8925.241354484942;8928.73213803587;10671.679849604525;13488.32558372097;13668.726625467634;13728;13907.443702252202;11455.792378133803;11764.470598822902;11909.761804763682;11936.761264774483;12371.69070773202;10538.420386878795;10738.785224295627;10763.94618026905;11083.667489975314;11089.33463992163;10213.846792297973;10831.566737330733;11777.411129443664;11792.882071179178;12042.21725587834;8674.175953284428;8713.30293576525;8773.254273386834;8896.909226331936;9052.954735226282;11827.82258266144;12034.699132521757;12113.636590902288;12213.816792748294;12217.69455763613 18:33:42 INFO - PID 2710 | |3;mootools.html;1360.4830164537475;1417.8369487508933;1436.8706816386523;1440.4742269071785;1445.1401416157396;11047.502862371193;11469.59856405026;11539.769204615903;11547.538098476072;11549.942250288746;11234.269772464784;11335.546578136864;11648.708782280448;11673.941630291858;11680.824787628193;2260.706108205931;2397.113068164773;2474.3195621204177;2517.7356377580363;2527.835690680109;2361.5749165150264;2499.787518060967;2531.354504601324;2534.6324782906468;2536.2771610091127;2038.3782946201402;2163.8377121715935;2167.815735662463;2440.9145679901208;2522.7098883628432;2508.9623655645087;2536.3025168078743;2539.657146285265;2541.1741184115053;2542.1102614085016;2389.1637926725507;2426.0538390027905;2433.3186707721866;2435.391152211947;2481.6401621764744;11459.255148415326;11569.479373428216;11572.958433741003;11582.305061696325;11629.825552616718;6373.649449280288;6631.204255489324;6632.137822083147;6645.302243264484;6650.301718319603;3292.8024318540724;3934.547527034399;4184.121334519755;4230.5732356791095;4262.381954616594;1213.7329787446747;1250.6498180509473;1257.578711131772;1260.023481801602;1270.3203785974551;16098.517044488684;16199;16225.756613650805;16227.432039878666;16269.593260168473;4672.486026537078;5179.430262671103;5357.651752636144;5577.107662773937;5580.414056524088;12184.695382615328;12190.024798016137;12196;12209.206401584046;12212.084093693009;6508.60948343101;6509.641969691713;6516.055172000033;6533.477321814244;6537.378948999837;5798.82603521895;6089;6143.447089761943;6208.441240288395;6222.502199824004;4139.79301034947;4265.7440553566885;4284.121755040209;4318.373835793793;4354.8258069677495;1247.9438425270885;1268.847738271395;1279.3731071774948;1281.5706738242793;1287.060445874498;761.8895260187242;780.7345502529167;784.3685832904453;787.0673252196166;789.7078081110026;5414.592206026461;6054.727537260834;6083.178770865965;6132.448079672851;6220.968895155495;870.7866572689732;871.304028540667;883.9823203535893;926.416357694648;938.6292414496236;3816.5229346331707;4230.471191101113;4252.744835309892;4312.7627980461375;4318.222719910446;14324.283785810669;15097.773533397009;15100.622484437758;15124.924375378052;15146.697066058618;14701.191434471206;14840.10959342443;14885.21377434693;14901.92549037248;15253.771193431885;8012.959935200403;8088;8149.348052155813;8244.876326855101;8587.570621469027;6314.905276420765;6888.552244104118;6891.069705589681;6941.34057264559;7044.718211271696;10465.90108038645;13202.33988300562;13395.531156409479;13501.527446539716;13630.863691363356;13093.476260949836;13641.590752277447;13649.658758531115;13804.447822087004;13840.4463821446;8608.354373421893;9217.631294748404;9530.809383812146;9698;9707.369021013612;10951.233413661286;11032.172587056164;11040.558377665126;11049;11059.22585419013;8211.302039326723;8365.163483651586;8436.704715334934;8452.830943380975;8536.274416674652;8816.382853200472;8925.375223734536;8966.506842123485;9020.819583608421;9044.276457883221;8017.997750281214;8056.597170141352;8066.354691624539;8112.716054938049;8140.918590814016;5617.522510586646;6067.514598831995;6101.115338276014;6116.17431646722;6192.473639740492;6060.515158787198;6060.878782424239;6103.267607886904;6151.907721384272;6235.937640623717 18:33:42 INFO - PID 2710 | |4;prototype.html;386.30272358393097;390.5645205595762;394.50095629029266;399.78811230048075;402.629580785677;5753.7917037422085;5757.453041961012;5766.769329226826;5770.855728606787;5790.305163380395;4654.3716598259225;5222.686638801665;5621.0444224481835;5688.516476099537;5722.656640601567;4386.34204869269;4400.339949007643;4410.64714822815;4417.801198946056;4424.3142312941545;3509.3856825860134;3600.4239321708533;4409.492908315553;4445.777711114431;4460.910781784362;4069.0671930832914;4215.747055176684;4402.977985110086;4411.735295882258;4445.333200020007;3887.9502534315575;4165.791710414467;4336.696431249814;4410.889727756832;4442.511323754385;3880.0881792778673;3910.8240129194255;3934.15415685629;3936.2127574485216;3949.6840252779707;4600.757795395224;5058.570021548173;5363.414488957664;5485.396606373296;5619.578531610105;4606.746628935407;5034.86715489012;5278.683279003272;5302;5302.814401495968;5320.467953204688;6112;6139.78510752126;6148.569600127993;6198.566100372976;4299.462567179103;4420.381146639473;4461.152381047623;4462.219111655448;4470.077866517397;5505.871296384232;5617.157426386075;5805.564582656318;6527.3472652734345;6570.70431830564;4221.248182004434;4569.1547063793305;4590.793414296365;4596.172688916027;4614.400127983341;4643.048175124091;4652.278896771006;4668.229742092584;4672.322513235562;4679.298105284234;4102.261592913304;4542.6151270583205;4844.612431005511;5029.094762942632;5158.690478571298;6172.105044768484;6209.844753881189;6211.223597050369;6228.688565571703;6243.656598887014;1551.4569900534723;1560.7112684153467;1570.9607259818597;1600;1602.1828867277773;1088.7495875948418;1094.8467214589964;1105.9225854190129;1111.7825980551283;1122.2761318949395;5602.355729091125;5803.47768700819;5823.505002074787;5842.474177324061;5846.736896839652;5587.357453892854;5803.390643982321;6351.872962540723;6373.936260637427;6377.617342959437;4731.787069581877;5023.819402440424;5327.413984461706;5422;5694.572907031989;3029.106413607991;3281.360134773744;3317.369699757087;3343.3146205028397;3346.682065203802;5145.044882944457;5974.223350964434;6443.677816109176;6539.934600654028;6546.312637173029;5156.484351564814;6251.218597675291;6434.581752186093;6438.871222575523;6470.676466176767;6456.7740129095255;6466.612003279818;6490.221173459215;6493.220813502316;6505.772297969548;5965.582409231312;6288;6302.968485157545;6344.682765861596;6378.808635740936;6260.718267677874;6302.077866250837;6309.305976342507;6329.430351268408;6336.619802811846;5236.528712415901;5559.444055594408;6009.579329446897;6457.806265812033;6502.154719886385;3781.3193625147737;4464.799084041291;4518.570735780096;4541.818327266872;4550.203714350042;6086.72609732572;6334.746610135543;6367.52243581724;6384.329645387167;6395.616263024233;5346.492083252085;5572.944270557243;5634.7464364104535;5641.746121424628;6115.602485838406;5342.839714808562;5395.622306438511;6155.692215389123;6159.84600384994;6172.444479996642;5638.379778224474;5923.526117910643;6004.5796794225735;6019.608725432833;6042.546808989256;5087.338645976148;5804.651720896591;6113.938860611515;6137.158852344368;6153.846153846011;5389.841184145271;5623.943760562342;5750.913736294043;6060.6969651516365;6082.574219804571 18:33:42 INFO - PID 2710 | |5;yui.html;702.8805103132466;848.1518481518482;856.0412338181238;856.1609622569881;860.733172716458;13000.219986800787;17816.643667126667;18547.44357669272;18611.813881861177;18656.906715466404;16598.004119752793;17221.13894305286;17270.136493175323;17350.219240134193;17351.785375023723;656.2223764838658;831.580052073702;905.5562774240608;916.3860213656833;917.0874979395511;946;946.4084946908182;946.8769060022188;947.8720372749659;955.1212884146568;1016.7900198763448;1016.8779746430401;1017.4963393120415;1019.6176433837311;1025.707673313106;1001.4241810958691;1005.8642083318731;1007.2345017786462;1008.0927165551026;1019.23557332001;747.495440577608;751.2675141736818;752.3567349915797;754.4530215593705;760.2131793593604;15792.131432771193;15819.446319378767;15826.683466330724;16646.001239925645;17089.145542722938;13800.516981905686;16832;16833.831661683347;17061.82938170615;17205.9676419414;14009.229492377915;14068.08557443763;14176.93672974531;14462.710745785025;14665.633359165999;3610.891673249807;3618.583862855779;3625.1299688074673;3625.166211771281;3628.1836586767768;108189;133000;133602;136287;136585;11140.164487663458;11464.140189485823;11480.827787583194;12807;13236;24714;24770.380740481633;25122.74877251204;25491.87254063755;25492.74507254941;7725.92274077252;7737.8452430951065;7795.571243581658;7916.445848790529;7918.920810791818;3904.1996390739832;3963.2469830732643;3966.6231707987695;3967.960320396759;3972.781497017692;4212.515560710496;4270.9684473136185;4313.094250207491;4317.481902171697;4342.457192850894;827.8468483330603;929.2240978782792;932.4358762948455;956.9760755981156;958.755517343073;620.1163342237294;640.6092283706935;641.7689631732572;652.1619718661498;673.531895332739;10860;11728.472218750177;11972.281663100242;11979.221350612183;12115.273083615011;644.0564572900723;644.9742010319535;647.9416852483299;658.6410406328563;673.9789219332715;5293.655912365684;5533.474319939599;5536.2249285100115;5629.8592535186945;5685.6304340218585;81988;91526;91529.54235228915;92218.53890730503;94377;80743;82141;83637;84180;85704.71442928362;56649.71675141598;61450;77676;77728.61135694399;79513;8786.604602793017;11779.293242405483;12514.18657787241;12522.874771252535;12532.310722910328;31484.42577871143;37841.62158378381;40437.39343909785;41050.76847694574;41216.38175427431;60167.796644065995;72184;72252;72301;73056;15175.937684361988;15531.301091450687;15748.370065197263;15751.369945202063;15923.840761592699;17395.56511087233;17720.55698607545;17764.555886102433;17808.821911780717;17835.643287133924;13514.729705406033;16102;16248.837511625205;16278;16399.672006560042;14431.061980971203;15170.24148792578;15279.61800954985;15580.454684086002;15588.922055390105;15597.376104955674;15605;15640.92179539095;15807.525774226791;15821.604459888595;11584.247023943417;11789.174757766874;11924;12069.27584344942;12073.27560346382;11904.702382440508;12111.152219344562;12596.370181491073;12630.719279724348;12644.810327845275 18:33:42 INFO - PID 2710 | __end_tp_report 18:33:42 INFO - PID 2710 | __start_cc_report 18:33:42 INFO - PID 2710 | _x_x_mozilla_cycle_collect,1719 18:33:42 INFO - PID 2710 | __end_cc_report 18:33:42 INFO - PID 2710 | __startTimestamp1492652022810__endTimestamp 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | ------- Summary: start ------- 18:33:42 INFO - PID 2710 | Number of tests: 6 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#0] dojo.html Cycles:165 Average:5989.75 Median:6298.46 stddev:709.62 (11.3%) stddev-sans-first:683.46 18:33:42 INFO - PID 2710 | Values: 3459.0 3471.6 3478.4 3506.1 3525.3 5882.9 6032.9 6148.9 6171.6 6188.9 6097.8 6103.0 6149.7 6185.1 6207.5 5128.4 5299.1 5323.4 5331.3 5366.2 4438.3 5412.7 5447.2 5447.3 5496.5 5080.9 5142.5 5146.2 5295.1 5430.7 4346.6 4438.0 5338.0 5351.9 5418.9 5265.3 5309.4 5320.7 5347.3 5348.3 5941.3 6004.1 6113.1 6131.1 6148.2 6354.6 6396.7 6463.5 6484.1 6499.7 6559.5 6560.9 6567.4 6570.4 6580.6 6146.1 6218.5 6228.4 6246.3 6252.6 5402.5 6514.5 6546.7 6672.5 6759.3 6346.2 6347.2 6364.9 6371.5 6385.5 6391.6 6482.8 6484.2 6493.1 6495.7 6307.6 6393.3 6397.4 6404.7 6426.7 6394.5 6420.2 6421.7 6437.0 6444.8 5244.5 5245.0 5254.9 5288.6 5294.2 4654.7 4674.0 4674.2 4704.9 4730.5 5983.1 6001.0 6145.6 6148.0 6180.7 6500.3 6568.1 6573.9 6578.1 6583.4 6175.4 6177.8 6178.5 6190.1 6190.2 5506.2 5657.5 5666.4 5742.2 5767.4 6646.8 6685.2 6697.4 6699.8 6710.4 6304.4 6348.4 6355.8 6466.9 6533.1 6679.9 6760.1 6767.4 6767.8 6784.8 6428.7 6428.9 6437.9 6441.5 6452.5 5412.4 6355.3 6404.8 6495.2 6543.2 6335.4 6337.8 6353.1 6358.1 6364.9 6195.3 6342.4 6344.4 6348.7 6355.6 6134.6 6240.2 6252.5 6298.5 6341.7 6293.5 6326.3 6352.1 6377.0 6379.3 6311.5 6312.5 6330.0 6340.7 6341.7 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#1] ext.html Cycles:180 Average:43698.02 Median:3162.43 stddev:76595.57 (2422.0%) stddev-sans-first:76742.68 18:33:42 INFO - PID 2710 | Values: 786.3 1101.3 1119.0 1127.0 1253.7 35613.8 36521.3 36600.1 36633.6 36665.3 37429.8 37824.2 37873.6 37888.8 37927.4 5993.8 6157.3 7797.1 7937.1 7938.0 6062.2 6145.2 6151.1 8300.6 8502.4 8534.3 8586.8 8593.2 8597.8 8600.9 8494.5 8554.4 8563.4 8564.1 8568.9 5962.4 6115.6 8235.5 8340.1 8436.5 37340.3 37648.4 37718.0 37728.8 37794.0 3560.1 3602.4 3606.2 3607.4 3615.3 30548.5 30561.9 30593.8 30606.0 30644.8 3106.0 3108.4 3162.3 3162.6 3163.9 280616.0 289544.0 290165.0 290330.5 291001.0 39536.8 39658.8 48393.5 48425.8 48466.8 41481.2 47847.0 47941.5 48033.0 48299.3 13414.6 13519.4 13535.4 13549.9 13554.6 10984.6 10990.2 10993.5 10998.5 11033.9 12291.0 12291.4 12332.8 12370.6 12423.3 5040.3 5169.2 5554.4 5575.8 5583.3 848.4 857.5 993.8 1019.0 1031.0 24165.5 24301.5 24329.1 24333.8 24375.4 1084.3 1096.5 1131.7 1137.1 1137.7 11863.9 12440.7 12446.8 12463.4 12508.6 307947.0 308176.0 308595.7 309421.9 309467.0 52503.7 52579.7 52602.0 52679.0 52724.9 1488.4 1534.0 1541.8 1543.7 1555.9 19793.6 23808.9 23958.8 24044.8 24105.4 37584.8 37628.6 37630.4 37654.9 37662.0 277527.6 277731.0 278109.0 278622.2 279249.6 29390.9 29619.9 33847.7 33914.7 33931.2 42702.4 43409.3 43438.8 43439.7 43577.0 28423.4 29028.1 29480.9 30296.5 30506.5 23342.9 23921.0 24614.5 30119.0 30536.5 36315.1 36432.0 36434.8 36531.0 36874.6 25315.7 29965.0 30965.5 31121.7 31317.4 22642.4 23667.2 23674.8 23800.5 24082.6 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#2] jquery.html Cycles:180 Average:35244.72 Median:2319.48 stddev:90823.79 (3915.7%) stddev-sans-first:91040.87 18:33:42 INFO - PID 2710 | Values: 387.9 424.2 427.8 431.2 433.1 19153.9 20139.3 20872.4 20897.5 20972.2 21389.3 21542.8 22211.0 22496.3 22624.9 5897.4 6211.2 6219.7 6221.5 6225.7 5836.0 5840.3 6029.1 6169.8 6178.2 6131.9 6135.6 6140.2 6142.9 6158.7 5822.6 6017.9 6105.9 6115.1 6123.0 5416.6 5418.0 5428.2 5428.3 6413.2 28357.6 28399.6 28567.1 29020.6 29349.4 2254.1 2314.4 2315.7 2316.7 2322.3 17917.3 18101.3 18721.3 18894.9 18994.5 2014.8 2071.6 2131.6 2252.2 2258.8 121158.2 139370.3 148004.3 150706.0 151799.0 17582.6 21273.6 22242.0 22532.9 22597.6 39299.8 39362.6 39372.4 39509.0 39554.6 8626.5 8687.3 8975.0 9081.0 10744.6 8348.4 8363.8 8485.3 8508.5 8540.5 7549.4 7686.3 7691.3 7705.2 7716.7 2106.6 2107.8 2111.1 2113.2 2250.1 890.4 909.4 924.8 942.7 966.2 17352.5 20093.7 20132.8 20663.0 20694.6 866.9 998.7 1005.4 1007.9 1018.8 9475.4 10707.8 10790.1 10834.9 10879.1 526015.0 534496.0 536487.0 549155.3 551604.0 83383.0 119727.1 123448.0 128280.4 129215.0 1093.9 1148.0 1188.1 1193.6 1196.5 20718.5 20908.9 20997.7 21220.2 21255.9 54803.5 54968.5 55871.7 56451.0 56887.4 48075.8 73937.6 76664.6 76997.5 77612.8 8172.5 8476.3 8526.0 8925.2 8928.7 10671.7 13488.3 13668.7 13728.0 13907.4 11455.8 11764.5 11909.8 11936.8 12371.7 10538.4 10738.8 10763.9 11083.7 11089.3 10213.8 10831.6 11777.4 11792.9 12042.2 8674.2 8713.3 8773.3 8896.9 9053.0 11827.8 12034.7 12113.6 12213.8 12217.7 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#3] mootools.html Cycles:180 Average:7024.91 Median:3554.66 stddev:4477.52 (126.0%) stddev-sans-first:4469.85 18:33:42 INFO - PID 2710 | Values: 1360.5 1417.8 1436.9 1440.5 1445.1 11047.5 11469.6 11539.8 11547.5 11549.9 11234.3 11335.5 11648.7 11673.9 11680.8 2260.7 2397.1 2474.3 2517.7 2527.8 2361.6 2499.8 2531.4 2534.6 2536.3 2038.4 2163.8 2167.8 2440.9 2522.7 2509.0 2536.3 2539.7 2541.2 2542.1 2389.2 2426.1 2433.3 2435.4 2481.6 11459.3 11569.5 11573.0 11582.3 11629.8 6373.6 6631.2 6632.1 6645.3 6650.3 3292.8 3934.5 4184.1 4230.6 4262.4 1213.7 1250.6 1257.6 1260.0 1270.3 16098.5 16199.0 16225.8 16227.4 16269.6 4672.5 5179.4 5357.7 5577.1 5580.4 12184.7 12190.0 12196.0 12209.2 12212.1 6508.6 6509.6 6516.1 6533.5 6537.4 5798.8 6089.0 6143.4 6208.4 6222.5 4139.8 4265.7 4284.1 4318.4 4354.8 1247.9 1268.8 1279.4 1281.6 1287.1 761.9 780.7 784.4 787.1 789.7 5414.6 6054.7 6083.2 6132.4 6221.0 870.8 871.3 884.0 926.4 938.6 3816.5 4230.5 4252.7 4312.8 4318.2 14324.3 15097.8 15100.6 15124.9 15146.7 14701.2 14840.1 14885.2 14901.9 15253.8 8013.0 8088.0 8149.3 8244.9 8587.6 6314.9 6888.6 6891.1 6941.3 7044.7 10465.9 13202.3 13395.5 13501.5 13630.9 13093.5 13641.6 13649.7 13804.4 13840.4 8608.4 9217.6 9530.8 9698.0 9707.4 10951.2 11032.2 11040.6 11049.0 11059.2 8211.3 8365.2 8436.7 8452.8 8536.3 8816.4 8925.4 8966.5 9020.8 9044.3 8018.0 8056.6 8066.4 8112.7 8140.9 5617.5 6067.5 6101.1 6116.2 6192.5 6060.5 6060.9 6103.3 6151.9 6235.9 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#4] prototype.html Cycles:180 Average:4986.02 Median:5392.73 stddev:1481.58 (27.5%) stddev-sans-first:1444.95 18:33:42 INFO - PID 2710 | Values: 386.3 390.6 394.5 399.8 402.6 5753.8 5757.5 5766.8 5770.9 5790.3 4654.4 5222.7 5621.0 5688.5 5722.7 4386.3 4400.3 4410.6 4417.8 4424.3 3509.4 3600.4 4409.5 4445.8 4460.9 4069.1 4215.7 4403.0 4411.7 4445.3 3888.0 4165.8 4336.7 4410.9 4442.5 3880.1 3910.8 3934.2 3936.2 3949.7 4600.8 5058.6 5363.4 5485.4 5619.6 4606.7 5034.9 5278.7 5302.0 5302.8 5320.5 6112.0 6139.8 6148.6 6198.6 4299.5 4420.4 4461.2 4462.2 4470.1 5505.9 5617.2 5805.6 6527.3 6570.7 4221.2 4569.2 4590.8 4596.2 4614.4 4643.0 4652.3 4668.2 4672.3 4679.3 4102.3 4542.6 4844.6 5029.1 5158.7 6172.1 6209.8 6211.2 6228.7 6243.7 1551.5 1560.7 1571.0 1600.0 1602.2 1088.7 1094.8 1105.9 1111.8 1122.3 5602.4 5803.5 5823.5 5842.5 5846.7 5587.4 5803.4 6351.9 6373.9 6377.6 4731.8 5023.8 5327.4 5422.0 5694.6 3029.1 3281.4 3317.4 3343.3 3346.7 5145.0 5974.2 6443.7 6539.9 6546.3 5156.5 6251.2 6434.6 6438.9 6470.7 6456.8 6466.6 6490.2 6493.2 6505.8 5965.6 6288.0 6303.0 6344.7 6378.8 6260.7 6302.1 6309.3 6329.4 6336.6 5236.5 5559.4 6009.6 6457.8 6502.2 3781.3 4464.8 4518.6 4541.8 4550.2 6086.7 6334.7 6367.5 6384.3 6395.6 5346.5 5572.9 5634.7 5641.7 6115.6 5342.8 5395.6 6155.7 6159.8 6172.4 5638.4 5923.5 6004.6 6019.6 6042.5 5087.3 5804.7 6113.9 6137.2 6153.8 5389.8 5623.9 5750.9 6060.7 6082.6 18:33:42 INFO - PID 2710 | 18:33:42 INFO - PID 2710 | [#5] yui.html Cycles:180 Average:21140.02 Median:24946.56 stddev:29673.26 (118.9%) stddev-sans-first:29716.82 18:33:42 INFO - PID 2710 | Values: 702.9 848.2 856.0 856.2 860.7 13000.2 17816.6 18547.4 18611.8 18656.9 16598.0 17221.1 17270.1 17350.2 17351.8 656.2 831.6 905.6 916.4 917.1 946.0 946.4 946.9 947.9 955.1 1016.8 1016.9 1017.5 1019.6 1025.7 1001.4 1005.9 1007.2 1008.1 1019.2 747.5 751.3 752.4 754.5 760.2 15792.1 15819.4 15826.7 16646.0 17089.1 13800.5 16832.0 16833.8 17061.8 17206.0 14009.2 14068.1 14176.9 14462.7 14665.6 3610.9 3618.6 3625.1 3625.2 3628.2 108189.0 133000.0 133602.0 136287.0 136585.0 11140.2 11464.1 11480.8 12807.0 13236.0 24714.0 24770.4 25122.7 25491.9 25492.7 7725.9 7737.8 7795.6 7916.4 7918.9 3904.2 3963.2 3966.6 3968.0 3972.8 4212.5 4271.0 4313.1 4317.5 4342.5 827.8 929.2 932.4 957.0 958.8 620.1 640.6 641.8 652.2 673.5 10860.0 11728.5 11972.3 11979.2 12115.3 644.1 645.0 647.9 658.6 674.0 5293.7 5533.5 5536.2 5629.9 5685.6 81988.0 91526.0 91529.5 92218.5 94377.0 80743.0 82141.0 83637.0 84180.0 85704.7 56649.7 61450.0 77676.0 77728.6 79513.0 8786.6 11779.3 12514.2 12522.9 12532.3 31484.4 37841.6 40437.4 41050.8 41216.4 60167.8 72184.0 72252.0 72301.0 73056.0 15175.9 15531.3 15748.4 15751.4 15923.8 17395.6 17720.6 17764.6 17808.8 17835.6 13514.7 16102.0 16248.8 16278.0 16399.7 14431.1 15170.2 15279.6 15580.5 15588.9 15597.4 15605.0 15640.9 15807.5 15821.6 11584.2 11789.2 11924.0 12069.3 12073.3 11904.7 12111.2 12596.4 12630.7 12644.8 18:33:42 INFO - PID 2710 | -------- Summary: end -------- 18:33:42 INFO - PID 2710 | 18:33:43 INFO - TEST-INFO | 2710: exit 0 18:33:43 INFO - TEST-OK | dromaeo_css | took 1120086ms 18:33:43 INFO - TEST-START | kraken 18:33:43 INFO - operating with platform_type : mac_ 18:33:43 INFO - Initialising browser for kraken test... 18:33:43 INFO - TEST-INFO | started process 2714 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmp3s_JTw/profile http://localhost:64288/getInfo.html) 18:33:45 INFO - PID 2714 | __metrics Screen width/height:1600/1200 18:33:45 INFO - PID 2714 | colorDepth:24 18:33:45 INFO - PID 2714 | Browser inner width/height: 1024/689 18:33:45 INFO - PID 2714 | __metrics 18:33:46 INFO - PID 2714 | 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" 18:33:46 INFO - PID 2714 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:33:47 INFO - TEST-INFO | 2714: exit 0 18:33:47 INFO - Browser initialized. 18:33:47 INFO - Running cycle 1/1 for kraken test... 18:33:47 INFO - TEST-INFO | started process 2715 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmp3s_JTw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:59 INFO - PID 2715 | RSS: Main: 241537024 18:33:59 INFO - PID 2715 | 18:34:02 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64288/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:03 INFO - PID 2715 | RSS: Main: 313257984 18:34:03 INFO - PID 2715 | 18:34:05 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64288/tests/kraken/driver.html?testName=audio-dft) 18:34:06 INFO - PID 2715 | RSS: Main: 294064128 18:34:06 INFO - PID 2715 | 18:34:09 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64288/tests/kraken/driver.html?testName=audio-fft) 18:34:10 INFO - PID 2715 | RSS: Main: 384188416 18:34:10 INFO - PID 2715 | 18:34:12 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64288/tests/kraken/driver.html?testName=audio-oscillator) 18:34:13 INFO - PID 2715 | RSS: Main: 288956416 18:34:13 INFO - PID 2715 | 18:34:15 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64288/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:15 INFO - PID 2715 | RSS: Main: 291328000 18:34:15 INFO - PID 2715 | 18:34:18 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64288/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:19 INFO - PID 2715 | RSS: Main: 355106816 18:34:19 INFO - PID 2715 | 18:34:22 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64288/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:22 INFO - PID 2715 | RSS: Main: 374194176 18:34:22 INFO - PID 2715 | 18:34:25 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64288/tests/kraken/driver.html?testName=json-parse-financial) 18:34:26 INFO - PID 2715 | RSS: Main: 383012864 18:34:26 INFO - PID 2715 | 18:34:27 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64288/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:28 INFO - PID 2715 | RSS: Main: 333426688 18:34:28 INFO - PID 2715 | 18:34:30 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:30 INFO - PID 2715 | RSS: Main: 333930496 18:34:30 INFO - PID 2715 | 18:34:32 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:33 INFO - PID 2715 | RSS: Main: 336097280 18:34:33 INFO - PID 2715 | 18:34:36 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:37 INFO - PID 2715 | RSS: Main: 333475840 18:34:37 INFO - PID 2715 | 18:34:40 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:40 INFO - PID 2715 | RSS: Main: 342360064 18:34:40 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | Cycle 1(1): loaded http://localhost:64288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64288/tests/kraken/driver.html?testName=ai-astar) 18:34:42 INFO - PID 2715 | RSS: Main: 384884736 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | __start_tp_report 18:34:42 INFO - PID 2715 | _x_x_mozilla_page_load 18:34:42 INFO - PID 2715 | _x_x_mozilla_page_load_details 18:34:42 INFO - PID 2715 | |i|pagename|runs| 18:34:42 INFO - PID 2715 | |0;ai-astar;108;109;110;110;112;92;157;165;166;111 18:34:42 INFO - PID 2715 | |1;audio-beat-detection;144;119;137;186;121;110;106;107;137;109 18:34:42 INFO - PID 2715 | |2;audio-dft;183;185;187;189;182;185;185;181;233;262 18:34:42 INFO - PID 2715 | |3;audio-fft;78;59;59;93;67;56;62;63;81;59 18:34:42 INFO - PID 2715 | |4;audio-oscillator;94;68;87;69;68;69;90;68;70;90 18:34:42 INFO - PID 2715 | |5;imaging-gaussian-blur;88;88;88;89;89;87;89;89;88;89 18:34:42 INFO - PID 2715 | |6;imaging-darkroom;96;96;95;94;94;95;95;94;94;94 18:34:42 INFO - PID 2715 | |7;imaging-desaturate;83;84;84;86;85;84;83;83;84;84 18:34:42 INFO - PID 2715 | |8;json-parse-financial;50;49;47;47;49;48;48;49;49;49 18:34:42 INFO - PID 2715 | |9;json-stringify-tinderbox;55;64;58;69;65;61;58;57;59;58 18:34:42 INFO - PID 2715 | |10;stanford-crypto-aes;83;84;82;83;83;84;96;84;83;82 18:34:42 INFO - PID 2715 | |11;stanford-crypto-ccm;138;145;135;134;137;136;147;140;176;161 18:34:42 INFO - PID 2715 | |12;stanford-crypto-pbkdf2;185;184;183;184;181;186;184;185;164;184 18:34:42 INFO - PID 2715 | |13;stanford-crypto-sha256-iterative;69;68;70;87;91;93;70;69;67;68 18:34:42 INFO - PID 2715 | __end_tp_report 18:34:42 INFO - PID 2715 | __start_cc_report 18:34:42 INFO - PID 2715 | _x_x_mozilla_cycle_collect,2182 18:34:42 INFO - PID 2715 | __end_cc_report 18:34:42 INFO - PID 2715 | __startTimestamp1492652082884__endTimestamp 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | ------- Summary: start ------- 18:34:42 INFO - PID 2715 | Number of tests: 14 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#0] ai-astar Cycles:10 Average:124.00 Median:134.50 stddev:27.37 (20.4%) stddev-sans-first:28.42 18:34:42 INFO - PID 2715 | Values: 108.0 109.0 110.0 110.0 112.0 92.0 157.0 165.0 166.0 111.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:129.00 stddev:24.77 (19.2%) stddev-sans-first:25.55 18:34:42 INFO - PID 2715 | Values: 144.0 119.0 137.0 186.0 121.0 110.0 106.0 107.0 137.0 109.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#2] audio-dft Cycles:10 Average:197.20 Median:186.00 stddev:27.47 (14.8%) stddev-sans-first:28.66 18:34:42 INFO - PID 2715 | Values: 183.0 185.0 187.0 189.0 182.0 185.0 185.0 181.0 233.0 262.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#3] audio-fft Cycles:10 Average:67.70 Median:65.00 stddev:12.21 (18.8%) stddev-sans-first:12.37 18:34:42 INFO - PID 2715 | Values: 78.0 59.0 59.0 93.0 67.0 56.0 62.0 63.0 81.0 59.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.50 stddev:11.28 (14.4%) stddev-sans-first:10.22 18:34:42 INFO - PID 2715 | Values: 94.0 68.0 87.0 69.0 68.0 69.0 90.0 68.0 70.0 90.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 18:34:42 INFO - PID 2715 | Values: 88.0 88.0 88.0 89.0 89.0 87.0 89.0 89.0 88.0 89.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 18:34:42 INFO - PID 2715 | Values: 96.0 96.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:0.93 18:34:42 INFO - PID 2715 | Values: 83.0 84.0 84.0 86.0 85.0 84.0 83.0 83.0 84.0 84.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#8] json-parse-financial Cycles:10 Average:48.50 Median:49.00 stddev:0.97 (2.0%) stddev-sans-first:0.87 18:34:42 INFO - PID 2715 | Values: 50.0 49.0 47.0 47.0 49.0 48.0 48.0 49.0 49.0 49.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:4.33 (7.2%) stddev-sans-first:4.12 18:34:42 INFO - PID 2715 | Values: 55.0 64.0 58.0 69.0 65.0 61.0 58.0 57.0 59.0 58.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:4.14 (5.0%) stddev-sans-first:4.36 18:34:42 INFO - PID 2715 | Values: 83.0 84.0 82.0 83.0 83.0 84.0 96.0 84.0 83.0 82.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:142.50 stddev:13.58 (9.5%) stddev-sans-first:14.18 18:34:42 INFO - PID 2715 | Values: 138.0 145.0 135.0 134.0 137.0 136.0 147.0 140.0 176.0 161.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:184.00 stddev:6.46 (3.5%) stddev-sans-first:6.76 18:34:42 INFO - PID 2715 | Values: 185.0 184.0 183.0 184.0 181.0 186.0 184.0 185.0 164.0 184.0 18:34:42 INFO - PID 2715 | 18:34:42 INFO - PID 2715 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:70.00 stddev:10.58 (15.1%) stddev-sans-first:10.98 18:34:42 INFO - PID 2715 | Values: 69.0 68.0 70.0 87.0 91.0 93.0 70.0 69.0 67.0 68.0 18:34:42 INFO - PID 2715 | -------- Summary: end -------- 18:34:42 INFO - PID 2715 | 18:34:43 INFO - TEST-INFO | 2715: exit 0 18:34:43 INFO - TEST-OK | kraken | took 60467ms 18:34:43 INFO - SUITE-END | took 1180s 18:34:44 INFO - Completed test suite (00:19:40) 18:34:44 INFO - javascript benchmark 18:34:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7722.867935170647, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3459.031471188065, 3471.5660542432197, 3478.391281525733, 3506.088417011577, 3525.294941011798, 5882.852928676785, 6032.939670603293, 6148.938510614898, 6171.598846075008, 6188.876222475547, 6097.847553811146, 6103.034636161429, 6149.661768602725, 6185.103160041791, 6207.5033997280225, 5128.358955130609, 5299.125644268691, 5323.387810401795, 5331.333583302077, 5366.195070739382, 4438.289873620221, 5412.729363531827, 5447.237386765856, 5447.264619276387, 5496.477834605706, 5080.949190508103, 5142.537171654531, 5146.228065790124, 5295.126304159809, 5430.70131142787, 4346.565343465628, 4437.955620443787, 5338.039152952467, 5351.892962140735, 5418.864528386822, 5265.341832270966, 5309.4425085365865, 5320.654157479751, 5347.331583552056, 5348.304720386363, 5941.316748573883, 6004.129401236796, 6113.144159817629, 6131.14164017038, 6148.169997050386, 6354.5551811373225, 6396.712147953353, 6463.48292136628, 6484.124643173159, 6499.675016249169, 6559.540832141752, 6560.901586476207, 6567.376099270562, 6570.408663220285, 6580.5722628029025, 6146.139540464339, 6218.471429928416, 6228.408301211332, 6246.312905580383, 6252.5935814171935, 5402.459754024645, 6514.5114116441455, 6546.738130474823, 6672.466202703772, 6759.256481787098, 6346.174997250328, 6347.206599175103, 6364.904526432108, 6371.458426033839, 6385.5210859185745, 6391.616503009835, 6482.773102941374, 6484.221893372825, 6493.123428337209, 6495.7076931538195, 6307.590006649553, 6393.264774550893, 6397.360263973565, 6404.71178796946, 6426.678666066678, 6394.488440924715, 6420.165378500765, 6421.711023003976, 6437.034444833313, 6444.7744328948265, 5244.5279924806955, 5245.029669511153, 5254.868628284324, 5288.629795914249, 5294.205869119586, 4654.743989080633, 4673.976630116896, 4674.182018146811, 4704.88237794051, 4730.5032971538185, 5983.132445795335, 6001.0, 6145.631262124287, 6148.016317389286, 6180.721867515973, 6500.284968653445, 6568.113304703899, 6573.868522629521, 6578.111954886125, 6583.374579414948, 6175.44421002103, 6177.752889884354, 6178.474829639531, 6190.102435146879, 6190.1952746142715, 5506.229127922094, 5657.51911087554, 5666.433356664383, 5742.196092547047, 5767.394423585547, 6646.8005959821285, 6685.197776266685, 6697.397234248941, 6699.799006029827, 6710.429613482908, 6304.401081897213, 6348.365163483615, 6355.809325720235, 6466.870662586816, 6533.052707357501, 6679.899801503078, 6760.053592497153, 6767.35710107539, 6767.830804229934, 6784.830379240361, 6428.710708018244, 6428.874946884183, 6437.935620643921, 6441.452476539546, 6452.516061295515, 5412.350517937873, 6355.3326900674765, 6404.775832845828, 6495.220573531206, 6543.214814222324, 6335.3981371769605, 6337.809865704036, 6353.14232578596, 6358.109864618865, 6364.936350636434, 6195.349488303844, 6342.39747224013, 6344.429001389897, 6348.714307856065, 6355.618662880242, 6134.631922084689, 6240.157578726996, 6252.531060170414, 6298.464630506275, 6341.651209183539, 6293.496520278458, 6326.335734747785, 6352.142460767922, 6377.0, 6379.298277189599, 6311.463525600376, 6312.495000399865, 6330.0, 6340.7463701451425, 6341.682915854281], "value": 5942.811970409382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.256987147146, 1101.2896681640339, 1119.0320372877463, 1127.0194930410544, 1253.7241806802501, 35613.82193089034, 36521.26957460852, 36600.08499787506, 36633.63366366336, 36665.26669466613, 37429.81285093578, 37824.24351512968, 37873.621263787354, 37888.81055594718, 37927.431088533696, 5993.760249590033, 6157.261128664567, 7797.103333116694, 7937.087234967995, 7938.0, 6062.181605483247, 6145.170401995718, 6151.077338399231, 8300.626471808782, 8502.404831661786, 8534.274586660142, 8586.828263434727, 8593.183647553504, 8597.785055373603, 8600.913990860105, 8494.490330580154, 8554.35842311829, 8563.400561960665, 8564.100769419183, 8568.914310856873, 5962.3739507351975, 6115.602485838406, 8235.50586964784, 8340.124286949904, 8436.493810371398, 37340.253194936224, 37648.43527347092, 37718.0, 37728.81135594332, 37794.0, 3560.127768696686, 3602.35157671619, 3606.1886075633033, 3607.4228123500375, 3615.276944611062, 30548.54177187344, 30561.930332438256, 30593.847030764704, 30606.0, 30644.84677576598, 3106.0371284901753, 3108.4249413858283, 3162.288485090859, 3162.5730526378875, 3163.920901977469, 280616.0, 289544.0, 290165.0, 290330.548347259, 291001.0, 39536.802315988236, 39658.80170599128, 48393.54819355425, 48425.7893552664, 48466.757666211444, 41481.1703765923, 47847.0, 47941.5205847937, 48033.03933921302, 48299.27551086737, 13414.597562073153, 13519.391627376792, 13535.39090740919, 13549.932250338685, 13554.59336219915, 10984.615538456115, 10990.230683852213, 10993.505292261867, 10998.450077496253, 11033.889661103447, 12291.0, 12291.446884890202, 12332.81500777489, 12370.6288811334, 12423.254604723745, 5040.269160971712, 5169.198774190007, 5554.444555544494, 5575.804846830422, 5583.274174357308, 848.3806821020567, 857.498363457372, 993.7764003099389, 1018.9949050254701, 1030.994845025785, 24165.51668966646, 24301.513969720505, 24329.148479803123, 24333.75666243315, 24375.390615234763, 1084.2952081147316, 1096.4956120184645, 1131.6718151735904, 1137.0903277378065, 1137.732632831284, 11863.940680296715, 12440.688982775322, 12446.81329780054, 12463.37976063106, 12508.562200322946, 307947.0, 308176.0, 308595.7421277365, 309421.9057809393, 309467.0, 52503.687407814345, 52579.73710131425, 52602.0, 52679.0, 52724.945501089765, 1488.3525666334963, 1533.9769903451681, 1541.7841502189704, 1543.6526781474079, 1555.922203889801, 19793.60412791765, 23808.880955595112, 23958.76041239565, 24044.75955240425, 24105.397365066012, 37584.812075939444, 37628.623713763605, 37630.43554346632, 37654.870353889426, 37662.0, 277527.6123619369, 277731.0, 278109.0, 278622.2137778677, 279249.6037519881, 29390.853045734635, 29619.85190074036, 33847.66152338445, 33914.66085339214, 33931.15172120717, 42702.359464607434, 43409.34885976776, 43438.782806085765, 43439.701243320174, 43577.0, 28423.43153136967, 29028.12915612535, 29480.852595737742, 30296.54555181718, 30506.542401864433, 23342.883285583463, 23921.0, 24614.50770984606, 30119.0, 30536.541951871182, 36315.09212269714, 36432.0, 36434.8178259107, 36531.0, 36874.631253688196, 25315.74684253134, 29965.0, 30965.535516967713, 31121.68878311188, 31317.373652527276, 22642.43393915165, 23667.171648992204, 23674.763252367255, 23800.523989519766, 24082.638760418256], "value": 16929.086998397164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.89720724008123, 424.19826527862324, 427.83100675233305, 431.2496256513666, 433.0753840550425, 19153.904230478845, 20139.29512467064, 20872.37382878516, 20897.477563060893, 20972.16111355546, 21389.251376201835, 21542.784572154273, 22211.0, 22496.32511024668, 22624.8868755656, 5897.351291357958, 6211.192544969148, 6219.720112594921, 6221.471174950135, 6225.688715564226, 5836.0, 5840.328362238333, 6029.1257767623665, 6169.784057557987, 6178.19683441152, 6131.8773624527485, 6135.570510064297, 6140.232470941132, 6142.907856382158, 6158.6920653967345, 5822.563307751914, 6017.939820601804, 6105.938940610581, 6115.052166914135, 6123.020316749344, 5416.620836541444, 5417.972910135424, 5428.212909128194, 5428.348598168205, 6413.198350206224, 28357.574636380472, 28399.57400638992, 28567.142985710256, 29020.564691529646, 29349.41301173986, 2254.0645632062674, 2314.351981445198, 2315.710536182977, 2316.6756654068445, 2322.2800931711054, 17917.283308667636, 18101.275948962026, 18721.344752933583, 18894.905525472423, 18994.52513687155, 2014.7810574602302, 2071.554615757604, 2131.6056529542084, 2252.155441709359, 2258.7741225877444, 121158.18262726066, 139370.3031484846, 148004.2599787005, 150706.0, 151799.0, 17582.648347032988, 21273.574528509344, 22241.999110040088, 22532.887335563217, 22597.638873616866, 39299.80350098288, 39362.60637393647, 39372.40941385881, 39509.0, 39554.62672239617, 8626.482411055356, 8687.305015598737, 8975.0, 9081.0, 10744.570217191225, 8348.373871959628, 8363.818090954523, 8485.321174306164, 8508.532030738244, 8540.53027083504, 7549.3583045440655, 7686.269804368688, 7691.269329413697, 7705.190954949761, 7716.6527506262355, 2106.5892151030707, 2107.7681455039933, 2111.0711287033964, 2113.2286715348787, 2250.1449449209194, 890.3678388344218, 909.4406939732017, 924.7641851327868, 942.7360339105062, 966.246327864267, 17352.47942561725, 20093.74424060664, 20132.798672013385, 20662.96685165736, 20694.586108277752, 866.8947092457167, 998.6654470752234, 1005.4269066631949, 1007.9344842585355, 1018.8267994440963, 9475.384100033614, 10707.839382409271, 10790.136789056856, 10834.855826515106, 10879.075278601249, 526015.0, 534496.0, 536487.0, 549155.2542237263, 551604.0, 83383.0, 119727.07565358876, 123448.0, 128280.35859820641, 129215.0, 1093.9260677496102, 1147.9598214062469, 1188.1247381337184, 1193.5524178433088, 1196.5034510678145, 20718.505412780687, 20908.895455523234, 20997.685034724796, 21220.151193952068, 21255.893720531298, 54803.45196548143, 54968.45031549793, 55871.72064139653, 56451.0, 56887.431125688214, 48075.75962120167, 73937.6303118481, 76664.61667691625, 76997.46005079756, 77612.8358074618, 8172.468789528662, 8476.32189424857, 8525.989177629881, 8925.241354484942, 8928.73213803587, 10671.679849604525, 13488.32558372097, 13668.726625467634, 13728.0, 13907.443702252202, 11455.792378133803, 11764.470598822902, 11909.761804763682, 11936.761264774483, 12371.69070773202, 10538.420386878795, 10738.785224295627, 10763.94618026905, 11083.667489975314, 11089.33463992163, 10213.846792297973, 10831.566737330733, 11777.411129443664, 11792.882071179178, 12042.21725587834, 8674.175953284428, 8713.30293576525, 8773.254273386834, 8896.909226331936, 9052.954735226282, 11827.82258266144, 12034.699132521757, 12113.636590902288, 12213.816792748294, 12217.69455763613], "value": 10711.561306942174, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.4830164537475, 1417.8369487508933, 1436.8706816386523, 1440.4742269071785, 1445.1401416157396, 11047.502862371193, 11469.59856405026, 11539.769204615903, 11547.538098476072, 11549.942250288746, 11234.269772464784, 11335.546578136864, 11648.708782280448, 11673.941630291858, 11680.824787628193, 2260.706108205931, 2397.113068164773, 2474.3195621204177, 2517.7356377580363, 2527.835690680109, 2361.5749165150264, 2499.787518060967, 2531.354504601324, 2534.6324782906468, 2536.2771610091127, 2038.3782946201402, 2163.8377121715935, 2167.815735662463, 2440.9145679901208, 2522.7098883628432, 2508.9623655645087, 2536.3025168078743, 2539.657146285265, 2541.1741184115053, 2542.1102614085016, 2389.1637926725507, 2426.0538390027905, 2433.3186707721866, 2435.391152211947, 2481.6401621764744, 11459.255148415326, 11569.479373428216, 11572.958433741003, 11582.305061696325, 11629.825552616718, 6373.649449280288, 6631.204255489324, 6632.137822083147, 6645.302243264484, 6650.301718319603, 3292.8024318540724, 3934.547527034399, 4184.121334519755, 4230.5732356791095, 4262.381954616594, 1213.7329787446747, 1250.6498180509473, 1257.578711131772, 1260.023481801602, 1270.3203785974551, 16098.517044488684, 16199.0, 16225.756613650805, 16227.432039878666, 16269.593260168473, 4672.486026537078, 5179.430262671103, 5357.651752636144, 5577.107662773937, 5580.414056524088, 12184.695382615328, 12190.024798016137, 12196.0, 12209.206401584046, 12212.084093693009, 6508.60948343101, 6509.641969691713, 6516.055172000033, 6533.477321814244, 6537.378948999837, 5798.82603521895, 6089.0, 6143.447089761943, 6208.441240288395, 6222.502199824004, 4139.79301034947, 4265.7440553566885, 4284.121755040209, 4318.373835793793, 4354.8258069677495, 1247.9438425270885, 1268.847738271395, 1279.3731071774948, 1281.5706738242793, 1287.060445874498, 761.8895260187242, 780.7345502529167, 784.3685832904453, 787.0673252196166, 789.7078081110026, 5414.592206026461, 6054.727537260834, 6083.178770865965, 6132.448079672851, 6220.968895155495, 870.7866572689732, 871.304028540667, 883.9823203535893, 926.416357694648, 938.6292414496236, 3816.5229346331707, 4230.471191101113, 4252.744835309892, 4312.7627980461375, 4318.222719910446, 14324.283785810669, 15097.773533397009, 15100.622484437758, 15124.924375378052, 15146.697066058618, 14701.191434471206, 14840.10959342443, 14885.21377434693, 14901.92549037248, 15253.771193431885, 8012.959935200403, 8088.0, 8149.348052155813, 8244.876326855101, 8587.570621469027, 6314.905276420765, 6888.552244104118, 6891.069705589681, 6941.34057264559, 7044.718211271696, 10465.90108038645, 13202.33988300562, 13395.531156409479, 13501.527446539716, 13630.863691363356, 13093.476260949836, 13641.590752277447, 13649.658758531115, 13804.447822087004, 13840.4463821446, 8608.354373421893, 9217.631294748404, 9530.809383812146, 9698.0, 9707.369021013612, 10951.233413661286, 11032.172587056164, 11040.558377665126, 11049.0, 11059.22585419013, 8211.302039326723, 8365.163483651586, 8436.704715334934, 8452.830943380975, 8536.274416674652, 8816.382853200472, 8925.375223734536, 8966.506842123485, 9020.819583608421, 9044.276457883221, 8017.997750281214, 8056.597170141352, 8066.354691624539, 8112.716054938049, 8140.918590814016, 5617.522510586646, 6067.514598831995, 6101.115338276014, 6116.17431646722, 6192.473639740492, 6060.515158787198, 6060.878782424239, 6103.267607886904, 6151.907721384272, 6235.937640623717], "value": 5302.114408576136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.30272358393097, 390.5645205595762, 394.50095629029266, 399.78811230048075, 402.629580785677, 5753.7917037422085, 5757.453041961012, 5766.769329226826, 5770.855728606787, 5790.305163380395, 4654.3716598259225, 5222.686638801665, 5621.0444224481835, 5688.516476099537, 5722.656640601567, 4386.34204869269, 4400.339949007643, 4410.64714822815, 4417.801198946056, 4424.3142312941545, 3509.3856825860134, 3600.4239321708533, 4409.492908315553, 4445.777711114431, 4460.910781784362, 4069.0671930832914, 4215.747055176684, 4402.977985110086, 4411.735295882258, 4445.333200020007, 3887.9502534315575, 4165.791710414467, 4336.696431249814, 4410.889727756832, 4442.511323754385, 3880.0881792778673, 3910.8240129194255, 3934.15415685629, 3936.2127574485216, 3949.6840252779707, 4600.757795395224, 5058.570021548173, 5363.414488957664, 5485.396606373296, 5619.578531610105, 4606.746628935407, 5034.86715489012, 5278.683279003272, 5302.0, 5302.814401495968, 5320.467953204688, 6112.0, 6139.78510752126, 6148.569600127993, 6198.566100372976, 4299.462567179103, 4420.381146639473, 4461.152381047623, 4462.219111655448, 4470.077866517397, 5505.871296384232, 5617.157426386075, 5805.564582656318, 6527.3472652734345, 6570.70431830564, 4221.248182004434, 4569.1547063793305, 4590.793414296365, 4596.172688916027, 4614.400127983341, 4643.048175124091, 4652.278896771006, 4668.229742092584, 4672.322513235562, 4679.298105284234, 4102.261592913304, 4542.6151270583205, 4844.612431005511, 5029.094762942632, 5158.690478571298, 6172.105044768484, 6209.844753881189, 6211.223597050369, 6228.688565571703, 6243.656598887014, 1551.4569900534723, 1560.7112684153467, 1570.9607259818597, 1600.0, 1602.1828867277773, 1088.7495875948418, 1094.8467214589964, 1105.9225854190129, 1111.7825980551283, 1122.2761318949395, 5602.355729091125, 5803.47768700819, 5823.505002074787, 5842.474177324061, 5846.736896839652, 5587.357453892854, 5803.390643982321, 6351.872962540723, 6373.936260637427, 6377.617342959437, 4731.787069581877, 5023.819402440424, 5327.413984461706, 5422.0, 5694.572907031989, 3029.106413607991, 3281.360134773744, 3317.369699757087, 3343.3146205028397, 3346.682065203802, 5145.044882944457, 5974.223350964434, 6443.677816109176, 6539.934600654028, 6546.312637173029, 5156.484351564814, 6251.218597675291, 6434.581752186093, 6438.871222575523, 6470.676466176767, 6456.7740129095255, 6466.612003279818, 6490.221173459215, 6493.220813502316, 6505.772297969548, 5965.582409231312, 6288.0, 6302.968485157545, 6344.682765861596, 6378.808635740936, 6260.718267677874, 6302.077866250837, 6309.305976342507, 6329.430351268408, 6336.619802811846, 5236.528712415901, 5559.444055594408, 6009.579329446897, 6457.806265812033, 6502.154719886385, 3781.3193625147737, 4464.799084041291, 4518.570735780096, 4541.818327266872, 4550.203714350042, 6086.72609732572, 6334.746610135543, 6367.52243581724, 6384.329645387167, 6395.616263024233, 5346.492083252085, 5572.944270557243, 5634.7464364104535, 5641.746121424628, 6115.602485838406, 5342.839714808562, 5395.622306438511, 6155.692215389123, 6159.84600384994, 6172.444479996642, 5638.379778224474, 5923.526117910643, 6004.5796794225735, 6019.608725432833, 6042.546808989256, 5087.338645976148, 5804.651720896591, 6113.938860611515, 6137.158852344368, 6153.846153846011, 5389.841184145271, 5623.943760562342, 5750.913736294043, 6060.6969651516365, 6082.574219804571], "value": 4543.057895968639, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.8805103132466, 848.1518481518482, 856.0412338181238, 856.1609622569881, 860.733172716458, 13000.219986800787, 17816.643667126667, 18547.44357669272, 18611.813881861177, 18656.906715466404, 16598.004119752793, 17221.13894305286, 17270.136493175323, 17350.219240134193, 17351.785375023723, 656.2223764838658, 831.580052073702, 905.5562774240608, 916.3860213656833, 917.0874979395511, 946.0, 946.4084946908182, 946.8769060022188, 947.8720372749659, 955.1212884146568, 1016.7900198763448, 1016.8779746430401, 1017.4963393120415, 1019.6176433837311, 1025.707673313106, 1001.4241810958691, 1005.8642083318731, 1007.2345017786462, 1008.0927165551026, 1019.23557332001, 747.495440577608, 751.2675141736818, 752.3567349915797, 754.4530215593705, 760.2131793593604, 15792.131432771193, 15819.446319378767, 15826.683466330724, 16646.001239925645, 17089.145542722938, 13800.516981905686, 16832.0, 16833.831661683347, 17061.82938170615, 17205.9676419414, 14009.229492377915, 14068.08557443763, 14176.93672974531, 14462.710745785025, 14665.633359165999, 3610.891673249807, 3618.583862855779, 3625.1299688074673, 3625.166211771281, 3628.1836586767768, 108189.0, 133000.0, 133602.0, 136287.0, 136585.0, 11140.164487663458, 11464.140189485823, 11480.827787583194, 12807.0, 13236.0, 24714.0, 24770.380740481633, 25122.74877251204, 25491.87254063755, 25492.74507254941, 7725.92274077252, 7737.8452430951065, 7795.571243581658, 7916.445848790529, 7918.920810791818, 3904.1996390739832, 3963.2469830732643, 3966.6231707987695, 3967.960320396759, 3972.781497017692, 4212.515560710496, 4270.9684473136185, 4313.094250207491, 4317.481902171697, 4342.457192850894, 827.8468483330603, 929.2240978782792, 932.4358762948455, 956.9760755981156, 958.755517343073, 620.1163342237294, 640.6092283706935, 641.7689631732572, 652.1619718661498, 673.531895332739, 10860.0, 11728.472218750177, 11972.281663100242, 11979.221350612183, 12115.273083615011, 644.0564572900723, 644.9742010319535, 647.9416852483299, 658.6410406328563, 673.9789219332715, 5293.655912365684, 5533.474319939599, 5536.2249285100115, 5629.8592535186945, 5685.6304340218585, 81988.0, 91526.0, 91529.54235228915, 92218.53890730503, 94377.0, 80743.0, 82141.0, 83637.0, 84180.0, 85704.71442928362, 56649.71675141598, 61450.0, 77676.0, 77728.61135694399, 79513.0, 8786.604602793017, 11779.293242405483, 12514.18657787241, 12522.874771252535, 12532.310722910328, 31484.42577871143, 37841.62158378381, 40437.39343909785, 41050.76847694574, 41216.38175427431, 60167.796644065995, 72184.0, 72252.0, 72301.0, 73056.0, 15175.937684361988, 15531.301091450687, 15748.370065197263, 15751.369945202063, 15923.840761592699, 17395.56511087233, 17720.55698607545, 17764.555886102433, 17808.821911780717, 17835.643287133924, 13514.729705406033, 16102.0, 16248.837511625205, 16278.0, 16399.672006560042, 14431.061980971203, 15170.24148792578, 15279.61800954985, 15580.454684086002, 15588.922055390105, 15597.376104955674, 15605.0, 15640.92179539095, 15807.525774226791, 15821.604459888595, 11584.247023943417, 11789.174757766874, 11924.0, 12069.27584344942, 12073.27560346382, 11904.702382440508, 12111.152219344562, 12596.370181491073, 12630.719279724348, 12644.810327845275], "value": 8172.660815636161, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 110.0, 110.0, 112.0, 92.0, 157.0, 165.0, 166.0, 111.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 137.0, 186.0, 121.0, 110.0, 106.0, 107.0, 137.0, 109.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 187.0, 189.0, 182.0, 185.0, 185.0, 181.0, 233.0, 262.0], "value": 197.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 93.0, 67.0, 56.0, 62.0, 63.0, 81.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 68.0, 87.0, 69.0, 68.0, 69.0, 90.0, 68.0, 70.0, 90.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 89.0, 89.0, 87.0, 89.0, 89.0, 88.0, 89.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 86.0, 85.0, 84.0, 83.0, 83.0, 84.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 47.0, 47.0, 49.0, 48.0, 48.0, 49.0, 49.0, 49.0], "value": 48.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 58.0, 69.0, 65.0, 61.0, 58.0, 57.0, 59.0, 58.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 83.0, 83.0, 84.0, 96.0, 84.0, 83.0, 82.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 145.0, 135.0, 134.0, 137.0, 136.0, 147.0, 140.0, 176.0, 161.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 184.0, 181.0, 186.0, 184.0, 185.0, 164.0, 184.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 87.0, 91.0, 93.0, 70.0, 69.0, 67.0, 68.0], "value": 75.2, "unit": "score"}]}]} 18:34:44 INFO - Return code: 0 18:34:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:34:44 ERROR - # TBPL SUCCESS # 18:34:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:34:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:34:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:34:44 INFO - 2017-04-19 18:34:44.226 screenresolution[2719:40938] starting screenresolution argv=screenresolution get 18:34:44 INFO - 2017-04-19 18:34:44.229 screenresolution[2719:40938] Display 0: 1600x1200x32@60 18:34:44 INFO - 2017-04-19 18:34:44.236 screenresolution[2720:40941] starting screenresolution argv=screenresolution list 18:34:44 INFO - Available Modes on Display 0 18:34:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:34:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:34:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:34:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:34:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:34:44 INFO - Intel Iris: 18:34:44 INFO - Chipset Model: Intel Iris 18:34:44 INFO - Type: GPU 18:34:44 INFO - Bus: Built-In 18:34:44 INFO - VRAM (Dynamic, Max): 1536 MB 18:34:44 INFO - Vendor: Intel (0x8086) 18:34:44 INFO - Device ID: 0x0a2e 18:34:44 INFO - Revision ID: 0x0009 18:34:44 INFO - Displays: 18:34:44 INFO - Display: 18:34:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:34:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:34:44 INFO - Display Serial Number: 1600x1200 60 18:34:44 INFO - Main Display: Yes 18:34:44 INFO - Mirror: Off 18:34:44 INFO - Online: Yes 18:34:44 INFO - Rotation: Supported 18:34:44 INFO - Return code: 0 18:34:44 INFO - Running post-action listener: _resource_record_post_action 18:34:44 INFO - [mozharness: 2017-04-20 01:34:44.482326Z] Finished run-tests step (success) 18:34:44 INFO - Running post-run listener: _resource_record_post_run 18:34:44 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 512; Write bytes: 513261056; Read time: 1; Write time: 1605 18:34:44 INFO - TinderboxPrint: CPU usage
53.4% 18:34:44 INFO - TinderboxPrint: I/O read bytes / time
512 / 1 18:34:44 INFO - TinderboxPrint: I/O write bytes / time
513,261,056 / 1,605 18:34:44 INFO - TinderboxPrint: CPU idle
2,239.0 (46.6%) 18:34:44 INFO - TinderboxPrint: CPU system
964.7 (20.1%) 18:34:44 INFO - TinderboxPrint: CPU user
1,602.1 (33.3%) 18:34:44 INFO - TinderboxPrint: Swap in / out
207,233,024 / 0 18:34:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208761344; Write bytes: 208662528; Read time: 16039; Write time: 469 18:34:44 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 0; Write bytes: 303201792; Read time: 0; Write time: 1125 18:34:44 INFO - Running post-run listener: _upload_blobber_files 18:34:44 INFO - Blob upload gear active. 18:34:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:34:44 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 18:34:44 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'] 18:34:44 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 18:34:45 INFO - (blobuploader) - INFO - Open directory for files ... 18:34:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:46 INFO - (blobuploader) - INFO - Done attempting. 18:34:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:47 INFO - (blobuploader) - INFO - Done attempting. 18:34:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:34:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:34:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:48 INFO - (blobuploader) - INFO - Done attempting. 18:34:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:34:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:34:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:50 INFO - (blobuploader) - INFO - Done attempting. 18:34:50 INFO - (blobuploader) - INFO - Iteration through files over. 18:34:50 INFO - Return code: 0 18:34:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:34:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:34:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5d4917aadcd6cbd1dc5345f0510ab3312c55f7d25d18b818fa9e505fac9fd15f1bfa97659535ee78ff91915bc70a4fe6b3622ad0fb12ec3b0cbf259329db7a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0976f0d7c629611e4a02f83204300d943a76704d83d0a78490577db34ecbefa6a36c94f39485f5319e65cc12e099262eca4332ec53f28d28064c39c154bcf34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49db97553a64a264a3b85c344ce74fd0d5a733e478b688c27ecb38356e181c1ee374c6713dc22b9c4f732204399c55e2be6de324e495fbfd4a0c1644b2899b73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9531541871721731b689e5bf73d3769c25b3c39435ce6122f2ebcd67ad73af2991ebbe872be6a3b7de160688a5a967d5d35aaf9a92fa8a2f8a035fa5c633547"} 18:34:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:34:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:34:50 INFO - Contents: 18:34:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5d4917aadcd6cbd1dc5345f0510ab3312c55f7d25d18b818fa9e505fac9fd15f1bfa97659535ee78ff91915bc70a4fe6b3622ad0fb12ec3b0cbf259329db7a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0976f0d7c629611e4a02f83204300d943a76704d83d0a78490577db34ecbefa6a36c94f39485f5319e65cc12e099262eca4332ec53f28d28064c39c154bcf34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49db97553a64a264a3b85c344ce74fd0d5a733e478b688c27ecb38356e181c1ee374c6713dc22b9c4f732204399c55e2be6de324e495fbfd4a0c1644b2899b73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9531541871721731b689e5bf73d3769c25b3c39435ce6122f2ebcd67ad73af2991ebbe872be6a3b7de160688a5a967d5d35aaf9a92fa8a2f8a035fa5c633547"} 18:34:50 INFO - Running post-run listener: copy_logs_to_upload_dir 18:34:50 INFO - Copying logs to upload dir... 18:34:50 INFO - mkdir: /builds/slave/test/build/upload/logs 18:34:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1347.116929 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2017-04-19 18:34:50.762849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 18:34:50.766911) ========= 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.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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/b5d4917aadcd6cbd1dc5345f0510ab3312c55f7d25d18b818fa9e505fac9fd15f1bfa97659535ee78ff91915bc70a4fe6b3622ad0fb12ec3b0cbf259329db7a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0976f0d7c629611e4a02f83204300d943a76704d83d0a78490577db34ecbefa6a36c94f39485f5319e65cc12e099262eca4332ec53f28d28064c39c154bcf34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49db97553a64a264a3b85c344ce74fd0d5a733e478b688c27ecb38356e181c1ee374c6713dc22b9c4f732204399c55e2be6de324e495fbfd4a0c1644b2899b73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9531541871721731b689e5bf73d3769c25b3c39435ce6122f2ebcd67ad73af2991ebbe872be6a3b7de160688a5a967d5d35aaf9a92fa8a2f8a035fa5c633547"} build_url:https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/FJToXoTTRVa78_Z8fY-4RQ/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/b5d4917aadcd6cbd1dc5345f0510ab3312c55f7d25d18b818fa9e505fac9fd15f1bfa97659535ee78ff91915bc70a4fe6b3622ad0fb12ec3b0cbf259329db7a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0976f0d7c629611e4a02f83204300d943a76704d83d0a78490577db34ecbefa6a36c94f39485f5319e65cc12e099262eca4332ec53f28d28064c39c154bcf34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49db97553a64a264a3b85c344ce74fd0d5a733e478b688c27ecb38356e181c1ee374c6713dc22b9c4f732204399c55e2be6de324e495fbfd4a0c1644b2899b73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9531541871721731b689e5bf73d3769c25b3c39435ce6122f2ebcd67ad73af2991ebbe872be6a3b7de160688a5a967d5d35aaf9a92fa8a2f8a035fa5c633547"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 18:34:50.801745) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:34:50.802097) ========= 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.yviXuZeS2k/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.CT1dYRwif4/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.004851 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 18:34:50.850232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 18:34:50.850594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 18:34:50.850938) ========= ========= Total master_lag: 2.50 =========