builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0078 starttime: 1477971391.33 results: success (0) buildid: 20161031183806 builduid: 5c1309ba02a242e1bfe279050b2dbb8d revision: 0899c2b63e21814a51d18c415baa534bcb29fa8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.335086) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.336485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.336888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.366353) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.367092) ========= 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.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 --2016-10-31 20:36:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2016-10-31 20:36:32 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.753857 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.207471) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.208153) ========= 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.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.027522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.261019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 20:36:32.261813) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0899c2b63e21814a51d18c415baa534bcb29fa8d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0899c2b63e21814a51d18c415baa534bcb29fa8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 2016-10-31 20:36:32,339 truncating revision to first 12 chars 2016-10-31 20:36:32,340 Setting DEBUG logging. 2016-10-31 20:36:32,340 attempt 1/10 2016-10-31 20:36:32,340 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0899c2b63e21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 20:36:33,124 unpacking tar archive at: mozilla-central-0899c2b63e21/testing/mozharness/ program finished with exit code 0 elapsedTime=1.050745 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 20:36:33.341024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:33.341858) ========= script_repo_revision: 0899c2b63e21814a51d18c415baa534bcb29fa8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:33.342768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:33.343191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:33.453352) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-31 20:36:33.454083) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:36:33 INFO - MultiFileLogger online at 20161031 20:36:33 in /builds/slave/test 20:36:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:36:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:36:33 INFO - {'append_to_log': False, 20:36:33 INFO - 'base_work_dir': '/builds/slave/test', 20:36:33 INFO - 'blob_upload_branch': 'Firefox', 20:36:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:36:33 INFO - 'branch': 'Firefox', 20:36:33 INFO - 'buildbot_json_path': 'buildprops.json', 20:36:33 INFO - 'config_files': ('talos/mac_config.py',), 20:36:33 INFO - 'default_actions': ('clobber', 20:36:33 INFO - 'read-buildbot-config', 20:36:33 INFO - 'download-and-extract', 20:36:33 INFO - 'populate-webroot', 20:36:33 INFO - 'create-virtualenv', 20:36:33 INFO - 'install', 20:36:33 INFO - 'run-tests'), 20:36:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:36:33 INFO - 'download_minidump_stackwalk': True, 20:36:33 INFO - 'download_symbols': 'ondemand', 20:36:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:36:33 INFO - 'tooltool.py': '/tools/tooltool.py', 20:36:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:36:33 INFO - '/tools/misc-python/virtualenv.py')}, 20:36:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:36:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:36:33 INFO - 'installer_path': 'installer.exe', 20:36:33 INFO - 'log_level': 'info', 20:36:33 INFO - 'log_name': 'talos', 20:36:33 INFO - 'log_to_console': True, 20:36:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:36:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:36:33 INFO - 'opt_config_files': (), 20:36:33 INFO - 'pip_index': False, 20:36:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:36:33 INFO - 'cmd': ('bash', 20:36:33 INFO - '-c', 20:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:36:33 INFO - 'enabled': True, 20:36:33 INFO - 'halt_on_failure': False, 20:36:33 INFO - 'name': 'check_screen_resolution'},), 20:36:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:36:33 INFO - 'cmd': ('bash', 20:36:33 INFO - '-c', 20:36:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:36:33 INFO - 'enabled': True, 20:36:33 INFO - 'halt_on_failure': False, 20:36:33 INFO - 'name': 'check_screen_resolution'},), 20:36:33 INFO - 'run_cmd_checks_enabled': True, 20:36:33 INFO - 'sps_profile': False, 20:36:33 INFO - 'sps_profile_interval': 0, 20:36:33 INFO - 'suite': 'dromaeojs', 20:36:33 INFO - 'system_bits': '32', 20:36:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:36:33 INFO - 'title': 't-yosemite-r7-0078', 20:36:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:36:33 INFO - 'use_talos_json': True, 20:36:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:36:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:36:33 INFO - 'work_dir': 'build'} 20:36:33 INFO - [mozharness: 2016-11-01 03:36:33.670112Z] Running clobber step. 20:36:33 INFO - Running pre-action listener: _resource_record_pre_action 20:36:33 INFO - Running main action method: clobber 20:36:33 INFO - rmtree: /builds/slave/test/build 20:36:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:36:35 INFO - Running post-action listener: _resource_record_post_action 20:36:35 INFO - [mozharness: 2016-11-01 03:36:35.757480Z] Finished clobber step (success) 20:36:35 INFO - [mozharness: 2016-11-01 03:36:35.757680Z] Running read-buildbot-config step. 20:36:35 INFO - Running pre-action listener: _resource_record_pre_action 20:36:35 INFO - Running main action method: read_buildbot_config 20:36:35 INFO - Using buildbot properties: 20:36:35 INFO - { 20:36:35 INFO - "project": "", 20:36:35 INFO - "product": "firefox", 20:36:35 INFO - "script_repo_revision": "production", 20:36:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:36:35 INFO - "repository": "", 20:36:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:36:35 INFO - "buildid": "20161031183806", 20:36:35 INFO - "pgo_build": "False", 20:36:35 INFO - "basedir": "/builds/slave/test", 20:36:35 INFO - "buildnumber": 0, 20:36:35 INFO - "slavename": "t-yosemite-r7-0078", 20:36:35 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 20:36:35 INFO - "platform": "yosemite_r7", 20:36:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:36:35 INFO - "branch": "mozilla-central", 20:36:35 INFO - "repo_path": "mozilla-central", 20:36:35 INFO - "revision": "0899c2b63e21814a51d18c415baa534bcb29fa8d", 20:36:35 INFO - "stage_platform": "macosx64", 20:36:35 INFO - "builduid": "5c1309ba02a242e1bfe279050b2dbb8d", 20:36:35 INFO - "slavebuilddir": "test" 20:36:35 INFO - } 20:36:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:36:35 INFO - Running post-action listener: _resource_record_post_action 20:36:35 INFO - [mozharness: 2016-11-01 03:36:35.760900Z] Finished read-buildbot-config step (success) 20:36:35 INFO - [mozharness: 2016-11-01 03:36:35.761029Z] Running download-and-extract step. 20:36:35 INFO - Running pre-action listener: _resource_record_pre_action 20:36:35 INFO - Running main action method: download_and_extract 20:36:35 INFO - mkdir: /builds/slave/test/build/tests 20:36:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:35 INFO - trying https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:36:35 INFO - Downloading https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:36:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:36:36 INFO - Downloaded 1199 bytes. 20:36:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:36:36 INFO - Using the following test package requirements: 20:36:36 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:36:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:36:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:36:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'jsshell-mac.zip'], 20:36:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:36:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:36:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:36:36 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:36:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:36:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:36:36 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:36:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:36:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:36:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:36:36 INFO - Fetch https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:36:37 INFO - Http code: 200 20:36:37 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 20:36:37 INFO - x-amz-cf-id: fD14MY79pWTZPyRsYmiJJIdgTDhLhD-OreEv3y2IgT4ey_10OhnKbg== 20:36:37 INFO - x-amz-version-id: cHM675KUxxyAyFJm6gxZlZotvoi8BfYK 20:36:37 INFO - Expected file size: 16404806 20:36:37 INFO - Obtained file size: 16404806 20:36:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:36:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:36:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:36:44 INFO - Fetch https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:36:45 INFO - Http code: 200 20:36:45 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 20:36:45 INFO - x-amz-cf-id: oEbj6x5JGeMf8W0XDuU9HeYmWouQi0jAZ-wvLtsBjlUCR7lZZ5WkSA== 20:36:45 INFO - x-amz-version-id: cHM675KUxxyAyFJm6gxZlZotvoi8BfYK 20:36:45 INFO - Expected file size: 16404806 20:36:45 INFO - Obtained file size: 16404806 20:36:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:36:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:36:51 INFO - Fetch https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:36:52 INFO - Http code: 200 20:36:52 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 20:36:52 INFO - x-amz-cf-id: J8bK0h2EGfoqPDX6zqdA1qaFRXKyEGKTuFNbKNkI6G5Lufhu-73hzA== 20:36:52 INFO - x-amz-version-id: _5mzPc4c73LcKedKFOu6ZCJT3A.Gq39N 20:36:54 INFO - Expected file size: 14409038 20:36:54 INFO - Obtained file size: 14409038 20:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:36:54 INFO - trying https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:36:54 INFO - Downloading https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:36:57 INFO - Downloaded 112085590 bytes. 20:36:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:36:57 INFO - mkdir: /builds/slave/test/properties 20:36:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:36:57 INFO - Writing to file /builds/slave/test/properties/build_url 20:36:57 INFO - Contents: 20:36:57 INFO - build_url:https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:36:58 INFO - Running post-action listener: _resource_record_post_action 20:36:58 INFO - Running post-action listener: set_extra_try_arguments 20:36:58 INFO - [mozharness: 2016-11-01 03:36:58.027647Z] Finished download-and-extract step (success) 20:36:58 INFO - [mozharness: 2016-11-01 03:36:58.027789Z] Running populate-webroot step. 20:36:58 INFO - Running pre-action listener: _resource_record_pre_action 20:36:58 INFO - Running main action method: populate_webroot 20:36:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:36:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:36:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:36:58 INFO - '--tppagecycles', 20:36:58 INFO - '7'], 20:36:58 INFO - 'tests': ['tsvgm']}}, 20:36:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:36:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:36:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:36:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:36:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['damp', 'tps']}, 20:36:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'tests': ['damp', 'tps']}, 20:36:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['dromaeo_dom']}, 20:36:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:36:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['basic_compositor_video']}, 20:36:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:36:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'other-e10s': {'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['a11yr', 20:36:58 INFO - 'ts_paint', 20:36:58 INFO - 'tpaint', 20:36:58 INFO - 'sessionrestore', 20:36:58 INFO - 'sessionrestore_no_auto_restore', 20:36:58 INFO - 'tabpaint']}, 20:36:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['tsvgx', 20:36:58 INFO - 'tsvgr_opacity', 20:36:58 INFO - 'tart', 20:36:58 INFO - 'tscrollx', 20:36:58 INFO - 'cart']}, 20:36:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:36:58 INFO - 'tsvgr_opacity', 20:36:58 INFO - 'tart', 20:36:58 INFO - 'tscrollx', 20:36:58 INFO - 'cart']}, 20:36:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'talos_options': ['--disable-e10s'], 20:36:58 INFO - 'tests': ['tp5o']}, 20:36:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'tests': ['tp5o']}, 20:36:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'talos_options': ['--disable-e10s', 20:36:58 INFO - '--xperf_path', 20:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:58 INFO - 'tests': ['tp5n']}, 20:36:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:58 INFO - 'talos_options': ['--xperf_path', 20:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:58 INFO - 'tests': ['tp5n']}}, 20:36:58 INFO - 'talos.zip': {'path': '', 20:36:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:36:58 INFO - Running post-action listener: _resource_record_post_action 20:36:58 INFO - [mozharness: 2016-11-01 03:36:58.046333Z] Finished populate-webroot step (success) 20:36:58 INFO - [mozharness: 2016-11-01 03:36:58.046443Z] Running create-virtualenv step. 20:36:58 INFO - Running pre-action listener: _resource_record_pre_action 20:36:58 INFO - Running main action method: create_virtualenv 20:36:58 INFO - Creating virtualenv /builds/slave/test/build/venv 20:36:58 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 20:36:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:36:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:36:58 INFO - Using real prefix '/tools/python27' 20:36:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:36:58 INFO - Installing distribute.............................................................................................................................................................................................done. 20:37:01 INFO - Installing pip.................done. 20:37:01 INFO - Return code: 0 20:37:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:37:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:37:02 INFO - Reading from file tmpfile_stdout 20:37:02 INFO - Output received: 20:37:02 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) 20:37:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:02 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:02 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 20:37:02 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 20:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:02 INFO - 'HOME': '/Users/cltbld', 20:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:02 INFO - 'LOGNAME': 'cltbld', 20:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:02 INFO - 'MOZ_NO_REMOTE': '1', 20:37:02 INFO - 'NO_EM_RESTART': '1', 20:37:02 INFO - 'PAGER': '/bin/cat', 20:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:02 INFO - 'PWD': '/builds/slave/test', 20:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:02 INFO - 'SHELL': '/bin/bash', 20:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:02 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:02 INFO - 'USER': 'cltbld', 20:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:02 INFO - 'XPC_FLAGS': '0x0', 20:37:02 INFO - 'XPC_SERVICE_NAME': '0', 20:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:02 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 20:37:02 INFO - Cleaning up... 20:37:02 INFO - Return code: 0 20:37:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:02 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:02 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 20:37:02 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 20:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:02 INFO - 'HOME': '/Users/cltbld', 20:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:02 INFO - 'LOGNAME': 'cltbld', 20:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:02 INFO - 'MOZ_NO_REMOTE': '1', 20:37:02 INFO - 'NO_EM_RESTART': '1', 20:37:02 INFO - 'PAGER': '/bin/cat', 20:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:02 INFO - 'PWD': '/builds/slave/test', 20:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:02 INFO - 'SHELL': '/bin/bash', 20:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:02 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:02 INFO - 'USER': 'cltbld', 20:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:02 INFO - 'XPC_FLAGS': '0x0', 20:37:02 INFO - 'XPC_SERVICE_NAME': '0', 20:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:02 INFO - Downloading/unpacking psutil>=3.1.1 20:37:02 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 20:37:02 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 20:37:02 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 20:37:02 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 20:37:02 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 20:37:02 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 20:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:37:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:37:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:37:06 INFO - Installing collected packages: psutil 20:37:06 INFO - Running setup.py install for psutil 20:37:06 INFO - building 'psutil._psutil_osx' extension 20:37:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:37:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:37:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:37:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:37:07 INFO - building 'psutil._psutil_posix' extension 20:37:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:37:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:37:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:37:07 INFO - ^ 20:37:07 INFO - 1 warning generated. 20:37:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:37:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:37:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:37:07 INFO - Successfully installed psutil 20:37:07 INFO - Cleaning up... 20:37:07 INFO - Return code: 0 20:37:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:07 INFO - 'HOME': '/Users/cltbld', 20:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:07 INFO - 'LOGNAME': 'cltbld', 20:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:07 INFO - 'MOZ_NO_REMOTE': '1', 20:37:07 INFO - 'NO_EM_RESTART': '1', 20:37:07 INFO - 'PAGER': '/bin/cat', 20:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:07 INFO - 'PWD': '/builds/slave/test', 20:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:07 INFO - 'SHELL': '/bin/bash', 20:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:07 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:07 INFO - 'USER': 'cltbld', 20:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:07 INFO - 'XPC_FLAGS': '0x0', 20:37:07 INFO - 'XPC_SERVICE_NAME': '0', 20:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:37:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:37:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:37:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:37:10 INFO - Installing collected packages: mozsystemmonitor 20:37:10 INFO - Running setup.py install for mozsystemmonitor 20:37:10 INFO - Successfully installed mozsystemmonitor 20:37:10 INFO - Cleaning up... 20:37:10 INFO - Return code: 0 20:37:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:37: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')]} 20:37:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37: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')]} 20:37:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37: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', '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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:37: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 jsonschema==2.5.1 20:37:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:10 INFO - 'HOME': '/Users/cltbld', 20:37:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:10 INFO - 'LOGNAME': 'cltbld', 20:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:10 INFO - 'MOZ_NO_REMOTE': '1', 20:37:10 INFO - 'NO_EM_RESTART': '1', 20:37:10 INFO - 'PAGER': '/bin/cat', 20:37:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:10 INFO - 'PWD': '/builds/slave/test', 20:37:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:10 INFO - 'SHELL': '/bin/bash', 20:37:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:10 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:10 INFO - 'USER': 'cltbld', 20:37:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:10 INFO - 'XPC_FLAGS': '0x0', 20:37:10 INFO - 'XPC_SERVICE_NAME': '0', 20:37:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:10 INFO - Downloading/unpacking jsonschema==2.5.1 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:37:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37:13 INFO - Downloading functools32-3.2.3-2.tar.gz 20:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:37:13 INFO - warning: no files found matching '*.txt' 20:37:13 INFO - no previously-included directories found matching 'build' 20:37:13 INFO - no previously-included directories found matching 'dist' 20:37:13 INFO - no previously-included directories found matching '.git*' 20:37:13 INFO - Installing collected packages: jsonschema, functools32 20:37:13 INFO - Running setup.py install for functools32 20:37:14 INFO - warning: no files found matching '*.txt' 20:37:14 INFO - no previously-included directories found matching 'build' 20:37:14 INFO - no previously-included directories found matching 'dist' 20:37:14 INFO - no previously-included directories found matching '.git*' 20:37:14 INFO - Successfully installed jsonschema functools32 20:37:14 INFO - Cleaning up... 20:37:14 INFO - Return code: 0 20:37:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:14 INFO - 'HOME': '/Users/cltbld', 20:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:14 INFO - 'LOGNAME': 'cltbld', 20:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:14 INFO - 'MOZ_NO_REMOTE': '1', 20:37:14 INFO - 'NO_EM_RESTART': '1', 20:37:14 INFO - 'PAGER': '/bin/cat', 20:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:14 INFO - 'PWD': '/builds/slave/test', 20:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:14 INFO - 'SHELL': '/bin/bash', 20:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:14 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:14 INFO - 'USER': 'cltbld', 20:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:14 INFO - 'XPC_FLAGS': '0x0', 20:37:14 INFO - 'XPC_SERVICE_NAME': '0', 20:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:37:14 INFO - Cleaning up... 20:37:14 INFO - Return code: 0 20:37:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:14 INFO - 'HOME': '/Users/cltbld', 20:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:14 INFO - 'LOGNAME': 'cltbld', 20:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:14 INFO - 'MOZ_NO_REMOTE': '1', 20:37:14 INFO - 'NO_EM_RESTART': '1', 20:37:14 INFO - 'PAGER': '/bin/cat', 20:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:14 INFO - 'PWD': '/builds/slave/test', 20:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:14 INFO - 'SHELL': '/bin/bash', 20:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:14 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:14 INFO - 'USER': 'cltbld', 20:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:14 INFO - 'XPC_FLAGS': '0x0', 20:37:14 INFO - 'XPC_SERVICE_NAME': '0', 20:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37: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 20:37:17 INFO - Downloading blobuploader-1.2.4.tar.gz 20:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:37:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:37:17 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 20:37:17 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 20:37:17 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 20:37:17 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 20:37:17 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 20:37:17 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 20:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:37:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:37:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:37:18 INFO - Downloading docopt-0.6.1.tar.gz 20:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:37:18 INFO - Installing collected packages: blobuploader, requests, docopt 20:37:18 INFO - Running setup.py install for blobuploader 20:37:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:37:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:37:18 INFO - Running setup.py install for requests 20:37:19 INFO - Running setup.py install for docopt 20:37:19 INFO - Successfully installed blobuploader requests docopt 20:37:19 INFO - Cleaning up... 20:37:19 INFO - Return code: 0 20:37:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:19 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:19 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 20:37:19 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 20:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:19 INFO - 'HOME': '/Users/cltbld', 20:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:19 INFO - 'LOGNAME': 'cltbld', 20:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:19 INFO - 'MOZ_NO_REMOTE': '1', 20:37:19 INFO - 'NO_EM_RESTART': '1', 20:37:19 INFO - 'PAGER': '/bin/cat', 20:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:19 INFO - 'PWD': '/builds/slave/test', 20:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:19 INFO - 'SHELL': '/bin/bash', 20:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:19 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:19 INFO - 'USER': 'cltbld', 20:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:19 INFO - 'XPC_FLAGS': '0x0', 20:37:19 INFO - 'XPC_SERVICE_NAME': '0', 20:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:37:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-XXfBUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:37:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8t3KHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:37:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-3kzdZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-4CRsku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-s_9s8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-HXTR6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ff0dQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-hg43tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-yd5pyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-nwaNSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-fRaIv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-C1ADBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:37:20 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-uYJZFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:37:21 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-rHZqbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:37:21 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-jlJPHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:37:21 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-NlGIOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:37:21 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-YG2MUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:37:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:37:21 INFO - Running setup.py install for manifestparser 20:37:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:37:21 INFO - Running setup.py install for mozcrash 20:37:21 INFO - Running setup.py install for mozdebug 20:37:21 INFO - Running setup.py install for mozdevice 20:37:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Running setup.py install for mozfile 20:37:22 INFO - Running setup.py install for mozhttpd 20:37:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Running setup.py install for mozinfo 20:37:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Running setup.py install for mozInstall 20:37:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Running setup.py install for mozleak 20:37:22 INFO - Running setup.py install for mozlog 20:37:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Running setup.py install for moznetwork 20:37:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Running setup.py install for mozprocess 20:37:23 INFO - Running setup.py install for mozprofile 20:37:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Running setup.py install for mozrunner 20:37:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Running setup.py install for mozscreenshot 20:37:23 INFO - Running setup.py install for moztest 20:37:23 INFO - Running setup.py install for mozversion 20:37:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:37:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:37:24 INFO - Cleaning up... 20:37:24 INFO - Return code: 0 20:37:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:24 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:24 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 20:37:24 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 20:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:24 INFO - 'HOME': '/Users/cltbld', 20:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:24 INFO - 'LOGNAME': 'cltbld', 20:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:24 INFO - 'MOZ_NO_REMOTE': '1', 20:37:24 INFO - 'NO_EM_RESTART': '1', 20:37:24 INFO - 'PAGER': '/bin/cat', 20:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:24 INFO - 'PWD': '/builds/slave/test', 20:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:24 INFO - 'SHELL': '/bin/bash', 20:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:24 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:24 INFO - 'USER': 'cltbld', 20:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:24 INFO - 'XPC_FLAGS': '0x0', 20:37:24 INFO - 'XPC_SERVICE_NAME': '0', 20:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-NIuWi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:37:24 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)) 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-BObkfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Xlr7xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:37:24 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)) 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-imrdcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:37:24 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)) 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-2RK8Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:37:24 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)) 20:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:37:24 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-yVDfIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-oLyAhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-5s1gyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-vm3Kin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-g9VFD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-hGsCOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-vk0OVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-EzQdo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-byFX84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:37:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-uH_8DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:37:25 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)) 20:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:37:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ozEwUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 20:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:37:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-i0YcjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:37:26 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)) 20:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:37:26 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)) 20:37:26 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)) 20:37:26 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)) 20:37:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:37:26 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 20:37:26 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 20:37:26 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 20:37:26 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 20:37:26 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 20:37:26 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 20:37:28 INFO - Downloading blessings-1.6.tar.gz 20:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:37:28 INFO - Installing collected packages: blessings 20:37:28 INFO - Running setup.py install for blessings 20:37:29 INFO - Successfully installed blessings 20:37:29 INFO - Cleaning up... 20:37:29 INFO - Return code: 0 20:37:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:37:29 INFO - Reading from file tmpfile_stdout 20:37:29 INFO - Current package versions: 20:37:29 INFO - blessings == 1.6 20:37:29 INFO - blobuploader == 1.2.4 20:37:29 INFO - docopt == 0.6.1 20:37:29 INFO - functools32 == 3.2.3-2 20:37:29 INFO - jsonschema == 2.5.1 20:37:29 INFO - manifestparser == 1.1 20:37:29 INFO - mozInstall == 1.12 20:37:29 INFO - mozcrash == 0.17 20:37:29 INFO - mozdebug == 0.1 20:37:29 INFO - mozdevice == 0.48 20:37:29 INFO - mozfile == 1.2 20:37:29 INFO - mozhttpd == 0.7 20:37:29 INFO - mozinfo == 0.9 20:37:29 INFO - mozleak == 0.1 20:37:29 INFO - mozlog == 3.4 20:37:29 INFO - moznetwork == 0.27 20:37:29 INFO - mozprocess == 0.23 20:37:29 INFO - mozprofile == 0.28 20:37:29 INFO - mozrunner == 6.12 20:37:29 INFO - mozscreenshot == 0.1 20:37:29 INFO - mozsystemmonitor == 0.3 20:37:29 INFO - moztest == 0.7 20:37:29 INFO - mozversion == 1.4 20:37:29 INFO - psutil == 3.1.1 20:37:29 INFO - requests == 1.2.3 20:37:29 INFO - wsgiref == 0.1.2 20:37:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:29 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:29 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 20:37:29 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 20:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:29 INFO - 'HOME': '/Users/cltbld', 20:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:29 INFO - 'LOGNAME': 'cltbld', 20:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:29 INFO - 'MOZ_NO_REMOTE': '1', 20:37:29 INFO - 'NO_EM_RESTART': '1', 20:37:29 INFO - 'PAGER': '/bin/cat', 20:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:29 INFO - 'PWD': '/builds/slave/test', 20:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:29 INFO - 'SHELL': '/bin/bash', 20:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:29 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:29 INFO - 'USER': 'cltbld', 20:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:29 INFO - 'XPC_FLAGS': '0x0', 20:37:29 INFO - 'XPC_SERVICE_NAME': '0', 20:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 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)) 20:37:29 INFO - Cleaning up... 20:37:29 INFO - Return code: 0 20:37:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:29 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 0x102f6b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ee1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9315758a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, '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.xEuV7hUZt6/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.tCwriiHC08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 20:37:29 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 20:37:29 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 20:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:29 INFO - 'HOME': '/Users/cltbld', 20:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:29 INFO - 'LOGNAME': 'cltbld', 20:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:29 INFO - 'MOZ_NO_REMOTE': '1', 20:37:29 INFO - 'NO_EM_RESTART': '1', 20:37:29 INFO - 'PAGER': '/bin/cat', 20:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:29 INFO - 'PWD': '/builds/slave/test', 20:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:29 INFO - 'SHELL': '/bin/bash', 20:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:37:29 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:29 INFO - 'USER': 'cltbld', 20:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:29 INFO - 'XPC_FLAGS': '0x0', 20:37:29 INFO - 'XPC_SERVICE_NAME': '0', 20:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:37:29 INFO - Cleaning up... 20:37:29 INFO - Return code: 0 20:37:29 INFO - Running post-action listener: _resource_record_post_action 20:37:29 INFO - Running post-action listener: _start_resource_monitoring 20:37:29 INFO - Starting resource monitoring. 20:37:29 INFO - [mozharness: 2016-11-01 03:37:29.949955Z] Finished create-virtualenv step (success) 20:37:29 INFO - [mozharness: 2016-11-01 03:37:29.950936Z] Running install step. 20:37:29 INFO - Running pre-action listener: _resource_record_pre_action 20:37:29 INFO - Running main action method: install 20:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:37:30 INFO - Reading from file tmpfile_stdout 20:37:30 INFO - Detecting whether we're running mozinstall >=1.0... 20:37:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:37:30 INFO - Reading from file tmpfile_stdout 20:37:30 INFO - Output received: 20:37:30 INFO - Usage: mozinstall [options] installer 20:37:30 INFO - Options: 20:37:30 INFO - -h, --help show this help message and exit 20:37:30 INFO - -d DEST, --destination=DEST 20:37:30 INFO - Directory to install application into. [default: 20:37:30 INFO - "/builds/slave/test"] 20:37:30 INFO - --app=APP Application being installed. [default: firefox] 20:37:30 INFO - mkdir: /builds/slave/test/build/application 20:37:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:38:05 INFO - Reading from file tmpfile_stdout 20:38:05 INFO - Output received: 20:38:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:38:05 INFO - Running post-action listener: _resource_record_post_action 20:38:05 INFO - [mozharness: 2016-11-01 03:38:05.431214Z] Finished install step (success) 20:38:05 INFO - [mozharness: 2016-11-01 03:38:05.431374Z] Running run-tests step. 20:38:05 INFO - Running pre-action listener: _resource_record_pre_action 20:38:05 INFO - Running main action method: run_tests 20:38:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:38:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:38:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:38:05 INFO - 2016-10-31 20:38:05.445 screenresolution[1652:8435] starting screenresolution argv=screenresolution get 20:38:05 INFO - 2016-10-31 20:38:05.448 screenresolution[1652:8435] Display 0: 1600x1200x32@60 20:38:05 INFO - 2016-10-31 20:38:05.454 screenresolution[1653:8437] starting screenresolution argv=screenresolution list 20:38:05 INFO - Available Modes on Display 0 20:38:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:38:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:38:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:38:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:38:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:38:05 INFO - Intel Iris: 20:38:05 INFO - Chipset Model: Intel Iris 20:38:05 INFO - Type: GPU 20:38:05 INFO - Bus: Built-In 20:38:05 INFO - VRAM (Dynamic, Max): 1536 MB 20:38:05 INFO - Vendor: Intel (0x8086) 20:38:05 INFO - Device ID: 0x0a2e 20:38:05 INFO - Revision ID: 0x0009 20:38:05 INFO - Displays: 20:38:05 INFO - Display: 20:38:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:38:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:38:05 INFO - Display Serial Number: 1600x1200 60 20:38:05 INFO - Main Display: Yes 20:38:05 INFO - Mirror: Off 20:38:05 INFO - Online: Yes 20:38:05 INFO - Rotation: Supported 20:38:05 INFO - Return code: 0 20:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:38:05 INFO - Python 2.7.3 20:38:05 INFO - Return code: 0 20:38:05 INFO - grabbing minidump binary from tooltool 20:38:05 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')]} 20:38:05 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 0x102d7fd30>, '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 0x102ba5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d77b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:38:05 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 20:38:05 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 20:38:05 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 20:38:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:38:05 INFO - Return code: 0 20:38:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:38:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:38:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:38:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:38:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 20:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0078 --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 20:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEuV7hUZt6/Render', 20:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:05 INFO - 'HOME': '/Users/cltbld', 20:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:05 INFO - 'LOGNAME': 'cltbld', 20:38:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:38:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:05 INFO - 'MOZ_NO_REMOTE': '1', 20:38:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:38:05 INFO - 'NO_EM_RESTART': '1', 20:38:05 INFO - 'PAGER': '/bin/cat', 20:38:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:05 INFO - 'PWD': '/builds/slave/test', 20:38:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:05 INFO - 'SHELL': '/bin/bash', 20:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tCwriiHC08/Listeners', 20:38:05 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 20:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:05 INFO - 'USER': 'cltbld', 20:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:05 INFO - 'XPC_FLAGS': '0x0', 20:38:05 INFO - 'XPC_SERVICE_NAME': '0', 20:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 20:38:06 INFO - mozversion application_buildid: 20161031183806 20:38:06 INFO - mozversion application_changeset: 0899c2b63e21814a51d18c415baa534bcb29fa8d 20:38:06 INFO - mozversion application_display_name: Nightly 20:38:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:38:06 INFO - mozversion application_name: Firefox 20:38:06 INFO - mozversion application_remotingname: firefox 20:38:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:38:06 INFO - mozversion application_vendor: Mozilla 20:38:06 INFO - mozversion application_version: 52.0a1 20:38:06 INFO - mozversion platform_buildid: 20161031183806 20:38:06 INFO - mozversion platform_changeset: 0899c2b63e21814a51d18c415baa534bcb29fa8d 20:38:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:38:06 INFO - mozversion platform_version: 52.0a1 20:38:06 INFO - using testdate: 1477971486 20:38:06 INFO - actual date: 1477971486 20:38:06 INFO - starting webserver on 'localhost:49280' 20:38:06 INFO - SUITE-START | Running 2 tests 20:38:06 INFO - TEST-START | dromaeo_css 20:38:06 INFO - operating with platform_type : mac_ 20:38:06 INFO - Initialising browser for dromaeo_css test... 20:38:06 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpbuY2QP/profile http://localhost:49280/getInfo.html) 20:38:08 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 20:38:08 INFO - PROCESS | 1663 | colorDepth:24 20:38:08 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 20:38:08 INFO - PROCESS | 1663 | __metrics 20:38:08 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:38:08 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:38:09 INFO - TEST-INFO | 1663: exit 0 20:38:09 INFO - Browser initialized. 20:38:09 INFO - Running cycle 1/1 for dromaeo_css test... 20:38:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpbuY2QP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:38:21 INFO - PROCESS | 1666 | RSS: Main: 251981824 20:38:21 INFO - PROCESS | 1666 | 20:39:12 INFO - PROCESS | 1666 | 1477971552300 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:41:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 20:41:28 INFO - PROCESS | 1666 | RSS: Main: 446353408 20:41:28 INFO - PROCESS | 1666 | 20:44:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 20:44:31 INFO - PROCESS | 1666 | RSS: Main: 393650176 20:44:31 INFO - PROCESS | 1666 | 20:47:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 20:47:34 INFO - PROCESS | 1666 | RSS: Main: 366067712 20:47:34 INFO - PROCESS | 1666 | 20:50:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 20:50:37 INFO - PROCESS | 1666 | RSS: Main: 365821952 20:50:37 INFO - PROCESS | 1666 | 20:53:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 20:53:41 INFO - PROCESS | 1666 | RSS: Main: 353841152 20:53:41 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 20:56:45 INFO - PROCESS | 1666 | RSS: Main: 528904192 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | __start_tp_report 20:56:45 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 20:56:45 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 20:56:45 INFO - PROCESS | 1666 | |i|pagename|runs| 20:56:45 INFO - PROCESS | 1666 | |0;dojo.html;3268.5750852389187;3297.093299342682;3316.3698897209515;3318.634950155484;3322.4850148227024;5506.03644362237;5640.097584386499;5640.492355687988;5695.231143795586;5732.2261494698205;5599.664020158783;5616.691081990489;5722.198892155102;5737.713114344287;5739.909417210727;5015.673981191229;5029.723365214912;5084.542391184793;5130.4356520782685;5144.996725638502;5193.324867767185;5203.375594928614;5215.973920130394;5216.6869987800665;5247.370315562138;5045.167547354682;5183.092958732207;5187.118189907725;5200.791968321262;5285.392179899303;5071.290019397287;5121.052605268;5128.84613461593;5135.3067335909545;5140.203268493389;5039.496050394968;5105.157648987913;5113.054084994251;5125.103106956305;5127.102757017507;5581.553475721973;5585.385607583163;5589.860253493654;5590.217369568264;5592.804251851207;6104.694765261719;6111.083337499366;6115.052166914135;6115.205023346981;6120.173776540155;6098.583513184978;6114.367021223657;6126.212334160686;6180.9381906180815;6200.069989501566;5722.54219662426;5747.281589801275;5755.079187330049;5767.538596912237;5775.220345253421;6182.443580077815;6187.938120618736;6195.96902015496;6220.191375121205;6221.09794079865;5897.528197744256;5954.37479064692;5964.910526342108;5970.611910225822;6058.878822423527;6140.201773769381;6151.815445536641;6166.568340216231;6173.98129308659;6189.597676151036;6090.482309003784;6090.931246984629;6102.45608457191;6128.108695869081;6137.202163718688;4975.90048199034;6035.064564992434;6050.30421501533;6096.237970253718;6128.601640893327;4805.975970120127;4820.855374338776;4824.879378015507;4825.879353016203;4843.709377437365;4324.394584758136;4398.988232706495;4426.579474949875;4446.688731788744;4451.488078870972;5512.39363669996;5608.327000759853;5664.178694089334;5674.120511320752;5682.6306290091015;6085.208764443269;6088.313232125663;6096.254562767882;6100.066499260575;6114.449440885012;5683.431656834284;5692.829215123553;5698.2592263005545;5777.537796976232;5830.271216097988;5328.333958255218;5334.493223143795;5337.679739215582;5339.492748188916;5348.197770334481;6176.876462470816;6238.313785483593;6247.250329960434;6249.031400132987;6265.248170219603;6091.360407157184;6123.953766440777;6124.777645458507;6145.832291864532;6150.384961503813;6224;6238.968805155945;6239.407256310643;6261.68691565429;6277.058441233851;6070.575059745952;6084.543659225487;6088;6089.269287685506;6098.5426093042315;6013.609115407659;6112.877742445037;6114.816555503342;6118.710550942845;6152.261728592597;5975.521958243243;5986.042233242661;6042.3655516170165;6069.2110025696375;6135.141080248858;5836.620619659709;6068.393160683896;6070.848228794139;6077.453029227391;6091.390860913873;5727.68497732612;5817.9127313091185;5818.592698511064;5878.353381127987;5885.558583106199;5614.29821272341;5670.829875103754;5700.25896633435;5957.3744756799915;5973.193618861398;5568.136938774496;5587.720613969204;5592.049351610155;5605.719714014364;5613.354464236665 20:56:45 INFO - PROCESS | 1666 | |1;ext.html;435.1579693293479;447.1950489119584;491.793446752364;520.2898044169709;528.3554064041867;30617.540736888935;39925;40831.79584102075;40883;40963.59036409635;33481.33037339252;41558.37662435058;41694;41871.79064104683;42140.57859421405;9411.71764847054;9622.807543849154;9625.614975400975;9629.855552166724;9663.903360966371;9511.429314241168;9567.330286879922;9574.186194173504;9577.138057574817;9580.041995800433;9548.092931171528;9548.997355277665;9605.807883842319;9621;9627;9496.90503094967;9551.85672214914;9565.760855978586;9587.90412095877;9594.904050959542;9385.483798391086;9463.444869885161;9483.146516813451;9496.857547136799;9513.143817056429;39422.60577394218;39694.80152599247;39747.8012609938;39775.60224397748;39804;3266.1671273825023;3490.2670439207795;3697.149655579205;3966.5636779954184;4096.180763847213;29670.70329296701;30983.845080774678;31040.0687979361;31055.534166987512;31085.844570777226;3125.7811953163286;3223.3231021485517;3265.363254165439;3299.059767966118;3308.8511017004294;351746.99999999744;355704;357467;365486;468014;58197.70901145467;58569.70715146397;58702.412975870546;58808.41191588029;58990.70504647535;61662.60048794173;61824.381756181865;61835;61882.69058654678;61993.1402057939;15285;15594.142322172389;15595.064296142267;15602.297896594453;15604.45384411563;11981.52073917051;12076.758464830655;12077.214981026205;12077.27536347822;12086.87913120875;13303.93348033273;14591.562253132422;16372.918135409485;16610.252538635792;16631.417900373428;5145.76844042019;5389.27244821944;6091.878162436815;6155.569110162246;6180.227471566054;908.6592527802075;987.2250283527396;996.7757254617653;1010.570507534295;1124.3310230412892;27950.7422166003;28313.150605481867;29219;29917.850410747807;30021.549676754865;1036.18659352408;1047.5809676129456;1060.4591658254192;1074.9731256718644;1194.3908606610516;13322.909768108939;14551;14934.775978360334;15254.940583021373;15405.383784648713;497912;503187;503307;504650.4767476139;505715;56748;56898;56926;56958.71520642453;57057.714711426175;1323.371398585664;1342.6777573382317;1368.363710874448;1369.5754316162022;1372.6225288045669;27902.86048569744;28449.43101138007;28957.565636515046;29806;29980.800767969038;49845.252321215936;50202.744931377005;50287;50364.74817625889;50412.24381634205;437368;506471;509906;512832;512872;39450.80274598609;39925.60074399335;40128.59871401366;40239;40260;45581.544184559054;46159;46763.766181168874;46931.8267043313;47489.28766068443;38302.80848595851;38364.23271534498;38637.6136238634;39746;39826.20347592974;37852.242955140195;38165;38657.613423865405;39494.80252598719;39740.205195896495;36958.98380007361;37914.62085379111;38097.4285385725;38253.4261986076;38320.80839595784;31051.68948310488;33995.15012124717;34095.14762130967;34207.4868876972;34302.92068729104;20768.58462830765;21274.468138296666;24940.251792446255;25150.874245629388;25387.492250154522 20:56:45 INFO - PROCESS | 1666 | |2;jquery.html;321.17778487073093;349.2874535946669;363.4911124425805;371.6320842366057;399.73085453684547;15173.696526069472;15662.608434789136;17643.206055727493;19494.122764475596;20312.984350782437;16834.83165168348;20023.299184528543;20116.899415502903;20317.288894888683;20684.379468615967;5497.945020549783;5609.691466969315;5833.358330583633;5880.7059647017695;5940.88118237635;5572.4706152915405;5784.479396854283;5855.151003104547;5867.266591676041;5871.735771890275;5873.970630146865;5979.521638268928;5986.07215881539;5988.940110598904;5989.371116032819;5720.8283751487525;5878.470937615635;5973.970130149365;5974.761009559613;5976.342602313742;5741.3110426748635;5743.483086522191;5745.253117094751;5755.338136114358;5760.070376423903;12908.87091129086;13204.801927971088;22452.887735561217;23499.647505287434;23684.88157559201;2109.567538654572;2293.9885300573555;2539.4540173862706;2550.7066687330826;2871.2104171352835;9684.661036863676;9712.368696034806;9731.805363892683;10246.846297305547;10268.94865525675;1568.4432026630495;1815.7730283714536;1875.9812401875943;1905.9047047647562;1921.0970843703437;109891.45054274757;111412;112917;113620;123307.01579052587;10530.420826854443;10894.67315980522;10940.070094042096;11027.842076582005;11344.773104537862;12349.629511114501;12888.871111288767;12902.870971290167;13187.736245275233;13221.338933053308;7026.227115017344;7584.241575842371;7584.2794934481135;8133.227343402367;8277.089520152778;7802.492837965628;7963.243491868263;7988.640511177011;7999.720009799746;8126.918730812735;4044.595540445932;4410.69125161242;4424.977875110604;4434.467863856358;4505.481869584974;1646.9588260293588;1795.9931043214683;1799.8290162434548;1816.554944038719;1879.2013394307364;894.7673604862782;940.3840484482674;963.9903600963901;1016.1114955026222;1027.8047171037479;14994.775078373834;20313.898430507754;20833.68749468759;20849.89575052115;21230.78769212319;751.7030772844805;898.1916275352236;920.1902325953118;954.4082668745423;977.6236149082655;10529.578816847394;10927.672169834917;10932.836007459895;10980.615678451375;10981;500879;502153;566560;570062;572862;95103;119809;133833;134392;139150;424.9213895429356;447.9776011199427;448.47304417309533;449.0233741611981;450.1514644894463;9722.75693107678;10944.45277736094;11010.229283950366;11028.5037173329;11643.708907277047;42474.575254247065;47175.16948983072;54372;54550.727246363516;54656;74640;75705.6214718945;75875.29880676148;76870;80709.59645201736;6503.382178693017;7438.181800002104;7532.397408207221;7794;7967.521948683097;10515.211359148247;11776.175667703179;11914.344711040976;12338.198017128858;12388.752224955631;10398.896011039793;10616.416097114732;10704.89295107039;10745.892541074489;10831.512581933674;9843;10513.57945682164;10707.464626768475;10918.344899306067;11134.27627204229;8850.123709794214;10602.840957385491;11063.170262230526;11070.612528561462;11138.55445782192;7533.8116547086765;7805.14143444232;7902.565358905085;7916.564588947663;8248.422610417454;10083.747906302107;10198;10202.948985255027;10397.036844499282;10540.051395374454 20:56:45 INFO - PROCESS | 1666 | |3;mootools.html;1394.1426022995856;1465.8900582456317;1604.8876578639492;1757.1653464604306;1793.757842691237;7393.149787774408;7651.426143039281;7910.683572657094;8331.291840193584;8342.998840139184;7521.774346769592;7899.052113746344;8090.433669643127;8092.635831387587;8212.260896519312;2271.82961277904;2310.803581695558;2315.8842057897123;2316.6177580699164;2317.3163916644544;2066.2974588639936;2292.839501234914;2316.119874447716;2344.6014177589764;2347.694799676048;2091.1530830013867;2233.519793244452;2306.9964565414016;2310;2319.7680231976838;2261.7625149359246;2283.600369935263;2291.450051987528;2297.9655305170436;2300.5858945389905;2166.5883482138343;2298.712660917385;2311.341267738703;2314.9652755208517;2317.1774020222915;8122;8187;8222.876656850152;8245.587720613945;8268;4531.116432295704;4986.775595098229;5083.339165908408;5154.53609175176;5162.948370516285;3248.6285011941413;3343.5820522434697;3605.963940360615;3607.512985746917;3615.584207816082;937.8358787212279;1056.3661802918264;1064.2975636080228;1067.5089458848938;1086.1745073744012;10647.680569582924;10655.627053053184;10763.3541987481;10767.353958762422;10789.62236321725;4365.607095361433;4466.307722303049;4567.4062371892105;4571.657125715585;4628.583427491542;7936.642851071716;9208.447493150432;9211.309151813506;9218.677346292847;9250.21373183287;5102.489751024942;5114.15616423286;5116.488351164928;5117.565006974449;5130.948690513122;4657.534246575316;4668.743219122981;4694.36626055485;4713.316569097462;4721.220998535272;3645.0887278180453;3665.193657395369;3670.100825297819;3740.345439548068;3755.9812200938823;1147.4721628051022;1231.5689508671894;1235.993820030894;1241.8013117901091;1259.1752402176405;763.6410886883156;837.3552364679163;857.6955180910811;877.0878286581883;934.4206591912922;5527.39198688144;5542.251796007568;5579.637323573955;5699.458551437607;5872.941270587325;890.3633901760143;928.5774972387683;928.893177284621;945.2438049560461;961.7211008807508;3666.3034023535974;3696.4085746281003;3698.3158115748674;3764.9435258471144;3795;9801.803963920824;9987.151092157103;10083.495825208856;10087.495625218857;10087.949560252298;10055.843577988479;10060.899391006142;10061.931111011212;10062.496875156214;10080.546375413123;7578;7648.196939321402;7699.037620297463;7771.366033230592;7835.255650713173;5320.06898792702;5444.428335024923;5459.426760190123;5476.2333273342565;5552.194931734956;7740.7677769667;9315.161635452841;9324.207442367475;9326.580303886478;9342.112499312554;7928.286454219148;9129.863052054092;9304;9434.103760142776;9453.007434219307;6579.506537009648;6715.597064176165;6717.56335838169;6720.8319792005595;6737.59574425536;7652.426068044808;7743.4966727162555;7743.883841742266;7744.535327880345;7750.418718596015;6844.8973265402055;6988.266232045766;7138.107736532934;7141.82145446368;7197.568145911262;6659.7003134860015;6914.101166848278;6923.723051077901;6945.409640180439;7023.47323950716;6322.336154703874;6486.189226346707;6577.901331480128;6579.046038324512;6652.301508341748;4684.039771846831;4704.764761761967;4753.382060332135;4787.4494433140635;4860.100881336823;4641.675082744176;4664.323673067454;4773.069251495925;4804.495527969513;4915.43472500667 20:56:45 INFO - PROCESS | 1666 | |4;prototype.html;293.0914166085136;296.2948183323688;302.605100344051;308.6481411191241;312.0358093491112;5026.97486512567;5160.561352285051;5199.8700032499155;5296.417394086652;5299.973500132503;4473.903893546078;4741.905161896769;5080.872978175547;5140.922886156711;5146.691198528081;3394.4738565522384;3668.9910274674544;4011.6991225658194;4015.8996025099314;4030.778307193103;3410.38613049651;4039.9798001009954;4040.636342729154;4044.5146582410007;4049.5140583130064;3926.450296958428;3950.841966321358;3956;3981.86063487778;3990.361542153256;3908.6482216600357;3927.2145570885937;3939.9212015759813;3945.684345252373;3963.861264855745;2854.985725071382;3376.9831150844334;3401.829908504565;3408.403529382373;3445.1903802606366;4397.758123303217;4801.68789028712;5026.220935754964;5109;5124.4106927703415;3829.1767270037076;4514.7291162530355;4519.344695019204;4534.841280555198;4552.385427967249;4780.928286075712;4830.584707646177;4962.751862406902;5469.453054694539;5500.669959802384;3243.5783348164587;3730.7388482806214;3748.756330838487;3777.7700066443344;3791.791451470168;5013.718757965117;5122.129238029544;5318.808714771145;5442.700651464168;5781.439011466922;3365.5936005756002;3503.2468019375447;3521.4717792330844;3769.660730534265;3804.2201348723997;3223.7098661120613;3674.5957944626603;3829.8659546915724;3873.133880928149;3887.8250478728523;3466.843992020365;3823.4264860271182;3932.803359831997;4448.822047118079;4476.462824461086;4707.411573553306;4874.098291816025;5308.442613525602;5345.973270133624;5599.552035837123;1203.3080978437433;1237.789575772121;1254.6737848159544;1280.21266920843;1296.2093123194843;779.4933293359248;793.508025024477;793.928546430824;802.8996375453069;805.4361946637378;3824.8852534424013;4146.709730318909;4205.642520385741;4206.92894187245;4231.005713657288;4548.545145485491;4589.095525356939;4593.862184134481;4597.057603191338;4726.669133160714;3974.3044967130636;4026.557078721397;4296.291111966553;4315.568443155722;4327.437433133672;2123.6389813731703;2170.446536133276;2303.8387312887935;2309.110992267955;2323.1404380379367;3831.923361532754;4430.955690443118;4574.588287054181;4837.7097374157665;4838.0082083172865;3816.263600061965;4654.230073640362;4680.782996420955;4757.500462451462;4778.607188895923;4738.360321356642;4756.833510827105;4778.522147785194;4838.661293709477;4843.885906241655;4044.51465824103;4348.391225228407;4664.346991421272;4688.695234809727;4702.764861756967;3619.164523419908;4512.3908272382805;4559.8372415033955;4589.944312808139;4688.718676879398;4211.810468528863;4661.557152070548;4814.15752243349;4891.241857512091;4923.753812309442;2715.3660948879556;3170.3831046167084;3202.3275112226693;3284.490903909802;3286.753493488046;3730.328540862562;4674.368960190467;4683.531646835289;4692.788824502837;4742.336072949859;4134.470246008996;4407.669424793217;4560.749158796298;4577;4606.838760643362;4033.515978082649;4109.773962432095;4158.8336466540995;4310.297432514077;4469.486009108864;4116.403121547299;4157.792110394407;4378.2119218540965;5068.391792984866;5286.312779338661;4870.537298956593;5201.76592053366;5367.597430192673;5490.917636235539;5567.582431317586;4568.200564901195;5193.896122077613;5332.6000549958135;5334.919976200283;5371.167469042305 20:56:45 INFO - PROCESS | 1666 | |5;yui.html;685.5372623479151;729.9963500182502;736.2379936765448;770.6801677303919;785.7210690204973;11799.999999999989;14891.329890154942;14894.776578351333;14928.178950157751;14951.925240373797;13196.670083247924;13293.800592991112;13308.800367994463;13336.59990200293;13339.399727012284;659.2122413715612;677.5257319876082;803.1566854802463;811.3711873298197;828.1883753921161;818.7461886815076;827.6813426830653;831.501099340397;832.7834762961613;832.8584140696096;872.7556284240391;874.1171416868945;874.903760586335;877.1623099939543;878.0166213840507;683.702589373624;710.3677726823131;869.7869022089566;869.8086420987372;869.969551065716;626.4643729611208;643.6073994863129;644.3556443556444;645.554567348528;646.4828137490036;13860.514881979183;13911.930440347835;13930.51243206493;13933;13935.721285574231;12671;12776.297303648296;12852.678683032906;12898.290594017326;12955.935220323932;11694.883051169465;11746.119041071868;11834.940825295904;11836.940815295955;12171.0871684624;4146.150039241948;4152.91694166118;4252.383404406375;4337.0458499130145;4343.652507799368;79555.60222198852;80510.59744701297;80657;81612.59193703994;82588.58705706433;10199.949000254952;10215.897841021495;10246.38521688701;10281.074703276741;10456.843147352796;19010.144543495575;19034.904825475784;19034.953077580867;19038.428847134608;19043.619127617647;7112.395446387011;7378.225286344857;7466.216047314953;7472.626368681653;7483.438742094365;4055.858044968412;4057.715959882839;4167.8958026049595;4211.389348544444;4285.057287396768;3189.059227527885;3279.3277378137423;3284.0476261883787;3293.555370025064;3341.7827841190247;556.9610127291131;615.4215037864393;626.5551458464541;626.6709977261974;643.2441880790053;497.8182963218399;520.6875874475285;522.4775224775225;690.7720452250745;691.1982100763091;8244.54654993969;8908.198262156437;9061;9077.273818094536;9146;576.766409604111;582.4001278682963;585.8183962971407;612.8896798576299;692.3872372949875;3971.860984865516;4108.281050816096;4412.691111622155;4450.888727781767;4466.59800617946;102916;106959.46520267504;109981;113240.43379783048;116216.41891790487;59918;60196;65981;71658.64170679217;82750.17249827425;65208.71769488045;69432.61134777276;77569;82521;84964;9164.963387772239;9475.810483790148;9625.03749625032;9648.75878103053;9728.906518287928;42657.37412319988;50757.731056723875;50987.725306866145;53875.73062134664;56566;78023;78036.60981695056;78152;80111;80697;12314.630561083182;12467.314297713712;12497;12830.498052879972;13033.47866085346;14083.295835208404;14122.717545648824;14199.290035497977;14801.111933284037;14853.48012819546;12242.265464072185;12527.31099789484;13009.934950325187;13018.804717929428;13772.173669579857;10513.737156571147;12573.182743121668;13340.733185336432;13486.595402137951;13502.527411540548;12659;12765;13385.732285354434;13534.796978045533;13540.119892206974;10757.677269681923;10778.29941053829;11151.721206969567;11170.88829111731;11442.94278528602;8925.375223734276;10447.111995480469;10812.837807432737;10947.945260273647;11044.061254793432 20:56:45 INFO - PROCESS | 1666 | __end_tp_report 20:56:45 INFO - PROCESS | 1666 | __start_cc_report 20:56:45 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1985 20:56:45 INFO - PROCESS | 1666 | __end_cc_report 20:56:45 INFO - PROCESS | 1666 | __startTimestamp1477972605733__endTimestamp 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | ------- Summary: start ------- 20:56:45 INFO - PROCESS | 1666 | Number of tests: 6 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5646.52 Median:5775.22 stddev:621.93 (10.8%) stddev-sans-first:595.21 20:56:45 INFO - PROCESS | 1666 | Values: 3268.6 3297.1 3316.4 3318.6 3322.5 5506.0 5640.1 5640.5 5695.2 5732.2 5599.7 5616.7 5722.2 5737.7 5739.9 5015.7 5029.7 5084.5 5130.4 5145.0 5193.3 5203.4 5216.0 5216.7 5247.4 5045.2 5183.1 5187.1 5200.8 5285.4 5071.3 5121.1 5128.8 5135.3 5140.2 5039.5 5105.2 5113.1 5125.1 5127.1 5581.6 5585.4 5589.9 5590.2 5592.8 6104.7 6111.1 6115.1 6115.2 6120.2 6098.6 6114.4 6126.2 6180.9 6200.1 5722.5 5747.3 5755.1 5767.5 5775.2 6182.4 6187.9 6196.0 6220.2 6221.1 5897.5 5954.4 5964.9 5970.6 6058.9 6140.2 6151.8 6166.6 6174.0 6189.6 6090.5 6090.9 6102.5 6128.1 6137.2 4975.9 6035.1 6050.3 6096.2 6128.6 4806.0 4820.9 4824.9 4825.9 4843.7 4324.4 4399.0 4426.6 4446.7 4451.5 5512.4 5608.3 5664.2 5674.1 5682.6 6085.2 6088.3 6096.3 6100.1 6114.4 5683.4 5692.8 5698.3 5777.5 5830.3 5328.3 5334.5 5337.7 5339.5 5348.2 6176.9 6238.3 6247.3 6249.0 6265.2 6091.4 6124.0 6124.8 6145.8 6150.4 6224.0 6239.0 6239.4 6261.7 6277.1 6070.6 6084.5 6088.0 6089.3 6098.5 6013.6 6112.9 6114.8 6118.7 6152.3 5975.5 5986.0 6042.4 6069.2 6135.1 5836.6 6068.4 6070.8 6077.5 6091.4 5727.7 5817.9 5818.6 5878.4 5885.6 5614.3 5670.8 5700.3 5957.4 5973.2 5568.1 5587.7 5592.0 5605.7 5613.4 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:60973.13 Median:39789.80 stddev:123233.93 (309.7%) stddev-sans-first:123495.81 20:56:45 INFO - PROCESS | 1666 | Values: 435.2 447.2 491.8 520.3 528.4 30617.5 39925.0 40831.8 40883.0 40963.6 33481.3 41558.4 41694.0 41871.8 42140.6 9411.7 9622.8 9625.6 9629.9 9663.9 9511.4 9567.3 9574.2 9577.1 9580.0 9548.1 9549.0 9605.8 9621.0 9627.0 9496.9 9551.9 9565.8 9587.9 9594.9 9385.5 9463.4 9483.1 9496.9 9513.1 39422.6 39694.8 39747.8 39775.6 39804.0 3266.2 3490.3 3697.1 3966.6 4096.2 29670.7 30983.8 31040.1 31055.5 31085.8 3125.8 3223.3 3265.4 3299.1 3308.9 351747.0 355704.0 357467.0 365486.0 468014.0 58197.7 58569.7 58702.4 58808.4 58990.7 61662.6 61824.4 61835.0 61882.7 61993.1 15285.0 15594.1 15595.1 15602.3 15604.5 11981.5 12076.8 12077.2 12077.3 12086.9 13303.9 14591.6 16372.9 16610.3 16631.4 5145.8 5389.3 6091.9 6155.6 6180.2 908.7 987.2 996.8 1010.6 1124.3 27950.7 28313.2 29219.0 29917.9 30021.5 1036.2 1047.6 1060.5 1075.0 1194.4 13322.9 14551.0 14934.8 15254.9 15405.4 497912.0 503187.0 503307.0 504650.5 505715.0 56748.0 56898.0 56926.0 56958.7 57057.7 1323.4 1342.7 1368.4 1369.6 1372.6 27902.9 28449.4 28957.6 29806.0 29980.8 49845.3 50202.7 50287.0 50364.7 50412.2 437368.0 506471.0 509906.0 512832.0 512872.0 39450.8 39925.6 40128.6 40239.0 40260.0 45581.5 46159.0 46763.8 46931.8 47489.3 38302.8 38364.2 38637.6 39746.0 39826.2 37852.2 38165.0 38657.6 39494.8 39740.2 36959.0 37914.6 38097.4 38253.4 38320.8 31051.7 33995.2 34095.1 34207.5 34302.9 20768.6 21274.5 24940.3 25150.9 25387.5 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:32426.98 Median:335.23 stddev:91239.05 (27216.6%) stddev-sans-first:91463.15 20:56:45 INFO - PROCESS | 1666 | Values: 321.2 349.3 363.5 371.6 399.7 15173.7 15662.6 17643.2 19494.1 20313.0 16834.8 20023.3 20116.9 20317.3 20684.4 5497.9 5609.7 5833.4 5880.7 5940.9 5572.5 5784.5 5855.2 5867.3 5871.7 5874.0 5979.5 5986.1 5988.9 5989.4 5720.8 5878.5 5974.0 5974.8 5976.3 5741.3 5743.5 5745.3 5755.3 5760.1 12908.9 13204.8 22452.9 23499.6 23684.9 2109.6 2294.0 2539.5 2550.7 2871.2 9684.7 9712.4 9731.8 10246.8 10268.9 1568.4 1815.8 1876.0 1905.9 1921.1 109891.5 111412.0 112917.0 113620.0 123307.0 10530.4 10894.7 10940.1 11027.8 11344.8 12349.6 12888.9 12902.9 13187.7 13221.3 7026.2 7584.2 7584.3 8133.2 8277.1 7802.5 7963.2 7988.6 7999.7 8126.9 4044.6 4410.7 4425.0 4434.5 4505.5 1647.0 1796.0 1799.8 1816.6 1879.2 894.8 940.4 964.0 1016.1 1027.8 14994.8 20313.9 20833.7 20849.9 21230.8 751.7 898.2 920.2 954.4 977.6 10529.6 10927.7 10932.8 10980.6 10981.0 500879.0 502153.0 566560.0 570062.0 572862.0 95103.0 119809.0 133833.0 134392.0 139150.0 424.9 448.0 448.5 449.0 450.2 9722.8 10944.5 11010.2 11028.5 11643.7 42474.6 47175.2 54372.0 54550.7 54656.0 74640.0 75705.6 75875.3 76870.0 80709.6 6503.4 7438.2 7532.4 7794.0 7967.5 10515.2 11776.2 11914.3 12338.2 12388.8 10398.9 10616.4 10704.9 10745.9 10831.5 9843.0 10513.6 10707.5 10918.3 11134.3 8850.1 10602.8 11063.2 11070.6 11138.6 7533.8 7805.1 7902.6 7916.6 8248.4 10083.7 10198.0 10202.9 10397.0 10540.1 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5344.73 Median:5108.32 stddev:2910.31 (57.0%) stddev-sans-first:2903.33 20:56:45 INFO - PROCESS | 1666 | Values: 1394.1 1465.9 1604.9 1757.2 1793.8 7393.1 7651.4 7910.7 8331.3 8343.0 7521.8 7899.1 8090.4 8092.6 8212.3 2271.8 2310.8 2315.9 2316.6 2317.3 2066.3 2292.8 2316.1 2344.6 2347.7 2091.2 2233.5 2307.0 2310.0 2319.8 2261.8 2283.6 2291.5 2298.0 2300.6 2166.6 2298.7 2311.3 2315.0 2317.2 8122.0 8187.0 8222.9 8245.6 8268.0 4531.1 4986.8 5083.3 5154.5 5162.9 3248.6 3343.6 3606.0 3607.5 3615.6 937.8 1056.4 1064.3 1067.5 1086.2 10647.7 10655.6 10763.4 10767.4 10789.6 4365.6 4466.3 4567.4 4571.7 4628.6 7936.6 9208.4 9211.3 9218.7 9250.2 5102.5 5114.2 5116.5 5117.6 5130.9 4657.5 4668.7 4694.4 4713.3 4721.2 3645.1 3665.2 3670.1 3740.3 3756.0 1147.5 1231.6 1236.0 1241.8 1259.2 763.6 837.4 857.7 877.1 934.4 5527.4 5542.3 5579.6 5699.5 5872.9 890.4 928.6 928.9 945.2 961.7 3666.3 3696.4 3698.3 3764.9 3795.0 9801.8 9987.2 10083.5 10087.5 10087.9 10055.8 10060.9 10061.9 10062.5 10080.5 7578.0 7648.2 7699.0 7771.4 7835.3 5320.1 5444.4 5459.4 5476.2 5552.2 7740.8 9315.2 9324.2 9326.6 9342.1 7928.3 9129.9 9304.0 9434.1 9453.0 6579.5 6715.6 6717.6 6720.8 6737.6 7652.4 7743.5 7743.9 7744.5 7750.4 6844.9 6988.3 7138.1 7141.8 7197.6 6659.7 6914.1 6923.7 6945.4 7023.5 6322.3 6486.2 6577.9 6579.0 6652.3 4684.0 4704.8 4753.4 4787.4 4860.1 4641.7 4664.3 4773.1 4804.5 4915.4 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4034.18 Median:4419.31 stddev:1209.71 (27.4%) stddev-sans-first:1180.06 20:56:45 INFO - PROCESS | 1666 | Values: 293.1 296.3 302.6 308.6 312.0 5027.0 5160.6 5199.9 5296.4 5300.0 4473.9 4741.9 5080.9 5140.9 5146.7 3394.5 3669.0 4011.7 4015.9 4030.8 3410.4 4040.0 4040.6 4044.5 4049.5 3926.5 3950.8 3956.0 3981.9 3990.4 3908.6 3927.2 3939.9 3945.7 3963.9 2855.0 3377.0 3401.8 3408.4 3445.2 4397.8 4801.7 5026.2 5109.0 5124.4 3829.2 4514.7 4519.3 4534.8 4552.4 4780.9 4830.6 4962.8 5469.5 5500.7 3243.6 3730.7 3748.8 3777.8 3791.8 5013.7 5122.1 5318.8 5442.7 5781.4 3365.6 3503.2 3521.5 3769.7 3804.2 3223.7 3674.6 3829.9 3873.1 3887.8 3466.8 3823.4 3932.8 4448.8 4476.5 4707.4 4874.1 5308.4 5346.0 5599.6 1203.3 1237.8 1254.7 1280.2 1296.2 779.5 793.5 793.9 802.9 805.4 3824.9 4146.7 4205.6 4206.9 4231.0 4548.5 4589.1 4593.9 4597.1 4726.7 3974.3 4026.6 4296.3 4315.6 4327.4 2123.6 2170.4 2303.8 2309.1 2323.1 3831.9 4431.0 4574.6 4837.7 4838.0 3816.3 4654.2 4680.8 4757.5 4778.6 4738.4 4756.8 4778.5 4838.7 4843.9 4044.5 4348.4 4664.3 4688.7 4702.8 3619.2 4512.4 4559.8 4589.9 4688.7 4211.8 4661.6 4814.2 4891.2 4923.8 2715.4 3170.4 3202.3 3284.5 3286.8 3730.3 4674.4 4683.5 4692.8 4742.3 4134.5 4407.7 4560.7 4577.0 4606.8 4033.5 4109.8 4158.8 4310.3 4469.5 4116.4 4157.8 4378.2 5068.4 5286.3 4870.5 5201.8 5367.6 5490.9 5567.6 4568.2 5193.9 5332.6 5334.9 5371.2 20:56:45 INFO - PROCESS | 1666 | 20:56:45 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:19330.17 Median:4340.35 stddev:27635.43 (636.7%) stddev-sans-first:27677.50 20:56:45 INFO - PROCESS | 1666 | Values: 685.5 730.0 736.2 770.7 785.7 11800.0 14891.3 14894.8 14928.2 14951.9 13196.7 13293.8 13308.8 13336.6 13339.4 659.2 677.5 803.2 811.4 828.2 818.7 827.7 831.5 832.8 832.9 872.8 874.1 874.9 877.2 878.0 683.7 710.4 869.8 869.8 870.0 626.5 643.6 644.4 645.6 646.5 13860.5 13911.9 13930.5 13933.0 13935.7 12671.0 12776.3 12852.7 12898.3 12955.9 11694.9 11746.1 11834.9 11836.9 12171.1 4146.2 4152.9 4252.4 4337.0 4343.7 79555.6 80510.6 80657.0 81612.6 82588.6 10199.9 10215.9 10246.4 10281.1 10456.8 19010.1 19034.9 19035.0 19038.4 19043.6 7112.4 7378.2 7466.2 7472.6 7483.4 4055.9 4057.7 4167.9 4211.4 4285.1 3189.1 3279.3 3284.0 3293.6 3341.8 557.0 615.4 626.6 626.7 643.2 497.8 520.7 522.5 690.8 691.2 8244.5 8908.2 9061.0 9077.3 9146.0 576.8 582.4 585.8 612.9 692.4 3971.9 4108.3 4412.7 4450.9 4466.6 102916.0 106959.5 109981.0 113240.4 116216.4 59918.0 60196.0 65981.0 71658.6 82750.2 65208.7 69432.6 77569.0 82521.0 84964.0 9165.0 9475.8 9625.0 9648.8 9728.9 42657.4 50757.7 50987.7 53875.7 56566.0 78023.0 78036.6 78152.0 80111.0 80697.0 12314.6 12467.3 12497.0 12830.5 13033.5 14083.3 14122.7 14199.3 14801.1 14853.5 12242.3 12527.3 13009.9 13018.8 13772.2 10513.7 12573.2 13340.7 13486.6 13502.5 12659.0 12765.0 13385.7 13534.8 13540.1 10757.7 10778.3 11151.7 11170.9 11442.9 8925.4 10447.1 10812.8 10947.9 11044.1 20:56:45 INFO - PROCESS | 1666 | -------- Summary: end -------- 20:56:45 INFO - PROCESS | 1666 | 20:56:46 INFO - TEST-INFO | 1666: exit 0 20:56:46 INFO - TEST-OK | dromaeo_css | took 1120361ms 20:56:46 INFO - TEST-START | kraken 20:56:46 INFO - operating with platform_type : mac_ 20:56:46 INFO - Initialising browser for kraken test... 20:56:46 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpU6AYVp/profile http://localhost:49280/getInfo.html) 20:56:48 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 20:56:48 INFO - PROCESS | 1672 | colorDepth:24 20:56:48 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 20:56:48 INFO - PROCESS | 1672 | __metrics 20:56:48 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:56:48 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:56:49 INFO - TEST-INFO | 1672: exit 0 20:56:49 INFO - Browser initialized. 20:56:49 INFO - Running cycle 1/1 for kraken test... 20:56:49 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpU6AYVp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:57:01 INFO - PROCESS | 1673 | RSS: Main: 256147456 20:57:01 INFO - PROCESS | 1673 | 20:57:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 20:57:05 INFO - PROCESS | 1673 | RSS: Main: 304877568 20:57:05 INFO - PROCESS | 1673 | 20:57:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 20:57:08 INFO - PROCESS | 1673 | RSS: Main: 305352704 20:57:08 INFO - PROCESS | 1673 | 20:57:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 20:57:12 INFO - PROCESS | 1673 | RSS: Main: 394866688 20:57:12 INFO - PROCESS | 1673 | 20:57:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 20:57:15 INFO - PROCESS | 1673 | RSS: Main: 297222144 20:57:15 INFO - PROCESS | 1673 | 20:57:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:57:18 INFO - PROCESS | 1673 | RSS: Main: 299253760 20:57:18 INFO - PROCESS | 1673 | 20:57:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 20:57:21 INFO - PROCESS | 1673 | RSS: Main: 334807040 20:57:21 INFO - PROCESS | 1673 | 20:57:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 20:57:25 INFO - PROCESS | 1673 | RSS: Main: 337330176 20:57:25 INFO - PROCESS | 1673 | 20:57:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 20:57:28 INFO - PROCESS | 1673 | RSS: Main: 335302656 20:57:28 INFO - PROCESS | 1673 | 20:57:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:57:30 INFO - PROCESS | 1673 | RSS: Main: 313212928 20:57:30 INFO - PROCESS | 1673 | 20:57:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:57:33 INFO - PROCESS | 1673 | RSS: Main: 317153280 20:57:33 INFO - PROCESS | 1673 | 20:57:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:57:36 INFO - PROCESS | 1673 | RSS: Main: 319447040 20:57:36 INFO - PROCESS | 1673 | 20:57:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:57:39 INFO - PROCESS | 1673 | RSS: Main: 333737984 20:57:39 INFO - PROCESS | 1673 | 20:57:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:57:43 INFO - PROCESS | 1673 | RSS: Main: 327475200 20:57:43 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 20:57:45 INFO - PROCESS | 1673 | RSS: Main: 370724864 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | __start_tp_report 20:57:45 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 20:57:45 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 20:57:45 INFO - PROCESS | 1673 | |i|pagename|runs| 20:57:45 INFO - PROCESS | 1673 | |0;ai-astar;114;112;114;114;115;113;114;115;115;118 20:57:45 INFO - PROCESS | 1673 | |1;audio-beat-detection;147;116;113;163;141;98;128;97;161;139 20:57:45 INFO - PROCESS | 1673 | |2;audio-dft;186;183;185;185;187;181;182;182;184;183 20:57:45 INFO - PROCESS | 1673 | |3;audio-fft;77;71;83;112;83;65;58;58;80;58 20:57:45 INFO - PROCESS | 1673 | |4;audio-oscillator;82;71;89;55;89;97;124;95;97;92 20:57:45 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;90;87;91;87;90;88;91;87;89;130 20:57:45 INFO - PROCESS | 1673 | |6;imaging-darkroom;98;99;99;99;98;99;118;162;151;99 20:57:45 INFO - PROCESS | 1673 | |7;imaging-desaturate;83;81;82;81;84;82;95;136;126;83 20:57:45 INFO - PROCESS | 1673 | |8;json-parse-financial;58;56;55;55;54;56;57;55;56;56 20:57:45 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;73;66;81;74;73;74;69;71;71;71 20:57:45 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;86;86;87;89;89;86;85;86;86;90 20:57:45 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;150;147;148;146;134;151;159;161;203;149 20:57:45 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;177;175;174;171;174;174;177;174;175;224 20:57:45 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;66;66;66;67;74;73;86;85;69 20:57:45 INFO - PROCESS | 1673 | __end_tp_report 20:57:45 INFO - PROCESS | 1673 | __start_cc_report 20:57:45 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2016 20:57:45 INFO - PROCESS | 1673 | __end_cc_report 20:57:45 INFO - PROCESS | 1673 | __startTimestamp1477972665495__endTimestamp 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | ------- Summary: start ------- 20:57:45 INFO - PROCESS | 1673 | Number of tests: 14 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.50 stddev:1.58 (1.4%) stddev-sans-first:1.67 20:57:45 INFO - PROCESS | 1673 | Values: 114.0 112.0 114.0 114.0 115.0 113.0 114.0 115.0 115.0 118.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:154.00 stddev:23.90 (15.5%) stddev-sans-first:24.58 20:57:45 INFO - PROCESS | 1673 | Values: 147.0 116.0 113.0 163.0 141.0 98.0 128.0 97.0 161.0 139.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.93 (1.0%) stddev-sans-first:1.88 20:57:45 INFO - PROCESS | 1673 | Values: 186.0 183.0 185.0 185.0 187.0 181.0 182.0 182.0 184.0 183.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:74.50 Median:74.00 stddev:16.69 (22.6%) stddev-sans-first:17.68 20:57:45 INFO - PROCESS | 1673 | Values: 77.0 71.0 83.0 112.0 83.0 65.0 58.0 58.0 80.0 58.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:90.50 stddev:18.03 (19.9%) stddev-sans-first:18.94 20:57:45 INFO - PROCESS | 1673 | Values: 82.0 71.0 89.0 55.0 89.0 97.0 124.0 95.0 97.0 92.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:89.50 stddev:13.10 (14.6%) stddev-sans-first:13.85 20:57:45 INFO - PROCESS | 1673 | Values: 90.0 87.0 91.0 87.0 90.0 88.0 91.0 87.0 89.0 130.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:112.20 Median:99.00 stddev:24.25 (24.5%) stddev-sans-first:25.17 20:57:45 INFO - PROCESS | 1673 | Values: 98.0 99.0 99.0 99.0 98.0 99.0 118.0 162.0 151.0 99.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:82.50 stddev:20.42 (24.7%) stddev-sans-first:21.31 20:57:45 INFO - PROCESS | 1673 | Values: 83.0 81.0 82.0 81.0 84.0 82.0 95.0 136.0 126.0 83.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.14 (2.0%) stddev-sans-first:0.88 20:57:45 INFO - PROCESS | 1673 | Values: 58.0 56.0 55.0 55.0 54.0 56.0 57.0 55.0 56.0 56.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:3.92 (5.4%) stddev-sans-first:4.15 20:57:45 INFO - PROCESS | 1673 | Values: 73.0 66.0 81.0 74.0 73.0 74.0 69.0 71.0 71.0 71.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.76 20:57:45 INFO - PROCESS | 1673 | Values: 86.0 86.0 87.0 89.0 89.0 86.0 85.0 86.0 86.0 90.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:150.50 stddev:18.46 (12.3%) stddev-sans-first:19.50 20:57:45 INFO - PROCESS | 1673 | Values: 150.0 147.0 148.0 146.0 134.0 151.0 159.0 161.0 203.0 149.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:175.00 stddev:15.73 (9.0%) stddev-sans-first:16.66 20:57:45 INFO - PROCESS | 1673 | Values: 177.0 175.0 174.0 171.0 174.0 174.0 177.0 174.0 175.0 224.0 20:57:45 INFO - PROCESS | 1673 | 20:57:45 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:71.00 stddev:7.72 (10.9%) stddev-sans-first:7.99 20:57:45 INFO - PROCESS | 1673 | Values: 67.0 66.0 66.0 66.0 67.0 74.0 73.0 86.0 85.0 69.0 20:57:45 INFO - PROCESS | 1673 | -------- Summary: end -------- 20:57:45 INFO - PROCESS | 1673 | 20:57:46 INFO - TEST-INFO | 1673: exit 0 20:57:46 INFO - TEST-OK | kraken | took 59948ms 20:57:46 INFO - SUITE-END | took 1180s 20:57:46 INFO - Completed test suite (00:19:40) 20:57:46 INFO - javascript benchmark 20:57:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.5750852389187, 3297.093299342682, 3316.3698897209515, 3318.634950155484, 3322.4850148227024, 5506.03644362237, 5640.097584386499, 5640.492355687988, 5695.231143795586, 5732.2261494698205, 5599.664020158783, 5616.691081990489, 5722.198892155102, 5737.713114344287, 5739.909417210727, 5015.673981191229, 5029.723365214912, 5084.542391184793, 5130.4356520782685, 5144.996725638502, 5193.324867767185, 5203.375594928614, 5215.973920130394, 5216.6869987800665, 5247.370315562138, 5045.167547354682, 5183.092958732207, 5187.118189907725, 5200.791968321262, 5285.392179899303, 5071.290019397287, 5121.052605268, 5128.84613461593, 5135.3067335909545, 5140.203268493389, 5039.496050394968, 5105.157648987913, 5113.054084994251, 5125.103106956305, 5127.102757017507, 5581.553475721973, 5585.385607583163, 5589.860253493654, 5590.217369568264, 5592.804251851207, 6104.694765261719, 6111.083337499366, 6115.052166914135, 6115.205023346981, 6120.173776540155, 6098.583513184978, 6114.367021223657, 6126.212334160686, 6180.9381906180815, 6200.069989501566, 5722.54219662426, 5747.281589801275, 5755.079187330049, 5767.538596912237, 5775.220345253421, 6182.443580077815, 6187.938120618736, 6195.96902015496, 6220.191375121205, 6221.09794079865, 5897.528197744256, 5954.37479064692, 5964.910526342108, 5970.611910225822, 6058.878822423527, 6140.201773769381, 6151.815445536641, 6166.568340216231, 6173.98129308659, 6189.597676151036, 6090.482309003784, 6090.931246984629, 6102.45608457191, 6128.108695869081, 6137.202163718688, 4975.90048199034, 6035.064564992434, 6050.30421501533, 6096.237970253718, 6128.601640893327, 4805.975970120127, 4820.855374338776, 4824.879378015507, 4825.879353016203, 4843.709377437365, 4324.394584758136, 4398.988232706495, 4426.579474949875, 4446.688731788744, 4451.488078870972, 5512.39363669996, 5608.327000759853, 5664.178694089334, 5674.120511320752, 5682.6306290091015, 6085.208764443269, 6088.313232125663, 6096.254562767882, 6100.066499260575, 6114.449440885012, 5683.431656834284, 5692.829215123553, 5698.2592263005545, 5777.537796976232, 5830.271216097988, 5328.333958255218, 5334.493223143795, 5337.679739215582, 5339.492748188916, 5348.197770334481, 6176.876462470816, 6238.313785483593, 6247.250329960434, 6249.031400132987, 6265.248170219603, 6091.360407157184, 6123.953766440777, 6124.777645458507, 6145.832291864532, 6150.384961503813, 6224.0, 6238.968805155945, 6239.407256310643, 6261.68691565429, 6277.058441233851, 6070.575059745952, 6084.543659225487, 6088.0, 6089.269287685506, 6098.5426093042315, 6013.609115407659, 6112.877742445037, 6114.816555503342, 6118.710550942845, 6152.261728592597, 5975.521958243243, 5986.042233242661, 6042.3655516170165, 6069.2110025696375, 6135.141080248858, 5836.620619659709, 6068.393160683896, 6070.848228794139, 6077.453029227391, 6091.390860913873, 5727.68497732612, 5817.9127313091185, 5818.592698511064, 5878.353381127987, 5885.558583106199, 5614.29821272341, 5670.829875103754, 5700.25896633435, 5957.3744756799915, 5973.193618861398, 5568.136938774496, 5587.720613969204, 5592.049351610155, 5605.719714014364, 5613.354464236665], "value": 5606.706908717554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.1579693293479, 447.1950489119584, 491.793446752364, 520.2898044169709, 528.3554064041867, 30617.540736888935, 39925.0, 40831.79584102075, 40883.0, 40963.59036409635, 33481.33037339252, 41558.37662435058, 41694.0, 41871.79064104683, 42140.57859421405, 9411.71764847054, 9622.807543849154, 9625.614975400975, 9629.855552166724, 9663.903360966371, 9511.429314241168, 9567.330286879922, 9574.186194173504, 9577.138057574817, 9580.041995800433, 9548.092931171528, 9548.997355277665, 9605.807883842319, 9621.0, 9627.0, 9496.90503094967, 9551.85672214914, 9565.760855978586, 9587.90412095877, 9594.904050959542, 9385.483798391086, 9463.444869885161, 9483.146516813451, 9496.857547136799, 9513.143817056429, 39422.60577394218, 39694.80152599247, 39747.8012609938, 39775.60224397748, 39804.0, 3266.1671273825023, 3490.2670439207795, 3697.149655579205, 3966.5636779954184, 4096.180763847213, 29670.70329296701, 30983.845080774678, 31040.0687979361, 31055.534166987512, 31085.844570777226, 3125.7811953163286, 3223.3231021485517, 3265.363254165439, 3299.059767966118, 3308.8511017004294, 351746.99999999744, 355704.0, 357467.0, 365486.0, 468014.0, 58197.70901145467, 58569.70715146397, 58702.412975870546, 58808.41191588029, 58990.70504647535, 61662.60048794173, 61824.381756181865, 61835.0, 61882.69058654678, 61993.1402057939, 15285.0, 15594.142322172389, 15595.064296142267, 15602.297896594453, 15604.45384411563, 11981.52073917051, 12076.758464830655, 12077.214981026205, 12077.27536347822, 12086.87913120875, 13303.93348033273, 14591.562253132422, 16372.918135409485, 16610.252538635792, 16631.417900373428, 5145.76844042019, 5389.27244821944, 6091.878162436815, 6155.569110162246, 6180.227471566054, 908.6592527802075, 987.2250283527396, 996.7757254617653, 1010.570507534295, 1124.3310230412892, 27950.7422166003, 28313.150605481867, 29219.0, 29917.850410747807, 30021.549676754865, 1036.18659352408, 1047.5809676129456, 1060.4591658254192, 1074.9731256718644, 1194.3908606610516, 13322.909768108939, 14551.0, 14934.775978360334, 15254.940583021373, 15405.383784648713, 497912.0, 503187.0, 503307.0, 504650.4767476139, 505715.0, 56748.0, 56898.0, 56926.0, 56958.71520642453, 57057.714711426175, 1323.371398585664, 1342.6777573382317, 1368.363710874448, 1369.5754316162022, 1372.6225288045669, 27902.86048569744, 28449.43101138007, 28957.565636515046, 29806.0, 29980.800767969038, 49845.252321215936, 50202.744931377005, 50287.0, 50364.74817625889, 50412.24381634205, 437368.0, 506471.0, 509906.0, 512832.0, 512872.0, 39450.80274598609, 39925.60074399335, 40128.59871401366, 40239.0, 40260.0, 45581.544184559054, 46159.0, 46763.766181168874, 46931.8267043313, 47489.28766068443, 38302.80848595851, 38364.23271534498, 38637.6136238634, 39746.0, 39826.20347592974, 37852.242955140195, 38165.0, 38657.613423865405, 39494.80252598719, 39740.205195896495, 36958.98380007361, 37914.62085379111, 38097.4285385725, 38253.4261986076, 38320.80839595784, 31051.68948310488, 33995.15012124717, 34095.14762130967, 34207.4868876972, 34302.92068729104, 20768.58462830765, 21274.468138296666, 24940.251792446255, 25150.874245629388, 25387.492250154522], "value": 19387.930653763964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.17778487073093, 349.2874535946669, 363.4911124425805, 371.6320842366057, 399.73085453684547, 15173.696526069472, 15662.608434789136, 17643.206055727493, 19494.122764475596, 20312.984350782437, 16834.83165168348, 20023.299184528543, 20116.899415502903, 20317.288894888683, 20684.379468615967, 5497.945020549783, 5609.691466969315, 5833.358330583633, 5880.7059647017695, 5940.88118237635, 5572.4706152915405, 5784.479396854283, 5855.151003104547, 5867.266591676041, 5871.735771890275, 5873.970630146865, 5979.521638268928, 5986.07215881539, 5988.940110598904, 5989.371116032819, 5720.8283751487525, 5878.470937615635, 5973.970130149365, 5974.761009559613, 5976.342602313742, 5741.3110426748635, 5743.483086522191, 5745.253117094751, 5755.338136114358, 5760.070376423903, 12908.87091129086, 13204.801927971088, 22452.887735561217, 23499.647505287434, 23684.88157559201, 2109.567538654572, 2293.9885300573555, 2539.4540173862706, 2550.7066687330826, 2871.2104171352835, 9684.661036863676, 9712.368696034806, 9731.805363892683, 10246.846297305547, 10268.94865525675, 1568.4432026630495, 1815.7730283714536, 1875.9812401875943, 1905.9047047647562, 1921.0970843703437, 109891.45054274757, 111412.0, 112917.0, 113620.0, 123307.01579052587, 10530.420826854443, 10894.67315980522, 10940.070094042096, 11027.842076582005, 11344.773104537862, 12349.629511114501, 12888.871111288767, 12902.870971290167, 13187.736245275233, 13221.338933053308, 7026.227115017344, 7584.241575842371, 7584.2794934481135, 8133.227343402367, 8277.089520152778, 7802.492837965628, 7963.243491868263, 7988.640511177011, 7999.720009799746, 8126.918730812735, 4044.595540445932, 4410.69125161242, 4424.977875110604, 4434.467863856358, 4505.481869584974, 1646.9588260293588, 1795.9931043214683, 1799.8290162434548, 1816.554944038719, 1879.2013394307364, 894.7673604862782, 940.3840484482674, 963.9903600963901, 1016.1114955026222, 1027.8047171037479, 14994.775078373834, 20313.898430507754, 20833.68749468759, 20849.89575052115, 21230.78769212319, 751.7030772844805, 898.1916275352236, 920.1902325953118, 954.4082668745423, 977.6236149082655, 10529.578816847394, 10927.672169834917, 10932.836007459895, 10980.615678451375, 10981.0, 500879.0, 502153.0, 566560.0, 570062.0, 572862.0, 95103.0, 119809.0, 133833.0, 134392.0, 139150.0, 424.9213895429356, 447.9776011199427, 448.47304417309533, 449.0233741611981, 450.1514644894463, 9722.75693107678, 10944.45277736094, 11010.229283950366, 11028.5037173329, 11643.708907277047, 42474.575254247065, 47175.16948983072, 54372.0, 54550.727246363516, 54656.0, 74640.0, 75705.6214718945, 75875.29880676148, 76870.0, 80709.59645201736, 6503.382178693017, 7438.181800002104, 7532.397408207221, 7794.0, 7967.521948683097, 10515.211359148247, 11776.175667703179, 11914.344711040976, 12338.198017128858, 12388.752224955631, 10398.896011039793, 10616.416097114732, 10704.89295107039, 10745.892541074489, 10831.512581933674, 9843.0, 10513.57945682164, 10707.464626768475, 10918.344899306067, 11134.27627204229, 8850.123709794214, 10602.840957385491, 11063.170262230526, 11070.612528561462, 11138.55445782192, 7533.8116547086765, 7805.14143444232, 7902.565358905085, 7916.564588947663, 8248.422610417454, 10083.747906302107, 10198.0, 10202.948985255027, 10397.036844499282, 10540.051395374454], "value": 8828.692161491848, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.1426022995856, 1465.8900582456317, 1604.8876578639492, 1757.1653464604306, 1793.757842691237, 7393.149787774408, 7651.426143039281, 7910.683572657094, 8331.291840193584, 8342.998840139184, 7521.774346769592, 7899.052113746344, 8090.433669643127, 8092.635831387587, 8212.260896519312, 2271.82961277904, 2310.803581695558, 2315.8842057897123, 2316.6177580699164, 2317.3163916644544, 2066.2974588639936, 2292.839501234914, 2316.119874447716, 2344.6014177589764, 2347.694799676048, 2091.1530830013867, 2233.519793244452, 2306.9964565414016, 2310.0, 2319.7680231976838, 2261.7625149359246, 2283.600369935263, 2291.450051987528, 2297.9655305170436, 2300.5858945389905, 2166.5883482138343, 2298.712660917385, 2311.341267738703, 2314.9652755208517, 2317.1774020222915, 8122.0, 8187.0, 8222.876656850152, 8245.587720613945, 8268.0, 4531.116432295704, 4986.775595098229, 5083.339165908408, 5154.53609175176, 5162.948370516285, 3248.6285011941413, 3343.5820522434697, 3605.963940360615, 3607.512985746917, 3615.584207816082, 937.8358787212279, 1056.3661802918264, 1064.2975636080228, 1067.5089458848938, 1086.1745073744012, 10647.680569582924, 10655.627053053184, 10763.3541987481, 10767.353958762422, 10789.62236321725, 4365.607095361433, 4466.307722303049, 4567.4062371892105, 4571.657125715585, 4628.583427491542, 7936.642851071716, 9208.447493150432, 9211.309151813506, 9218.677346292847, 9250.21373183287, 5102.489751024942, 5114.15616423286, 5116.488351164928, 5117.565006974449, 5130.948690513122, 4657.534246575316, 4668.743219122981, 4694.36626055485, 4713.316569097462, 4721.220998535272, 3645.0887278180453, 3665.193657395369, 3670.100825297819, 3740.345439548068, 3755.9812200938823, 1147.4721628051022, 1231.5689508671894, 1235.993820030894, 1241.8013117901091, 1259.1752402176405, 763.6410886883156, 837.3552364679163, 857.6955180910811, 877.0878286581883, 934.4206591912922, 5527.39198688144, 5542.251796007568, 5579.637323573955, 5699.458551437607, 5872.941270587325, 890.3633901760143, 928.5774972387683, 928.893177284621, 945.2438049560461, 961.7211008807508, 3666.3034023535974, 3696.4085746281003, 3698.3158115748674, 3764.9435258471144, 3795.0, 9801.803963920824, 9987.151092157103, 10083.495825208856, 10087.495625218857, 10087.949560252298, 10055.843577988479, 10060.899391006142, 10061.931111011212, 10062.496875156214, 10080.546375413123, 7578.0, 7648.196939321402, 7699.037620297463, 7771.366033230592, 7835.255650713173, 5320.06898792702, 5444.428335024923, 5459.426760190123, 5476.2333273342565, 5552.194931734956, 7740.7677769667, 9315.161635452841, 9324.207442367475, 9326.580303886478, 9342.112499312554, 7928.286454219148, 9129.863052054092, 9304.0, 9434.103760142776, 9453.007434219307, 6579.506537009648, 6715.597064176165, 6717.56335838169, 6720.8319792005595, 6737.59574425536, 7652.426068044808, 7743.4966727162555, 7743.883841742266, 7744.535327880345, 7750.418718596015, 6844.8973265402055, 6988.266232045766, 7138.107736532934, 7141.82145446368, 7197.568145911262, 6659.7003134860015, 6914.101166848278, 6923.723051077901, 6945.409640180439, 7023.47323950716, 6322.336154703874, 6486.189226346707, 6577.901331480128, 6579.046038324512, 6652.301508341748, 4684.039771846831, 4704.764761761967, 4753.382060332135, 4787.4494433140635, 4860.100881336823, 4641.675082744176, 4664.323673067454, 4773.069251495925, 4804.495527969513, 4915.43472500667], "value": 4346.516960791658, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.0914166085136, 296.2948183323688, 302.605100344051, 308.6481411191241, 312.0358093491112, 5026.97486512567, 5160.561352285051, 5199.8700032499155, 5296.417394086652, 5299.973500132503, 4473.903893546078, 4741.905161896769, 5080.872978175547, 5140.922886156711, 5146.691198528081, 3394.4738565522384, 3668.9910274674544, 4011.6991225658194, 4015.8996025099314, 4030.778307193103, 3410.38613049651, 4039.9798001009954, 4040.636342729154, 4044.5146582410007, 4049.5140583130064, 3926.450296958428, 3950.841966321358, 3956.0, 3981.86063487778, 3990.361542153256, 3908.6482216600357, 3927.2145570885937, 3939.9212015759813, 3945.684345252373, 3963.861264855745, 2854.985725071382, 3376.9831150844334, 3401.829908504565, 3408.403529382373, 3445.1903802606366, 4397.758123303217, 4801.68789028712, 5026.220935754964, 5109.0, 5124.4106927703415, 3829.1767270037076, 4514.7291162530355, 4519.344695019204, 4534.841280555198, 4552.385427967249, 4780.928286075712, 4830.584707646177, 4962.751862406902, 5469.453054694539, 5500.669959802384, 3243.5783348164587, 3730.7388482806214, 3748.756330838487, 3777.7700066443344, 3791.791451470168, 5013.718757965117, 5122.129238029544, 5318.808714771145, 5442.700651464168, 5781.439011466922, 3365.5936005756002, 3503.2468019375447, 3521.4717792330844, 3769.660730534265, 3804.2201348723997, 3223.7098661120613, 3674.5957944626603, 3829.8659546915724, 3873.133880928149, 3887.8250478728523, 3466.843992020365, 3823.4264860271182, 3932.803359831997, 4448.822047118079, 4476.462824461086, 4707.411573553306, 4874.098291816025, 5308.442613525602, 5345.973270133624, 5599.552035837123, 1203.3080978437433, 1237.789575772121, 1254.6737848159544, 1280.21266920843, 1296.2093123194843, 779.4933293359248, 793.508025024477, 793.928546430824, 802.8996375453069, 805.4361946637378, 3824.8852534424013, 4146.709730318909, 4205.642520385741, 4206.92894187245, 4231.005713657288, 4548.545145485491, 4589.095525356939, 4593.862184134481, 4597.057603191338, 4726.669133160714, 3974.3044967130636, 4026.557078721397, 4296.291111966553, 4315.568443155722, 4327.437433133672, 2123.6389813731703, 2170.446536133276, 2303.8387312887935, 2309.110992267955, 2323.1404380379367, 3831.923361532754, 4430.955690443118, 4574.588287054181, 4837.7097374157665, 4838.0082083172865, 3816.263600061965, 4654.230073640362, 4680.782996420955, 4757.500462451462, 4778.607188895923, 4738.360321356642, 4756.833510827105, 4778.522147785194, 4838.661293709477, 4843.885906241655, 4044.51465824103, 4348.391225228407, 4664.346991421272, 4688.695234809727, 4702.764861756967, 3619.164523419908, 4512.3908272382805, 4559.8372415033955, 4589.944312808139, 4688.718676879398, 4211.810468528863, 4661.557152070548, 4814.15752243349, 4891.241857512091, 4923.753812309442, 2715.3660948879556, 3170.3831046167084, 3202.3275112226693, 3284.490903909802, 3286.753493488046, 3730.328540862562, 4674.368960190467, 4683.531646835289, 4692.788824502837, 4742.336072949859, 4134.470246008996, 4407.669424793217, 4560.749158796298, 4577.0, 4606.838760643362, 4033.515978082649, 4109.773962432095, 4158.8336466540995, 4310.297432514077, 4469.486009108864, 4116.403121547299, 4157.792110394407, 4378.2119218540965, 5068.391792984866, 5286.312779338661, 4870.537298956593, 5201.76592053366, 5367.597430192673, 5490.917636235539, 5567.582431317586, 4568.200564901195, 5193.896122077613, 5332.6000549958135, 5334.919976200283, 5371.167469042305], "value": 3659.1184510556413, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.5372623479151, 729.9963500182502, 736.2379936765448, 770.6801677303919, 785.7210690204973, 11799.999999999989, 14891.329890154942, 14894.776578351333, 14928.178950157751, 14951.925240373797, 13196.670083247924, 13293.800592991112, 13308.800367994463, 13336.59990200293, 13339.399727012284, 659.2122413715612, 677.5257319876082, 803.1566854802463, 811.3711873298197, 828.1883753921161, 818.7461886815076, 827.6813426830653, 831.501099340397, 832.7834762961613, 832.8584140696096, 872.7556284240391, 874.1171416868945, 874.903760586335, 877.1623099939543, 878.0166213840507, 683.702589373624, 710.3677726823131, 869.7869022089566, 869.8086420987372, 869.969551065716, 626.4643729611208, 643.6073994863129, 644.3556443556444, 645.554567348528, 646.4828137490036, 13860.514881979183, 13911.930440347835, 13930.51243206493, 13933.0, 13935.721285574231, 12671.0, 12776.297303648296, 12852.678683032906, 12898.290594017326, 12955.935220323932, 11694.883051169465, 11746.119041071868, 11834.940825295904, 11836.940815295955, 12171.0871684624, 4146.150039241948, 4152.91694166118, 4252.383404406375, 4337.0458499130145, 4343.652507799368, 79555.60222198852, 80510.59744701297, 80657.0, 81612.59193703994, 82588.58705706433, 10199.949000254952, 10215.897841021495, 10246.38521688701, 10281.074703276741, 10456.843147352796, 19010.144543495575, 19034.904825475784, 19034.953077580867, 19038.428847134608, 19043.619127617647, 7112.395446387011, 7378.225286344857, 7466.216047314953, 7472.626368681653, 7483.438742094365, 4055.858044968412, 4057.715959882839, 4167.8958026049595, 4211.389348544444, 4285.057287396768, 3189.059227527885, 3279.3277378137423, 3284.0476261883787, 3293.555370025064, 3341.7827841190247, 556.9610127291131, 615.4215037864393, 626.5551458464541, 626.6709977261974, 643.2441880790053, 497.8182963218399, 520.6875874475285, 522.4775224775225, 690.7720452250745, 691.1982100763091, 8244.54654993969, 8908.198262156437, 9061.0, 9077.273818094536, 9146.0, 576.766409604111, 582.4001278682963, 585.8183962971407, 612.8896798576299, 692.3872372949875, 3971.860984865516, 4108.281050816096, 4412.691111622155, 4450.888727781767, 4466.59800617946, 102916.0, 106959.46520267504, 109981.0, 113240.43379783048, 116216.41891790487, 59918.0, 60196.0, 65981.0, 71658.64170679217, 82750.17249827425, 65208.71769488045, 69432.61134777276, 77569.0, 82521.0, 84964.0, 9164.963387772239, 9475.810483790148, 9625.03749625032, 9648.75878103053, 9728.906518287928, 42657.37412319988, 50757.731056723875, 50987.725306866145, 53875.73062134664, 56566.0, 78023.0, 78036.60981695056, 78152.0, 80111.0, 80697.0, 12314.630561083182, 12467.314297713712, 12497.0, 12830.498052879972, 13033.47866085346, 14083.295835208404, 14122.717545648824, 14199.290035497977, 14801.111933284037, 14853.48012819546, 12242.265464072185, 12527.31099789484, 13009.934950325187, 13018.804717929428, 13772.173669579857, 10513.737156571147, 12573.182743121668, 13340.733185336432, 13486.595402137951, 13502.527411540548, 12659.0, 12765.0, 13385.732285354434, 13534.796978045533, 13540.119892206974, 10757.677269681923, 10778.29941053829, 11151.721206969567, 11170.88829111731, 11442.94278528602, 8925.375223734276, 10447.111995480469, 10812.837807432737, 10947.945260273647, 11044.061254793432], "value": 7113.3417762330755, "unit": "score"}], "name": "dromaeo_css", "value": 6907.111090036592, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 114.0, 114.0, 115.0, 113.0, 114.0, 115.0, 115.0, 118.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 113.0, 163.0, 141.0, 98.0, 128.0, 97.0, 161.0, 139.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 185.0, 185.0, 187.0, 181.0, 182.0, 182.0, 184.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 83.0, 112.0, 83.0, 65.0, 58.0, 58.0, 80.0, 58.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 89.0, 55.0, 89.0, 97.0, 124.0, 95.0, 97.0, 92.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 90.0, 88.0, 91.0, 87.0, 89.0, 130.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 99.0, 99.0, 98.0, 99.0, 118.0, 162.0, 151.0, 99.0], "value": 112.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 81.0, 84.0, 82.0, 95.0, 136.0, 126.0, 83.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 55.0, 55.0, 54.0, 56.0, 57.0, 55.0, 56.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 66.0, 81.0, 74.0, 73.0, 74.0, 69.0, 71.0, 71.0, 71.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 89.0, 89.0, 86.0, 85.0, 86.0, 86.0, 90.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 148.0, 146.0, 134.0, 151.0, 159.0, 161.0, 203.0, 149.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 174.0, 171.0, 174.0, 174.0, 177.0, 174.0, 175.0, 224.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 67.0, 74.0, 73.0, 86.0, 85.0, 69.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1511.8999999999999, "alertThreshold": 2.0}]} 20:57:46 INFO - Return code: 0 20:57:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:57:46 INFO - # TBPL SUCCESS # 20:57:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:57:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:57:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:57:46 INFO - 2016-10-31 20:57:46.815 screenresolution[1677:10710] starting screenresolution argv=screenresolution get 20:57:46 INFO - 2016-10-31 20:57:46.818 screenresolution[1677:10710] Display 0: 1600x1200x32@60 20:57:46 INFO - 2016-10-31 20:57:46.824 screenresolution[1678:10714] starting screenresolution argv=screenresolution list 20:57:46 INFO - Available Modes on Display 0 20:57:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:57:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:57:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:57:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:57:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:57:47 INFO - Intel Iris: 20:57:47 INFO - Chipset Model: Intel Iris 20:57:47 INFO - Type: GPU 20:57:47 INFO - Bus: Built-In 20:57:47 INFO - VRAM (Dynamic, Max): 1536 MB 20:57:47 INFO - Vendor: Intel (0x8086) 20:57:47 INFO - Device ID: 0x0a2e 20:57:47 INFO - Revision ID: 0x0009 20:57:47 INFO - Displays: 20:57:47 INFO - Display: 20:57:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:57:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:57:47 INFO - Display Serial Number: 1600x1200 60 20:57:47 INFO - Main Display: Yes 20:57:47 INFO - Mirror: Off 20:57:47 INFO - Online: Yes 20:57:47 INFO - Rotation: Supported 20:57:47 INFO - Return code: 0 20:57:47 INFO - Running post-action listener: _resource_record_post_action 20:57:47 INFO - [mozharness: 2016-11-01 03:57:47.061646Z] Finished run-tests step (success) 20:57:47 INFO - Running post-run listener: _resource_record_post_run 20:57:47 INFO - Total resource usage - Wall time: 1216s; CPU: 54.0%; Read bytes: 33131520; Write bytes: 710041088; Read time: 313; Write time: 2558 20:57:47 INFO - TinderboxPrint: CPU usage
53.8% 20:57:47 INFO - TinderboxPrint: I/O read bytes / time
33,131,520 / 313 20:57:47 INFO - TinderboxPrint: I/O write bytes / time
710,041,088 / 2,558 20:57:47 INFO - TinderboxPrint: CPU idle
2,248.6 (46.2%) 20:57:47 INFO - TinderboxPrint: CPU system
974.9 (20.0%) 20:57:47 INFO - TinderboxPrint: CPU user
1,642.3 (33.8%) 20:57:47 INFO - TinderboxPrint: Swap in / out
436,469,760 / 0 20:57:47 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400042496; Write bytes: 395331584; Read time: 30506; Write time: 780 20:57:47 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31144960; Write bytes: 298358272; Read time: 290; Write time: 1734 20:57:47 INFO - Running post-run listener: _upload_blobber_files 20:57:47 INFO - Blob upload gear active. 20:57:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:57:47 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 20:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:57:47 INFO - (blobuploader) - INFO - Open directory for files ... 20:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:57:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:49 INFO - (blobuploader) - INFO - Done attempting. 20:57:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:57:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:50 INFO - (blobuploader) - INFO - Done attempting. 20:57:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:57:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:57:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:52 INFO - (blobuploader) - INFO - Done attempting. 20:57:52 INFO - (blobuploader) - INFO - Iteration through files over. 20:57:52 INFO - Return code: 0 20:57:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:57:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:57:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01df27d98c91ff2ee77e14c8bef0d58007ea607bb711bcd5f88789aa30c7c9f4f543d562e69919a2255b95fe6d1169620aa39c2ad639ace1e065fcf458502ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9550cf85485abc8d67923368893c8d701b44ee082adac9a6fb8c96d3e30a2a352745604238a5e6042dbd3acabcc85120b5c8d416b821cd49ff7571154d99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edd5a64e59893c5afc9c8d54269b181224c3f61ea1dee9f6a33082c4d9805264af4fdbf30e3aa78eb56c76a5a05e747d100606f9f745ad1ef14f18b9392e1d6f"} 20:57:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:57:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:57:52 INFO - Contents: 20:57:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01df27d98c91ff2ee77e14c8bef0d58007ea607bb711bcd5f88789aa30c7c9f4f543d562e69919a2255b95fe6d1169620aa39c2ad639ace1e065fcf458502ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9550cf85485abc8d67923368893c8d701b44ee082adac9a6fb8c96d3e30a2a352745604238a5e6042dbd3acabcc85120b5c8d416b821cd49ff7571154d99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edd5a64e59893c5afc9c8d54269b181224c3f61ea1dee9f6a33082c4d9805264af4fdbf30e3aa78eb56c76a5a05e747d100606f9f745ad1ef14f18b9392e1d6f"} 20:57:52 INFO - Running post-run listener: copy_logs_to_upload_dir 20:57:52 INFO - Copying logs to upload dir... 20:57:52 INFO - mkdir: /builds/slave/test/build/upload/logs 20:57:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.425289 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-31 20:57:52.932317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:52.936866) ========= 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.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01df27d98c91ff2ee77e14c8bef0d58007ea607bb711bcd5f88789aa30c7c9f4f543d562e69919a2255b95fe6d1169620aa39c2ad639ace1e065fcf458502ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9550cf85485abc8d67923368893c8d701b44ee082adac9a6fb8c96d3e30a2a352745604238a5e6042dbd3acabcc85120b5c8d416b821cd49ff7571154d99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edd5a64e59893c5afc9c8d54269b181224c3f61ea1dee9f6a33082c4d9805264af4fdbf30e3aa78eb56c76a5a05e747d100606f9f745ad1ef14f18b9392e1d6f"} build_url:https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01df27d98c91ff2ee77e14c8bef0d58007ea607bb711bcd5f88789aa30c7c9f4f543d562e69919a2255b95fe6d1169620aa39c2ad639ace1e065fcf458502ef7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9550cf85485abc8d67923368893c8d701b44ee082adac9a6fb8c96d3e30a2a352745604238a5e6042dbd3acabcc85120b5c8d416b821cd49ff7571154d99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edd5a64e59893c5afc9c8d54269b181224c3f61ea1dee9f6a33082c4d9805264af4fdbf30e3aa78eb56c76a5a05e747d100606f9f745ad1ef14f18b9392e1d6f"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:52.984278) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:52.985189) ========= 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.xEuV7hUZt6/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.tCwriiHC08/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005056 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:53.049862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 20:57:53.051146) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 20:57:53.052390) ========= ========= Total master_lag: 0.29 =========