builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1485808469.05 results: success (0) buildid: 20170130093342 builduid: 5462acac1d9748c7b41ff08c20dcfc0f revision: 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.047491) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.047988) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.048321) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.072953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.073270) ========= 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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-30 12:34:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-01-30 12:34:29 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117305 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.214570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.214929) ========= 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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.020422 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:29.267064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-30 12:34:29.267781) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-30 12:34:29,344 truncating revision to first 12 chars 2017-01-30 12:34:29,344 Setting DEBUG logging. 2017-01-30 12:34:29,344 attempt 1/10 2017-01-30 12:34:29,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/71e0c2ed4fa4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 12:34:31,252 unpacking tar archive at: mozilla-beta-71e0c2ed4fa4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.162474 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-30 12:34:31.456631) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:31.457002) ========= script_repo_revision: 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:31.457469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:31.457773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 12:34:31.479054) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-30 12:34:31.479555) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:34:31 INFO - MultiFileLogger online at 20170130 12:34:31 in /builds/slave/test 12:34:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:34:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:34:31 INFO - {'append_to_log': False, 12:34:31 INFO - 'base_work_dir': '/builds/slave/test', 12:34:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:34:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:34:31 INFO - 'branch': 'Mozilla-Beta', 12:34:31 INFO - 'buildbot_json_path': 'buildprops.json', 12:34:31 INFO - 'config_files': ('talos/mac_config.py',), 12:34:31 INFO - 'default_actions': ('clobber', 12:34:31 INFO - 'read-buildbot-config', 12:34:31 INFO - 'download-and-extract', 12:34:31 INFO - 'populate-webroot', 12:34:31 INFO - 'create-virtualenv', 12:34:31 INFO - 'install', 12:34:31 INFO - 'run-tests'), 12:34:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:34:31 INFO - 'download_minidump_stackwalk': True, 12:34:31 INFO - 'download_symbols': 'ondemand', 12:34:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:34:31 INFO - 'tooltool.py': '/tools/tooltool.py', 12:34:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:34:31 INFO - '/tools/misc-python/virtualenv.py')}, 12:34:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:34:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:34:31 INFO - 'installer_path': 'installer.exe', 12:34:31 INFO - 'log_level': 'info', 12:34:31 INFO - 'log_name': 'talos', 12:34:31 INFO - 'log_to_console': True, 12:34:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:34:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:34:31 INFO - 'opt_config_files': (), 12:34:31 INFO - 'pip_index': False, 12:34:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:31 INFO - 'cmd': ('bash', 12:34:31 INFO - '-c', 12:34:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:31 INFO - 'enabled': True, 12:34:31 INFO - 'halt_on_failure': False, 12:34:31 INFO - 'name': 'check_screen_resolution'},), 12:34:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:31 INFO - 'cmd': ('bash', 12:34:31 INFO - '-c', 12:34:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:31 INFO - 'enabled': True, 12:34:31 INFO - 'halt_on_failure': False, 12:34:31 INFO - 'name': 'check_screen_resolution'},), 12:34:31 INFO - 'run_cmd_checks_enabled': True, 12:34:31 INFO - 'sps_profile': False, 12:34:31 INFO - 'sps_profile_interval': 0, 12:34:31 INFO - 'suite': 'dromaeojs', 12:34:31 INFO - 'system_bits': '32', 12:34:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:34:31 INFO - 'title': 't-yosemite-r7-0167', 12:34:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:34:31 INFO - 'use_talos_json': True, 12:34:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:34:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:34:31 INFO - 'work_dir': 'build'} 12:34:31 INFO - [mozharness: 2017-01-30 20:34:31.675767Z] Running clobber step. 12:34:31 INFO - Running pre-action listener: _resource_record_pre_action 12:34:31 INFO - Running main action method: clobber 12:34:31 INFO - rmtree: /builds/slave/test/build 12:34:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:34:33 INFO - Running post-action listener: _resource_record_post_action 12:34:33 INFO - [mozharness: 2017-01-30 20:34:33.813188Z] Finished clobber step (success) 12:34:33 INFO - [mozharness: 2017-01-30 20:34:33.813343Z] Running read-buildbot-config step. 12:34:33 INFO - Running pre-action listener: _resource_record_pre_action 12:34:33 INFO - Running main action method: read_buildbot_config 12:34:33 INFO - Using buildbot properties: 12:34:33 INFO - { 12:34:33 INFO - "project": "", 12:34:33 INFO - "product": "firefox", 12:34:33 INFO - "script_repo_revision": "production", 12:34:33 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:34:33 INFO - "repository": "", 12:34:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:34:33 INFO - "buildid": "20170130093342", 12:34:33 INFO - "pgo_build": "False", 12:34:33 INFO - "basedir": "/builds/slave/test", 12:34:33 INFO - "buildnumber": 10, 12:34:33 INFO - "slavename": "t-yosemite-r7-0167", 12:34:33 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:34:33 INFO - "platform": "yosemite_r7", 12:34:33 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:34:33 INFO - "branch": "mozilla-beta", 12:34:33 INFO - "repo_path": "releases/mozilla-beta", 12:34:33 INFO - "revision": "71e0c2ed4fa4521cc452d56e0ff280a05b9051e0", 12:34:33 INFO - "stage_platform": "macosx64", 12:34:33 INFO - "builduid": "5462acac1d9748c7b41ff08c20dcfc0f", 12:34:33 INFO - "slavebuilddir": "test" 12:34:33 INFO - } 12:34:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 12:34:33 INFO - Running post-action listener: _resource_record_post_action 12:34:33 INFO - [mozharness: 2017-01-30 20:34:33.816382Z] Finished read-buildbot-config step (success) 12:34:33 INFO - [mozharness: 2017-01-30 20:34:33.816532Z] Running download-and-extract step. 12:34:33 INFO - Running pre-action listener: _resource_record_pre_action 12:34:33 INFO - Running main action method: download_and_extract 12:34:33 INFO - mkdir: /builds/slave/test/build/tests 12:34:33 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')]} 12:34:33 INFO - trying https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 12:34:33 INFO - Downloading https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 12:34:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 12:34:35 INFO - Downloaded 1165 bytes. 12:34:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 12:34:35 INFO - Using the following test package requirements: 12:34:35 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 12:34:35 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 12:34:35 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 12:34:35 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:34:35 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 12:34:35 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 12:34:35 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 12:34:35 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 12:34:35 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 12:34:35 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 12:34:35 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 12:34:35 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 12:34:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 12:34:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 12:34:35 INFO - Fetch https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 12:34:35 INFO - Http code: 200 12:34:35 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 12:34:35 INFO - x-amz-cf-id: palkWvrHc6vrbQNPPZesuAwVjsXEOLOYNheo0ye-f5BJJ34Cvv2hEQ== 12:34:35 INFO - x-amz-version-id: 0IWg85KdGMu15w9Sr2h8oGZwe54WMzeb 12:34:36 INFO - Expected file size: 16087674 12:34:36 INFO - Obtained file size: 16087674 12:34:42 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:34:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 12:34:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 12:34:42 INFO - Fetch https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 12:34:43 INFO - Http code: 200 12:34:43 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 12:34:43 INFO - x-amz-cf-id: o2NvmxKSkL2qIXJzJK0s1W6tiQhzUwzbdhJREnCjz2dY9tG_BPbdYg== 12:34:43 INFO - x-amz-version-id: 0IWg85KdGMu15w9Sr2h8oGZwe54WMzeb 12:34:43 INFO - Expected file size: 16087674 12:34:43 INFO - Obtained file size: 16087674 12:34:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 12:34:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 12:34:50 INFO - Fetch https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 12:34:50 INFO - Http code: 200 12:34:50 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 12:34:50 INFO - x-amz-cf-id: YMD_8mahyKrfBWbnt1W8bsfsA8Bs1oNylCe2KHm_6j2ahyiEANY_XQ== 12:34:50 INFO - x-amz-version-id: 7X1SRmNs._gwtZHzmu8OsK828A4gI1Gp 12:34:51 INFO - Expected file size: 14402992 12:34:51 INFO - Obtained file size: 14402992 12:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:51 INFO - trying https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:34:51 INFO - Downloading https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 12:34:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 12:34:53 INFO - Downloaded 82137621 bytes. 12:34:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:34:53 INFO - mkdir: /builds/slave/test/properties 12:34:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:34:53 INFO - Writing to file /builds/slave/test/properties/build_url 12:34:53 INFO - Contents: 12:34:53 INFO - build_url:https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg 12:34:54 INFO - Running post-action listener: _resource_record_post_action 12:34:54 INFO - Running post-action listener: set_extra_try_arguments 12:34:54 INFO - [mozharness: 2017-01-30 20:34:54.273057Z] Finished download-and-extract step (success) 12:34:54 INFO - [mozharness: 2017-01-30 20:34:54.273210Z] Running populate-webroot step. 12:34:54 INFO - Running pre-action listener: _resource_record_pre_action 12:34:54 INFO - Running main action method: populate_webroot 12:34:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:34:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:34:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:34:54 INFO - '--tppagecycles', 12:34:54 INFO - '7'], 12:34:54 INFO - 'tests': ['tsvgm']}}, 12:34:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:34:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:34:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:34:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:34:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['damp', 'tps']}, 12:34:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'tests': ['damp', 'tps']}, 12:34:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['dromaeo_dom']}, 12:34:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:34:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['basic_compositor_video']}, 12:34:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:34:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'other-e10s': {'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['a11yr', 12:34:54 INFO - 'ts_paint', 12:34:54 INFO - 'tpaint', 12:34:54 INFO - 'sessionrestore', 12:34:54 INFO - 'sessionrestore_no_auto_restore', 12:34:54 INFO - 'tabpaint']}, 12:34:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['tsvgx', 12:34:54 INFO - 'tsvgr_opacity', 12:34:54 INFO - 'tart', 12:34:54 INFO - 'tscrollx', 12:34:54 INFO - 'cart']}, 12:34:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:34:54 INFO - 'tsvgr_opacity', 12:34:54 INFO - 'tart', 12:34:54 INFO - 'tscrollx', 12:34:54 INFO - 'cart']}, 12:34:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'talos_options': ['--disable-e10s'], 12:34:54 INFO - 'tests': ['tp5o']}, 12:34:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'tests': ['tp5o']}, 12:34:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'talos_options': ['--disable-e10s', 12:34:54 INFO - '--xperf_path', 12:34:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:54 INFO - 'tests': ['tp5n']}, 12:34:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:34:54 INFO - 'talos_options': ['--xperf_path', 12:34:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:54 INFO - 'tests': ['tp5n']}}, 12:34:54 INFO - 'talos.zip': {'path': '', 12:34:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:34:54 INFO - Running post-action listener: _resource_record_post_action 12:34:54 INFO - [mozharness: 2017-01-30 20:34:54.291784Z] Finished populate-webroot step (success) 12:34:54 INFO - [mozharness: 2017-01-30 20:34:54.291874Z] Running create-virtualenv step. 12:34:54 INFO - Running pre-action listener: _resource_record_pre_action 12:34:54 INFO - Running main action method: create_virtualenv 12:34:54 INFO - Creating virtualenv /builds/slave/test/build/venv 12:34:54 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 12:34:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:34:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:34:54 INFO - Using real prefix '/tools/python27' 12:34:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:34:55 INFO - Installing distribute.............................................................................................................................................................................................done. 12:34:58 INFO - Installing pip.................done. 12:34:58 INFO - Return code: 0 12:34:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:34:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:34:58 INFO - Reading from file tmpfile_stdout 12:34:58 INFO - Output received: 12:34:58 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) 12:34:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:34:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:34:58 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 12:34:58 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 12:34:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:34:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:34:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:34:58 INFO - 'HOME': '/Users/cltbld', 12:34:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:34:58 INFO - 'LOGNAME': 'cltbld', 12:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:58 INFO - 'MOZ_NO_REMOTE': '1', 12:34:58 INFO - 'NO_EM_RESTART': '1', 12:34:58 INFO - 'PAGER': '/bin/cat', 12:34:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:58 INFO - 'PWD': '/builds/slave/test', 12:34:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:34:58 INFO - 'SHELL': '/bin/bash', 12:34:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:34:58 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:34:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:34:58 INFO - 'USER': 'cltbld', 12:34:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:34:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:58 INFO - 'XPC_FLAGS': '0x0', 12:34:58 INFO - 'XPC_SERVICE_NAME': '0', 12:34:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:58 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 12:34:58 INFO - Cleaning up... 12:34:58 INFO - Return code: 0 12:34:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:34:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:34:58 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 12:34:58 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 12:34:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:34:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:34:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:34:58 INFO - 'HOME': '/Users/cltbld', 12:34:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:34:58 INFO - 'LOGNAME': 'cltbld', 12:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:58 INFO - 'MOZ_NO_REMOTE': '1', 12:34:58 INFO - 'NO_EM_RESTART': '1', 12:34:58 INFO - 'PAGER': '/bin/cat', 12:34:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:58 INFO - 'PWD': '/builds/slave/test', 12:34:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:34:58 INFO - 'SHELL': '/bin/bash', 12:34:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:34:58 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:34:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:34:58 INFO - 'USER': 'cltbld', 12:34:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:34:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:58 INFO - 'XPC_FLAGS': '0x0', 12:34:58 INFO - 'XPC_SERVICE_NAME': '0', 12:34:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:58 INFO - Downloading/unpacking psutil>=3.1.1 12:34:58 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 12:34:58 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 12:34:58 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 12:34:58 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 12:34:58 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 12:34:58 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 12:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:35:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:02 INFO - Installing collected packages: psutil 12:35:02 INFO - Running setup.py install for psutil 12:35:02 INFO - building 'psutil._psutil_osx' extension 12:35:02 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 12:35:02 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 12:35:02 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 12:35:02 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 12:35:03 INFO - building 'psutil._psutil_posix' extension 12:35:03 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 12:35:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:35:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:35:03 INFO - ^ 12:35:03 INFO - 1 warning generated. 12:35:03 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 12:35:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:03 INFO - Successfully installed psutil 12:35:03 INFO - Cleaning up... 12:35:03 INFO - Return code: 0 12:35:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:35: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')]} 12:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35: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')]} 12:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:35:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:03 INFO - 'HOME': '/Users/cltbld', 12:35:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:03 INFO - 'LOGNAME': 'cltbld', 12:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:03 INFO - 'MOZ_NO_REMOTE': '1', 12:35:03 INFO - 'NO_EM_RESTART': '1', 12:35:03 INFO - 'PAGER': '/bin/cat', 12:35:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:03 INFO - 'PWD': '/builds/slave/test', 12:35:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:03 INFO - 'SHELL': '/bin/bash', 12:35:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:03 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:03 INFO - 'USER': 'cltbld', 12:35:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:03 INFO - 'XPC_FLAGS': '0x0', 12:35:03 INFO - 'XPC_SERVICE_NAME': '0', 12:35:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:35:03 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 12:35:03 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 12:35:03 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 12:35:03 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 12:35:03 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 12:35:03 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 12:35:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:35:06 INFO - Installing collected packages: mozsystemmonitor 12:35:06 INFO - Running setup.py install for mozsystemmonitor 12:35:06 INFO - Successfully installed mozsystemmonitor 12:35:06 INFO - Cleaning up... 12:35:06 INFO - Return code: 0 12:35:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:35:06 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')]} 12:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:06 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')]} 12:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:06 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:06 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 12:35:06 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 12:35:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:06 INFO - 'HOME': '/Users/cltbld', 12:35:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:06 INFO - 'LOGNAME': 'cltbld', 12:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:06 INFO - 'MOZ_NO_REMOTE': '1', 12:35:06 INFO - 'NO_EM_RESTART': '1', 12:35:06 INFO - 'PAGER': '/bin/cat', 12:35:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:06 INFO - 'PWD': '/builds/slave/test', 12:35:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:06 INFO - 'SHELL': '/bin/bash', 12:35:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:06 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:06 INFO - 'USER': 'cltbld', 12:35:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:06 INFO - 'XPC_FLAGS': '0x0', 12:35:06 INFO - 'XPC_SERVICE_NAME': '0', 12:35:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:06 INFO - Downloading/unpacking jsonschema==2.5.1 12:35:06 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 12:35:06 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 12:35:06 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 12:35:06 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 12:35:06 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 12:35:06 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 12:35:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:35:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:09 INFO - Downloading functools32-3.2.3-2.tar.gz 12:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:35:09 INFO - warning: no files found matching '*.txt' 12:35:09 INFO - no previously-included directories found matching 'build' 12:35:09 INFO - no previously-included directories found matching 'dist' 12:35:09 INFO - no previously-included directories found matching '.git*' 12:35:09 INFO - Installing collected packages: jsonschema, functools32 12:35:10 INFO - Running setup.py install for functools32 12:35:10 INFO - warning: no files found matching '*.txt' 12:35:10 INFO - no previously-included directories found matching 'build' 12:35:10 INFO - no previously-included directories found matching 'dist' 12:35:10 INFO - no previously-included directories found matching '.git*' 12:35:10 INFO - Successfully installed jsonschema functools32 12:35:10 INFO - Cleaning up... 12:35:10 INFO - Return code: 0 12:35:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:35:10 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')]} 12:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:10 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')]} 12:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:10 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:10 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 12:35:10 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 12:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:10 INFO - 'HOME': '/Users/cltbld', 12:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:10 INFO - 'LOGNAME': 'cltbld', 12:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:10 INFO - 'MOZ_NO_REMOTE': '1', 12:35:10 INFO - 'NO_EM_RESTART': '1', 12:35:10 INFO - 'PAGER': '/bin/cat', 12:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:10 INFO - 'PWD': '/builds/slave/test', 12:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:10 INFO - 'SHELL': '/bin/bash', 12:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:10 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:10 INFO - 'USER': 'cltbld', 12:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:10 INFO - 'XPC_FLAGS': '0x0', 12:35:10 INFO - 'XPC_SERVICE_NAME': '0', 12:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:35:10 INFO - Cleaning up... 12:35:10 INFO - Return code: 0 12:35:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:35:10 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')]} 12:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:10 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')]} 12:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:10 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:10 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 12:35:10 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 12:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:10 INFO - 'HOME': '/Users/cltbld', 12:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:10 INFO - 'LOGNAME': 'cltbld', 12:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:10 INFO - 'MOZ_NO_REMOTE': '1', 12:35:10 INFO - 'NO_EM_RESTART': '1', 12:35:10 INFO - 'PAGER': '/bin/cat', 12:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:10 INFO - 'PWD': '/builds/slave/test', 12:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:10 INFO - 'SHELL': '/bin/bash', 12:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:10 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:10 INFO - 'USER': 'cltbld', 12:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:10 INFO - 'XPC_FLAGS': '0x0', 12:35:10 INFO - 'XPC_SERVICE_NAME': '0', 12:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:35:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:35:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:14 INFO - Downloading docopt-0.6.1.tar.gz 12:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:35:14 INFO - Installing collected packages: blobuploader, requests, docopt 12:35:14 INFO - Running setup.py install for blobuploader 12:35:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:35:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:35:14 INFO - Running setup.py install for requests 12:35:15 INFO - Running setup.py install for docopt 12:35:15 INFO - Successfully installed blobuploader requests docopt 12:35:15 INFO - Cleaning up... 12:35:15 INFO - Return code: 0 12:35:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:15 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:15 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 12:35:15 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 12:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:15 INFO - 'HOME': '/Users/cltbld', 12:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:15 INFO - 'LOGNAME': 'cltbld', 12:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:15 INFO - 'MOZ_NO_REMOTE': '1', 12:35:15 INFO - 'NO_EM_RESTART': '1', 12:35:15 INFO - 'PAGER': '/bin/cat', 12:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:15 INFO - 'PWD': '/builds/slave/test', 12:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:15 INFO - 'SHELL': '/bin/bash', 12:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:15 INFO - 'USER': 'cltbld', 12:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:15 INFO - 'XPC_FLAGS': '0x0', 12:35:15 INFO - 'XPC_SERVICE_NAME': '0', 12:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:15 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-138qIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oFJ1yC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MPkliB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wkQ49M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pD_gDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QLvhQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pVZc6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wZEdzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-9q32ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-vGPrQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:16 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E4hfBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-055zAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-e596Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qqowCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-uFLVX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-RSQIiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:17 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jNwvKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:35:17 INFO - Running setup.py install for manifestparser 12:35:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:35:17 INFO - Running setup.py install for mozcrash 12:35:17 INFO - Running setup.py install for mozdebug 12:35:18 INFO - Running setup.py install for mozdevice 12:35:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Running setup.py install for mozfile 12:35:18 INFO - Running setup.py install for mozhttpd 12:35:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Running setup.py install for mozinfo 12:35:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Running setup.py install for mozInstall 12:35:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:35:18 INFO - Running setup.py install for mozleak 12:35:18 INFO - Running setup.py install for mozlog 12:35:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Running setup.py install for moznetwork 12:35:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Running setup.py install for mozprocess 12:35:19 INFO - Running setup.py install for mozprofile 12:35:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Running setup.py install for mozrunner 12:35:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:35:19 INFO - Running setup.py install for mozscreenshot 12:35:19 INFO - Running setup.py install for moztest 12:35:20 INFO - Running setup.py install for mozversion 12:35:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:35:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:35:20 INFO - Cleaning up... 12:35:20 INFO - Return code: 0 12:35:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:20 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 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:20 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 12:35:20 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 12:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:20 INFO - 'HOME': '/Users/cltbld', 12:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:20 INFO - 'LOGNAME': 'cltbld', 12:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:20 INFO - 'MOZ_NO_REMOTE': '1', 12:35:20 INFO - 'NO_EM_RESTART': '1', 12:35:20 INFO - 'PAGER': '/bin/cat', 12:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:20 INFO - 'PWD': '/builds/slave/test', 12:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:20 INFO - 'SHELL': '/bin/bash', 12:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:20 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:20 INFO - 'USER': 'cltbld', 12:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:20 INFO - 'XPC_FLAGS': '0x0', 12:35:20 INFO - 'XPC_SERVICE_NAME': '0', 12:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:20 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-LYzwzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:20 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)) 12:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:20 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-c0Z6aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:20 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)) 12:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:20 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0CSXtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:20 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)) 12:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:20 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Ga5nbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 12:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:20 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-XDwIc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-t20Fyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-R30k94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-O8MOvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Xtof_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zPIhf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DhndbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-w9QsgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-NXfQtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:21 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)) 12:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:21 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Z8oTQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:22 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-JVWB9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:22 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KzkXcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:22 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)) 12:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:22 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yWmtvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:22 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)) 12:35:22 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)) 12:35:22 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)) 12:35:22 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)) 12:35:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:35:22 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:35:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:24 INFO - Downloading blessings-1.6.tar.gz 12:35:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:35:25 INFO - Installing collected packages: blessings 12:35:25 INFO - Running setup.py install for blessings 12:35:25 INFO - Successfully installed blessings 12:35:25 INFO - Cleaning up... 12:35:25 INFO - Return code: 0 12:35:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:35:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:25 INFO - Reading from file tmpfile_stdout 12:35:25 INFO - Current package versions: 12:35:25 INFO - blessings == 1.6 12:35:25 INFO - blobuploader == 1.2.4 12:35:25 INFO - docopt == 0.6.1 12:35:25 INFO - functools32 == 3.2.3-2 12:35:25 INFO - jsonschema == 2.5.1 12:35:25 INFO - manifestparser == 1.1 12:35:25 INFO - mozInstall == 1.12 12:35:25 INFO - mozcrash == 1.0 12:35:25 INFO - mozdebug == 0.1 12:35:25 INFO - mozdevice == 0.48 12:35:25 INFO - mozfile == 1.2 12:35:25 INFO - mozhttpd == 0.7 12:35:25 INFO - mozinfo == 0.9 12:35:25 INFO - mozleak == 0.1 12:35:25 INFO - mozlog == 3.4 12:35:25 INFO - moznetwork == 0.27 12:35:25 INFO - mozprocess == 0.23 12:35:25 INFO - mozprofile == 0.28 12:35:25 INFO - mozrunner == 6.13 12:35:25 INFO - mozscreenshot == 0.1 12:35:25 INFO - mozsystemmonitor == 0.3 12:35:25 INFO - moztest == 0.8 12:35:25 INFO - mozversion == 1.4 12:35:25 INFO - psutil == 3.1.1 12:35:25 INFO - requests == 1.2.3 12:35:25 INFO - wsgiref == 0.1.2 12:35:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:25 INFO - 'HOME': '/Users/cltbld', 12:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:25 INFO - 'LOGNAME': 'cltbld', 12:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:25 INFO - 'MOZ_NO_REMOTE': '1', 12:35:25 INFO - 'NO_EM_RESTART': '1', 12:35:25 INFO - 'PAGER': '/bin/cat', 12:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:25 INFO - 'PWD': '/builds/slave/test', 12:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:25 INFO - 'SHELL': '/bin/bash', 12:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:25 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:25 INFO - 'USER': 'cltbld', 12:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:25 INFO - 'XPC_FLAGS': '0x0', 12:35:25 INFO - 'XPC_SERVICE_NAME': '0', 12:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 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)) 12:35:26 INFO - Cleaning up... 12:35:26 INFO - Return code: 0 12:35:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:35: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')]} 12:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35: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')]} 12:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109122490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109098570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc9dbfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, '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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 12:35: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', 'jsonschema'] in /builds/slave/test/build 12:35: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 jsonschema 12:35:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:26 INFO - 'HOME': '/Users/cltbld', 12:35:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:26 INFO - 'LOGNAME': 'cltbld', 12:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:26 INFO - 'MOZ_NO_REMOTE': '1', 12:35:26 INFO - 'NO_EM_RESTART': '1', 12:35:26 INFO - 'PAGER': '/bin/cat', 12:35:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:26 INFO - 'PWD': '/builds/slave/test', 12:35:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:26 INFO - 'SHELL': '/bin/bash', 12:35:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:26 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:26 INFO - 'USER': 'cltbld', 12:35:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:26 INFO - 'XPC_FLAGS': '0x0', 12:35:26 INFO - 'XPC_SERVICE_NAME': '0', 12:35:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:35:26 INFO - Cleaning up... 12:35:26 INFO - Return code: 0 12:35:26 INFO - Running post-action listener: _resource_record_post_action 12:35:26 INFO - Running post-action listener: _start_resource_monitoring 12:35:26 INFO - Starting resource monitoring. 12:35:26 INFO - [mozharness: 2017-01-30 20:35:26.992652Z] Finished create-virtualenv step (success) 12:35:26 INFO - [mozharness: 2017-01-30 20:35:26.993637Z] Running install step. 12:35:26 INFO - Running pre-action listener: _resource_record_pre_action 12:35:26 INFO - Running main action method: install 12:35:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:27 INFO - Reading from file tmpfile_stdout 12:35:27 INFO - Detecting whether we're running mozinstall >=1.0... 12:35:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:35:27 INFO - Reading from file tmpfile_stdout 12:35:27 INFO - Output received: 12:35:27 INFO - Usage: mozinstall [options] installer 12:35:27 INFO - Options: 12:35:27 INFO - -h, --help show this help message and exit 12:35:27 INFO - -d DEST, --destination=DEST 12:35:27 INFO - Directory to install application into. [default: 12:35:27 INFO - "/builds/slave/test"] 12:35:27 INFO - --app=APP Application being installed. [default: firefox] 12:35:27 INFO - mkdir: /builds/slave/test/build/application 12:35:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:35:49 INFO - Reading from file tmpfile_stdout 12:35:49 INFO - Output received: 12:35:49 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:35:49 INFO - Running post-action listener: _resource_record_post_action 12:35:49 INFO - [mozharness: 2017-01-30 20:35:49.108673Z] Finished install step (success) 12:35:49 INFO - [mozharness: 2017-01-30 20:35:49.108819Z] Running run-tests step. 12:35:49 INFO - Running pre-action listener: _resource_record_pre_action 12:35:49 INFO - Running main action method: run_tests 12:35:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:35:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:35:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:35:49 INFO - 2017-01-30 12:35:49.123 screenresolution[1895:18639] starting screenresolution argv=screenresolution get 12:35:49 INFO - 2017-01-30 12:35:49.125 screenresolution[1895:18639] Display 0: 1600x1200x32@60 12:35:49 INFO - 2017-01-30 12:35:49.131 screenresolution[1896:18641] starting screenresolution argv=screenresolution list 12:35:49 INFO - Available Modes on Display 0 12:35:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:35:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:35:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:35:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:35:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:35:49 INFO - Intel Iris: 12:35:49 INFO - Chipset Model: Intel Iris 12:35:49 INFO - Type: GPU 12:35:49 INFO - Bus: Built-In 12:35:49 INFO - VRAM (Dynamic, Max): 1536 MB 12:35:49 INFO - Vendor: Intel (0x8086) 12:35:49 INFO - Device ID: 0x0a2e 12:35:49 INFO - Revision ID: 0x0009 12:35:49 INFO - Displays: 12:35:49 INFO - Display: 12:35:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:35:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:35:49 INFO - Display Serial Number: 1600x1200 60 12:35:49 INFO - Main Display: Yes 12:35:49 INFO - Mirror: Off 12:35:49 INFO - Online: Yes 12:35:49 INFO - Rotation: Supported 12:35:49 INFO - Return code: 0 12:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:35:49 INFO - Python 2.7.3 12:35:49 INFO - Return code: 0 12:35:49 INFO - grabbing minidump binary from tooltool 12:35:49 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')]} 12:35:49 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 0x108f36d30>, '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 0x108d9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f2eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:35:49 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 12:35:49 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 12:35:49 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 12:35:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:35:49 INFO - Return code: 0 12:35:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:35:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:35:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:35:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:35:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 12:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 12:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WOcNCEAM7v/Render', 12:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:49 INFO - 'HOME': '/Users/cltbld', 12:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:49 INFO - 'LOGNAME': 'cltbld', 12:35:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:35:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:49 INFO - 'MOZ_NO_REMOTE': '1', 12:35:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:35:49 INFO - 'NO_EM_RESTART': '1', 12:35:49 INFO - 'PAGER': '/bin/cat', 12:35:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:49 INFO - 'PWD': '/builds/slave/test', 12:35:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:49 INFO - 'SHELL': '/bin/bash', 12:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WiFbsE1uV0/Listeners', 12:35:49 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 12:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:49 INFO - 'USER': 'cltbld', 12:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:49 INFO - 'XPC_FLAGS': '0x0', 12:35:49 INFO - 'XPC_SERVICE_NAME': '0', 12:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 12:35:49 INFO - mozversion application_buildid: 20170130093342 12:35:49 INFO - mozversion application_changeset: 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 12:35:49 INFO - mozversion application_display_name: Firefox 12:35:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:35:49 INFO - mozversion application_name: Firefox 12:35:49 INFO - mozversion application_remotingname: firefox 12:35:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:35:49 INFO - mozversion application_vendor: Mozilla 12:35:49 INFO - mozversion application_version: 52.0 12:35:49 INFO - mozversion platform_buildid: 20170130093342 12:35:49 INFO - mozversion platform_changeset: 71e0c2ed4fa4521cc452d56e0ff280a05b9051e0 12:35:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:35:49 INFO - mozversion platform_version: 52.0 12:35:49 INFO - using testdate: 1485808549 12:35:49 INFO - actual date: 1485808549 12:35:49 INFO - starting webserver on 'localhost:57171' 12:35:49 INFO - SUITE-START | Running 2 tests 12:35:49 INFO - TEST-START | dromaeo_css 12:35:49 INFO - operating with platform_type : mac_ 12:35:49 INFO - Initialising browser for dromaeo_css test... 12:35:49 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp0p52I6/profile http://localhost:57171/getInfo.html) 12:35:52 INFO - PROCESS | 1906 | __metrics Screen width/height:1600/1200 12:35:52 INFO - PROCESS | 1906 | colorDepth:24 12:35:52 INFO - PROCESS | 1906 | Browser inner width/height: 1024/689 12:35:52 INFO - PROCESS | 1906 | __metrics 12:35:52 INFO - PROCESS | 1906 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:35:52 INFO - PROCESS | 1906 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:35:53 INFO - TEST-INFO | 1906: exit 0 12:35:53 INFO - Browser initialized. 12:35:53 INFO - Running cycle 1/1 for dromaeo_css test... 12:35:53 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp0p52I6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:36:05 INFO - PROCESS | 1909 | RSS: Main: 171585536 12:36:05 INFO - PROCESS | 1909 | 12:39:08 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57171/tests/dromaeo/cssquery-ext.html) 12:39:09 INFO - PROCESS | 1909 | RSS: Main: 313593856 12:39:09 INFO - PROCESS | 1909 | 12:42:12 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-ext.html (next: http://localhost:57171/tests/dromaeo/cssquery-jquery.html) 12:42:13 INFO - PROCESS | 1909 | RSS: Main: 299642880 12:42:13 INFO - PROCESS | 1909 | 12:45:15 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57171/tests/dromaeo/cssquery-mootools.html) 12:45:16 INFO - PROCESS | 1909 | RSS: Main: 270286848 12:45:16 INFO - PROCESS | 1909 | 12:48:18 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57171/tests/dromaeo/cssquery-prototype.html) 12:48:19 INFO - PROCESS | 1909 | RSS: Main: 274055168 12:48:19 INFO - PROCESS | 1909 | 12:51:22 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57171/tests/dromaeo/cssquery-yui.html) 12:51:22 INFO - PROCESS | 1909 | RSS: Main: 258232320 12:51:22 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:57171/tests/dromaeo/cssquery-yui.html (next: http://localhost:57171/tests/dromaeo/cssquery-dojo.html) 12:54:25 INFO - PROCESS | 1909 | RSS: Main: 313147392 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | __start_tp_report 12:54:25 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 12:54:25 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 12:54:25 INFO - PROCESS | 1909 | |i|pagename|runs| 12:54:25 INFO - PROCESS | 1909 | |0;dojo.html;3392.5759280089987;3445.844936977836;3464.792112473252;3506.964930350699;3524.295140971805;5731.885362292752;5828.737706803194;5858.5313174946;5873.676947767871;5910.054391297399;5871.647701137935;5889.6171748836405;5919.704014799265;5927.525797936166;5934.8219553413355;5248.212768084799;5275.446078161776;5324.653897496651;5348.358197016363;5369.114096174127;5233.528982391584;5237;5237.083510385667;5377.274068000828;5399.838004859841;5248.580113590903;5309.867253318678;5311.442298558653;5312.096943519611;5312.335958005249;5308.203769434577;5331.493508116722;5377.247185394048;5378.381486129106;5386.326709161355;5213.4004589472315;5264.947350526522;5319.760610772486;5322.387925388591;5373.059714549938;5677.290338707661;5712.971435142797;5723.599348045638;5738.7991420300505;5744.971275143639;6110.172019488887;6206.596571222856;6207.913615117382;6230.501559875244;6234.314225435199;6251;6258;6270.059491076329;6284.654344011077;6290.245170579514;5776.046952252873;5821.476067153976;5851;5879.823605291848;5979.4618484336615;6291.748330066837;6318.652474113968;6329.240491141092;6332.905006424908;6349.936500635027;6224.9377506224355;6226.62640241587;6246.437820596168;6256.405641464054;6263.185785847811;6118.694065296718;6169.167162433104;6221.626702397871;6235.438810506986;6249;5177.767000484987;6122.316816195983;6178.227721534809;6222.0355844844125;6227.06594010893;6176.69116544171;6241.906371404432;6246.187995560639;6257.217847769029;6258.092576576371;4984.925226121611;5052.141136006814;5060.165072762953;5066.138756411419;5073.315102461121;3759.909626208369;4440;4481.955180448153;4498.0104377037;4550.453945526492;5939.732712027882;6000.819975400745;6013.729382177812;6024.759009639653;6053.51571874249;5204.557612602971;6227.96886015567;6231.2522497300615;6235.875282494416;6239.220097487814;5799.536037117021;5854.063349864001;5921.348651648315;6028.3971602839365;6056.788012419544;4642.837500687459;5464.12573988168;5466.508014278734;5501.449855014466;5581.748821303051;6375.71309291083;6416.711247993758;6419.325970773001;6433.453156481658;6437.356264373525;6027.427394397525;6237.937620623918;6238.781642642486;6242.250929888442;6248.062790581267;6221.751129954751;6229.719662615103;6284.748610055546;6284.905726414199;6297.52768542352;6205.875882482415;6232.844178895564;6237.688115594111;6249.593776404519;6256;6115.4190351916495;6238.001919692827;6238.500919926304;6239.844003899939;6254.030625253093;6076.605020673642;6120.816375508742;6143.020634991936;6164.907526387018;6214.844628884314;5144.279800827812;6108.008189064015;6183.443490085914;6185.597936134137;6199.969000155151;5968.134620480093;6118.60229085108;6128.66292353907;6146.477549408351;6165.167702360303;6082.391760824059;6111.348653136637;6177;6188.271912847395;6264.436200741955;6046.425589568984;6073.937061014393;6089.725962331617;6126.866639400673;6144.324124346408 12:54:25 INFO - PROCESS | 1909 | |1;ext.html;474.2601541595111;482.1730731794972;512.800007996881;532.2123257578784;532.9173978033404;30650.54024189636;32607.51088733671;41410.79294603527;42629;44740;43822.78088609553;44113;44154.55845441545;44189;44266.77866610671;9598.136167744902;9806.264530160232;9814.901850981505;9817.410955342666;9837.606495740161;8986.191242788147;9185.678501252458;11630.651080467556;11862.991645710124;11898.167128301022;11710.414479276045;11749;11791.233569817978;11791.882081179207;11802.350870702108;12018.278903265787;12100.394980250952;12103.334316612583;12105.334206618632;12125.151239413244;11627.6511704649;11685.70785730355;11695.590654327058;11697.239679420896;11703.94148029263;41243;41564.79217603923;41588.75233742962;41645.79177104125;41664;3307.4377355849565;3343.498475228735;3386.407378708741;3426.194844211608;3428.9828550857337;30336;30672.92644757445;31907.84046079778;32080.35839283201;32702;3289.079057863802;3327.4676051831834;3346;3352.1116904020455;3417.923354143437;478911;482175;482568;484374;485377;34651.4802277966;35164.82417587947;35522.0925698173;36767.264654707295;65384.67307663431;35667;35702.64297357045;35736.46395304072;35763.82118089393;35764;14725;14914.776278355834;14921.40314387434;14930.552083437515;14936.178510182044;11138.49876755548;11464.369459679798;12626.116171868065;12723.681907952376;12748.916342110839;8015.998000249969;8220;8246.793830154294;8258.917410825814;8276.379271554537;3377.2401209728105;3412.061683037135;3416.0435078178148;3420.521127042216;3431.262278610111;935.1350001248818;976.9755756106155;979.9706008819746;1058.0424715632364;1083.128081894067;27108.457830843272;30562.694373055983;30800.075997720105;30997.225069373446;31026.534601980988;967.4920666649924;1079.2930630437077;1086.8641419822522;1130.5421304371744;1132.8753837077916;15506.612334691723;15913.363465461252;15989.280482378088;16011.919940400223;16304.673906521803;703981;712973;714491;714900;715601;60943.781124378154;61253.77492450126;61284;61426.77146457135;61456;1273.6433798536239;1317.2623330934707;1376.2980879751399;1419.7728363461797;1433.4481224728347;28539.143825685263;30830.691693082783;30892.22769430782;31732.524012139376;32418.02745917626;52507.9498410022;53247.73376133094;53283.73358133184;53351;53377;563900;567402;568026;568075;788508.057459709;24249;26783.86608066947;27769.16692499228;27798.861005694842;28290.151295461168;46784.53215467802;47035.294470583656;47308.76345618388;47891.28163077487;47965.760171200316;39140.6085939137;39457.80271098626;39904.60095399125;39939;41449.79275103605;37842.864714058625;38005.23989520139;38705.806470967465;38733;39615.2076958465;34724.30551389009;38336;39271.41092883666;39284.214315714096;39372.80313598413;33512.66487335096;34185.06871709785;34519.82740086384;35364.469532956515;35609.46585801163;25945;26083.478330433663;26280.229904572534;26380.0766973155;26599.60100598454 12:54:25 INFO - PROCESS | 1909 | |2;jquery.html;377.6167190301844;382.5600559356739;392.75060336686215;402.2357520710651;404.6722155054406;17880.642387152282;19528.511787205327;21114.894425527888;21748.673769893445;22050.33848984531;20210.292639757612;20474.795252047476;21127.894360528175;21133.154673813067;21216.151353945825;5894.351621321651;5984.64092154471;6126.142340072393;6211.968940155293;6224.8132556023165;4402.4937132229725;4632.235681112612;4638.350630911675;6069.969650151743;6194.7831825886105;4550.863474095782;4604.654650901179;4638.97680511597;6797.184337879461;6882.827929301758;5836.620619659709;6846.828829279283;6852.45180385568;6855.58866468011;6871.553349032322;4732.668713190078;6018.548608854309;6026.879462410728;6027.035674292092;6029.517638588903;13575;13641.454341826315;13644;13806.17162970225;13810.447582096804;2282.0073268128417;2367.9644805327935;2407.4222186675247;2652.774514166298;2706.201670507205;8257.628406721711;10265.846012309821;10444;10676.786464270748;10849.620263290746;1837.3018253063883;1841.4107485604611;1988.3836010836687;2030.532977415188;2136.273666953228;129164.3541782285;142834;149815;152029;158913.2054339721;21184.894075529523;22093.33719988403;22197.891200336264;22227.22204722859;22543.323700289016;36499.897508711525;36588.26823463516;37204.44193337048;37314;37610.24779504394;9883.505824708736;11200.887991120147;11271.605493807841;11274.605388811353;11479.081673466102;7583.544987300779;9145.811044564163;9146.496942668216;9164.541772911327;9304.488253146006;9713.805723885484;9814.95092524547;9911.950440247752;9922.305438619227;9953.552090155961;3805.980970095132;3823.2926908522013;3832.597577254404;3839.6544311012144;3928.744631598937;843.6920524008692;960.6109525642127;968.6125549780004;972.4311277902365;996.9651062212932;20576.691349629768;20908.268210612554;20950;21437.46406339854;21542.676859847114;943.3726571829657;945.0974319524837;947.7298969793713;948.127722495306;1024.1294899335505;9245.121713437213;10134.949325253474;10475.161987041018;10749.032587067202;10989.6153634624;587248;588818;593689;606731;607892;64127.35872641214;78897.08271879632;105884;124869;131782.34108829586;963.5375019990444;977.379364103785;985.4530735441755;1004.1665417703289;1006.4716024087413;19085.71371429402;19821.900890495453;19895.60208795845;19906.50233744168;19942.800571994096;39080.41379379368;46970;55157;56626;57095;72899.80351670168;73185.6340718293;74411;78275;78454;6659.966700166468;7157.64211789398;7508.399328053633;8215.465994710325;8239.629216685144;7396.852062958818;7740.954971078832;9825.754356141153;13090.672733181365;13189.80215296752;11078.612248571262;11246.550137994389;11322.547098115983;11338.886611133783;11692.240004399688;9810.313278070753;10355.275130741076;10666.679999600025;10978.451077445936;11063.336199828036;10296.279260451696;10344.689659310232;11450.198486106226;11743;12008.519659213884;7325.157606875277;7537;7577.583232922242;7654.8086297843;7794.493357931717;9926.305158638826;11082.390468524309;11093.722656933642;11310.717232068935;11476.942615286871 12:54:25 INFO - PROCESS | 1909 | |3;mootools.html;1474.7124310759407;1516.7573188289873;1691.6447546015336;1842.4841044507543;1877.14589861613;8168.142345053766;8593;8607.78480537986;8619.741407757761;8627.784305392368;7544.3964482841375;7773.922260777391;7784.416168787336;7876.842463150748;8413.158684131598;1808.4303444414968;2194.188150384352;2323.8954247058837;2393.8324317297797;2401.111588712179;2409.361519197414;2412.433078226615;2415.2874901903892;2416.6012607919674;2417.9758202417925;2348.8473249238746;2402.1952645863657;2403.507281007398;2405.8797060147012;2407.1093695332665;2015.8790472571693;2360.5042940982416;2384.6423036544484;2386.7613238676167;2390.438247011951;2378.774016468433;2384.6303822907303;2402.7356990731005;2406.205952035824;2406.2420337593603;8361.665533378658;8543.658253669846;8545.914540854636;8548.444351117158;8556.82886342276;5122.846314610568;5273.419923808378;5281.867953301198;5286.365636123652;5289.86775330616;3322.6178989416285;3525.5240542526685;3603.891883243507;3621.800800955946;3626.492291079251;1034.3586976074882;1063.8297872340465;1084.4631907205905;1104.1553211792984;1107.379867274321;11177.21759476837;11196.104311655048;11202.663920082328;11221.270617409842;11226.326420414802;4599.264117741212;4662.347271382009;4746.5728084471875;4749.097671442484;4806.990531988252;9644.18024467914;9663.661771838093;9667.951660241653;9676.225901927828;9679.177269932135;5308.177232528964;5322.813701520428;5327.547158491494;5331.5201631853315;5332.360116786011;4116.217918595457;4648.465426475931;4794.2808578712775;4901.534354236342;4911.042346742351;3631.2374401375478;3741.2330472253125;3749.250149970017;3756.69946404287;3762.1535154590547;1216.823560583728;1248.2822377133184;1267.854196767365;1295.6825577733516;1297.033709886122;723.2008630463263;750.808543821333;771.4792515052317;885.818407226517;888.9199972002551;5713.800016999385;5838.649681019152;5970.745743745563;5997.220361352996;6117.480014198843;895.4403497813867;899.7480705402497;906.981860362789;940.3934462271797;1001.5793366785967;3656.3966945453703;3843.1545060086737;3855.2289542091694;3946.2502124596235;3953.584873588289;8619.482831030158;9774.609015639437;10186.847197292047;10201.591936322464;10285.845712314322;9482.905170948203;9922.255830812717;9939.453330066772;10109.494525273854;10144.847827282596;7738.729144479917;7936.127026027132;7949.801254968556;7952.880706789519;7956.840863182704;4632.86101416958;5344.091504444332;5793.217915581343;5824.796132135355;5853.356130825691;9130.589123489592;9415.62337506492;9425.905740942502;9565.808683826424;9566.851977762713;7782.844343113219;9068.821053263033;9702.75743106428;9710.465924373944;9739.123478886933;7027.227005029545;7129.857402851811;7166.140063192451;7227.494075414881;7280.745173918887;7367.484276100621;8052.275295223458;8120.228578285053;8136.023677158779;8139.430239883388;5776.797812076557;6306.4639505642535;6540.018997150465;6582.868342633216;6696.5647232929705;7082.893756593759;7093.077899872984;7113.324234197743;7129.679164437509;7209.531380460253;6715.1941766988275;6732.023856540676;6817.659117044227;6830.965845170742;6840.931590684029;4854.980454104678;4879.170541007966;4888.315635810918;4896.559309662148;4902.2156454967035;4562.817487300604;4759.1671457495495;4787.210110331756;4917.655764096479;4980.427250866197 12:54:25 INFO - PROCESS | 1909 | |4;prototype.html;272.7231859662443;312.13538498359543;318.3664507629816;321.8744689571068;324.77590462580804;4953.281774142747;5197.662151960125;5233.461771041047;5330.840074797758;5370.812021579245;5047.570956468696;5148.510891465314;5181.818636347719;5182.455842136578;5373.086575282202;3413.6004238262317;3554.6800787929083;3595;3776.9046976376817;4112.342025275956;3635.672789448949;3927.253821773854;4020.241074595263;4080.837156307162;4111.671066314688;3946.4481966230337;3981.343078392076;4047.6964227682893;4072.5927407259333;4086.4891888513935;4045.575214602454;4060.431539584431;4064.309067458539;4075.8369665213354;4084.9387259191135;3111.315510587667;3397.570736923087;3554.449060395617;3557.839897204632;3567.8037707926055;4281.207976524353;4964.87587810304;5182.300389447451;5206.271122042917;5208.609354298443;3852.653261206505;4630.536946305376;4647.488776234612;4669.976650116761;4702.294655801623;5475.4524547544925;5498.725063746837;5504.119340905436;5505.559555235572;5523.751431185607;3893.532776066862;3909.7849618270984;3919.8432062717743;3945.4748960665197;3949.8420063197436;5569.164625306196;5736.332605338537;5782.97108514459;5822.883542329129;5865.618734782225;3418.4359580669407;3529.6823285904393;3988.940165897514;4004.4794176756836;4007.4189242559664;4087.2234275487563;4101.200265948111;4102.651274641629;4122.361034039729;4125.443065186161;4571.9314210286875;4620.145273124483;4693.5071817458675;4703.623710103184;4730.101280756645;4883.511648835088;5588.580856435702;5594.692291923902;5621.269234999506;5645.858853528613;1190.7558950415143;1283.6341642631805;1307.5096838685492;1318.1234479071502;1331.7070244546185;776.2780614029007;882.9337799665051;885.3935054487697;888.7422647432172;913.9991709078548;4949.381327334083;4973.626977976666;4989.675671081368;5001.049380865307;5049.116404629249;5330.786768529293;5408.810691625774;5426.860359324507;5438.537724293479;5458.235846981426;4068.1726200291105;4392.407025051641;4435.733855968588;4825.252085926687;5034.370703662043;2384.4158181245707;2694.9461010780064;2698.6356841826496;2827.8586070696383;2841.2328671258642;4981.501849814989;5231.529162375405;5553.916691249635;5576.553875690016;5606;4810.39870016248;5236.345456817898;5520.972395137998;5556.555475561945;5557.694326812064;5190.8961820762615;5625.746841392147;5639.774409023676;5640.746166422521;5647.322321321386;4818.855434336975;5323.361196656426;5328.6802791832615;5384.326959130109;5438.483344082464;4560.954390456053;5783.624064435799;5809.448102430431;5820.166647506022;5865.208196893364;5002.899942001212;5831.422333468207;5835.94164058354;6018.578699490994;6094.50125448564;3079.4610943085318;3126.5622812806682;3359.3953088444314;3561.1453251220037;3956.4460975464035;5819;5819.854503637443;5825.951328760863;5839.678817665069;5859.326177489473;4690.835820746258;5426;5626.718664066862;5648.096304591246;5657.717114144359;4672.556107169813;5102.923456148235;5247.790088396421;5423.671200555889;5597.524210442158;5298.4171741107675;5443.428440013741;5467.7266136692215;5491.780328786964;5505.339359276913;5456.890862182813;5567.693776842312;5592.5525957922455;5677.318721753416;5684;4571.931421028621;5226.503482169188;5378.892422151614;5535.3357597087;5594.328680558359 12:54:25 INFO - PROCESS | 1909 | |5;yui.html;619.6468013232458;692.5567636712505;693.4591019005178;730.7004128307392;763.6105586151061;13936.930315348422;15057.17185554794;15077.924610376933;15114;15170.544883653494;13348.599542013731;13354.799678004838;13442.73114537711;13519.256440895746;13534.984876134306;686.0703746423599;704.1233664088218;706.6219572528698;708.8405108850493;841.02441168245;847.1824689174945;869.8086420987404;870.3081050564784;870.899846517649;871.0897112517434;922.903095175007;924.6162842420388;925.0887875442717;925.5742358515091;929.665320484625;914.8124634449922;914.8307563100781;916.2395211974045;917.8210249001447;919.7194855569048;582.2808831093596;613.6716856481761;683.5044592670274;683.579598546895;685.0204207982583;14343.78484322736;14542.636434089127;14701.558953231313;14719.484818031318;14835.332410041467;13805.585832425044;14074.577762667137;14093.1817616477;14110.50613228532;14242.216678082701;11482.88517114835;11572.132090093277;11612.303261804234;11709.765804683859;11797.174197806156;3614.8734794282336;3692.723045771551;3767.1900541383734;3806.276807406584;3866.149447121657;80327;83110;83382.58308708479;88447.55776221142;91732.54133729289;10487;10513.106385957126;10543.736406589896;10809.297395669257;10931.289466184673;21049.789502104784;21080.894595526923;21083.156673733185;21089.57820843605;21093.261735839173;7551.320381165721;7713;7907.644156012993;7960.522368657899;8051.315638170821;3827.3876179811095;3866.4973553438435;3878.5733569307918;3948.842046318115;4043.7169398141846;3494.4234201357;3592.407252803258;3616.0779001354485;3619.8371073301764;3624.257027309395;650.7071817682062;652.9249136349285;661.1966460750116;664.4020381656547;671.1141293492543;597.9820605381846;604.1873679900526;618.113007833751;673.1888074869868;685.1914740606132;8622.78443038929;9160.312976526788;9374.250059995184;9380.671676491427;9613.51932403377;522.3288074823834;565.0196908363985;617.7213168740753;647.3914520350855;696.3663066609361;4407.581279778416;4515.774211289422;4590.104929538775;4628.629709623289;4635.096156249499;77961;103968.48015759872;116495.8350416485;118279;120010;105096;105781;108471;108914;109653;76704;93200;95462.5226873875;97511.51244243875;99562;7825.5304681717;8724.56377181151;9071.591778369857;9071.682491112779;9075.50084745319;48951.26573101478;50701.99999999852;53307;56427.71786141043;58271;77605.61197194204;79517;80102;82300.17699822925;84340;10935.781284374427;10960.835587466036;10995.560177592806;11357.659270221906;12060.3216019115;11726.472308746297;12086.335251561248;12270.815937760763;12349.75300494003;12735.55425560101;12684.809727854274;12957.287349195507;13293.003024773374;14033.789493157406;14645.194514301817;11258.943705281421;12095.81856272139;13919.23444210578;13942.930285348508;14102;11699.824502632637;11827.172097953406;12314.261144331369;12343.259404435763;12687.0484713645;9714;11166.665000050012;11459.942700286445;11549.6535103947;12002.39988000614;11092.389918554558;11523.711907202052;11548.600902000713;11562.653120406401;11736.471858766205 12:54:25 INFO - PROCESS | 1909 | __end_tp_report 12:54:25 INFO - PROCESS | 1909 | __start_cc_report 12:54:25 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,1562 12:54:25 INFO - PROCESS | 1909 | __end_cc_report 12:54:25 INFO - PROCESS | 1909 | __startTimestamp1485809665978__endTimestamp 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | ------- Summary: start ------- 12:54:25 INFO - PROCESS | 1909 | Number of tests: 6 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5797.47 Median:6076.61 stddev:635.79 (10.5%) stddev-sans-first:609.10 12:54:25 INFO - PROCESS | 1909 | Values: 3392.6 3445.8 3464.8 3507.0 3524.3 5731.9 5828.7 5858.5 5873.7 5910.1 5871.6 5889.6 5919.7 5927.5 5934.8 5248.2 5275.4 5324.7 5348.4 5369.1 5233.5 5237.0 5237.1 5377.3 5399.8 5248.6 5309.9 5311.4 5312.1 5312.3 5308.2 5331.5 5377.2 5378.4 5386.3 5213.4 5264.9 5319.8 5322.4 5373.1 5677.3 5713.0 5723.6 5738.8 5745.0 6110.2 6206.6 6207.9 6230.5 6234.3 6251.0 6258.0 6270.1 6284.7 6290.2 5776.0 5821.5 5851.0 5879.8 5979.5 6291.7 6318.7 6329.2 6332.9 6349.9 6224.9 6226.6 6246.4 6256.4 6263.2 6118.7 6169.2 6221.6 6235.4 6249.0 5177.8 6122.3 6178.2 6222.0 6227.1 6176.7 6241.9 6246.2 6257.2 6258.1 4984.9 5052.1 5060.2 5066.1 5073.3 3759.9 4440.0 4482.0 4498.0 4550.5 5939.7 6000.8 6013.7 6024.8 6053.5 5204.6 6228.0 6231.3 6235.9 6239.2 5799.5 5854.1 5921.3 6028.4 6056.8 4642.8 5464.1 5466.5 5501.4 5581.7 6375.7 6416.7 6419.3 6433.5 6437.4 6027.4 6237.9 6238.8 6242.3 6248.1 6221.8 6229.7 6284.7 6284.9 6297.5 6205.9 6232.8 6237.7 6249.6 6256.0 6115.4 6238.0 6238.5 6239.8 6254.0 6076.6 6120.8 6143.0 6164.9 6214.8 5144.3 6108.0 6183.4 6185.6 6200.0 5968.1 6118.6 6128.7 6146.5 6165.2 6082.4 6111.3 6177.0 6188.3 6264.4 6046.4 6073.9 6089.7 6126.9 6144.3 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:71883.56 Median:34944.56 stddev:164132.02 (469.7%) stddev-sans-first:164504.89 12:54:25 INFO - PROCESS | 1909 | Values: 474.3 482.2 512.8 532.2 532.9 30650.5 32607.5 41410.8 42629.0 44740.0 43822.8 44113.0 44154.6 44189.0 44266.8 9598.1 9806.3 9814.9 9817.4 9837.6 8986.2 9185.7 11630.7 11863.0 11898.2 11710.4 11749.0 11791.2 11791.9 11802.4 12018.3 12100.4 12103.3 12105.3 12125.2 11627.7 11685.7 11695.6 11697.2 11703.9 41243.0 41564.8 41588.8 41645.8 41664.0 3307.4 3343.5 3386.4 3426.2 3429.0 30336.0 30672.9 31907.8 32080.4 32702.0 3289.1 3327.5 3346.0 3352.1 3417.9 478911.0 482175.0 482568.0 484374.0 485377.0 34651.5 35164.8 35522.1 36767.3 65384.7 35667.0 35702.6 35736.5 35763.8 35764.0 14725.0 14914.8 14921.4 14930.6 14936.2 11138.5 11464.4 12626.1 12723.7 12748.9 8016.0 8220.0 8246.8 8258.9 8276.4 3377.2 3412.1 3416.0 3420.5 3431.3 935.1 977.0 980.0 1058.0 1083.1 27108.5 30562.7 30800.1 30997.2 31026.5 967.5 1079.3 1086.9 1130.5 1132.9 15506.6 15913.4 15989.3 16011.9 16304.7 703981.0 712973.0 714491.0 714900.0 715601.0 60943.8 61253.8 61284.0 61426.8 61456.0 1273.6 1317.3 1376.3 1419.8 1433.4 28539.1 30830.7 30892.2 31732.5 32418.0 52507.9 53247.7 53283.7 53351.0 53377.0 563900.0 567402.0 568026.0 568075.0 788508.1 24249.0 26783.9 27769.2 27798.9 28290.2 46784.5 47035.3 47308.8 47891.3 47965.8 39140.6 39457.8 39904.6 39939.0 41449.8 37842.9 38005.2 38705.8 38733.0 39615.2 34724.3 38336.0 39271.4 39284.2 39372.8 33512.7 34185.1 34519.8 35364.5 35609.5 25945.0 26083.5 26280.2 26380.1 26599.6 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:35729.33 Median:2102.93 stddev:99771.51 (4744.4%) stddev-sans-first:100016.09 12:54:25 INFO - PROCESS | 1909 | Values: 377.6 382.6 392.8 402.2 404.7 17880.6 19528.5 21114.9 21748.7 22050.3 20210.3 20474.8 21127.9 21133.2 21216.2 5894.4 5984.6 6126.1 6212.0 6224.8 4402.5 4632.2 4638.4 6070.0 6194.8 4550.9 4604.7 4639.0 6797.2 6882.8 5836.6 6846.8 6852.5 6855.6 6871.6 4732.7 6018.5 6026.9 6027.0 6029.5 13575.0 13641.5 13644.0 13806.2 13810.4 2282.0 2368.0 2407.4 2652.8 2706.2 8257.6 10265.8 10444.0 10676.8 10849.6 1837.3 1841.4 1988.4 2030.5 2136.3 129164.4 142834.0 149815.0 152029.0 158913.2 21184.9 22093.3 22197.9 22227.2 22543.3 36499.9 36588.3 37204.4 37314.0 37610.2 9883.5 11200.9 11271.6 11274.6 11479.1 7583.5 9145.8 9146.5 9164.5 9304.5 9713.8 9815.0 9912.0 9922.3 9953.6 3806.0 3823.3 3832.6 3839.7 3928.7 843.7 960.6 968.6 972.4 997.0 20576.7 20908.3 20950.0 21437.5 21542.7 943.4 945.1 947.7 948.1 1024.1 9245.1 10134.9 10475.2 10749.0 10989.6 587248.0 588818.0 593689.0 606731.0 607892.0 64127.4 78897.1 105884.0 124869.0 131782.3 963.5 977.4 985.5 1004.2 1006.5 19085.7 19821.9 19895.6 19906.5 19942.8 39080.4 46970.0 55157.0 56626.0 57095.0 72899.8 73185.6 74411.0 78275.0 78454.0 6660.0 7157.6 7508.4 8215.5 8239.6 7396.9 7741.0 9825.8 13090.7 13189.8 11078.6 11246.6 11322.5 11338.9 11692.2 9810.3 10355.3 10666.7 10978.5 11063.3 10296.3 10344.7 11450.2 11743.0 12008.5 7325.2 7537.0 7577.6 7654.8 7794.5 9926.3 11082.4 11093.7 11310.7 11476.9 12:54:25 INFO - PROCESS | 1909 | 12:54:25 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5476.30 Median:5288.12 stddev:2987.04 (56.5%) stddev-sans-first:2980.28 12:54:25 INFO - PROCESS | 1909 | Values: 1474.7 1516.8 1691.6 1842.5 1877.1 8168.1 8593.0 8607.8 8619.7 8627.8 7544.4 7773.9 7784.4 7876.8 8413.2 1808.4 2194.2 2323.9 2393.8 2401.1 2409.4 2412.4 2415.3 2416.6 2418.0 2348.8 2402.2 2403.5 2405.9 2407.1 2015.9 2360.5 2384.6 2386.8 2390.4 2378.8 2384.6 2402.7 2406.2 2406.2 8361.7 8543.7 8545.9 8548.4 8556.8 5122.8 5273.4 5281.9 5286.4 5289.9 3322.6 3525.5 3603.9 3621.8 3626.5 1034.4 1063.8 1084.5 1104.2 1107.4 11177.2 11196.1 11202.7 11221.3 11226.3 4599.3 4662.3 4746.6 4749.1 4807.0 9644.2 9663.7 9668.0 9676.2 9679.2 5308.2 5322.8 5327.5 5331.5 5332.4 4116.2 4648.5 4794.3 4901.5 4911.0 3631.2 3741.2 3749.3 3756.7 3762.2 1216.8 1248.3 1267.9 1295.7 1297.0 723.2 750.8 771.5 885.8 888.9 5713.8 5838.6 5970.7 5997.2 6117.5 895.4 899.7 907.0 940.4 1001.6 3656.4 3843.2 3855.2 3946.3 3953.6 8619.5 9774.6 10186.8 10201.6 10285.8 9482.9 9922.3 9939.5 10109.5 10144.8 7738.7 7936.1 7949.8 7952.9 7956.8 4632.9 5344.1 5793.2 5824.8 5853.4 9130.6 9415.6 9425.9 9565.8 9566.9 7782.8 9068.8 9702.8 9710.5 9739.1 7027.2 7129.9 7166.1 7227.5 7280.7 7367.5 8052.3 8120.2 8136.0 8139.4 5776.8 6306.5 6540.0 6582.9 6696.6 7082.9 7093.1 7113.3 7129.7 7209.5 6715.2 6732.0 6817.7 6831.0 6840.9 4855.0 4879.2 4888.3 4896.6 4902.2 4562.8 4759.2 4787.2 4917.7 4980.4 12:54:26 INFO - PROCESS | 1909 | 12:54:26 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4483.20 Median:5040.97 stddev:1381.22 (27.4%) stddev-sans-first:1348.45 12:54:26 INFO - PROCESS | 1909 | Values: 272.7 312.1 318.4 321.9 324.8 4953.3 5197.7 5233.5 5330.8 5370.8 5047.6 5148.5 5181.8 5182.5 5373.1 3413.6 3554.7 3595.0 3776.9 4112.3 3635.7 3927.3 4020.2 4080.8 4111.7 3946.4 3981.3 4047.7 4072.6 4086.5 4045.6 4060.4 4064.3 4075.8 4084.9 3111.3 3397.6 3554.4 3557.8 3567.8 4281.2 4964.9 5182.3 5206.3 5208.6 3852.7 4630.5 4647.5 4670.0 4702.3 5475.5 5498.7 5504.1 5505.6 5523.8 3893.5 3909.8 3919.8 3945.5 3949.8 5569.2 5736.3 5783.0 5822.9 5865.6 3418.4 3529.7 3988.9 4004.5 4007.4 4087.2 4101.2 4102.7 4122.4 4125.4 4571.9 4620.1 4693.5 4703.6 4730.1 4883.5 5588.6 5594.7 5621.3 5645.9 1190.8 1283.6 1307.5 1318.1 1331.7 776.3 882.9 885.4 888.7 914.0 4949.4 4973.6 4989.7 5001.0 5049.1 5330.8 5408.8 5426.9 5438.5 5458.2 4068.2 4392.4 4435.7 4825.3 5034.4 2384.4 2694.9 2698.6 2827.9 2841.2 4981.5 5231.5 5553.9 5576.6 5606.0 4810.4 5236.3 5521.0 5556.6 5557.7 5190.9 5625.7 5639.8 5640.7 5647.3 4818.9 5323.4 5328.7 5384.3 5438.5 4561.0 5783.6 5809.4 5820.2 5865.2 5002.9 5831.4 5835.9 6018.6 6094.5 3079.5 3126.6 3359.4 3561.1 3956.4 5819.0 5819.9 5826.0 5839.7 5859.3 4690.8 5426.0 5626.7 5648.1 5657.7 4672.6 5102.9 5247.8 5423.7 5597.5 5298.4 5443.4 5467.7 5491.8 5505.3 5456.9 5567.7 5592.6 5677.3 5684.0 4571.9 5226.5 5378.9 5535.3 5594.3 12:54:26 INFO - PROCESS | 1909 | 12:54:26 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:21131.77 Median:3913.71 stddev:31466.51 (804.0%) stddev-sans-first:31517.09 12:54:26 INFO - PROCESS | 1909 | Values: 619.6 692.6 693.5 730.7 763.6 13936.9 15057.2 15077.9 15114.0 15170.5 13348.6 13354.8 13442.7 13519.3 13535.0 686.1 704.1 706.6 708.8 841.0 847.2 869.8 870.3 870.9 871.1 922.9 924.6 925.1 925.6 929.7 914.8 914.8 916.2 917.8 919.7 582.3 613.7 683.5 683.6 685.0 14343.8 14542.6 14701.6 14719.5 14835.3 13805.6 14074.6 14093.2 14110.5 14242.2 11482.9 11572.1 11612.3 11709.8 11797.2 3614.9 3692.7 3767.2 3806.3 3866.1 80327.0 83110.0 83382.6 88447.6 91732.5 10487.0 10513.1 10543.7 10809.3 10931.3 21049.8 21080.9 21083.2 21089.6 21093.3 7551.3 7713.0 7907.6 7960.5 8051.3 3827.4 3866.5 3878.6 3948.8 4043.7 3494.4 3592.4 3616.1 3619.8 3624.3 650.7 652.9 661.2 664.4 671.1 598.0 604.2 618.1 673.2 685.2 8622.8 9160.3 9374.3 9380.7 9613.5 522.3 565.0 617.7 647.4 696.4 4407.6 4515.8 4590.1 4628.6 4635.1 77961.0 103968.5 116495.8 118279.0 120010.0 105096.0 105781.0 108471.0 108914.0 109653.0 76704.0 93200.0 95462.5 97511.5 99562.0 7825.5 8724.6 9071.6 9071.7 9075.5 48951.3 50702.0 53307.0 56427.7 58271.0 77605.6 79517.0 80102.0 82300.2 84340.0 10935.8 10960.8 10995.6 11357.7 12060.3 11726.5 12086.3 12270.8 12349.8 12735.6 12684.8 12957.3 13293.0 14033.8 14645.2 11258.9 12095.8 13919.2 13942.9 14102.0 11699.8 11827.2 12314.3 12343.3 12687.0 9714.0 11166.7 11459.9 11549.7 12002.4 11092.4 11523.7 11548.6 11562.7 11736.5 12:54:26 INFO - PROCESS | 1909 | -------- Summary: end -------- 12:54:26 INFO - PROCESS | 1909 | 12:54:26 INFO - TEST-INFO | 1909: exit 0 12:54:26 INFO - TEST-OK | dromaeo_css | took 1116876ms 12:54:26 INFO - TEST-START | kraken 12:54:26 INFO - operating with platform_type : mac_ 12:54:26 INFO - Initialising browser for kraken test... 12:54:26 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpRq4elJ/profile http://localhost:57171/getInfo.html) 12:54:29 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 12:54:29 INFO - PROCESS | 1912 | colorDepth:24 12:54:29 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 12:54:29 INFO - PROCESS | 1912 | __metrics 12:54:29 INFO - PROCESS | 1912 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:54:29 INFO - PROCESS | 1912 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:54:29 INFO - TEST-INFO | 1912: exit 0 12:54:29 INFO - Browser initialized. 12:54:29 INFO - Running cycle 1/1 for kraken test... 12:54:30 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpRq4elJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:42 INFO - PROCESS | 1914 | RSS: Main: 171798528 12:54:42 INFO - PROCESS | 1914 | 12:54:44 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57171/tests/kraken/driver.html?testName=audio-beat-detection) 12:54:45 INFO - PROCESS | 1914 | RSS: Main: 222003200 12:54:45 INFO - PROCESS | 1914 | 12:54:48 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57171/tests/kraken/driver.html?testName=audio-dft) 12:54:49 INFO - PROCESS | 1914 | RSS: Main: 234082304 12:54:49 INFO - PROCESS | 1914 | 12:54:52 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57171/tests/kraken/driver.html?testName=audio-fft) 12:54:52 INFO - PROCESS | 1914 | RSS: Main: 333430784 12:54:52 INFO - PROCESS | 1914 | 12:54:55 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57171/tests/kraken/driver.html?testName=audio-oscillator) 12:54:55 INFO - PROCESS | 1914 | RSS: Main: 233897984 12:54:55 INFO - PROCESS | 1914 | 12:54:57 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57171/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:54:58 INFO - PROCESS | 1914 | RSS: Main: 235552768 12:54:58 INFO - PROCESS | 1914 | 12:55:00 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57171/tests/kraken/driver.html?testName=imaging-darkroom) 12:55:01 INFO - PROCESS | 1914 | RSS: Main: 274485248 12:55:01 INFO - PROCESS | 1914 | 12:55:04 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57171/tests/kraken/driver.html?testName=imaging-desaturate) 12:55:05 INFO - PROCESS | 1914 | RSS: Main: 276946944 12:55:05 INFO - PROCESS | 1914 | 12:55:07 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57171/tests/kraken/driver.html?testName=json-parse-financial) 12:55:08 INFO - PROCESS | 1914 | RSS: Main: 275550208 12:55:08 INFO - PROCESS | 1914 | 12:55:09 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57171/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:55:10 INFO - PROCESS | 1914 | RSS: Main: 248844288 12:55:10 INFO - PROCESS | 1914 | 12:55:12 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:55:13 INFO - PROCESS | 1914 | RSS: Main: 251695104 12:55:13 INFO - PROCESS | 1914 | 12:55:15 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:55:15 INFO - PROCESS | 1914 | RSS: Main: 254263296 12:55:15 INFO - PROCESS | 1914 | 12:55:18 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:55:19 INFO - PROCESS | 1914 | RSS: Main: 268361728 12:55:19 INFO - PROCESS | 1914 | 12:55:22 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:55:22 INFO - PROCESS | 1914 | RSS: Main: 268435456 12:55:22 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:57171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57171/tests/kraken/driver.html?testName=ai-astar) 12:55:24 INFO - PROCESS | 1914 | RSS: Main: 312172544 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | __start_tp_report 12:55:24 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 12:55:24 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 12:55:24 INFO - PROCESS | 1914 | |i|pagename|runs| 12:55:24 INFO - PROCESS | 1914 | |0;ai-astar;114;114;110;113;113;114;113;112;113;112 12:55:24 INFO - PROCESS | 1914 | |1;audio-beat-detection;171;138;94;164;145;139;111;107;136;106 12:55:24 INFO - PROCESS | 1914 | |2;audio-dft;189;254;183;181;185;182;185;184;184;184 12:55:24 INFO - PROCESS | 1914 | |3;audio-fft;81;60;61;96;90;83;48;71;102;84 12:55:24 INFO - PROCESS | 1914 | |4;audio-oscillator;93;70;92;69;68;69;88;69;69;91 12:55:24 INFO - PROCESS | 1914 | |5;imaging-gaussian-blur;89;86;89;85;89;86;89;88;89;86 12:55:24 INFO - PROCESS | 1914 | |6;imaging-darkroom;98;97;95;96;127;124;96;97;96;98 12:55:24 INFO - PROCESS | 1914 | |7;imaging-desaturate;82;81;82;78;84;83;84;86;82;85 12:55:24 INFO - PROCESS | 1914 | |8;json-parse-financial;56;55;53;53;56;58;58;57;56;56 12:55:24 INFO - PROCESS | 1914 | |9;json-stringify-tinderbox;63;64;78;74;72;68;60;67;72;101 12:55:24 INFO - PROCESS | 1914 | |10;stanford-crypto-aes;81;81;81;82;82;82;81;74;94;100 12:55:24 INFO - PROCESS | 1914 | |11;stanford-crypto-ccm;135;138;136;136;137;140;141;137;197;139 12:55:24 INFO - PROCESS | 1914 | |12;stanford-crypto-pbkdf2;162;161;161;163;164;214;215;160;161;161 12:55:24 INFO - PROCESS | 1914 | |13;stanford-crypto-sha256-iterative;67;66;66;66;65;68;67;69;66;67 12:55:24 INFO - PROCESS | 1914 | __end_tp_report 12:55:24 INFO - PROCESS | 1914 | __start_cc_report 12:55:24 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,1919 12:55:24 INFO - PROCESS | 1914 | __end_cc_report 12:55:24 INFO - PROCESS | 1914 | __startTimestamp1485809724896__endTimestamp 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | ------- Summary: start ------- 12:55:24 INFO - PROCESS | 1914 | Number of tests: 14 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.23 (1.1%) stddev-sans-first:1.22 12:55:24 INFO - PROCESS | 1914 | Values: 114.0 114.0 110.0 113.0 113.0 114.0 113.0 112.0 113.0 112.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:142.00 stddev:25.80 (18.2%) stddev-sans-first:22.98 12:55:24 INFO - PROCESS | 1914 | Values: 171.0 138.0 94.0 164.0 145.0 139.0 111.0 107.0 136.0 106.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#2] audio-dft Cycles:10 Average:191.10 Median:184.50 stddev:22.20 (12.0%) stddev-sans-first:23.54 12:55:24 INFO - PROCESS | 1914 | Values: 189.0 254.0 183.0 181.0 185.0 182.0 185.0 184.0 184.0 184.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#3] audio-fft Cycles:10 Average:77.60 Median:82.00 stddev:17.24 (21.0%) stddev-sans-first:18.24 12:55:24 INFO - PROCESS | 1914 | Values: 81.0 60.0 61.0 96.0 90.0 83.0 48.0 71.0 102.0 84.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.44 (14.5%) stddev-sans-first:10.73 12:55:24 INFO - PROCESS | 1914 | Values: 93.0 70.0 92.0 69.0 68.0 69.0 88.0 69.0 69.0 91.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:89.00 stddev:1.65 (1.9%) stddev-sans-first:1.67 12:55:24 INFO - PROCESS | 1914 | Values: 89.0 86.0 89.0 85.0 89.0 86.0 89.0 88.0 89.0 86.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:96.50 stddev:12.23 (12.7%) stddev-sans-first:12.87 12:55:24 INFO - PROCESS | 1914 | Values: 98.0 97.0 95.0 96.0 127.0 124.0 96.0 97.0 96.0 98.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.50 stddev:2.26 (2.7%) stddev-sans-first:2.39 12:55:24 INFO - PROCESS | 1914 | Values: 82.0 81.0 82.0 78.0 84.0 83.0 84.0 86.0 82.0 85.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.75 (3.1%) stddev-sans-first:1.86 12:55:24 INFO - PROCESS | 1914 | Values: 56.0 55.0 53.0 53.0 56.0 58.0 58.0 57.0 56.0 56.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:70.00 stddev:11.60 (16.6%) stddev-sans-first:11.85 12:55:24 INFO - PROCESS | 1914 | Values: 63.0 64.0 78.0 74.0 72.0 68.0 60.0 67.0 72.0 101.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:81.50 stddev:7.48 (9.2%) stddev-sans-first:7.87 12:55:24 INFO - PROCESS | 1914 | Values: 81.0 81.0 81.0 82.0 82.0 82.0 81.0 74.0 94.0 100.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:138.50 stddev:18.86 (13.6%) stddev-sans-first:19.74 12:55:24 INFO - PROCESS | 1914 | Values: 135.0 138.0 136.0 136.0 137.0 140.0 141.0 137.0 197.0 139.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:162.50 stddev:22.32 (13.7%) stddev-sans-first:23.37 12:55:24 INFO - PROCESS | 1914 | Values: 162.0 161.0 161.0 163.0 164.0 214.0 215.0 160.0 161.0 161.0 12:55:24 INFO - PROCESS | 1914 | 12:55:24 INFO - PROCESS | 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 12:55:24 INFO - PROCESS | 1914 | Values: 67.0 66.0 66.0 66.0 65.0 68.0 67.0 69.0 66.0 67.0 12:55:24 INFO - PROCESS | 1914 | -------- Summary: end -------- 12:55:24 INFO - PROCESS | 1914 | 12:55:25 INFO - TEST-INFO | 1914: exit 0 12:55:25 INFO - TEST-OK | kraken | took 59344ms 12:55:25 INFO - SUITE-END | took 1176s 12:55:26 INFO - Completed test suite (00:19:36) 12:55:26 INFO - javascript benchmark 12:55:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.5759280089987, 3445.844936977836, 3464.792112473252, 3506.964930350699, 3524.295140971805, 5731.885362292752, 5828.737706803194, 5858.5313174946, 5873.676947767871, 5910.054391297399, 5871.647701137935, 5889.6171748836405, 5919.704014799265, 5927.525797936166, 5934.8219553413355, 5248.212768084799, 5275.446078161776, 5324.653897496651, 5348.358197016363, 5369.114096174127, 5233.528982391584, 5237.0, 5237.083510385667, 5377.274068000828, 5399.838004859841, 5248.580113590903, 5309.867253318678, 5311.442298558653, 5312.096943519611, 5312.335958005249, 5308.203769434577, 5331.493508116722, 5377.247185394048, 5378.381486129106, 5386.326709161355, 5213.4004589472315, 5264.947350526522, 5319.760610772486, 5322.387925388591, 5373.059714549938, 5677.290338707661, 5712.971435142797, 5723.599348045638, 5738.7991420300505, 5744.971275143639, 6110.172019488887, 6206.596571222856, 6207.913615117382, 6230.501559875244, 6234.314225435199, 6251.0, 6258.0, 6270.059491076329, 6284.654344011077, 6290.245170579514, 5776.046952252873, 5821.476067153976, 5851.0, 5879.823605291848, 5979.4618484336615, 6291.748330066837, 6318.652474113968, 6329.240491141092, 6332.905006424908, 6349.936500635027, 6224.9377506224355, 6226.62640241587, 6246.437820596168, 6256.405641464054, 6263.185785847811, 6118.694065296718, 6169.167162433104, 6221.626702397871, 6235.438810506986, 6249.0, 5177.767000484987, 6122.316816195983, 6178.227721534809, 6222.0355844844125, 6227.06594010893, 6176.69116544171, 6241.906371404432, 6246.187995560639, 6257.217847769029, 6258.092576576371, 4984.925226121611, 5052.141136006814, 5060.165072762953, 5066.138756411419, 5073.315102461121, 3759.909626208369, 4440.0, 4481.955180448153, 4498.0104377037, 4550.453945526492, 5939.732712027882, 6000.819975400745, 6013.729382177812, 6024.759009639653, 6053.51571874249, 5204.557612602971, 6227.96886015567, 6231.2522497300615, 6235.875282494416, 6239.220097487814, 5799.536037117021, 5854.063349864001, 5921.348651648315, 6028.3971602839365, 6056.788012419544, 4642.837500687459, 5464.12573988168, 5466.508014278734, 5501.449855014466, 5581.748821303051, 6375.71309291083, 6416.711247993758, 6419.325970773001, 6433.453156481658, 6437.356264373525, 6027.427394397525, 6237.937620623918, 6238.781642642486, 6242.250929888442, 6248.062790581267, 6221.751129954751, 6229.719662615103, 6284.748610055546, 6284.905726414199, 6297.52768542352, 6205.875882482415, 6232.844178895564, 6237.688115594111, 6249.593776404519, 6256.0, 6115.4190351916495, 6238.001919692827, 6238.500919926304, 6239.844003899939, 6254.030625253093, 6076.605020673642, 6120.816375508742, 6143.020634991936, 6164.907526387018, 6214.844628884314, 5144.279800827812, 6108.008189064015, 6183.443490085914, 6185.597936134137, 6199.969000155151, 5968.134620480093, 6118.60229085108, 6128.66292353907, 6146.477549408351, 6165.167702360303, 6082.391760824059, 6111.348653136637, 6177.0, 6188.271912847395, 6264.436200741955, 6046.425589568984, 6073.937061014393, 6089.725962331617, 6126.866639400673, 6144.324124346408], "value": 5758.360699110011, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.2601541595111, 482.1730731794972, 512.800007996881, 532.2123257578784, 532.9173978033404, 30650.54024189636, 32607.51088733671, 41410.79294603527, 42629.0, 44740.0, 43822.78088609553, 44113.0, 44154.55845441545, 44189.0, 44266.77866610671, 9598.136167744902, 9806.264530160232, 9814.901850981505, 9817.410955342666, 9837.606495740161, 8986.191242788147, 9185.678501252458, 11630.651080467556, 11862.991645710124, 11898.167128301022, 11710.414479276045, 11749.0, 11791.233569817978, 11791.882081179207, 11802.350870702108, 12018.278903265787, 12100.394980250952, 12103.334316612583, 12105.334206618632, 12125.151239413244, 11627.6511704649, 11685.70785730355, 11695.590654327058, 11697.239679420896, 11703.94148029263, 41243.0, 41564.79217603923, 41588.75233742962, 41645.79177104125, 41664.0, 3307.4377355849565, 3343.498475228735, 3386.407378708741, 3426.194844211608, 3428.9828550857337, 30336.0, 30672.92644757445, 31907.84046079778, 32080.35839283201, 32702.0, 3289.079057863802, 3327.4676051831834, 3346.0, 3352.1116904020455, 3417.923354143437, 478911.0, 482175.0, 482568.0, 484374.0, 485377.0, 34651.4802277966, 35164.82417587947, 35522.0925698173, 36767.264654707295, 65384.67307663431, 35667.0, 35702.64297357045, 35736.46395304072, 35763.82118089393, 35764.0, 14725.0, 14914.776278355834, 14921.40314387434, 14930.552083437515, 14936.178510182044, 11138.49876755548, 11464.369459679798, 12626.116171868065, 12723.681907952376, 12748.916342110839, 8015.998000249969, 8220.0, 8246.793830154294, 8258.917410825814, 8276.379271554537, 3377.2401209728105, 3412.061683037135, 3416.0435078178148, 3420.521127042216, 3431.262278610111, 935.1350001248818, 976.9755756106155, 979.9706008819746, 1058.0424715632364, 1083.128081894067, 27108.457830843272, 30562.694373055983, 30800.075997720105, 30997.225069373446, 31026.534601980988, 967.4920666649924, 1079.2930630437077, 1086.8641419822522, 1130.5421304371744, 1132.8753837077916, 15506.612334691723, 15913.363465461252, 15989.280482378088, 16011.919940400223, 16304.673906521803, 703981.0, 712973.0, 714491.0, 714900.0, 715601.0, 60943.781124378154, 61253.77492450126, 61284.0, 61426.77146457135, 61456.0, 1273.6433798536239, 1317.2623330934707, 1376.2980879751399, 1419.7728363461797, 1433.4481224728347, 28539.143825685263, 30830.691693082783, 30892.22769430782, 31732.524012139376, 32418.02745917626, 52507.9498410022, 53247.73376133094, 53283.73358133184, 53351.0, 53377.0, 563900.0, 567402.0, 568026.0, 568075.0, 788508.057459709, 24249.0, 26783.86608066947, 27769.16692499228, 27798.861005694842, 28290.151295461168, 46784.53215467802, 47035.294470583656, 47308.76345618388, 47891.28163077487, 47965.760171200316, 39140.6085939137, 39457.80271098626, 39904.60095399125, 39939.0, 41449.79275103605, 37842.864714058625, 38005.23989520139, 38705.806470967465, 38733.0, 39615.2076958465, 34724.30551389009, 38336.0, 39271.41092883666, 39284.214315714096, 39372.80313598413, 33512.66487335096, 34185.06871709785, 34519.82740086384, 35364.469532956515, 35609.46585801163, 25945.0, 26083.478330433663, 26280.229904572534, 26380.0766973155, 26599.60100598454], "value": 19244.763713232802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.6167190301844, 382.5600559356739, 392.75060336686215, 402.2357520710651, 404.6722155054406, 17880.642387152282, 19528.511787205327, 21114.894425527888, 21748.673769893445, 22050.33848984531, 20210.292639757612, 20474.795252047476, 21127.894360528175, 21133.154673813067, 21216.151353945825, 5894.351621321651, 5984.64092154471, 6126.142340072393, 6211.968940155293, 6224.8132556023165, 4402.4937132229725, 4632.235681112612, 4638.350630911675, 6069.969650151743, 6194.7831825886105, 4550.863474095782, 4604.654650901179, 4638.97680511597, 6797.184337879461, 6882.827929301758, 5836.620619659709, 6846.828829279283, 6852.45180385568, 6855.58866468011, 6871.553349032322, 4732.668713190078, 6018.548608854309, 6026.879462410728, 6027.035674292092, 6029.517638588903, 13575.0, 13641.454341826315, 13644.0, 13806.17162970225, 13810.447582096804, 2282.0073268128417, 2367.9644805327935, 2407.4222186675247, 2652.774514166298, 2706.201670507205, 8257.628406721711, 10265.846012309821, 10444.0, 10676.786464270748, 10849.620263290746, 1837.3018253063883, 1841.4107485604611, 1988.3836010836687, 2030.532977415188, 2136.273666953228, 129164.3541782285, 142834.0, 149815.0, 152029.0, 158913.2054339721, 21184.894075529523, 22093.33719988403, 22197.891200336264, 22227.22204722859, 22543.323700289016, 36499.897508711525, 36588.26823463516, 37204.44193337048, 37314.0, 37610.24779504394, 9883.505824708736, 11200.887991120147, 11271.605493807841, 11274.605388811353, 11479.081673466102, 7583.544987300779, 9145.811044564163, 9146.496942668216, 9164.541772911327, 9304.488253146006, 9713.805723885484, 9814.95092524547, 9911.950440247752, 9922.305438619227, 9953.552090155961, 3805.980970095132, 3823.2926908522013, 3832.597577254404, 3839.6544311012144, 3928.744631598937, 843.6920524008692, 960.6109525642127, 968.6125549780004, 972.4311277902365, 996.9651062212932, 20576.691349629768, 20908.268210612554, 20950.0, 21437.46406339854, 21542.676859847114, 943.3726571829657, 945.0974319524837, 947.7298969793713, 948.127722495306, 1024.1294899335505, 9245.121713437213, 10134.949325253474, 10475.161987041018, 10749.032587067202, 10989.6153634624, 587248.0, 588818.0, 593689.0, 606731.0, 607892.0, 64127.35872641214, 78897.08271879632, 105884.0, 124869.0, 131782.34108829586, 963.5375019990444, 977.379364103785, 985.4530735441755, 1004.1665417703289, 1006.4716024087413, 19085.71371429402, 19821.900890495453, 19895.60208795845, 19906.50233744168, 19942.800571994096, 39080.41379379368, 46970.0, 55157.0, 56626.0, 57095.0, 72899.80351670168, 73185.6340718293, 74411.0, 78275.0, 78454.0, 6659.966700166468, 7157.64211789398, 7508.399328053633, 8215.465994710325, 8239.629216685144, 7396.852062958818, 7740.954971078832, 9825.754356141153, 13090.672733181365, 13189.80215296752, 11078.612248571262, 11246.550137994389, 11322.547098115983, 11338.886611133783, 11692.240004399688, 9810.313278070753, 10355.275130741076, 10666.679999600025, 10978.451077445936, 11063.336199828036, 10296.279260451696, 10344.689659310232, 11450.198486106226, 11743.0, 12008.519659213884, 7325.157606875277, 7537.0, 7577.583232922242, 7654.8086297843, 7794.493357931717, 9926.305158638826, 11082.390468524309, 11093.722656933642, 11310.717232068935, 11476.942615286871], "value": 10268.691065081584, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.7124310759407, 1516.7573188289873, 1691.6447546015336, 1842.4841044507543, 1877.14589861613, 8168.142345053766, 8593.0, 8607.78480537986, 8619.741407757761, 8627.784305392368, 7544.3964482841375, 7773.922260777391, 7784.416168787336, 7876.842463150748, 8413.158684131598, 1808.4303444414968, 2194.188150384352, 2323.8954247058837, 2393.8324317297797, 2401.111588712179, 2409.361519197414, 2412.433078226615, 2415.2874901903892, 2416.6012607919674, 2417.9758202417925, 2348.8473249238746, 2402.1952645863657, 2403.507281007398, 2405.8797060147012, 2407.1093695332665, 2015.8790472571693, 2360.5042940982416, 2384.6423036544484, 2386.7613238676167, 2390.438247011951, 2378.774016468433, 2384.6303822907303, 2402.7356990731005, 2406.205952035824, 2406.2420337593603, 8361.665533378658, 8543.658253669846, 8545.914540854636, 8548.444351117158, 8556.82886342276, 5122.846314610568, 5273.419923808378, 5281.867953301198, 5286.365636123652, 5289.86775330616, 3322.6178989416285, 3525.5240542526685, 3603.891883243507, 3621.800800955946, 3626.492291079251, 1034.3586976074882, 1063.8297872340465, 1084.4631907205905, 1104.1553211792984, 1107.379867274321, 11177.21759476837, 11196.104311655048, 11202.663920082328, 11221.270617409842, 11226.326420414802, 4599.264117741212, 4662.347271382009, 4746.5728084471875, 4749.097671442484, 4806.990531988252, 9644.18024467914, 9663.661771838093, 9667.951660241653, 9676.225901927828, 9679.177269932135, 5308.177232528964, 5322.813701520428, 5327.547158491494, 5331.5201631853315, 5332.360116786011, 4116.217918595457, 4648.465426475931, 4794.2808578712775, 4901.534354236342, 4911.042346742351, 3631.2374401375478, 3741.2330472253125, 3749.250149970017, 3756.69946404287, 3762.1535154590547, 1216.823560583728, 1248.2822377133184, 1267.854196767365, 1295.6825577733516, 1297.033709886122, 723.2008630463263, 750.808543821333, 771.4792515052317, 885.818407226517, 888.9199972002551, 5713.800016999385, 5838.649681019152, 5970.745743745563, 5997.220361352996, 6117.480014198843, 895.4403497813867, 899.7480705402497, 906.981860362789, 940.3934462271797, 1001.5793366785967, 3656.3966945453703, 3843.1545060086737, 3855.2289542091694, 3946.2502124596235, 3953.584873588289, 8619.482831030158, 9774.609015639437, 10186.847197292047, 10201.591936322464, 10285.845712314322, 9482.905170948203, 9922.255830812717, 9939.453330066772, 10109.494525273854, 10144.847827282596, 7738.729144479917, 7936.127026027132, 7949.801254968556, 7952.880706789519, 7956.840863182704, 4632.86101416958, 5344.091504444332, 5793.217915581343, 5824.796132135355, 5853.356130825691, 9130.589123489592, 9415.62337506492, 9425.905740942502, 9565.808683826424, 9566.851977762713, 7782.844343113219, 9068.821053263033, 9702.75743106428, 9710.465924373944, 9739.123478886933, 7027.227005029545, 7129.857402851811, 7166.140063192451, 7227.494075414881, 7280.745173918887, 7367.484276100621, 8052.275295223458, 8120.228578285053, 8136.023677158779, 8139.430239883388, 5776.797812076557, 6306.4639505642535, 6540.018997150465, 6582.868342633216, 6696.5647232929705, 7082.893756593759, 7093.077899872984, 7113.324234197743, 7129.679164437509, 7209.531380460253, 6715.1941766988275, 6732.023856540676, 6817.659117044227, 6830.965845170742, 6840.931590684029, 4854.980454104678, 4879.170541007966, 4888.315635810918, 4896.559309662148, 4902.2156454967035, 4562.817487300604, 4759.1671457495495, 4787.210110331756, 4917.655764096479, 4980.427250866197], "value": 4445.89358460634, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.7231859662443, 312.13538498359543, 318.3664507629816, 321.8744689571068, 324.77590462580804, 4953.281774142747, 5197.662151960125, 5233.461771041047, 5330.840074797758, 5370.812021579245, 5047.570956468696, 5148.510891465314, 5181.818636347719, 5182.455842136578, 5373.086575282202, 3413.6004238262317, 3554.6800787929083, 3595.0, 3776.9046976376817, 4112.342025275956, 3635.672789448949, 3927.253821773854, 4020.241074595263, 4080.837156307162, 4111.671066314688, 3946.4481966230337, 3981.343078392076, 4047.6964227682893, 4072.5927407259333, 4086.4891888513935, 4045.575214602454, 4060.431539584431, 4064.309067458539, 4075.8369665213354, 4084.9387259191135, 3111.315510587667, 3397.570736923087, 3554.449060395617, 3557.839897204632, 3567.8037707926055, 4281.207976524353, 4964.87587810304, 5182.300389447451, 5206.271122042917, 5208.609354298443, 3852.653261206505, 4630.536946305376, 4647.488776234612, 4669.976650116761, 4702.294655801623, 5475.4524547544925, 5498.725063746837, 5504.119340905436, 5505.559555235572, 5523.751431185607, 3893.532776066862, 3909.7849618270984, 3919.8432062717743, 3945.4748960665197, 3949.8420063197436, 5569.164625306196, 5736.332605338537, 5782.97108514459, 5822.883542329129, 5865.618734782225, 3418.4359580669407, 3529.6823285904393, 3988.940165897514, 4004.4794176756836, 4007.4189242559664, 4087.2234275487563, 4101.200265948111, 4102.651274641629, 4122.361034039729, 4125.443065186161, 4571.9314210286875, 4620.145273124483, 4693.5071817458675, 4703.623710103184, 4730.101280756645, 4883.511648835088, 5588.580856435702, 5594.692291923902, 5621.269234999506, 5645.858853528613, 1190.7558950415143, 1283.6341642631805, 1307.5096838685492, 1318.1234479071502, 1331.7070244546185, 776.2780614029007, 882.9337799665051, 885.3935054487697, 888.7422647432172, 913.9991709078548, 4949.381327334083, 4973.626977976666, 4989.675671081368, 5001.049380865307, 5049.116404629249, 5330.786768529293, 5408.810691625774, 5426.860359324507, 5438.537724293479, 5458.235846981426, 4068.1726200291105, 4392.407025051641, 4435.733855968588, 4825.252085926687, 5034.370703662043, 2384.4158181245707, 2694.9461010780064, 2698.6356841826496, 2827.8586070696383, 2841.2328671258642, 4981.501849814989, 5231.529162375405, 5553.916691249635, 5576.553875690016, 5606.0, 4810.39870016248, 5236.345456817898, 5520.972395137998, 5556.555475561945, 5557.694326812064, 5190.8961820762615, 5625.746841392147, 5639.774409023676, 5640.746166422521, 5647.322321321386, 4818.855434336975, 5323.361196656426, 5328.6802791832615, 5384.326959130109, 5438.483344082464, 4560.954390456053, 5783.624064435799, 5809.448102430431, 5820.166647506022, 5865.208196893364, 5002.899942001212, 5831.422333468207, 5835.94164058354, 6018.578699490994, 6094.50125448564, 3079.4610943085318, 3126.5622812806682, 3359.3953088444314, 3561.1453251220037, 3956.4460975464035, 5819.0, 5819.854503637443, 5825.951328760863, 5839.678817665069, 5859.326177489473, 4690.835820746258, 5426.0, 5626.718664066862, 5648.096304591246, 5657.717114144359, 4672.556107169813, 5102.923456148235, 5247.790088396421, 5423.671200555889, 5597.524210442158, 5298.4171741107675, 5443.428440013741, 5467.7266136692215, 5491.780328786964, 5505.339359276913, 5456.890862182813, 5567.693776842312, 5592.5525957922455, 5677.318721753416, 5684.0, 4571.931421028621, 5226.503482169188, 5378.892422151614, 5535.3357597087, 5594.328680558359], "value": 4041.1619397804466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.6468013232458, 692.5567636712505, 693.4591019005178, 730.7004128307392, 763.6105586151061, 13936.930315348422, 15057.17185554794, 15077.924610376933, 15114.0, 15170.544883653494, 13348.599542013731, 13354.799678004838, 13442.73114537711, 13519.256440895746, 13534.984876134306, 686.0703746423599, 704.1233664088218, 706.6219572528698, 708.8405108850493, 841.02441168245, 847.1824689174945, 869.8086420987404, 870.3081050564784, 870.899846517649, 871.0897112517434, 922.903095175007, 924.6162842420388, 925.0887875442717, 925.5742358515091, 929.665320484625, 914.8124634449922, 914.8307563100781, 916.2395211974045, 917.8210249001447, 919.7194855569048, 582.2808831093596, 613.6716856481761, 683.5044592670274, 683.579598546895, 685.0204207982583, 14343.78484322736, 14542.636434089127, 14701.558953231313, 14719.484818031318, 14835.332410041467, 13805.585832425044, 14074.577762667137, 14093.1817616477, 14110.50613228532, 14242.216678082701, 11482.88517114835, 11572.132090093277, 11612.303261804234, 11709.765804683859, 11797.174197806156, 3614.8734794282336, 3692.723045771551, 3767.1900541383734, 3806.276807406584, 3866.149447121657, 80327.0, 83110.0, 83382.58308708479, 88447.55776221142, 91732.54133729289, 10487.0, 10513.106385957126, 10543.736406589896, 10809.297395669257, 10931.289466184673, 21049.789502104784, 21080.894595526923, 21083.156673733185, 21089.57820843605, 21093.261735839173, 7551.320381165721, 7713.0, 7907.644156012993, 7960.522368657899, 8051.315638170821, 3827.3876179811095, 3866.4973553438435, 3878.5733569307918, 3948.842046318115, 4043.7169398141846, 3494.4234201357, 3592.407252803258, 3616.0779001354485, 3619.8371073301764, 3624.257027309395, 650.7071817682062, 652.9249136349285, 661.1966460750116, 664.4020381656547, 671.1141293492543, 597.9820605381846, 604.1873679900526, 618.113007833751, 673.1888074869868, 685.1914740606132, 8622.78443038929, 9160.312976526788, 9374.250059995184, 9380.671676491427, 9613.51932403377, 522.3288074823834, 565.0196908363985, 617.7213168740753, 647.3914520350855, 696.3663066609361, 4407.581279778416, 4515.774211289422, 4590.104929538775, 4628.629709623289, 4635.096156249499, 77961.0, 103968.48015759872, 116495.8350416485, 118279.0, 120010.0, 105096.0, 105781.0, 108471.0, 108914.0, 109653.0, 76704.0, 93200.0, 95462.5226873875, 97511.51244243875, 99562.0, 7825.5304681717, 8724.56377181151, 9071.591778369857, 9071.682491112779, 9075.50084745319, 48951.26573101478, 50701.99999999852, 53307.0, 56427.71786141043, 58271.0, 77605.61197194204, 79517.0, 80102.0, 82300.17699822925, 84340.0, 10935.781284374427, 10960.835587466036, 10995.560177592806, 11357.659270221906, 12060.3216019115, 11726.472308746297, 12086.335251561248, 12270.815937760763, 12349.75300494003, 12735.55425560101, 12684.809727854274, 12957.287349195507, 13293.003024773374, 14033.789493157406, 14645.194514301817, 11258.943705281421, 12095.81856272139, 13919.23444210578, 13942.930285348508, 14102.0, 11699.824502632637, 11827.172097953406, 12314.261144331369, 12343.259404435763, 12687.0484713645, 9714.0, 11166.665000050012, 11459.942700286445, 11549.6535103947, 12002.39988000614, 11092.389918554558, 11523.711907202052, 11548.600902000713, 11562.653120406401, 11736.471858766205], "value": 7351.041001051232, "unit": "score"}], "name": "dromaeo_css", "value": 7291.742212250068, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 110.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 138.0, 94.0, 164.0, 145.0, 139.0, 111.0, 107.0, 136.0, 106.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 254.0, 183.0, 181.0, 185.0, 182.0, 185.0, 184.0, 184.0, 184.0], "value": 191.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 96.0, 90.0, 83.0, 48.0, 71.0, 102.0, 84.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 70.0, 92.0, 69.0, 68.0, 69.0, 88.0, 69.0, 69.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 89.0, 86.0, 89.0, 88.0, 89.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 95.0, 96.0, 127.0, 124.0, 96.0, 97.0, 96.0, 98.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 78.0, 84.0, 83.0, 84.0, 86.0, 82.0, 85.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 53.0, 56.0, 58.0, 58.0, 57.0, 56.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 78.0, 74.0, 72.0, 68.0, 60.0, 67.0, 72.0, 101.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 82.0, 82.0, 82.0, 81.0, 74.0, 94.0, 100.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 136.0, 136.0, 137.0, 140.0, 141.0, 137.0, 197.0, 139.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 163.0, 164.0, 214.0, 215.0, 160.0, 161.0, 161.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 65.0, 68.0, 67.0, 69.0, 66.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1457.1, "alertThreshold": 2.0}]} 12:55:26 INFO - Return code: 0 12:55:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:55:26 INFO - # TBPL SUCCESS # 12:55:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:55:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:55:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:55:26 INFO - 2017-01-30 12:55:26.238 screenresolution[1918:20594] starting screenresolution argv=screenresolution get 12:55:26 INFO - 2017-01-30 12:55:26.241 screenresolution[1918:20594] Display 0: 1600x1200x32@60 12:55:26 INFO - 2017-01-30 12:55:26.247 screenresolution[1919:20598] starting screenresolution argv=screenresolution list 12:55:26 INFO - Available Modes on Display 0 12:55:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:55:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:55:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:55:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:55:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:55:26 INFO - Intel Iris: 12:55:26 INFO - Chipset Model: Intel Iris 12:55:26 INFO - Type: GPU 12:55:26 INFO - Bus: Built-In 12:55:26 INFO - VRAM (Dynamic, Max): 1536 MB 12:55:26 INFO - Vendor: Intel (0x8086) 12:55:26 INFO - Device ID: 0x0a2e 12:55:26 INFO - Revision ID: 0x0009 12:55:26 INFO - Displays: 12:55:26 INFO - Display: 12:55:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:55:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:55:26 INFO - Display Serial Number: 1600x1200 60 12:55:26 INFO - Main Display: Yes 12:55:26 INFO - Mirror: Off 12:55:26 INFO - Online: Yes 12:55:26 INFO - Rotation: Supported 12:55:26 INFO - Return code: 0 12:55:26 INFO - Running post-action listener: _resource_record_post_action 12:55:26 INFO - [mozharness: 2017-01-30 20:55:26.488037Z] Finished run-tests step (success) 12:55:26 INFO - Running post-run listener: _resource_record_post_run 12:55:26 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 364544; Write bytes: 496587776; Read time: 9; Write time: 1487 12:55:26 INFO - TinderboxPrint: CPU usage
53.3% 12:55:26 INFO - TinderboxPrint: I/O read bytes / time
364,544 / 9 12:55:26 INFO - TinderboxPrint: I/O write bytes / time
496,587,776 / 1,487 12:55:26 INFO - TinderboxPrint: CPU idle
2,239.8 (46.7%) 12:55:26 INFO - TinderboxPrint: CPU system
961.5 (20.0%) 12:55:26 INFO - TinderboxPrint: CPU user
1,596.3 (33.3%) 12:55:26 INFO - TinderboxPrint: Swap in / out
191,975,424 / 0 12:55:26 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 192086528; Write bytes: 191971328; Read time: 17727; Write time: 373 12:55:26 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 270336; Write bytes: 303072256; Read time: 7; Write time: 1106 12:55:26 INFO - Running post-run listener: _upload_blobber_files 12:55:26 INFO - Blob upload gear active. 12:55:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:55:26 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 12:55:26 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:55:26 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:55:27 INFO - (blobuploader) - INFO - Open directory for files ... 12:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:55:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:28 INFO - (blobuploader) - INFO - Done attempting. 12:55:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:55:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:29 INFO - (blobuploader) - INFO - Done attempting. 12:55:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:55:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:55:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:31 INFO - (blobuploader) - INFO - Done attempting. 12:55:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:55:31 INFO - Return code: 0 12:55:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:55:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:55:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9f11ca7c0126d6e6df80f8b2252275ead5016184f8e51e64e384dbafc7da7944234f0f7255e1d0c3c529cce389314a4969997df7ad66610e8677c5b4d6e1798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72b15104f1d11b9095a9692d1cd6cc03e70a029e6b0711076a3303192b1a21bca44e0fe1c090d4aabf3701dd87c5ce7a488fdad855e6c6b14f2fd75740a65de6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2593359c07a56b94ca0ff0506cca92625c5fabf27790eb0d532f1b2d2a6b0a1904f763680d6c829e01c9e6eae0f952035451be5b3fbafabbcea127b405360315"} 12:55:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:55:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:55:31 INFO - Contents: 12:55:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9f11ca7c0126d6e6df80f8b2252275ead5016184f8e51e64e384dbafc7da7944234f0f7255e1d0c3c529cce389314a4969997df7ad66610e8677c5b4d6e1798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72b15104f1d11b9095a9692d1cd6cc03e70a029e6b0711076a3303192b1a21bca44e0fe1c090d4aabf3701dd87c5ce7a488fdad855e6c6b14f2fd75740a65de6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2593359c07a56b94ca0ff0506cca92625c5fabf27790eb0d532f1b2d2a6b0a1904f763680d6c829e01c9e6eae0f952035451be5b3fbafabbcea127b405360315"} 12:55:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:55:31 INFO - Copying logs to upload dir... 12:55:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:55:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.114981 ========= master_lag: 1.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-30 12:55:32.723386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 12:55:32.726557) ========= 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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/Mozilla-Beta/sha512/a9f11ca7c0126d6e6df80f8b2252275ead5016184f8e51e64e384dbafc7da7944234f0f7255e1d0c3c529cce389314a4969997df7ad66610e8677c5b4d6e1798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72b15104f1d11b9095a9692d1cd6cc03e70a029e6b0711076a3303192b1a21bca44e0fe1c090d4aabf3701dd87c5ce7a488fdad855e6c6b14f2fd75740a65de6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2593359c07a56b94ca0ff0506cca92625c5fabf27790eb0d532f1b2d2a6b0a1904f763680d6c829e01c9e6eae0f952035451be5b3fbafabbcea127b405360315"} build_url:https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011097 build_url: 'https://queue.taskcluster.net/v1/task/X3EyvXvWSv-oBLotUgJX8g/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9f11ca7c0126d6e6df80f8b2252275ead5016184f8e51e64e384dbafc7da7944234f0f7255e1d0c3c529cce389314a4969997df7ad66610e8677c5b4d6e1798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72b15104f1d11b9095a9692d1cd6cc03e70a029e6b0711076a3303192b1a21bca44e0fe1c090d4aabf3701dd87c5ce7a488fdad855e6c6b14f2fd75740a65de6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2593359c07a56b94ca0ff0506cca92625c5fabf27790eb0d532f1b2d2a6b0a1904f763680d6c829e01c9e6eae0f952035451be5b3fbafabbcea127b405360315"}' ========= master_lag: 0.85 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 12:55:33.588395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:55:33.588855) ========= 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.WOcNCEAM7v/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.WiFbsE1uV0/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005245 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 12:55:33.615996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 12:55:33.619365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 12:55:33.619764) ========= ========= Total master_lag: 2.08 =========