builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0385 starttime: 1477971390.59 results: success (0) buildid: 20161031183806 builduid: 5c1309ba02a242e1bfe279050b2dbb8d revision: 0899c2b63e21814a51d18c415baa534bcb29fa8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:30.592522) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:30.592955) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:30.593223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:30.618563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:30.618864) ========= 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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2016-10-31 20:36:31 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.598522 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.246099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.246640) ========= 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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.024940 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:31.315867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 20:36:31.319523) ========= 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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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,406 truncating revision to first 12 chars 2016-10-31 20:36:31,406 Setting DEBUG logging. 2016-10-31 20:36:31,406 attempt 1/10 2016-10-31 20:36:31,406 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:32,158 unpacking tar archive at: mozilla-central-0899c2b63e21/testing/mozharness/ program finished with exit code 0 elapsedTime=1.016197 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 20:36:32.360064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.360622) ========= script_repo_revision: 0899c2b63e21814a51d18c415baa534bcb29fa8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.361189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.363860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 20:36:32.434807) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-31 20:36:32.435408) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:32 INFO - MultiFileLogger online at 20161031 20:36:32 in /builds/slave/test 20:36:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:36:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:36:32 INFO - {'append_to_log': False, 20:36:32 INFO - 'base_work_dir': '/builds/slave/test', 20:36:32 INFO - 'blob_upload_branch': 'Firefox', 20:36:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:36:32 INFO - 'branch': 'Firefox', 20:36:32 INFO - 'buildbot_json_path': 'buildprops.json', 20:36:32 INFO - 'config_files': ('talos/mac_config.py',), 20:36:32 INFO - 'default_actions': ('clobber', 20:36:32 INFO - 'read-buildbot-config', 20:36:32 INFO - 'download-and-extract', 20:36:32 INFO - 'populate-webroot', 20:36:32 INFO - 'create-virtualenv', 20:36:32 INFO - 'install', 20:36:32 INFO - 'run-tests'), 20:36:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:36:32 INFO - 'download_minidump_stackwalk': True, 20:36:32 INFO - 'download_symbols': 'ondemand', 20:36:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:36:32 INFO - 'tooltool.py': '/tools/tooltool.py', 20:36:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:36:32 INFO - '/tools/misc-python/virtualenv.py')}, 20:36:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:36:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:36:32 INFO - 'installer_path': 'installer.exe', 20:36:32 INFO - 'log_level': 'info', 20:36:32 INFO - 'log_name': 'talos', 20:36:32 INFO - 'log_to_console': True, 20:36:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:36:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:36:32 INFO - 'opt_config_files': (), 20:36:32 INFO - 'pip_index': False, 20:36:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:36:32 INFO - 'cmd': ('bash', 20:36:32 INFO - '-c', 20:36:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:36:32 INFO - 'enabled': True, 20:36:32 INFO - 'halt_on_failure': False, 20:36:32 INFO - 'name': 'check_screen_resolution'},), 20:36:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:36:32 INFO - 'cmd': ('bash', 20:36:32 INFO - '-c', 20:36:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:36:32 INFO - 'enabled': True, 20:36:32 INFO - 'halt_on_failure': False, 20:36:32 INFO - 'name': 'check_screen_resolution'},), 20:36:32 INFO - 'run_cmd_checks_enabled': True, 20:36:32 INFO - 'sps_profile': False, 20:36:32 INFO - 'sps_profile_interval': 0, 20:36:32 INFO - 'suite': 'dromaeojs-e10s', 20:36:32 INFO - 'system_bits': '32', 20:36:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:36:32 INFO - 'title': 't-yosemite-r7-0385', 20:36:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:36:32 INFO - 'use_talos_json': True, 20:36:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:36:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:36:32 INFO - 'work_dir': 'build'} 20:36:32 INFO - [mozharness: 2016-11-01 03:36:32.647748Z] Running clobber step. 20:36:32 INFO - Running pre-action listener: _resource_record_pre_action 20:36:32 INFO - Running main action method: clobber 20:36:32 INFO - rmtree: /builds/slave/test/build 20:36:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:36:34 INFO - Running post-action listener: _resource_record_post_action 20:36:34 INFO - [mozharness: 2016-11-01 03:36:34.860798Z] Finished clobber step (success) 20:36:34 INFO - [mozharness: 2016-11-01 03:36:34.861004Z] Running read-buildbot-config step. 20:36:34 INFO - Running pre-action listener: _resource_record_pre_action 20:36:34 INFO - Running main action method: read_buildbot_config 20:36:34 INFO - Using buildbot properties: 20:36:34 INFO - { 20:36:34 INFO - "project": "", 20:36:34 INFO - "product": "firefox", 20:36:34 INFO - "script_repo_revision": "production", 20:36:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:36:34 INFO - "repository": "", 20:36:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:36:34 INFO - "buildid": "20161031183806", 20:36:34 INFO - "pgo_build": "False", 20:36:34 INFO - "basedir": "/builds/slave/test", 20:36:34 INFO - "buildnumber": 83, 20:36:34 INFO - "slavename": "t-yosemite-r7-0385", 20:36:34 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 20:36:34 INFO - "platform": "yosemite_r7", 20:36:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:36:34 INFO - "branch": "mozilla-central", 20:36:34 INFO - "repo_path": "mozilla-central", 20:36:34 INFO - "revision": "0899c2b63e21814a51d18c415baa534bcb29fa8d", 20:36:34 INFO - "stage_platform": "macosx64", 20:36:34 INFO - "builduid": "5c1309ba02a242e1bfe279050b2dbb8d", 20:36:34 INFO - "slavebuilddir": "test" 20:36:34 INFO - } 20:36:34 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:34 INFO - Running post-action listener: _resource_record_post_action 20:36:34 INFO - [mozharness: 2016-11-01 03:36:34.865963Z] Finished read-buildbot-config step (success) 20:36:34 INFO - [mozharness: 2016-11-01 03:36:34.866095Z] Running download-and-extract step. 20:36:34 INFO - Running pre-action listener: _resource_record_pre_action 20:36:34 INFO - Running main action method: download_and_extract 20:36:34 INFO - mkdir: /builds/slave/test/build/tests 20:36:34 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:34 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:34 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:34 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:36 INFO - Http code: 200 20:36:36 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 20:36:36 INFO - x-amz-cf-id: n6Rvnjl9wM2qTZkjGSS3uraXXb8Bx28plIR1t7gpHTpr9wgLkBTf-w== 20:36:36 INFO - x-amz-version-id: cHM675KUxxyAyFJm6gxZlZotvoi8BfYK 20:36:37 INFO - Expected file size: 16404806 20:36:37 INFO - Obtained file size: 16404806 20:36:43 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:43 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:43 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:43 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:44 INFO - Http code: 200 20:36:44 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 20:36:44 INFO - x-amz-cf-id: oIwL3a0fUpzHzaberG1GVYbIPIh5w5-Sike122BE4x8P6WAhyy8WSA== 20:36:44 INFO - x-amz-version-id: cHM675KUxxyAyFJm6gxZlZotvoi8BfYK 20:36:44 INFO - Expected file size: 16404806 20:36:44 INFO - Obtained file size: 16404806 20:36:50 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:50 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:50 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:51 INFO - Http code: 200 20:36:51 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 20:36:51 INFO - x-amz-cf-id: Z2VZDJYIfYHvyPH8--Hcx6AXYmZt5SNzvkTLelcYmVAAoQ6_GOWOdg== 20:36:51 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:57 INFO - Running post-action listener: _resource_record_post_action 20:36:57 INFO - Running post-action listener: set_extra_try_arguments 20:36:57 INFO - [mozharness: 2016-11-01 03:36:57.883808Z] Finished download-and-extract step (success) 20:36:57 INFO - [mozharness: 2016-11-01 03:36:57.883921Z] Running populate-webroot step. 20:36:57 INFO - Running pre-action listener: _resource_record_pre_action 20:36:57 INFO - Running main action method: populate_webroot 20:36:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:36:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:36:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:36:57 INFO - '--tppagecycles', 20:36:57 INFO - '7'], 20:36:57 INFO - 'tests': ['tsvgm']}}, 20:36:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:36:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:36:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:36:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:36:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:36:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['damp', 'tps']}, 20:36:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'tests': ['damp', 'tps']}, 20:36:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['dromaeo_dom']}, 20:36:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:36:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['basic_compositor_video']}, 20:36:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:36:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['a11yr', 20:36:57 INFO - 'ts_paint', 20:36:57 INFO - 'tpaint', 20:36:57 INFO - 'sessionrestore', 20:36:57 INFO - 'sessionrestore_no_auto_restore', 20:36:57 INFO - 'tabpaint']}, 20:36:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['tsvgx', 20:36:57 INFO - 'tsvgr_opacity', 20:36:57 INFO - 'tart', 20:36:57 INFO - 'tscrollx', 20:36:57 INFO - 'cart']}, 20:36:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:36:57 INFO - 'tsvgr_opacity', 20:36:57 INFO - 'tart', 20:36:57 INFO - 'tscrollx', 20:36:57 INFO - 'cart']}, 20:36:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'talos_options': ['--disable-e10s'], 20:36:57 INFO - 'tests': ['tp5o']}, 20:36:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'tests': ['tp5o']}, 20:36:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'talos_options': ['--disable-e10s', 20:36:57 INFO - '--xperf_path', 20:36:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:57 INFO - 'tests': ['tp5n']}, 20:36:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:36:57 INFO - 'talos_options': ['--xperf_path', 20:36:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:36:57 INFO - 'tests': ['tp5n']}}, 20:36:57 INFO - 'talos.zip': {'path': '', 20:36:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:36:57 INFO - Running post-action listener: _resource_record_post_action 20:36:57 INFO - [mozharness: 2016-11-01 03:36:57.902777Z] Finished populate-webroot step (success) 20:36:57 INFO - [mozharness: 2016-11-01 03:36:57.902918Z] Running create-virtualenv step. 20:36:57 INFO - Running pre-action listener: _resource_record_pre_action 20:36:57 INFO - Running main action method: create_virtualenv 20:36:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:36:57 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:57 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:01 INFO - Reading from file tmpfile_stdout 20:37:01 INFO - Output received: 20:37:01 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:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:37:01 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:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:01 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:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:01 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:01 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:01 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:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvXwMP0hMD/Render', 20:37:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:01 INFO - 'HOME': '/Users/cltbld', 20:37:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:01 INFO - 'LOGNAME': 'cltbld', 20:37:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:01 INFO - 'MOZ_NO_REMOTE': '1', 20:37:01 INFO - 'NO_EM_RESTART': '1', 20:37:01 INFO - 'PAGER': '/bin/cat', 20:37:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:01 INFO - 'PWD': '/builds/slave/test', 20:37:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:01 INFO - 'SHELL': '/bin/bash', 20:37:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cz9MIOdXYD/Listeners', 20:37:01 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 20:37:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:01 INFO - 'USER': 'cltbld', 20:37:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:01 INFO - 'XPC_FLAGS': '0x0', 20:37:01 INFO - 'XPC_SERVICE_NAME': '0', 20:37:01 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:02 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:37:06 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:07 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:10 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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: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 - Successfully installed jsonschema functools32 20:37:13 INFO - Cleaning up... 20:37:13 INFO - Return code: 0 20:37:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:37:13 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:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:13 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:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:13 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:13 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:13 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:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvXwMP0hMD/Render', 20:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:13 INFO - 'HOME': '/Users/cltbld', 20:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:13 INFO - 'LOGNAME': 'cltbld', 20:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:13 INFO - 'MOZ_NO_REMOTE': '1', 20:37:13 INFO - 'NO_EM_RESTART': '1', 20:37:13 INFO - 'PAGER': '/bin/cat', 20:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:13 INFO - 'PWD': '/builds/slave/test', 20:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:13 INFO - 'SHELL': '/bin/bash', 20:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cz9MIOdXYD/Listeners', 20:37:13 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 20:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:13 INFO - 'USER': 'cltbld', 20:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:13 INFO - 'XPC_FLAGS': '0x0', 20:37:13 INFO - 'XPC_SERVICE_NAME': '0', 20:37:13 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:14 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:17 INFO - Downloading/unpacking docopt==0.6.1 (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: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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:19 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Zdi4e9-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-SAQSZE-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-o10T9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:37:19 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-F8fyQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:37:19 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-QLJXyC-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-CtQ4uJ-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-wJnLLZ-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-xMoPD3-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-nPDhnC-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Hn4dBL-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-UhNOyG-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-7ka3Kp-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-jC7RPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:37:20 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-uwiflm-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HMHerL-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-R90CJk-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-UNTnDU-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:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:37:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:37:21 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:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:37:22 INFO - Running setup.py install for moznetwork 20:37:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:37:22 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:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:37:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:37:23 INFO - Cleaning up... 20:37:23 INFO - Return code: 0 20:37:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:37:23 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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:23 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:23 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:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvXwMP0hMD/Render', 20:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:37:23 INFO - 'HOME': '/Users/cltbld', 20:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:37:23 INFO - 'LOGNAME': 'cltbld', 20:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:37:23 INFO - 'MOZ_NO_REMOTE': '1', 20:37:23 INFO - 'NO_EM_RESTART': '1', 20:37:23 INFO - 'PAGER': '/bin/cat', 20:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:37:23 INFO - 'PWD': '/builds/slave/test', 20:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:37:23 INFO - 'SHELL': '/bin/bash', 20:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cz9MIOdXYD/Listeners', 20:37:23 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 20:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:37:23 INFO - 'USER': 'cltbld', 20:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:37:23 INFO - 'XPC_FLAGS': '0x0', 20:37:23 INFO - 'XPC_SERVICE_NAME': '0', 20:37:23 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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-QsbBNl-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HgycYJ-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hxtdwl-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-T9CgmP-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-tZapYk-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-eKHIsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:37:24 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:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:37:24 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-5OgB6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:37:24 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:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:37:24 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-YsDzOD-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-NYQkQ1-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HHKZ6I-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vIWhW7-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rcqbQc-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-h86VFc-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-aO5FXM-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2DlEGT-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/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-f4PPTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:37:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:37:25 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-RsgQGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:37:25 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:25 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:25 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:25 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:25 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:25 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:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:37:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:28 INFO - Successfully installed blessings 20:37:28 INFO - Cleaning up... 20:37:28 INFO - Return code: 0 20:37:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:37:28 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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:29 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 0x109594490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10950a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0b0dee140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, '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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners', 20:37:29 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.740864Z] Finished create-virtualenv step (success) 20:37:29 INFO - [mozharness: 2016-11-01 03:37:29.741877Z] 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:29 INFO - Reading from file tmpfile_stdout 20:37:29 INFO - Detecting whether we're running mozinstall >=1.0... 20:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:37:29 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:04 INFO - Reading from file tmpfile_stdout 20:38:04 INFO - Output received: 20:38:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:38:04 INFO - Running post-action listener: _resource_record_post_action 20:38:04 INFO - [mozharness: 2016-11-01 03:38:04.504229Z] Finished install step (success) 20:38:04 INFO - [mozharness: 2016-11-01 03:38:04.504366Z] Running run-tests step. 20:38:04 INFO - Running pre-action listener: _resource_record_pre_action 20:38:04 INFO - Running main action method: run_tests 20:38:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:38:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:38:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:38:04 INFO - 2016-10-31 20:38:04.519 screenresolution[1642:8282] starting screenresolution argv=screenresolution get 20:38:04 INFO - 2016-10-31 20:38:04.522 screenresolution[1642:8282] Display 0: 1600x1200x32@60 20:38:04 INFO - 2016-10-31 20:38:04.528 screenresolution[1643:8284] starting screenresolution argv=screenresolution list 20:38:04 INFO - Available Modes on Display 0 20:38:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:38:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:38:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:38:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:38:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:38:04 INFO - Intel Iris: 20:38:04 INFO - Chipset Model: Intel Iris 20:38:04 INFO - Type: GPU 20:38:04 INFO - Bus: Built-In 20:38:04 INFO - VRAM (Dynamic, Max): 1536 MB 20:38:04 INFO - Vendor: Intel (0x8086) 20:38:04 INFO - Device ID: 0x0a2e 20:38:04 INFO - Revision ID: 0x0009 20:38:04 INFO - Displays: 20:38:04 INFO - Display: 20:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:38:04 INFO - Display Serial Number: 1600x1200 60 20:38:04 INFO - Main Display: Yes 20:38:04 INFO - Mirror: Off 20:38:04 INFO - Online: Yes 20:38:04 INFO - Rotation: Supported 20:38:04 INFO - Return code: 0 20:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:38:04 INFO - Python 2.7.3 20:38:04 INFO - Return code: 0 20:38:04 INFO - grabbing minidump binary from tooltool 20:38:04 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:04 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 0x1093a8d30>, '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 0x1091cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:38:04 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:04 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:04 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:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:38:04 INFO - Return code: 0 20:38:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:38:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:38:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:38:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:38:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --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-0385 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvXwMP0hMD/Render', 20:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:04 INFO - 'HOME': '/Users/cltbld', 20:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:04 INFO - 'LOGNAME': 'cltbld', 20:38:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:38:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:04 INFO - 'MOZ_NO_REMOTE': '1', 20:38:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:38:04 INFO - 'NO_EM_RESTART': '1', 20:38:04 INFO - 'PAGER': '/bin/cat', 20:38:04 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:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:04 INFO - 'PWD': '/builds/slave/test', 20:38:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:04 INFO - 'SHELL': '/bin/bash', 20:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cz9MIOdXYD/Listeners', 20:38:04 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 20:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:04 INFO - 'USER': 'cltbld', 20:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:04 INFO - 'XPC_FLAGS': '0x0', 20:38:04 INFO - 'XPC_SERVICE_NAME': '0', 20:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:38:05 INFO - mozversion application_buildid: 20161031183806 20:38:05 INFO - mozversion application_changeset: 0899c2b63e21814a51d18c415baa534bcb29fa8d 20:38:05 INFO - mozversion application_display_name: Nightly 20:38:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:38:05 INFO - mozversion application_name: Firefox 20:38:05 INFO - mozversion application_remotingname: firefox 20:38:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:38:05 INFO - mozversion application_vendor: Mozilla 20:38:05 INFO - mozversion application_version: 52.0a1 20:38:05 INFO - mozversion platform_buildid: 20161031183806 20:38:05 INFO - mozversion platform_changeset: 0899c2b63e21814a51d18c415baa534bcb29fa8d 20:38:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:38:05 INFO - mozversion platform_version: 52.0a1 20:38:05 INFO - using testdate: 1477971485 20:38:05 INFO - actual date: 1477971485 20:38:05 INFO - starting webserver on 'localhost:49274' 20:38:05 INFO - SUITE-START | Running 2 tests 20:38:05 INFO - TEST-START | dromaeo_css 20:38:05 INFO - operating with platform_type : mac_ 20:38:05 INFO - Initialising browser for dromaeo_css test... 20:38:05 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmp3nH92N/profile http://localhost:49274/getInfo.html) 20:38:07 INFO - PROCESS | 1653 | 2016-10-31 20:38:07.879 plugin-container[1656:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 20:38:07 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:38:07 INFO - PROCESS | 1653 | 2016-10-31 20:38:07.882 plugin-container[1656:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 20:38:07 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:38:07 INFO - PROCESS | 1653 | 2016-10-31 20:38:07.882 plugin-container[1656:8427] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:38:07 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 20:38:07 INFO - PROCESS | 1653 | colorDepth:24 20:38:07 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 20:38:07 INFO - PROCESS | 1653 | __metrics 20:38:08 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 20:38:08 INFO - TEST-INFO | 1653: exit 0 20:38:08 INFO - Browser initialized. 20:38:08 INFO - Running cycle 1/1 for dromaeo_css test... 20:38:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmp3nH92N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:38:11 INFO - PROCESS | 1657 | 2016-10-31 20:38:11.346 plugin-container[1658:8540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 20:38:11 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:38:11 INFO - PROCESS | 1657 | 2016-10-31 20:38:11.347 plugin-container[1658:8540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 20:38:11 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:38:11 INFO - PROCESS | 1657 | 2016-10-31 20:38:11.347 plugin-container[1658:8540] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:38:20 INFO - PROCESS | 1657 | RSS: Main: 237641728 20:38:20 INFO - PROCESS | 1657 | 20:39:10 INFO - PROCESS | 1657 | 1477971550506 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:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 20:41:25 INFO - PROCESS | 1657 | RSS: Main: 250400768 20:41:25 INFO - PROCESS | 1657 | 20:44:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 20:44:28 INFO - PROCESS | 1657 | RSS: Main: 260739072 20:44:28 INFO - PROCESS | 1657 | 20:47:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 20:47:31 INFO - PROCESS | 1657 | RSS: Main: 265195520 20:47:31 INFO - PROCESS | 1657 | 20:50:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 20:50:33 INFO - PROCESS | 1657 | RSS: Main: 263737344 20:50:33 INFO - PROCESS | 1657 | 20:53:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 20:53:37 INFO - PROCESS | 1657 | RSS: Main: 269922304 20:53:37 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 20:56:40 INFO - PROCESS | 1657 | RSS: Main: 273489920 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | __start_tp_report 20:56:40 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 20:56:40 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 20:56:40 INFO - PROCESS | 1657 | |i|pagename|runs| 20:56:40 INFO - PROCESS | 1657 | |0;dojo.html;2909.2726818295428;3277.88527401541;3311.188758754105;3340.615829179644;3354.7987120772755;4407.8236870525225;4476.216662084134;5484.808031718891;5541.806036788713;5558;4439.866803995886;5584.134459158827;5654.434556544334;5658.151277308406;5664.518515926141;5108.616853735966;5123.5388815006645;5142.151544995071;5198.844034678966;5259.658122222062;5107.514786095315;5218.112920803473;5229.764660590264;5265.552428043621;5275.683458992473;4179.498460184767;5028.396592408898;5178.508041736029;5223.320968274119;5249.895002099975;4101.446304748851;5088.821891233789;5170.1986192140275;5185.766640501187;5220.634555581111;5103.795848166069;5123.795048198068;5147.176451767699;5155.40712818027;5168.5606723428555;5481.205225242317;5526.917096243559;5691.459311365414;5692.1461780733225;5696.2594862668;5291.70353263449;6051.273847138372;6129.419919118773;6139.4001448443105;6175.3515880832765;6224.782132625336;6226;6241.531885108635;6246.219222597176;6262.405071518198;5754.539636829044;5762.481376676119;5774.422557744276;5782.10377391505;5833.591648584558;6162.383761623802;6207.937920620826;6211.472024877936;6233.719482623293;6240.282367527701;6028.517718582503;6066.3630318815885;6086;6123.632582045091;6125.724342404514;6047.425494578008;6202.504017936889;6217.409346112112;6234.251889773165;6265.71804268809;6026.6986650667295;6085.330613632497;6104.755809767648;6138.938610613926;6162;6202.34875338092;6211.068339749074;6220.906686399708;6222.160008398808;6246.625202487865;4213.9578604214175;4404.097160082176;4900.926486102711;4918.532739389752;4957.603391728653;4329.956700432956;4347.3261644445165;4368.912621747547;4400.977995110004;4464.285714285698;5724.427557244242;5747.48272655463;5762.366139724627;5786.218860453869;5811.56413268998;5496.587755918242;6132.049532322497;6176.104464852661;6179.16581261524;6245.812625621239;5595.580331475156;5699.715014249354;5708.143778433186;5750.942490575125;5781;5366.6779993200535;5372.838814835562;5376.865578360494;5391.649542779707;5426.186072089218;6241.843953901189;6275.529335299871;6283.434490895841;6293.55945083858;6297.023961285917;4663.903982564027;5376.817100237941;6115.788007244199;6246.250449946036;6255.374462553708;5208.921866171935;6169.136320914986;6249.1563638908165;6317.526185536011;6318.810435686937;6155.538334624832;6178.320384757583;6186.845328866814;6203.348648391854;6205.100260462117;6100.664463454553;6145.586515101641;6165.229346331708;6226;6244.406781355764;5375.19372094189;6010.278766548042;6020.578559500968;6126.540509461719;6128.869808884268;6039.124326972652;6071.210742603434;6083.939160608337;6087.908681369871;6088.117223002729;5608.242887210337;5790.073588225863;5830.82507524775;5870.618409803349;5950.434708702666;6070.271567411939;6074.665893375906;6087.512998959984;6108.389161083856;6110.663913484801;5779.393163717749;5830.2420685310635;5837.357890631942;5883.29400471929;5901.822945311647 20:56:40 INFO - PROCESS | 1657 | |1;ext.html;454.5363728996426;460.35550229678444;473.79863557987846;509.72729589669524;529.3938440485643;38732;39894.20211595766;39969.80015099924;40150.59849401505;41633.37549936753;40640.39039414411;41070.7946460268;41103;41266;41271.38092928608;9381.390209636385;9507;9536.618535258582;9567.760805979837;9583.137517623412;9407.247420206368;9443.716688499357;9492;9521.143097121258;9534.427934323929;9460;9495.050494950485;9550.904490955105;9564.18704410126;9593.328467007312;9441.103095205945;9450.85823712645;9496.477693726842;9517.428954262732;9527.23782097434;9363.25093992479;9389.248860091244;9448.90551094494;9466.337356385056;9481.905180948172;38379;38489.65286214969;38607;38749.806250968846;39010;3266.96733032669;3293.5059741038795;3380.323935212967;3381.7801842880135;3673.4857120002953;29261.26846828825;30315.848420757975;30784;30804.691953080408;30834;3135.9059228222964;3252.577164968539;3307.536944827726;3377.071305391037;3383.171123074838;433605.6639433597;437835.81082094705;438904;439311;441303;49808.750956244985;50038;54277;62835.371646283864;64516.67741661262;56451.71774141103;64141.67929160324;66000.33999659942;66852.665736671;67544.66227668831;15782.210889455482;16038.43865464703;16039;16087.517374478784;16096.356145754273;12023.338716370683;12030;12125.636230913087;12238.959688426405;12250.877491224974;19336.675474773474;19505.06411215869;19561;19671.60656786885;19693;6771.830704232335;8014.559199243981;8056.274935255738;8120.837583248302;8160.795980100426;697.9635241666122;908.0873721909438;937.4328531238639;957.4255446732044;1125.836753670713;29572;29703.257418564277;30134.110902637152;30272.243193919887;30281.69718302833;764.1288930619098;840.4411066640738;869.6608322754131;950.8447236607417;988.3526290279879;12974;13614.523491677943;14803.62990925214;14904.776428353583;14910.329035193443;541184;620847.8957605183;622715;624120.8793956001;628332;40897;51157.97684046298;51326;52210.95578088417;52649.947001059765;1171.9531218751324;1325.1121748428384;1342.3489607540334;1343.5029039255537;1364.3314775759807;27261;28249.77783990498;28306.57540136941;28573.857130714212;28736.712632873405;46094.07811843811;46986.530134698216;47025.76487117542;47135.528644713115;47139.76430117827;556723;558158.4184158107;560061;560092;560891;24529.877350613133;25758.227253181685;25839.095631652803;26092;26121.869390653686;43150;43837.34243986406;44135.55864441315;44578.66264012085;44780;33655.99032029043;36481.81759091187;36810.263794724495;37379.252414951006;37553;29968.951086712732;36563;36732.26535469329;37046;39526.40710389404;32143.517847231844;32663.836680816443;36460.63539364572;37361.69234076794;37718.8114059428;33090.33819323552;34051.31897362089;34213;34313.142171445914;34445.48331775075;21721.565568688853;24691.629625555273;25045.49909001846;25311.74688253094;25650.358741031625 20:56:40 INFO - PROCESS | 1657 | |2;jquery.html;294.91300066480386;313.5030975903193;328.4596838201159;363.28479770310264;384.31783584138134;19366.031698415074;19855.30506432275;20945.37163885084;21235;21356;14056.64858378541;19658.213671453126;19677.311294104707;19884;19917.502062448446;5174.767135478913;5405.405405405402;5413.702246376448;5415.566754659638;5438.238646589481;5055.4186268579215;5342.813001544947;5409.053415652264;5415.296011518497;5430.70131142787;5052.343195384595;5312.574994000471;5377.677339359631;5399.298091248133;5407.188921661763;4675.625949923998;5221.425643179247;5318.654287471302;5373.919391209135;5383.353997520284;5216.60875434344;5237.659552129099;5240.947590524084;5246.924380502026;5247.39654939683;12806.935965320206;15371.154586497738;20379.5924081519;24324.513509729884;24583.38541536458;2291.0721157931066;2307.2501437032865;2334.6147885598857;2365.101261520611;2365.6451532270125;9768.21854251658;9867.90132098677;9934.751631209205;10311.690649280534;10321;1790.7224380221087;1893;1902.2200897632042;1905.7998910114516;1937.6996565532365;116100;116265.99999999916;122742.38628806888;124011;124087;9413.811723765486;11023.83464248037;11100.944495277472;11569.595064172714;11575.30548167096;14404.279786010868;14430.350634221486;14436.639084022983;14468.059576127518;14488.203148826915;7857.921420785833;7974.282314591715;8261.958690206511;8359.749207523784;8402.41183117176;6226.813195604139;7944.245296696805;8069.556174410346;8112.918870811334;8216.712415065444;4465.732056076581;4472.888177795516;4495;4503.099380123988;4507;1891.5176629959547;1960.578475627747;1988.1947811136513;1995.820376166152;1998.8107076289584;762.24537707669;799.4963173200846;849.479431817053;859.4198915731856;908.8318661047728;18621;20672;21468.141274349164;21594.68106378699;21603.410066484517;886.4902680958346;903.5798353765531;942.8632848236967;949.990500095004;977.5210147027905;10814.513346899408;11304.208705390709;11326.830097548544;11363.772724545628;11396.316221026598;490854.09145908797;539246;543597;561141;563380;76911.61544192243;79512.40975180463;114839;120541.39729301297;125319;953.1993125774383;954.289054654287;1026.9486525673688;1034.6895931220604;1035.984460233097;18945.43163705091;19940.50148746235;19983;19989.20043198314;20076.297329593395;55968.88062238651;56885;57133.42866571448;57436.13845792233;57566.7121664389;79901.80147297912;80249;80659;81738;82053.58973205095;8000.279974802295;8035.55804430762;8188.344932405513;8227.177282271725;8354.87467687973;12332.260064396165;12501.187422817487;12741.49034038628;12817.87182128204;12887.742245155232;8834.558272086242;10621.734456638645;11016.89831016892;11040.227184097357;11041.889581104408;10743.516541755796;10783.730406739895;10953.80923815256;11276.379799111128;11384.316940983235;10706.411147386969;10739.140868730326;10751.0324070834;10761.30051546647;10922.235443518877;7021;7324.523905946309;7388;7522.811929701801;8222.753317400488;8236.805663178926;8810.735677929672;10766.73083172927;10942.343459392461;11141.164412669244 20:56:40 INFO - PROCESS | 1657 | |3;mootools.html;1446.0383844743246;1494.8280947691014;1645.1445248470795;1763.9118044097793;1777.3246166456752;7499.325060744532;7780.844383112334;7937.722179723711;8546.829063418729;8555.31557475402;7966.044074711028;8227.588620568962;8250.669973201066;8386.87419688705;8412.663493460253;2271.205078222625;2292.025888997178;2292.931212063641;2295.5064661097863;2299.7585253548386;2251.234580242717;2285.8057065149483;2288.8512246703995;2307.5846347657416;2321.8142548596156;1995.4612254691224;2274.3177046885867;2282.657601359793;2292.541491701658;2311.7457079721216;2252.5720113178445;2256.17649557911;2275.0672224388086;2285.1088075650428;2290.0725206291477;1950.4343740315292;2286.2341115726085;2300.827437942144;2309.734380546242;2315.166540045582;8378.078411374743;8400;8415.915840841575;8454.704085357045;8483;5038.874028149289;5106.336176297058;5198.974005129988;5202;5218.9739051304505;3135.667341379927;3275.6724327567053;3512.1921957949817;3520.8415621297104;3522.982385088084;1007.89417111203;1042.4579218806177;1056.1128651932413;1057.1067448006422;1063.8989296016866;10501.737456563571;10802.567897284178;10865.782684346348;10891.67324980244;11063.778724425547;3860.189360234382;4089;4320.719153255029;4375.2280326467835;4388.363687265329;7490.614236366289;8242.052164001216;8610.95694521536;8617.396782225309;8642.913570864337;4969.329140566049;5059.873503162376;5067.873303167376;5071.061853557104;5078.492150784892;4246.766427846498;4398.912021759611;4603.263477843529;4688.624910007191;4706.905861882743;3540.964590354115;3576.7853928764357;3655.981720091436;3689.5572531296416;3691.9446208306895;1096.1888202729922;1132.2979752553306;1133.7619099988929;1137.732632831284;1141.9429028548539;792.3977776889606;859.8839156713889;864.2308345572445;898.1826537850525;922.524899676667;5029.195328747456;5245.977034478316;5374.27447294618;5442.891142177214;5560.277163968658;856.1609622569916;895.5253715530649;909.2044461096541;912.7763697894059;913.6071489259549;2920.474314623346;3057.373238486105;3707.405815499307;3765.3034188675183;3811.561670407883;9512.28657850664;10297.53661085253;10316.43259620728;10328.535215915303;10384.792304153874;9545.713628591153;9840.409575425498;9888.505574721235;10103.84844227337;10148.98510148994;7685.23147685227;7737.883931741028;7891.171427000198;7952.005999250209;7957.244061814118;4616.838410655745;5684.772609095755;5774.971125144348;5777.364489906023;5858.945389829701;9340.71977840666;9351.625934962525;9371.906280937103;9407.012263712486;9494.430334179971;9381.921079075993;9489.051094890456;9506.524673766145;9628.422294662343;9646.614135434504;5817.563682723728;6610.272869984202;6808.28513006147;6994.090768200102;7265.527740696838;6663.467402497356;6971.5468494547695;7728.613569321399;7865.842683146419;8073.798155046171;6155.538334624832;7078.0444639975;7210.9639451804505;7238.746643867439;7292.635368231461;7066.646667666493;7074.398676112588;7098.858022839618;7112.03987461686;7174.569525828467;6601.504887133388;6625;6639.136912201576;6663.167104111986;6752.729890804313;4784.138855006132;4837.2260438329695;4853.369062021915;4874.902501950012;4937.407511098691;4788.353572267839;4851.58761505276;4872.878178045436;4925.482824303396;4955.752212389438 20:56:40 INFO - PROCESS | 1657 | |4;prototype.html;295.7988567773913;300.56418193619254;305.0588933141259;308.64968261023733;311.89395605494127;5110.233464980255;5253.842384728455;5318.202269659553;5440.782368705247;5526.226328314039;4345.78271086446;4863.36776219091;5343.759530821113;5352.2239275305055;5359.829269145992;4064.878053658395;4071.409645601386;4086.7547947123267;4092.386142078697;4096.692748043909;3544.8227588620593;3572.321258960802;3737.803902751121;4094.651954583864;4109.6712263019035;3978.1844721831953;4041.7777022263763;4045.7370270932297;4059.4925634295714;4068.877933661995;3711.2020915503294;3855.5373355197275;4029.597040295976;4073.5111786585503;4074.8166332515107;2952.1438782752975;3439.4324936385465;3555.4489054196642;3562.305350456662;3582.695470884978;3976.3991773655594;5129.487051294878;5198.2722418861385;5219;5242.6330156889035;4585.564371384747;4591.225125869092;4603.309503574457;4624.213883639755;4656.441227052742;5597.720113994284;5641.323041235078;5646.717664116819;5650.378458369576;5676.631018983794;3633.1643721943838;3652.520729104717;3670.3026424979435;3835.673967712748;3870.3420418528917;4939.74036620663;5841.941580584183;5872.383399743008;5874.441928016831;5881.176635271066;3105.5714371388963;3241.7730758846656;3470.8785192518644;3491.1097670094473;3711.684506816951;3772.4718539404507;3883.7475564088245;4004;4012.337964235868;4025.456563363967;3688.5020522229693;3777.86777462793;4066.4713587234064;4150.086980864205;4373.584509471595;5031.289361617068;5647.491725744702;5714.571407144481;5771.192033115367;5785.913211301834;1162.4013632979022;1288.4137717338588;1328.614701736505;1355.8847497962586;1362.1134728401237;910.6539514984264;924.8658944453017;924.9398789078689;937.7749340158313;940.5891163255118;4170.061736109412;4617.19811396151;4943.975280123576;5213.296205012351;5410.620291825638;4771;5830.271216097988;5834.883302333929;5843.561732870052;5866.472017518444;5113.769880355393;5348.973255133699;5371.811986580529;5385.337970180237;5386.283464409545;2252.637154521524;2482.250013740345;2542.7965762738936;2547.872606369674;2570.3292859641383;4968.677035992649;5028.74856257193;5074.2895994560795;5076.82231121909;5085.9745701271995;4648.604868586208;4795.568398844121;4835.550157691649;4846.491118432514;4995.775190116454;4695.3426520287185;4863.416390033219;4938.802447902115;5056.5223040710525;5093.567046801063;4475.172093162645;4524.909476838557;4728.243481043017;4885.633577481774;4931.526006133117;4245.320748680197;4850.041474700501;4889.315495830516;4957;5821.79623713168;5061.721605311744;5063.0380227756605;5644.158296163451;5707.116651505076;6026.397360263939;2905.2301140198288;3569.0720412692367;3829.961700383072;3879.670228030649;3893.454916311662;5677.290338707661;5679.460451257124;5689.516391106637;5763.452472015152;5869.647821130746;4482.506924238396;4628.4445866496235;5075.6193285502995;5620.775168993195;5620.85947851307;5598.3282006159525;5606.495940037476;5631.774729010957;5650.6327088740745;5710.828675139753;5388.120632221901;5468.988237176135;5473.507384335429;5473.698946557977;5480.382947254792;5382.165764306539;5584.888302234014;5640.305599831952;5640.420682208988;5691.459311365579;4867.659263851497;4870.610351171968;5374.193870919394;5513.283273174622;5517.944820551904 20:56:40 INFO - PROCESS | 1657 | |5;yui.html;565.5616896904899;638.9904151437728;690.7478770248861;725.4305370284328;741.1381338419963;14399.28003599821;14888.627784305398;15247.771283430757;15258.313375898097;15312.770308445382;13335.799778019977;13371.59885203443;13407.061505694604;13612.727745445085;13617.251051192181;679.9966000170004;822.5722624235394;824.4064273722939;826.7850358906697;827.4208054361941;656.2551504042898;678.6979793991676;801.1067659559608;826.4173757500947;838.3125836813815;878.5124256037897;882.6116508736168;883.1786438612088;884.2086332732201;884.6107712606432;860.182826314997;878.3851304087165;878.4597472554365;879.8196369744187;880.7533890510636;638.3743930947697;649.1431310669917;649.3928177154376;649.4317472211815;652.9943886414916;13948.93025534876;13990.930045349709;14244.715105697827;14292.142471451747;14334.784978225334;11353.943230283878;13800.585982420544;13837.238951857644;13902.16587004783;13942.790858137136;11389.658310250623;11413.885861141365;11454.48454819535;11488.310701357945;11495.42522873853;3627.3930718182164;3640.7087433005295;3679.172186258097;3687.760295580779;3809.676177524914;68617.65691171562;84862.57568712179;91070.1785964277;92139;92294.53852730761;10576.735581610521;10623.990720881658;10769.892301077045;10824.891751082389;10967.451627418597;20922;21207.469813254484;21224;21253.89373053156;21522.46193845135;7495.400367970549;7579.204183560757;7661.808454788564;7666.578338191453;7755.961220193863;4184.958150418518;4300.376445415397;4367.192069467159;4375.168717943644;4480.574345437179;3384.983075084609;3431.811250381203;3500.2474467989505;3507.7719948203285;3516.2967406518796;627.4572494792063;633.1168020611221;638.1162090504685;651.1404945471932;656.7405874679475;562.9592726366919;586.4487381861037;609.9298580663192;640.1173218125413;641.3137942401679;9026.909730902738;9077.409968352036;9207.539623018956;9317.487538185329;9336.439813611205;536.1609081786991;553.8753780399459;635.3487675133026;657.4477438951304;662.6719773712043;3701.3152566774693;4266.274733295348;4381.6494680425485;4470.240059189946;4523.296958899377;73117.6344118276;93984.30078496048;110116;116609.8339016616;117403;101020.49489752651;101024;102054.97945020454;102200.48899755602;102885;90096.09903901008;92216.53891730633;92748.07251927529;92869;93809.53095234616;8592.3555733319;8642.611082501428;8693.217610415093;8776.868346974672;8884.244839188741;56347.21048710741;56696;57981.71009144927;58863;59812.102818456886;76711;77058;82766.58616706877;83077.58461207655;83479.74780378178;10406.167506599606;10785.083267922315;11119.499622517167;11485.827712584485;11957.641270761891;13344.266065366499;13533.593992180251;13851.099678520864;14914.701705966037;15024.774628380364;12879.098463107492;13025.544105956626;13244.337783110614;13363.26502042397;13516.459341626225;12768.808467872803;12803.743925121631;13162.210267383987;13187;13483.19100853952;12498.375081246084;12609.49562017509;13166.341682915625;13316.800247996094;13319.467221311039;10331.891190040704;10759.623413180501;10839;11008.119350451785;11724.531018759155;10724.73188170302;11335.773284534429;11337.26307789991;11388.487518061873;11486.770264594828 20:56:40 INFO - PROCESS | 1657 | __end_tp_report 20:56:40 INFO - PROCESS | 1657 | __start_cc_report 20:56:40 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,371 20:56:40 INFO - PROCESS | 1657 | __end_cc_report 20:56:40 INFO - PROCESS | 1657 | __startTimestamp1477972600465__endTimestamp 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | ------- Summary: start ------- 20:56:40 INFO - PROCESS | 1657 | Number of tests: 6 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5628.50 Median:5811.56 stddev:696.37 (12.0%) stddev-sans-first:665.03 20:56:40 INFO - PROCESS | 1657 | Values: 2909.3 3277.9 3311.2 3340.6 3354.8 4407.8 4476.2 5484.8 5541.8 5558.0 4439.9 5584.1 5654.4 5658.2 5664.5 5108.6 5123.5 5142.2 5198.8 5259.7 5107.5 5218.1 5229.8 5265.6 5275.7 4179.5 5028.4 5178.5 5223.3 5249.9 4101.4 5088.8 5170.2 5185.8 5220.6 5103.8 5123.8 5147.2 5155.4 5168.6 5481.2 5526.9 5691.5 5692.1 5696.3 5291.7 6051.3 6129.4 6139.4 6175.4 6224.8 6226.0 6241.5 6246.2 6262.4 5754.5 5762.5 5774.4 5782.1 5833.6 6162.4 6207.9 6211.5 6233.7 6240.3 6028.5 6066.4 6086.0 6123.6 6125.7 6047.4 6202.5 6217.4 6234.3 6265.7 6026.7 6085.3 6104.8 6138.9 6162.0 6202.3 6211.1 6220.9 6222.2 6246.6 4214.0 4404.1 4900.9 4918.5 4957.6 4330.0 4347.3 4368.9 4401.0 4464.3 5724.4 5747.5 5762.4 5786.2 5811.6 5496.6 6132.0 6176.1 6179.2 6245.8 5595.6 5699.7 5708.1 5750.9 5781.0 5366.7 5372.8 5376.9 5391.6 5426.2 6241.8 6275.5 6283.4 6293.6 6297.0 4663.9 5376.8 6115.8 6246.3 6255.4 5208.9 6169.1 6249.2 6317.5 6318.8 6155.5 6178.3 6186.8 6203.3 6205.1 6100.7 6145.6 6165.2 6226.0 6244.4 5375.2 6010.3 6020.6 6126.5 6128.9 6039.1 6071.2 6083.9 6087.9 6088.1 5608.2 5790.1 5830.8 5870.6 5950.4 6070.3 6074.7 6087.5 6108.4 6110.7 5779.4 5830.2 5837.4 5883.3 5901.8 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:66489.75 Median:40768.70 stddev:144242.32 (353.8%) stddev-sans-first:144561.75 20:56:40 INFO - PROCESS | 1657 | Values: 454.5 460.4 473.8 509.7 529.4 38732.0 39894.2 39969.8 40150.6 41633.4 40640.4 41070.8 41103.0 41266.0 41271.4 9381.4 9507.0 9536.6 9567.8 9583.1 9407.2 9443.7 9492.0 9521.1 9534.4 9460.0 9495.1 9550.9 9564.2 9593.3 9441.1 9450.9 9496.5 9517.4 9527.2 9363.3 9389.2 9448.9 9466.3 9481.9 38379.0 38489.7 38607.0 38749.8 39010.0 3267.0 3293.5 3380.3 3381.8 3673.5 29261.3 30315.8 30784.0 30804.7 30834.0 3135.9 3252.6 3307.5 3377.1 3383.2 433605.7 437835.8 438904.0 439311.0 441303.0 49808.8 50038.0 54277.0 62835.4 64516.7 56451.7 64141.7 66000.3 66852.7 67544.7 15782.2 16038.4 16039.0 16087.5 16096.4 12023.3 12030.0 12125.6 12239.0 12250.9 19336.7 19505.1 19561.0 19671.6 19693.0 6771.8 8014.6 8056.3 8120.8 8160.8 698.0 908.1 937.4 957.4 1125.8 29572.0 29703.3 30134.1 30272.2 30281.7 764.1 840.4 869.7 950.8 988.4 12974.0 13614.5 14803.6 14904.8 14910.3 541184.0 620847.9 622715.0 624120.9 628332.0 40897.0 51158.0 51326.0 52211.0 52649.9 1172.0 1325.1 1342.3 1343.5 1364.3 27261.0 28249.8 28306.6 28573.9 28736.7 46094.1 46986.5 47025.8 47135.5 47139.8 556723.0 558158.4 560061.0 560092.0 560891.0 24529.9 25758.2 25839.1 26092.0 26121.9 43150.0 43837.3 44135.6 44578.7 44780.0 33656.0 36481.8 36810.3 37379.3 37553.0 29969.0 36563.0 36732.3 37046.0 39526.4 32143.5 32663.8 36460.6 37361.7 37718.8 33090.3 34051.3 34213.0 34313.1 34445.5 21721.6 24691.6 25045.5 25311.7 25650.4 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:32618.62 Median:320.98 stddev:90419.52 (28169.7%) stddev-sans-first:90640.59 20:56:40 INFO - PROCESS | 1657 | Values: 294.9 313.5 328.5 363.3 384.3 19366.0 19855.3 20945.4 21235.0 21356.0 14056.6 19658.2 19677.3 19884.0 19917.5 5174.8 5405.4 5413.7 5415.6 5438.2 5055.4 5342.8 5409.1 5415.3 5430.7 5052.3 5312.6 5377.7 5399.3 5407.2 4675.6 5221.4 5318.7 5373.9 5383.4 5216.6 5237.7 5240.9 5246.9 5247.4 12806.9 15371.2 20379.6 24324.5 24583.4 2291.1 2307.3 2334.6 2365.1 2365.6 9768.2 9867.9 9934.8 10311.7 10321.0 1790.7 1893.0 1902.2 1905.8 1937.7 116100.0 116266.0 122742.4 124011.0 124087.0 9413.8 11023.8 11100.9 11569.6 11575.3 14404.3 14430.4 14436.6 14468.1 14488.2 7857.9 7974.3 8262.0 8359.7 8402.4 6226.8 7944.2 8069.6 8112.9 8216.7 4465.7 4472.9 4495.0 4503.1 4507.0 1891.5 1960.6 1988.2 1995.8 1998.8 762.2 799.5 849.5 859.4 908.8 18621.0 20672.0 21468.1 21594.7 21603.4 886.5 903.6 942.9 950.0 977.5 10814.5 11304.2 11326.8 11363.8 11396.3 490854.1 539246.0 543597.0 561141.0 563380.0 76911.6 79512.4 114839.0 120541.4 125319.0 953.2 954.3 1026.9 1034.7 1036.0 18945.4 19940.5 19983.0 19989.2 20076.3 55968.9 56885.0 57133.4 57436.1 57566.7 79901.8 80249.0 80659.0 81738.0 82053.6 8000.3 8035.6 8188.3 8227.2 8354.9 12332.3 12501.2 12741.5 12817.9 12887.7 8834.6 10621.7 11016.9 11040.2 11041.9 10743.5 10783.7 10953.8 11276.4 11384.3 10706.4 10739.1 10751.0 10761.3 10922.2 7021.0 7324.5 7388.0 7522.8 8222.8 8236.8 8810.7 10766.7 10942.3 11141.2 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5348.58 Median:5063.87 stddev:2963.92 (58.5%) stddev-sans-first:2957.73 20:56:40 INFO - PROCESS | 1657 | Values: 1446.0 1494.8 1645.1 1763.9 1777.3 7499.3 7780.8 7937.7 8546.8 8555.3 7966.0 8227.6 8250.7 8386.9 8412.7 2271.2 2292.0 2292.9 2295.5 2299.8 2251.2 2285.8 2288.9 2307.6 2321.8 1995.5 2274.3 2282.7 2292.5 2311.7 2252.6 2256.2 2275.1 2285.1 2290.1 1950.4 2286.2 2300.8 2309.7 2315.2 8378.1 8400.0 8415.9 8454.7 8483.0 5038.9 5106.3 5199.0 5202.0 5219.0 3135.7 3275.7 3512.2 3520.8 3523.0 1007.9 1042.5 1056.1 1057.1 1063.9 10501.7 10802.6 10865.8 10891.7 11063.8 3860.2 4089.0 4320.7 4375.2 4388.4 7490.6 8242.1 8611.0 8617.4 8642.9 4969.3 5059.9 5067.9 5071.1 5078.5 4246.8 4398.9 4603.3 4688.6 4706.9 3541.0 3576.8 3656.0 3689.6 3691.9 1096.2 1132.3 1133.8 1137.7 1141.9 792.4 859.9 864.2 898.2 922.5 5029.2 5246.0 5374.3 5442.9 5560.3 856.2 895.5 909.2 912.8 913.6 2920.5 3057.4 3707.4 3765.3 3811.6 9512.3 10297.5 10316.4 10328.5 10384.8 9545.7 9840.4 9888.5 10103.8 10149.0 7685.2 7737.9 7891.2 7952.0 7957.2 4616.8 5684.8 5775.0 5777.4 5858.9 9340.7 9351.6 9371.9 9407.0 9494.4 9381.9 9489.1 9506.5 9628.4 9646.6 5817.6 6610.3 6808.3 6994.1 7265.5 6663.5 6971.5 7728.6 7865.8 8073.8 6155.5 7078.0 7211.0 7238.7 7292.6 7066.6 7074.4 7098.9 7112.0 7174.6 6601.5 6625.0 6639.1 6663.2 6752.7 4784.1 4837.2 4853.4 4874.9 4937.4 4788.4 4851.6 4872.9 4925.5 4955.8 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4399.90 Median:4878.12 stddev:1366.79 (28.0%) stddev-sans-first:1335.46 20:56:40 INFO - PROCESS | 1657 | Values: 295.8 300.6 305.1 308.6 311.9 5110.2 5253.8 5318.2 5440.8 5526.2 4345.8 4863.4 5343.8 5352.2 5359.8 4064.9 4071.4 4086.8 4092.4 4096.7 3544.8 3572.3 3737.8 4094.7 4109.7 3978.2 4041.8 4045.7 4059.5 4068.9 3711.2 3855.5 4029.6 4073.5 4074.8 2952.1 3439.4 3555.4 3562.3 3582.7 3976.4 5129.5 5198.3 5219.0 5242.6 4585.6 4591.2 4603.3 4624.2 4656.4 5597.7 5641.3 5646.7 5650.4 5676.6 3633.2 3652.5 3670.3 3835.7 3870.3 4939.7 5841.9 5872.4 5874.4 5881.2 3105.6 3241.8 3470.9 3491.1 3711.7 3772.5 3883.7 4004.0 4012.3 4025.5 3688.5 3777.9 4066.5 4150.1 4373.6 5031.3 5647.5 5714.6 5771.2 5785.9 1162.4 1288.4 1328.6 1355.9 1362.1 910.7 924.9 924.9 937.8 940.6 4170.1 4617.2 4944.0 5213.3 5410.6 4771.0 5830.3 5834.9 5843.6 5866.5 5113.8 5349.0 5371.8 5385.3 5386.3 2252.6 2482.3 2542.8 2547.9 2570.3 4968.7 5028.7 5074.3 5076.8 5086.0 4648.6 4795.6 4835.6 4846.5 4995.8 4695.3 4863.4 4938.8 5056.5 5093.6 4475.2 4524.9 4728.2 4885.6 4931.5 4245.3 4850.0 4889.3 4957.0 5821.8 5061.7 5063.0 5644.2 5707.1 6026.4 2905.2 3569.1 3830.0 3879.7 3893.5 5677.3 5679.5 5689.5 5763.5 5869.6 4482.5 4628.4 5075.6 5620.8 5620.9 5598.3 5606.5 5631.8 5650.6 5710.8 5388.1 5469.0 5473.5 5473.7 5480.4 5382.2 5584.9 5640.3 5640.4 5691.5 4867.7 4870.6 5374.2 5513.3 5517.9 20:56:40 INFO - PROCESS | 1657 | 20:56:40 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:20966.04 Median:4283.33 stddev:30808.51 (719.3%) stddev-sans-first:30856.85 20:56:40 INFO - PROCESS | 1657 | Values: 565.6 639.0 690.7 725.4 741.1 14399.3 14888.6 15247.8 15258.3 15312.8 13335.8 13371.6 13407.1 13612.7 13617.3 680.0 822.6 824.4 826.8 827.4 656.3 678.7 801.1 826.4 838.3 878.5 882.6 883.2 884.2 884.6 860.2 878.4 878.5 879.8 880.8 638.4 649.1 649.4 649.4 653.0 13948.9 13990.9 14244.7 14292.1 14334.8 11353.9 13800.6 13837.2 13902.2 13942.8 11389.7 11413.9 11454.5 11488.3 11495.4 3627.4 3640.7 3679.2 3687.8 3809.7 68617.7 84862.6 91070.2 92139.0 92294.5 10576.7 10624.0 10769.9 10824.9 10967.5 20922.0 21207.5 21224.0 21253.9 21522.5 7495.4 7579.2 7661.8 7666.6 7756.0 4185.0 4300.4 4367.2 4375.2 4480.6 3385.0 3431.8 3500.2 3507.8 3516.3 627.5 633.1 638.1 651.1 656.7 563.0 586.4 609.9 640.1 641.3 9026.9 9077.4 9207.5 9317.5 9336.4 536.2 553.9 635.3 657.4 662.7 3701.3 4266.3 4381.6 4470.2 4523.3 73117.6 93984.3 110116.0 116609.8 117403.0 101020.5 101024.0 102055.0 102200.5 102885.0 90096.1 92216.5 92748.1 92869.0 93809.5 8592.4 8642.6 8693.2 8776.9 8884.2 56347.2 56696.0 57981.7 58863.0 59812.1 76711.0 77058.0 82766.6 83077.6 83479.7 10406.2 10785.1 11119.5 11485.8 11957.6 13344.3 13533.6 13851.1 14914.7 15024.8 12879.1 13025.5 13244.3 13363.3 13516.5 12768.8 12803.7 13162.2 13187.0 13483.2 12498.4 12609.5 13166.3 13316.8 13319.5 10331.9 10759.6 10839.0 11008.1 11724.5 10724.7 11335.8 11337.3 11388.5 11486.8 20:56:40 INFO - PROCESS | 1657 | -------- Summary: end -------- 20:56:40 INFO - PROCESS | 1657 | 20:56:41 INFO - TEST-INFO | 1657: exit 0 20:56:41 INFO - TEST-OK | dromaeo_css | took 1115978ms 20:56:41 INFO - TEST-START | kraken 20:56:41 INFO - operating with platform_type : mac_ 20:56:41 INFO - Initialising browser for kraken test... 20:56:41 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpITeAII/profile http://localhost:49274/getInfo.html) 20:56:43 INFO - PROCESS | 1664 | 2016-10-31 20:56:43.620 plugin-container[1665:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 20:56:43 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:56:43 INFO - PROCESS | 1664 | 2016-10-31 20:56:43.623 plugin-container[1665:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 20:56:43 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:56:43 INFO - PROCESS | 1664 | 2016-10-31 20:56:43.623 plugin-container[1665:10488] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:56:43 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 20:56:43 INFO - PROCESS | 1664 | colorDepth:24 20:56:43 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 20:56:43 INFO - PROCESS | 1664 | __metrics 20:56:43 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 20:56:44 INFO - TEST-INFO | 1664: exit 0 20:56:44 INFO - Browser initialized. 20:56:44 INFO - Running cycle 1/1 for kraken test... 20:56:44 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpITeAII/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:56:46 INFO - PROCESS | 1666 | 2016-10-31 20:56:46.825 plugin-container[1667:10601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 20:56:46 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:56:46 INFO - PROCESS | 1666 | 2016-10-31 20:56:46.825 plugin-container[1667:10601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4327, name = 'com.apple.CFPasteboardClient' 20:56:46 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:56:46 INFO - PROCESS | 1666 | 2016-10-31 20:56:46.825 plugin-container[1667:10601] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:56:56 INFO - PROCESS | 1666 | RSS: Main: 238051328 20:56:56 INFO - PROCESS | 1666 | 20:56:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 20:57:00 INFO - PROCESS | 1666 | RSS: Main: 251863040 20:57:00 INFO - PROCESS | 1666 | 20:57:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 20:57:03 INFO - PROCESS | 1666 | RSS: Main: 259825664 20:57:03 INFO - PROCESS | 1666 | 20:57:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 20:57:08 INFO - PROCESS | 1666 | RSS: Main: 261611520 20:57:08 INFO - PROCESS | 1666 | 20:57:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 20:57:10 INFO - PROCESS | 1666 | RSS: Main: 263585792 20:57:10 INFO - PROCESS | 1666 | 20:57:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:57:13 INFO - PROCESS | 1666 | RSS: Main: 263987200 20:57:13 INFO - PROCESS | 1666 | 20:57:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 20:57:17 INFO - PROCESS | 1666 | RSS: Main: 263966720 20:57:17 INFO - PROCESS | 1666 | 20:57:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 20:57:20 INFO - PROCESS | 1666 | RSS: Main: 264478720 20:57:20 INFO - PROCESS | 1666 | 20:57:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 20:57:24 INFO - PROCESS | 1666 | RSS: Main: 264372224 20:57:24 INFO - PROCESS | 1666 | 20:57:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:57:26 INFO - PROCESS | 1666 | RSS: Main: 264273920 20:57:26 INFO - PROCESS | 1666 | 20:57:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:57:29 INFO - PROCESS | 1666 | RSS: Main: 264245248 20:57:29 INFO - PROCESS | 1666 | 20:57:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:57:32 INFO - PROCESS | 1666 | RSS: Main: 264323072 20:57:32 INFO - PROCESS | 1666 | 20:57:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:57:36 INFO - PROCESS | 1666 | RSS: Main: 264572928 20:57:36 INFO - PROCESS | 1666 | 20:57:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:57:39 INFO - PROCESS | 1666 | RSS: Main: 264536064 20:57:39 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 20:57:41 INFO - PROCESS | 1666 | RSS: Main: 266178560 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | __start_tp_report 20:57:41 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 20:57:41 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 20:57:41 INFO - PROCESS | 1666 | |i|pagename|runs| 20:57:41 INFO - PROCESS | 1666 | |0;ai-astar;86;122;145;129;98;165;175;174;120;164 20:57:41 INFO - PROCESS | 1666 | |1;audio-beat-detection;169;107;134;142;97;100;118;132;131;128 20:57:41 INFO - PROCESS | 1666 | |2;audio-dft;201;238;240;273;245;195;193;190;196;195 20:57:41 INFO - PROCESS | 1666 | |3;audio-fft;80;59;57;85;57;60;62;60;77;61 20:57:41 INFO - PROCESS | 1666 | |4;audio-oscillator;79;99;123;56;82;104;120;102;100;97 20:57:41 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;123;75;89;130;134;87;93;86;110;131 20:57:41 INFO - PROCESS | 1666 | |6;imaging-darkroom;105;98;116;172;124;172;153;101;157;101 20:57:41 INFO - PROCESS | 1666 | |7;imaging-desaturate;107;119;86;85;90;71;141;136;89;88 20:57:41 INFO - PROCESS | 1666 | |8;json-parse-financial;58;56;57;57;58;60;58;58;59;57 20:57:41 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;72;107;109;98;81;78;72;71;71;71 20:57:41 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;90;89;91;88;91;98;115;114;111;88 20:57:41 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;164;146;161;135;166;160;160;183;220;149 20:57:41 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;234;179;241;210;211;180;177;175;176;169 20:57:41 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;68;71;68;69;69;66;68;66;69;71 20:57:41 INFO - PROCESS | 1666 | __end_tp_report 20:57:41 INFO - PROCESS | 1666 | __start_cc_report 20:57:41 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,955 20:57:41 INFO - PROCESS | 1666 | __end_cc_report 20:57:41 INFO - PROCESS | 1666 | __startTimestamp1477972661848__endTimestamp 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | ------- Summary: start ------- 20:57:41 INFO - PROCESS | 1666 | Number of tests: 14 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:137.80 Median:169.50 stddev:31.77 (18.7%) stddev-sans-first:27.62 20:57:41 INFO - PROCESS | 1666 | Values: 86.0 122.0 145.0 129.0 98.0 165.0 175.0 174.0 120.0 164.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:125.80 Median:133.00 stddev:21.54 (16.2%) stddev-sans-first:16.21 20:57:41 INFO - PROCESS | 1666 | Values: 169.0 107.0 134.0 142.0 97.0 100.0 118.0 132.0 131.0 128.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:216.60 Median:219.50 stddev:29.55 (13.5%) stddev-sans-first:30.80 20:57:41 INFO - PROCESS | 1666 | Values: 201.0 238.0 240.0 273.0 245.0 195.0 193.0 190.0 196.0 195.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:65.80 Median:61.50 stddev:10.55 (17.2%) stddev-sans-first:9.86 20:57:41 INFO - PROCESS | 1666 | Values: 80.0 59.0 57.0 85.0 57.0 60.0 62.0 60.0 77.0 61.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:67.50 stddev:19.76 (29.3%) stddev-sans-first:19.96 20:57:41 INFO - PROCESS | 1666 | Values: 79.0 99.0 123.0 56.0 82.0 104.0 120.0 102.0 100.0 97.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:105.80 Median:80.50 stddev:22.29 (27.7%) stddev-sans-first:22.75 20:57:41 INFO - PROCESS | 1666 | Values: 123.0 75.0 89.0 130.0 134.0 87.0 93.0 86.0 110.0 131.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:129.90 Median:155.00 stddev:30.46 (19.6%) stddev-sans-first:30.94 20:57:41 INFO - PROCESS | 1666 | Values: 105.0 98.0 116.0 172.0 124.0 172.0 153.0 101.0 157.0 101.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:85.50 stddev:23.57 (27.6%) stddev-sans-first:24.91 20:57:41 INFO - PROCESS | 1666 | Values: 107.0 119.0 86.0 85.0 90.0 71.0 141.0 136.0 89.0 88.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:1.14 (2.0%) stddev-sans-first:1.20 20:57:41 INFO - PROCESS | 1666 | Values: 58.0 56.0 57.0 57.0 58.0 60.0 58.0 58.0 59.0 57.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:83.00 Median:72.00 stddev:15.56 (21.6%) stddev-sans-first:15.99 20:57:41 INFO - PROCESS | 1666 | Values: 72.0 107.0 109.0 98.0 81.0 78.0 72.0 71.0 71.0 71.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:89.50 stddev:11.33 (12.7%) stddev-sans-first:11.68 20:57:41 INFO - PROCESS | 1666 | Values: 90.0 89.0 91.0 88.0 91.0 98.0 115.0 114.0 111.0 88.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:164.40 Median:162.50 stddev:23.41 (14.4%) stddev-sans-first:24.82 20:57:41 INFO - PROCESS | 1666 | Values: 164.0 146.0 161.0 135.0 166.0 160.0 160.0 183.0 220.0 149.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.20 Median:195.00 stddev:26.58 (13.6%) stddev-sans-first:24.20 20:57:41 INFO - PROCESS | 1666 | Values: 234.0 179.0 241.0 210.0 211.0 180.0 177.0 175.0 176.0 169.0 20:57:41 INFO - PROCESS | 1666 | 20:57:41 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.72 (2.5%) stddev-sans-first:1.81 20:57:41 INFO - PROCESS | 1666 | Values: 68.0 71.0 68.0 69.0 69.0 66.0 68.0 66.0 69.0 71.0 20:57:41 INFO - PROCESS | 1666 | -------- Summary: end -------- 20:57:41 INFO - PROCESS | 1666 | 20:57:42 INFO - TEST-INFO | 1666: exit 0 20:57:42 INFO - TEST-OK | kraken | took 61748ms 20:57:42 INFO - SUITE-END | took 1177s 20:57:43 INFO - Completed test suite (00:19:37) 20:57:43 INFO - javascript benchmark 20:57:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7056.147028391716, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2909.2726818295428, 3277.88527401541, 3311.188758754105, 3340.615829179644, 3354.7987120772755, 4407.8236870525225, 4476.216662084134, 5484.808031718891, 5541.806036788713, 5558.0, 4439.866803995886, 5584.134459158827, 5654.434556544334, 5658.151277308406, 5664.518515926141, 5108.616853735966, 5123.5388815006645, 5142.151544995071, 5198.844034678966, 5259.658122222062, 5107.514786095315, 5218.112920803473, 5229.764660590264, 5265.552428043621, 5275.683458992473, 4179.498460184767, 5028.396592408898, 5178.508041736029, 5223.320968274119, 5249.895002099975, 4101.446304748851, 5088.821891233789, 5170.1986192140275, 5185.766640501187, 5220.634555581111, 5103.795848166069, 5123.795048198068, 5147.176451767699, 5155.40712818027, 5168.5606723428555, 5481.205225242317, 5526.917096243559, 5691.459311365414, 5692.1461780733225, 5696.2594862668, 5291.70353263449, 6051.273847138372, 6129.419919118773, 6139.4001448443105, 6175.3515880832765, 6224.782132625336, 6226.0, 6241.531885108635, 6246.219222597176, 6262.405071518198, 5754.539636829044, 5762.481376676119, 5774.422557744276, 5782.10377391505, 5833.591648584558, 6162.383761623802, 6207.937920620826, 6211.472024877936, 6233.719482623293, 6240.282367527701, 6028.517718582503, 6066.3630318815885, 6086.0, 6123.632582045091, 6125.724342404514, 6047.425494578008, 6202.504017936889, 6217.409346112112, 6234.251889773165, 6265.71804268809, 6026.6986650667295, 6085.330613632497, 6104.755809767648, 6138.938610613926, 6162.0, 6202.34875338092, 6211.068339749074, 6220.906686399708, 6222.160008398808, 6246.625202487865, 4213.9578604214175, 4404.097160082176, 4900.926486102711, 4918.532739389752, 4957.603391728653, 4329.956700432956, 4347.3261644445165, 4368.912621747547, 4400.977995110004, 4464.285714285698, 5724.427557244242, 5747.48272655463, 5762.366139724627, 5786.218860453869, 5811.56413268998, 5496.587755918242, 6132.049532322497, 6176.104464852661, 6179.16581261524, 6245.812625621239, 5595.580331475156, 5699.715014249354, 5708.143778433186, 5750.942490575125, 5781.0, 5366.6779993200535, 5372.838814835562, 5376.865578360494, 5391.649542779707, 5426.186072089218, 6241.843953901189, 6275.529335299871, 6283.434490895841, 6293.55945083858, 6297.023961285917, 4663.903982564027, 5376.817100237941, 6115.788007244199, 6246.250449946036, 6255.374462553708, 5208.921866171935, 6169.136320914986, 6249.1563638908165, 6317.526185536011, 6318.810435686937, 6155.538334624832, 6178.320384757583, 6186.845328866814, 6203.348648391854, 6205.100260462117, 6100.664463454553, 6145.586515101641, 6165.229346331708, 6226.0, 6244.406781355764, 5375.19372094189, 6010.278766548042, 6020.578559500968, 6126.540509461719, 6128.869808884268, 6039.124326972652, 6071.210742603434, 6083.939160608337, 6087.908681369871, 6088.117223002729, 5608.242887210337, 5790.073588225863, 5830.82507524775, 5870.618409803349, 5950.434708702666, 6070.271567411939, 6074.665893375906, 6087.512998959984, 6108.389161083856, 6110.663913484801, 5779.393163717749, 5830.2420685310635, 5837.357890631942, 5883.29400471929, 5901.822945311647], "value": 5583.656774016434, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.5363728996426, 460.35550229678444, 473.79863557987846, 509.72729589669524, 529.3938440485643, 38732.0, 39894.20211595766, 39969.80015099924, 40150.59849401505, 41633.37549936753, 40640.39039414411, 41070.7946460268, 41103.0, 41266.0, 41271.38092928608, 9381.390209636385, 9507.0, 9536.618535258582, 9567.760805979837, 9583.137517623412, 9407.247420206368, 9443.716688499357, 9492.0, 9521.143097121258, 9534.427934323929, 9460.0, 9495.050494950485, 9550.904490955105, 9564.18704410126, 9593.328467007312, 9441.103095205945, 9450.85823712645, 9496.477693726842, 9517.428954262732, 9527.23782097434, 9363.25093992479, 9389.248860091244, 9448.90551094494, 9466.337356385056, 9481.905180948172, 38379.0, 38489.65286214969, 38607.0, 38749.806250968846, 39010.0, 3266.96733032669, 3293.5059741038795, 3380.323935212967, 3381.7801842880135, 3673.4857120002953, 29261.26846828825, 30315.848420757975, 30784.0, 30804.691953080408, 30834.0, 3135.9059228222964, 3252.577164968539, 3307.536944827726, 3377.071305391037, 3383.171123074838, 433605.6639433597, 437835.81082094705, 438904.0, 439311.0, 441303.0, 49808.750956244985, 50038.0, 54277.0, 62835.371646283864, 64516.67741661262, 56451.71774141103, 64141.67929160324, 66000.33999659942, 66852.665736671, 67544.66227668831, 15782.210889455482, 16038.43865464703, 16039.0, 16087.517374478784, 16096.356145754273, 12023.338716370683, 12030.0, 12125.636230913087, 12238.959688426405, 12250.877491224974, 19336.675474773474, 19505.06411215869, 19561.0, 19671.60656786885, 19693.0, 6771.830704232335, 8014.559199243981, 8056.274935255738, 8120.837583248302, 8160.795980100426, 697.9635241666122, 908.0873721909438, 937.4328531238639, 957.4255446732044, 1125.836753670713, 29572.0, 29703.257418564277, 30134.110902637152, 30272.243193919887, 30281.69718302833, 764.1288930619098, 840.4411066640738, 869.6608322754131, 950.8447236607417, 988.3526290279879, 12974.0, 13614.523491677943, 14803.62990925214, 14904.776428353583, 14910.329035193443, 541184.0, 620847.8957605183, 622715.0, 624120.8793956001, 628332.0, 40897.0, 51157.97684046298, 51326.0, 52210.95578088417, 52649.947001059765, 1171.9531218751324, 1325.1121748428384, 1342.3489607540334, 1343.5029039255537, 1364.3314775759807, 27261.0, 28249.77783990498, 28306.57540136941, 28573.857130714212, 28736.712632873405, 46094.07811843811, 46986.530134698216, 47025.76487117542, 47135.528644713115, 47139.76430117827, 556723.0, 558158.4184158107, 560061.0, 560092.0, 560891.0, 24529.877350613133, 25758.227253181685, 25839.095631652803, 26092.0, 26121.869390653686, 43150.0, 43837.34243986406, 44135.55864441315, 44578.66264012085, 44780.0, 33655.99032029043, 36481.81759091187, 36810.263794724495, 37379.252414951006, 37553.0, 29968.951086712732, 36563.0, 36732.26535469329, 37046.0, 39526.40710389404, 32143.517847231844, 32663.836680816443, 36460.63539364572, 37361.69234076794, 37718.8114059428, 33090.33819323552, 34051.31897362089, 34213.0, 34313.142171445914, 34445.48331775075, 21721.565568688853, 24691.629625555273, 25045.49909001846, 25311.74688253094, 25650.358741031625], "value": 19237.42551261061, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.91300066480386, 313.5030975903193, 328.4596838201159, 363.28479770310264, 384.31783584138134, 19366.031698415074, 19855.30506432275, 20945.37163885084, 21235.0, 21356.0, 14056.64858378541, 19658.213671453126, 19677.311294104707, 19884.0, 19917.502062448446, 5174.767135478913, 5405.405405405402, 5413.702246376448, 5415.566754659638, 5438.238646589481, 5055.4186268579215, 5342.813001544947, 5409.053415652264, 5415.296011518497, 5430.70131142787, 5052.343195384595, 5312.574994000471, 5377.677339359631, 5399.298091248133, 5407.188921661763, 4675.625949923998, 5221.425643179247, 5318.654287471302, 5373.919391209135, 5383.353997520284, 5216.60875434344, 5237.659552129099, 5240.947590524084, 5246.924380502026, 5247.39654939683, 12806.935965320206, 15371.154586497738, 20379.5924081519, 24324.513509729884, 24583.38541536458, 2291.0721157931066, 2307.2501437032865, 2334.6147885598857, 2365.101261520611, 2365.6451532270125, 9768.21854251658, 9867.90132098677, 9934.751631209205, 10311.690649280534, 10321.0, 1790.7224380221087, 1893.0, 1902.2200897632042, 1905.7998910114516, 1937.6996565532365, 116100.0, 116265.99999999916, 122742.38628806888, 124011.0, 124087.0, 9413.811723765486, 11023.83464248037, 11100.944495277472, 11569.595064172714, 11575.30548167096, 14404.279786010868, 14430.350634221486, 14436.639084022983, 14468.059576127518, 14488.203148826915, 7857.921420785833, 7974.282314591715, 8261.958690206511, 8359.749207523784, 8402.41183117176, 6226.813195604139, 7944.245296696805, 8069.556174410346, 8112.918870811334, 8216.712415065444, 4465.732056076581, 4472.888177795516, 4495.0, 4503.099380123988, 4507.0, 1891.5176629959547, 1960.578475627747, 1988.1947811136513, 1995.820376166152, 1998.8107076289584, 762.24537707669, 799.4963173200846, 849.479431817053, 859.4198915731856, 908.8318661047728, 18621.0, 20672.0, 21468.141274349164, 21594.68106378699, 21603.410066484517, 886.4902680958346, 903.5798353765531, 942.8632848236967, 949.990500095004, 977.5210147027905, 10814.513346899408, 11304.208705390709, 11326.830097548544, 11363.772724545628, 11396.316221026598, 490854.09145908797, 539246.0, 543597.0, 561141.0, 563380.0, 76911.61544192243, 79512.40975180463, 114839.0, 120541.39729301297, 125319.0, 953.1993125774383, 954.289054654287, 1026.9486525673688, 1034.6895931220604, 1035.984460233097, 18945.43163705091, 19940.50148746235, 19983.0, 19989.20043198314, 20076.297329593395, 55968.88062238651, 56885.0, 57133.42866571448, 57436.13845792233, 57566.7121664389, 79901.80147297912, 80249.0, 80659.0, 81738.0, 82053.58973205095, 8000.279974802295, 8035.55804430762, 8188.344932405513, 8227.177282271725, 8354.87467687973, 12332.260064396165, 12501.187422817487, 12741.49034038628, 12817.87182128204, 12887.742245155232, 8834.558272086242, 10621.734456638645, 11016.89831016892, 11040.227184097357, 11041.889581104408, 10743.516541755796, 10783.730406739895, 10953.80923815256, 11276.379799111128, 11384.316940983235, 10706.411147386969, 10739.140868730326, 10751.0324070834, 10761.30051546647, 10922.235443518877, 7021.0, 7324.523905946309, 7388.0, 7522.811929701801, 8222.753317400488, 8236.805663178926, 8810.735677929672, 10766.73083172927, 10942.343459392461, 11141.164412669244], "value": 9168.73211805368, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.0383844743246, 1494.8280947691014, 1645.1445248470795, 1763.9118044097793, 1777.3246166456752, 7499.325060744532, 7780.844383112334, 7937.722179723711, 8546.829063418729, 8555.31557475402, 7966.044074711028, 8227.588620568962, 8250.669973201066, 8386.87419688705, 8412.663493460253, 2271.205078222625, 2292.025888997178, 2292.931212063641, 2295.5064661097863, 2299.7585253548386, 2251.234580242717, 2285.8057065149483, 2288.8512246703995, 2307.5846347657416, 2321.8142548596156, 1995.4612254691224, 2274.3177046885867, 2282.657601359793, 2292.541491701658, 2311.7457079721216, 2252.5720113178445, 2256.17649557911, 2275.0672224388086, 2285.1088075650428, 2290.0725206291477, 1950.4343740315292, 2286.2341115726085, 2300.827437942144, 2309.734380546242, 2315.166540045582, 8378.078411374743, 8400.0, 8415.915840841575, 8454.704085357045, 8483.0, 5038.874028149289, 5106.336176297058, 5198.974005129988, 5202.0, 5218.9739051304505, 3135.667341379927, 3275.6724327567053, 3512.1921957949817, 3520.8415621297104, 3522.982385088084, 1007.89417111203, 1042.4579218806177, 1056.1128651932413, 1057.1067448006422, 1063.8989296016866, 10501.737456563571, 10802.567897284178, 10865.782684346348, 10891.67324980244, 11063.778724425547, 3860.189360234382, 4089.0, 4320.719153255029, 4375.2280326467835, 4388.363687265329, 7490.614236366289, 8242.052164001216, 8610.95694521536, 8617.396782225309, 8642.913570864337, 4969.329140566049, 5059.873503162376, 5067.873303167376, 5071.061853557104, 5078.492150784892, 4246.766427846498, 4398.912021759611, 4603.263477843529, 4688.624910007191, 4706.905861882743, 3540.964590354115, 3576.7853928764357, 3655.981720091436, 3689.5572531296416, 3691.9446208306895, 1096.1888202729922, 1132.2979752553306, 1133.7619099988929, 1137.732632831284, 1141.9429028548539, 792.3977776889606, 859.8839156713889, 864.2308345572445, 898.1826537850525, 922.524899676667, 5029.195328747456, 5245.977034478316, 5374.27447294618, 5442.891142177214, 5560.277163968658, 856.1609622569916, 895.5253715530649, 909.2044461096541, 912.7763697894059, 913.6071489259549, 2920.474314623346, 3057.373238486105, 3707.405815499307, 3765.3034188675183, 3811.561670407883, 9512.28657850664, 10297.53661085253, 10316.43259620728, 10328.535215915303, 10384.792304153874, 9545.713628591153, 9840.409575425498, 9888.505574721235, 10103.84844227337, 10148.98510148994, 7685.23147685227, 7737.883931741028, 7891.171427000198, 7952.005999250209, 7957.244061814118, 4616.838410655745, 5684.772609095755, 5774.971125144348, 5777.364489906023, 5858.945389829701, 9340.71977840666, 9351.625934962525, 9371.906280937103, 9407.012263712486, 9494.430334179971, 9381.921079075993, 9489.051094890456, 9506.524673766145, 9628.422294662343, 9646.614135434504, 5817.563682723728, 6610.272869984202, 6808.28513006147, 6994.090768200102, 7265.527740696838, 6663.467402497356, 6971.5468494547695, 7728.613569321399, 7865.842683146419, 8073.798155046171, 6155.538334624832, 7078.0444639975, 7210.9639451804505, 7238.746643867439, 7292.635368231461, 7066.646667666493, 7074.398676112588, 7098.858022839618, 7112.03987461686, 7174.569525828467, 6601.504887133388, 6625.0, 6639.136912201576, 6663.167104111986, 6752.729890804313, 4784.138855006132, 4837.2260438329695, 4853.369062021915, 4874.902501950012, 4937.407511098691, 4788.353572267839, 4851.58761505276, 4872.878178045436, 4925.482824303396, 4955.752212389438], "value": 4323.300778743123, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.7988567773913, 300.56418193619254, 305.0588933141259, 308.64968261023733, 311.89395605494127, 5110.233464980255, 5253.842384728455, 5318.202269659553, 5440.782368705247, 5526.226328314039, 4345.78271086446, 4863.36776219091, 5343.759530821113, 5352.2239275305055, 5359.829269145992, 4064.878053658395, 4071.409645601386, 4086.7547947123267, 4092.386142078697, 4096.692748043909, 3544.8227588620593, 3572.321258960802, 3737.803902751121, 4094.651954583864, 4109.6712263019035, 3978.1844721831953, 4041.7777022263763, 4045.7370270932297, 4059.4925634295714, 4068.877933661995, 3711.2020915503294, 3855.5373355197275, 4029.597040295976, 4073.5111786585503, 4074.8166332515107, 2952.1438782752975, 3439.4324936385465, 3555.4489054196642, 3562.305350456662, 3582.695470884978, 3976.3991773655594, 5129.487051294878, 5198.2722418861385, 5219.0, 5242.6330156889035, 4585.564371384747, 4591.225125869092, 4603.309503574457, 4624.213883639755, 4656.441227052742, 5597.720113994284, 5641.323041235078, 5646.717664116819, 5650.378458369576, 5676.631018983794, 3633.1643721943838, 3652.520729104717, 3670.3026424979435, 3835.673967712748, 3870.3420418528917, 4939.74036620663, 5841.941580584183, 5872.383399743008, 5874.441928016831, 5881.176635271066, 3105.5714371388963, 3241.7730758846656, 3470.8785192518644, 3491.1097670094473, 3711.684506816951, 3772.4718539404507, 3883.7475564088245, 4004.0, 4012.337964235868, 4025.456563363967, 3688.5020522229693, 3777.86777462793, 4066.4713587234064, 4150.086980864205, 4373.584509471595, 5031.289361617068, 5647.491725744702, 5714.571407144481, 5771.192033115367, 5785.913211301834, 1162.4013632979022, 1288.4137717338588, 1328.614701736505, 1355.8847497962586, 1362.1134728401237, 910.6539514984264, 924.8658944453017, 924.9398789078689, 937.7749340158313, 940.5891163255118, 4170.061736109412, 4617.19811396151, 4943.975280123576, 5213.296205012351, 5410.620291825638, 4771.0, 5830.271216097988, 5834.883302333929, 5843.561732870052, 5866.472017518444, 5113.769880355393, 5348.973255133699, 5371.811986580529, 5385.337970180237, 5386.283464409545, 2252.637154521524, 2482.250013740345, 2542.7965762738936, 2547.872606369674, 2570.3292859641383, 4968.677035992649, 5028.74856257193, 5074.2895994560795, 5076.82231121909, 5085.9745701271995, 4648.604868586208, 4795.568398844121, 4835.550157691649, 4846.491118432514, 4995.775190116454, 4695.3426520287185, 4863.416390033219, 4938.802447902115, 5056.5223040710525, 5093.567046801063, 4475.172093162645, 4524.909476838557, 4728.243481043017, 4885.633577481774, 4931.526006133117, 4245.320748680197, 4850.041474700501, 4889.315495830516, 4957.0, 5821.79623713168, 5061.721605311744, 5063.0380227756605, 5644.158296163451, 5707.116651505076, 6026.397360263939, 2905.2301140198288, 3569.0720412692367, 3829.961700383072, 3879.670228030649, 3893.454916311662, 5677.290338707661, 5679.460451257124, 5689.516391106637, 5763.452472015152, 5869.647821130746, 4482.506924238396, 4628.4445866496235, 5075.6193285502995, 5620.775168993195, 5620.85947851307, 5598.3282006159525, 5606.495940037476, 5631.774729010957, 5650.6327088740745, 5710.828675139753, 5388.120632221901, 5468.988237176135, 5473.507384335429, 5473.698946557977, 5480.382947254792, 5382.165764306539, 5584.888302234014, 5640.305599831952, 5640.420682208988, 5691.459311365579, 4867.659263851497, 4870.610351171968, 5374.193870919394, 5513.283273174622, 5517.944820551904], "value": 3971.579094315781, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.5616896904899, 638.9904151437728, 690.7478770248861, 725.4305370284328, 741.1381338419963, 14399.28003599821, 14888.627784305398, 15247.771283430757, 15258.313375898097, 15312.770308445382, 13335.799778019977, 13371.59885203443, 13407.061505694604, 13612.727745445085, 13617.251051192181, 679.9966000170004, 822.5722624235394, 824.4064273722939, 826.7850358906697, 827.4208054361941, 656.2551504042898, 678.6979793991676, 801.1067659559608, 826.4173757500947, 838.3125836813815, 878.5124256037897, 882.6116508736168, 883.1786438612088, 884.2086332732201, 884.6107712606432, 860.182826314997, 878.3851304087165, 878.4597472554365, 879.8196369744187, 880.7533890510636, 638.3743930947697, 649.1431310669917, 649.3928177154376, 649.4317472211815, 652.9943886414916, 13948.93025534876, 13990.930045349709, 14244.715105697827, 14292.142471451747, 14334.784978225334, 11353.943230283878, 13800.585982420544, 13837.238951857644, 13902.16587004783, 13942.790858137136, 11389.658310250623, 11413.885861141365, 11454.48454819535, 11488.310701357945, 11495.42522873853, 3627.3930718182164, 3640.7087433005295, 3679.172186258097, 3687.760295580779, 3809.676177524914, 68617.65691171562, 84862.57568712179, 91070.1785964277, 92139.0, 92294.53852730761, 10576.735581610521, 10623.990720881658, 10769.892301077045, 10824.891751082389, 10967.451627418597, 20922.0, 21207.469813254484, 21224.0, 21253.89373053156, 21522.46193845135, 7495.400367970549, 7579.204183560757, 7661.808454788564, 7666.578338191453, 7755.961220193863, 4184.958150418518, 4300.376445415397, 4367.192069467159, 4375.168717943644, 4480.574345437179, 3384.983075084609, 3431.811250381203, 3500.2474467989505, 3507.7719948203285, 3516.2967406518796, 627.4572494792063, 633.1168020611221, 638.1162090504685, 651.1404945471932, 656.7405874679475, 562.9592726366919, 586.4487381861037, 609.9298580663192, 640.1173218125413, 641.3137942401679, 9026.909730902738, 9077.409968352036, 9207.539623018956, 9317.487538185329, 9336.439813611205, 536.1609081786991, 553.8753780399459, 635.3487675133026, 657.4477438951304, 662.6719773712043, 3701.3152566774693, 4266.274733295348, 4381.6494680425485, 4470.240059189946, 4523.296958899377, 73117.6344118276, 93984.30078496048, 110116.0, 116609.8339016616, 117403.0, 101020.49489752651, 101024.0, 102054.97945020454, 102200.48899755602, 102885.0, 90096.09903901008, 92216.53891730633, 92748.07251927529, 92869.0, 93809.53095234616, 8592.3555733319, 8642.611082501428, 8693.217610415093, 8776.868346974672, 8884.244839188741, 56347.21048710741, 56696.0, 57981.71009144927, 58863.0, 59812.102818456886, 76711.0, 77058.0, 82766.58616706877, 83077.58461207655, 83479.74780378178, 10406.167506599606, 10785.083267922315, 11119.499622517167, 11485.827712584485, 11957.641270761891, 13344.266065366499, 13533.593992180251, 13851.099678520864, 14914.701705966037, 15024.774628380364, 12879.098463107492, 13025.544105956626, 13244.337783110614, 13363.26502042397, 13516.459341626225, 12768.808467872803, 12803.743925121631, 13162.210267383987, 13187.0, 13483.19100853952, 12498.375081246084, 12609.49562017509, 13166.341682915625, 13316.800247996094, 13319.467221311039, 10331.891190040704, 10759.623413180501, 10839.0, 11008.119350451785, 11724.531018759155, 10724.73188170302, 11335.773284534429, 11337.26307789991, 11388.487518061873, 11486.770264594828], "value": 7298.008765682134, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1645.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 122.0, 145.0, 129.0, 98.0, 165.0, 175.0, 174.0, 120.0, 164.0], "value": 137.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 107.0, 134.0, 142.0, 97.0, 100.0, 118.0, 132.0, 131.0, 128.0], "value": 125.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 238.0, 240.0, 273.0, 245.0, 195.0, 193.0, 190.0, 196.0, 195.0], "value": 216.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 57.0, 85.0, 57.0, 60.0, 62.0, 60.0, 77.0, 61.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 99.0, 123.0, 56.0, 82.0, 104.0, 120.0, 102.0, 100.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 75.0, 89.0, 130.0, 134.0, 87.0, 93.0, 86.0, 110.0, 131.0], "value": 105.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 98.0, 116.0, 172.0, 124.0, 172.0, 153.0, 101.0, 157.0, 101.0], "value": 129.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 86.0, 85.0, 90.0, 71.0, 141.0, 136.0, 89.0, 88.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 57.0, 58.0, 60.0, 58.0, 58.0, 59.0, 57.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 107.0, 109.0, 98.0, 81.0, 78.0, 72.0, 71.0, 71.0, 71.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 88.0, 91.0, 98.0, 115.0, 114.0, 111.0, 88.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 146.0, 161.0, 135.0, 166.0, 160.0, 160.0, 183.0, 220.0, 149.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 179.0, 241.0, 210.0, 211.0, 180.0, 177.0, 175.0, 176.0, 169.0], "value": 195.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 68.0, 69.0, 69.0, 66.0, 68.0, 66.0, 69.0, 71.0], "value": 68.5, "unit": "score"}]}]} 20:57:43 INFO - Return code: 0 20:57:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:57:43 ERROR - # TBPL SUCCESS # 20:57:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:57:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:57:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:57:43 INFO - 2016-10-31 20:57:43.188 screenresolution[1671:10984] starting screenresolution argv=screenresolution get 20:57:43 INFO - 2016-10-31 20:57:43.190 screenresolution[1671:10984] Display 0: 1600x1200x32@60 20:57:43 INFO - 2016-10-31 20:57:43.196 screenresolution[1672:10987] starting screenresolution argv=screenresolution list 20:57:43 INFO - Available Modes on Display 0 20:57:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:57:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:57:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:57:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:57:43 INFO - Intel Iris: 20:57:43 INFO - Chipset Model: Intel Iris 20:57:43 INFO - Type: GPU 20:57:43 INFO - Bus: Built-In 20:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 20:57:43 INFO - Vendor: Intel (0x8086) 20:57:43 INFO - Device ID: 0x0a2e 20:57:43 INFO - Revision ID: 0x0009 20:57:43 INFO - Displays: 20:57:43 INFO - Display: 20:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:57:43 INFO - Display Serial Number: 1600x1200 60 20:57:43 INFO - Main Display: Yes 20:57:43 INFO - Mirror: Off 20:57:43 INFO - Online: Yes 20:57:43 INFO - Rotation: Supported 20:57:43 INFO - Return code: 0 20:57:43 INFO - Running post-action listener: _resource_record_post_action 20:57:43 INFO - [mozharness: 2016-11-01 03:57:43.435555Z] Finished run-tests step (success) 20:57:43 INFO - Running post-run listener: _resource_record_post_run 20:57:43 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 32899584; Write bytes: 712457216; Read time: 198; Write time: 1994 20:57:43 INFO - TinderboxPrint: CPU usage
54.6% 20:57:43 INFO - TinderboxPrint: I/O read bytes / time
32,899,584 / 198 20:57:43 INFO - TinderboxPrint: I/O write bytes / time
712,457,216 / 1,994 20:57:43 INFO - TinderboxPrint: CPU idle
2,205.9 (45.4%) 20:57:43 INFO - TinderboxPrint: CPU system
985.9 (20.3%) 20:57:43 INFO - TinderboxPrint: CPU user
1,661.8 (34.2%) 20:57:43 INFO - TinderboxPrint: Swap in / out
436,056,064 / 0 20:57:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391641600; Write bytes: 384976896; Read time: 29166; Write time: 785 20:57:43 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 31961600; Write bytes: 301604352; Read time: 186; Write time: 1143 20:57:43 INFO - Running post-run listener: _upload_blobber_files 20:57:43 INFO - Blob upload gear active. 20:57:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:57:43 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:43 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:43 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:44 INFO - (blobuploader) - INFO - Open directory for files ... 20:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:45 INFO - (blobuploader) - INFO - Done attempting. 20:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:46 INFO - (blobuploader) - INFO - Done attempting. 20:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:57:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:57:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:57:48 INFO - (blobuploader) - INFO - Done attempting. 20:57:48 INFO - (blobuploader) - INFO - Iteration through files over. 20:57:48 INFO - Return code: 0 20:57:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:57:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:57:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c03f545fd17f311f19d984feb26c4fc8d23ca74f50cac2ab4723652207f7ac62d0e9d21cf550f880d7b557e20cd58f1a92be4b9002fceb24b2ba232c1268000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c60d0f92304f29d9201aad4257da1a7433ff29ea234adec8ed90db2818fba4dd3d94bda2c93645a3a2a1902d5abdc2faa123b1335948113ec8699b049868677", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a0d2554a33690a421c10dfeff7274db57fe6fb069924421154dc8ba573b563504413d763462b7576ce2838c1f57a6aeee16a3309ceeabe4302a063852ab3e10"} 20:57:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:57:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:57:48 INFO - Contents: 20:57:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c03f545fd17f311f19d984feb26c4fc8d23ca74f50cac2ab4723652207f7ac62d0e9d21cf550f880d7b557e20cd58f1a92be4b9002fceb24b2ba232c1268000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c60d0f92304f29d9201aad4257da1a7433ff29ea234adec8ed90db2818fba4dd3d94bda2c93645a3a2a1902d5abdc2faa123b1335948113ec8699b049868677", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a0d2554a33690a421c10dfeff7274db57fe6fb069924421154dc8ba573b563504413d763462b7576ce2838c1f57a6aeee16a3309ceeabe4302a063852ab3e10"} 20:57:48 INFO - Running post-run listener: copy_logs_to_upload_dir 20:57:48 INFO - Copying logs to upload dir... 20:57:48 INFO - mkdir: /builds/slave/test/build/upload/logs 20:57:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.283311 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-31 20:57:48.759399) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:48.762735) ========= 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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c03f545fd17f311f19d984feb26c4fc8d23ca74f50cac2ab4723652207f7ac62d0e9d21cf550f880d7b557e20cd58f1a92be4b9002fceb24b2ba232c1268000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c60d0f92304f29d9201aad4257da1a7433ff29ea234adec8ed90db2818fba4dd3d94bda2c93645a3a2a1902d5abdc2faa123b1335948113ec8699b049868677", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a0d2554a33690a421c10dfeff7274db57fe6fb069924421154dc8ba573b563504413d763462b7576ce2838c1f57a6aeee16a3309ceeabe4302a063852ab3e10"} 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.010436 build_url: 'https://queue.taskcluster.net/v1/task/P-nbC7iTRP-ho4PwytkZTw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c03f545fd17f311f19d984feb26c4fc8d23ca74f50cac2ab4723652207f7ac62d0e9d21cf550f880d7b557e20cd58f1a92be4b9002fceb24b2ba232c1268000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c60d0f92304f29d9201aad4257da1a7433ff29ea234adec8ed90db2818fba4dd3d94bda2c93645a3a2a1902d5abdc2faa123b1335948113ec8699b049868677", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a0d2554a33690a421c10dfeff7274db57fe6fb069924421154dc8ba573b563504413d763462b7576ce2838c1f57a6aeee16a3309ceeabe4302a063852ab3e10"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:48.795523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:48.795867) ========= 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.qvXwMP0hMD/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.cz9MIOdXYD/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005069 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 20:57:48.844275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 20:57:48.844599) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 20:57:48.844926) ========= ========= Total master_lag: 0.20 =========