builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0388 starttime: 1478149962.91 results: success (0) buildid: 20161102193123 builduid: 658865c4db234c79a93cb633b715828f revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:42.913862) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:42.914873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:42.915192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:42.963734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:42.964034) ========= 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.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-11-02 22:12:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2016-11-02 22:12:43 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.493520 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:43.479006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:43.479330) ========= 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.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.025025 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:43.528260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 22:12:43.528814) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ade8d4a63e57560410de106450f37b50ed71cca5 --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 ade8d4a63e57560410de106450f37b50ed71cca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-11-02 22:12:43,611 truncating revision to first 12 chars 2016-11-02 22:12:43,611 Setting DEBUG logging. 2016-11-02 22:12:43,611 attempt 1/10 2016-11-02 22:12:43,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ade8d4a63e57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-02 22:12:44,836 unpacking tar archive at: mozilla-central-ade8d4a63e57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492948 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 22:12:45.043689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:45.044065) ========= script_repo_revision: ade8d4a63e57560410de106450f37b50ed71cca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:45.044520) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:45.044829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 22:12:45.062701) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-02 22:12:45.063124) ========= /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.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:12:45 INFO - MultiFileLogger online at 20161102 22:12:45 in /builds/slave/test 22:12:45 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 22:12:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:12:45 INFO - {'append_to_log': False, 22:12:45 INFO - 'base_work_dir': '/builds/slave/test', 22:12:45 INFO - 'blob_upload_branch': 'Firefox', 22:12:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:12:45 INFO - 'branch': 'Firefox', 22:12:45 INFO - 'buildbot_json_path': 'buildprops.json', 22:12:45 INFO - 'config_files': ('talos/mac_config.py',), 22:12:45 INFO - 'default_actions': ('clobber', 22:12:45 INFO - 'read-buildbot-config', 22:12:45 INFO - 'download-and-extract', 22:12:45 INFO - 'populate-webroot', 22:12:45 INFO - 'create-virtualenv', 22:12:45 INFO - 'install', 22:12:45 INFO - 'run-tests'), 22:12:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:12:45 INFO - 'download_minidump_stackwalk': True, 22:12:45 INFO - 'download_symbols': 'ondemand', 22:12:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:12:45 INFO - 'tooltool.py': '/tools/tooltool.py', 22:12:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:12:45 INFO - '/tools/misc-python/virtualenv.py')}, 22:12:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:12:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:12:45 INFO - 'installer_path': 'installer.exe', 22:12:45 INFO - 'log_level': 'info', 22:12:45 INFO - 'log_name': 'talos', 22:12:45 INFO - 'log_to_console': True, 22:12:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:12:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:12:45 INFO - 'opt_config_files': (), 22:12:45 INFO - 'pip_index': False, 22:12:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:12:45 INFO - 'cmd': ('bash', 22:12:45 INFO - '-c', 22:12:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:12:45 INFO - 'enabled': True, 22:12:45 INFO - 'halt_on_failure': False, 22:12:45 INFO - 'name': 'check_screen_resolution'},), 22:12:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:12:45 INFO - 'cmd': ('bash', 22:12:45 INFO - '-c', 22:12:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:12:45 INFO - 'enabled': True, 22:12:45 INFO - 'halt_on_failure': False, 22:12:45 INFO - 'name': 'check_screen_resolution'},), 22:12:45 INFO - 'run_cmd_checks_enabled': True, 22:12:45 INFO - 'sps_profile': False, 22:12:45 INFO - 'sps_profile_interval': 0, 22:12:45 INFO - 'suite': 'dromaeojs-e10s', 22:12:45 INFO - 'system_bits': '32', 22:12:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:12:45 INFO - 'title': 't-yosemite-r7-0388', 22:12:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:12:45 INFO - 'use_talos_json': True, 22:12:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:12:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:12:45 INFO - 'work_dir': 'build'} 22:12:45 INFO - [mozharness: 2016-11-03 05:12:45.269666Z] Running clobber step. 22:12:45 INFO - Running pre-action listener: _resource_record_pre_action 22:12:45 INFO - Running main action method: clobber 22:12:45 INFO - rmtree: /builds/slave/test/build 22:12:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:12:47 INFO - Running post-action listener: _resource_record_post_action 22:12:47 INFO - [mozharness: 2016-11-03 05:12:47.339080Z] Finished clobber step (success) 22:12:47 INFO - [mozharness: 2016-11-03 05:12:47.339203Z] Running read-buildbot-config step. 22:12:47 INFO - Running pre-action listener: _resource_record_pre_action 22:12:47 INFO - Running main action method: read_buildbot_config 22:12:47 INFO - Using buildbot properties: 22:12:47 INFO - { 22:12:47 INFO - "project": "", 22:12:47 INFO - "product": "firefox", 22:12:47 INFO - "script_repo_revision": "production", 22:12:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:12:47 INFO - "repository": "", 22:12:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:12:47 INFO - "buildid": "20161102193123", 22:12:47 INFO - "pgo_build": "False", 22:12:47 INFO - "basedir": "/builds/slave/test", 22:12:47 INFO - "buildnumber": 1, 22:12:47 INFO - "slavename": "t-yosemite-r7-0388", 22:12:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:12:47 INFO - "platform": "yosemite_r7", 22:12:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:12:47 INFO - "branch": "mozilla-central", 22:12:47 INFO - "repo_path": "mozilla-central", 22:12:47 INFO - "revision": "ade8d4a63e57560410de106450f37b50ed71cca5", 22:12:47 INFO - "stage_platform": "macosx64", 22:12:47 INFO - "builduid": "658865c4db234c79a93cb633b715828f", 22:12:47 INFO - "slavebuilddir": "test" 22:12:47 INFO - } 22:12:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:12:47 INFO - Running post-action listener: _resource_record_post_action 22:12:47 INFO - [mozharness: 2016-11-03 05:12:47.342530Z] Finished read-buildbot-config step (success) 22:12:47 INFO - [mozharness: 2016-11-03 05:12:47.342667Z] Running download-and-extract step. 22:12:47 INFO - Running pre-action listener: _resource_record_pre_action 22:12:47 INFO - Running main action method: download_and_extract 22:12:47 INFO - mkdir: /builds/slave/test/build/tests 22:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:47 INFO - trying https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:12:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/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 22:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/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 22:12:48 INFO - Downloaded 1199 bytes. 22:12:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:12:48 INFO - Using the following test package requirements: 22:12:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:12:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:12:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:12:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'jsshell-mac.zip'], 22:12:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:12:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:12:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:12:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:12:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:12:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:12:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:12:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:12:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:12:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:12:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:12:49 INFO - Http code: 200 22:12:49 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 22:12:49 INFO - x-amz-cf-id: IE-zJFn-6eYWmm7EijssjkiStZlb992kBVsbScROYCV78c3ZHu2ezg== 22:12:49 INFO - x-amz-version-id: rV5EstUOpVIu4.Ooul.7pLknvkphEOCE 22:12:49 INFO - Expected file size: 16389242 22:12:49 INFO - Obtained file size: 16389242 22:12:56 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 22:12:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:12:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:12:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:12:56 INFO - Http code: 200 22:12:56 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:12:56 INFO - x-amz-cf-id: _QY5bt386_l8FSaf5ufm4qpHItGAi-TyTmW5N0j-kgD-eRvJcNPGcA== 22:12:56 INFO - x-amz-version-id: rV5EstUOpVIu4.Ooul.7pLknvkphEOCE 22:12:57 INFO - Expected file size: 16389242 22:12:57 INFO - Obtained file size: 16389242 22:13:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:13:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:13:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:13:04 INFO - Http code: 200 22:13:04 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 22:13:04 INFO - x-amz-cf-id: EJkZ26UeYcgBKMpQG1fiPbahszEKdjOOh7i2Dx82ZSm1Jl7U3lg_Jg== 22:13:04 INFO - x-amz-version-id: KTuI8CwNPSjSJnweUA5GQCbLU8Qx0Pt_ 22:13:04 INFO - Expected file size: 14409038 22:13:04 INFO - Obtained file size: 14409038 22:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:05 INFO - trying https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:13:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/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 22:13:07 INFO - Downloaded 111950140 bytes. 22:13:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:13:07 INFO - mkdir: /builds/slave/test/properties 22:13:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:13:07 INFO - Writing to file /builds/slave/test/properties/build_url 22:13:07 INFO - Contents: 22:13:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:13:08 INFO - Running post-action listener: _resource_record_post_action 22:13:08 INFO - Running post-action listener: set_extra_try_arguments 22:13:08 INFO - [mozharness: 2016-11-03 05:13:08.638633Z] Finished download-and-extract step (success) 22:13:08 INFO - [mozharness: 2016-11-03 05:13:08.638784Z] Running populate-webroot step. 22:13:08 INFO - Running pre-action listener: _resource_record_pre_action 22:13:08 INFO - Running main action method: populate_webroot 22:13:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:13:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:13:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:13:08 INFO - '--tppagecycles', 22:13:08 INFO - '7'], 22:13:08 INFO - 'tests': ['tsvgm']}}, 22:13:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:13:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:13:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:13:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:13:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['damp', 'tps']}, 22:13:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'tests': ['damp', 'tps']}, 22:13:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['dromaeo_dom']}, 22:13:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:13:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['basic_compositor_video']}, 22:13:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:13:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'other-e10s': {'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['a11yr', 22:13:08 INFO - 'ts_paint', 22:13:08 INFO - 'tpaint', 22:13:08 INFO - 'sessionrestore', 22:13:08 INFO - 'sessionrestore_no_auto_restore', 22:13:08 INFO - 'tabpaint']}, 22:13:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['tsvgx', 22:13:08 INFO - 'tsvgr_opacity', 22:13:08 INFO - 'tart', 22:13:08 INFO - 'tscrollx', 22:13:08 INFO - 'cart']}, 22:13:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:13:08 INFO - 'tsvgr_opacity', 22:13:08 INFO - 'tart', 22:13:08 INFO - 'tscrollx', 22:13:08 INFO - 'cart']}, 22:13:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'talos_options': ['--disable-e10s'], 22:13:08 INFO - 'tests': ['tp5o']}, 22:13:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'tests': ['tp5o']}, 22:13:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'talos_options': ['--disable-e10s', 22:13:08 INFO - '--xperf_path', 22:13:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:08 INFO - 'tests': ['tp5n']}, 22:13:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:08 INFO - 'talos_options': ['--xperf_path', 22:13:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:08 INFO - 'tests': ['tp5n']}}, 22:13:08 INFO - 'talos.zip': {'path': '', 22:13:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:13:08 INFO - Running post-action listener: _resource_record_post_action 22:13:08 INFO - [mozharness: 2016-11-03 05:13:08.657871Z] Finished populate-webroot step (success) 22:13:08 INFO - [mozharness: 2016-11-03 05:13:08.658002Z] Running create-virtualenv step. 22:13:08 INFO - Running pre-action listener: _resource_record_pre_action 22:13:08 INFO - Running main action method: create_virtualenv 22:13:08 INFO - Creating virtualenv /builds/slave/test/build/venv 22:13:08 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 22:13:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:13:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:13:08 INFO - Using real prefix '/tools/python27' 22:13:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:13:09 INFO - Installing distribute.............................................................................................................................................................................................done. 22:13:12 INFO - Installing pip.................done. 22:13:12 INFO - Return code: 0 22:13:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:13:12 INFO - Reading from file tmpfile_stdout 22:13:12 INFO - Output received: 22:13:12 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) 22:13:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:13:12 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')]} 22:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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')]} 22:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:12 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 22:13:12 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 22:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:12 INFO - 'HOME': '/Users/cltbld', 22:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:12 INFO - 'LOGNAME': 'cltbld', 22:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:12 INFO - 'MOZ_NO_REMOTE': '1', 22:13:12 INFO - 'NO_EM_RESTART': '1', 22:13:12 INFO - 'PAGER': '/bin/cat', 22:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:12 INFO - 'PWD': '/builds/slave/test', 22:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:12 INFO - 'SHELL': '/bin/bash', 22:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:12 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:12 INFO - 'USER': 'cltbld', 22:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:12 INFO - 'XPC_FLAGS': '0x0', 22:13:12 INFO - 'XPC_SERVICE_NAME': '0', 22:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:12 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 22:13:12 INFO - Cleaning up... 22:13:12 INFO - Return code: 0 22:13:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:13:12 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')]} 22:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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')]} 22:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:12 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:12 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 22:13:12 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 22:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:12 INFO - 'HOME': '/Users/cltbld', 22:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:12 INFO - 'LOGNAME': 'cltbld', 22:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:12 INFO - 'MOZ_NO_REMOTE': '1', 22:13:12 INFO - 'NO_EM_RESTART': '1', 22:13:12 INFO - 'PAGER': '/bin/cat', 22:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:12 INFO - 'PWD': '/builds/slave/test', 22:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:12 INFO - 'SHELL': '/bin/bash', 22:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:12 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:12 INFO - 'USER': 'cltbld', 22:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:12 INFO - 'XPC_FLAGS': '0x0', 22:13:12 INFO - 'XPC_SERVICE_NAME': '0', 22:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:13 INFO - Downloading/unpacking psutil>=3.1.1 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:13:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:17 INFO - Installing collected packages: psutil 22:13:17 INFO - Running setup.py install for psutil 22:13:17 INFO - building 'psutil._psutil_osx' extension 22:13:17 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 22:13:17 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 22:13:17 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 22:13:17 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 22:13:17 INFO - building 'psutil._psutil_posix' extension 22:13:17 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 22:13:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:13:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:13:17 INFO - ^ 22:13:17 INFO - 1 warning generated. 22:13:17 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 22:13:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:18 INFO - Successfully installed psutil 22:13:18 INFO - Cleaning up... 22:13:18 INFO - Return code: 0 22:13:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:13:18 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')]} 22:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:18 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')]} 22:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:18 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:18 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 22:13:18 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 22:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:18 INFO - 'HOME': '/Users/cltbld', 22:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:18 INFO - 'LOGNAME': 'cltbld', 22:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:18 INFO - 'MOZ_NO_REMOTE': '1', 22:13:18 INFO - 'NO_EM_RESTART': '1', 22:13:18 INFO - 'PAGER': '/bin/cat', 22:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:18 INFO - 'PWD': '/builds/slave/test', 22:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:18 INFO - 'SHELL': '/bin/bash', 22:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:18 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:18 INFO - 'USER': 'cltbld', 22:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:18 INFO - 'XPC_FLAGS': '0x0', 22:13:18 INFO - 'XPC_SERVICE_NAME': '0', 22:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:13:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:13:20 INFO - Installing collected packages: mozsystemmonitor 22:13:20 INFO - Running setup.py install for mozsystemmonitor 22:13:21 INFO - Successfully installed mozsystemmonitor 22:13:21 INFO - Cleaning up... 22:13:21 INFO - Return code: 0 22:13:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:13:21 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')]} 22:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:21 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')]} 22:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:21 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:21 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 22:13:21 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 22:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:21 INFO - 'HOME': '/Users/cltbld', 22:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:21 INFO - 'LOGNAME': 'cltbld', 22:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:21 INFO - 'MOZ_NO_REMOTE': '1', 22:13:21 INFO - 'NO_EM_RESTART': '1', 22:13:21 INFO - 'PAGER': '/bin/cat', 22:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:21 INFO - 'PWD': '/builds/slave/test', 22:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:21 INFO - 'SHELL': '/bin/bash', 22:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:21 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:21 INFO - 'USER': 'cltbld', 22:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:21 INFO - 'XPC_FLAGS': '0x0', 22:13:21 INFO - 'XPC_SERVICE_NAME': '0', 22:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:21 INFO - Downloading/unpacking jsonschema==2.5.1 22:13:21 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 22:13:21 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 22:13:21 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 22:13:21 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 22:13:21 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 22:13:21 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 22:13:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:13:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:13:24 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 22:13:24 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 22:13:24 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 22:13:24 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 22:13:24 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 22:13:24 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 22:13:24 INFO - Downloading functools32-3.2.3-2.tar.gz 22:13:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:13:24 INFO - warning: no files found matching '*.txt' 22:13:24 INFO - no previously-included directories found matching 'build' 22:13:24 INFO - no previously-included directories found matching 'dist' 22:13:24 INFO - no previously-included directories found matching '.git*' 22:13:24 INFO - Installing collected packages: jsonschema, functools32 22:13:24 INFO - Running setup.py install for functools32 22:13:24 INFO - warning: no files found matching '*.txt' 22:13:24 INFO - no previously-included directories found matching 'build' 22:13:24 INFO - no previously-included directories found matching 'dist' 22:13:24 INFO - no previously-included directories found matching '.git*' 22:13:24 INFO - Successfully installed jsonschema functools32 22:13:24 INFO - Cleaning up... 22:13:24 INFO - Return code: 0 22:13:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:24 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:24 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 22:13:24 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 22:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:24 INFO - 'HOME': '/Users/cltbld', 22:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:24 INFO - 'LOGNAME': 'cltbld', 22:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:24 INFO - 'MOZ_NO_REMOTE': '1', 22:13:24 INFO - 'NO_EM_RESTART': '1', 22:13:24 INFO - 'PAGER': '/bin/cat', 22:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:24 INFO - 'PWD': '/builds/slave/test', 22:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:24 INFO - 'SHELL': '/bin/bash', 22:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:24 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:24 INFO - 'USER': 'cltbld', 22:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:24 INFO - 'XPC_FLAGS': '0x0', 22:13:24 INFO - 'XPC_SERVICE_NAME': '0', 22:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:13:25 INFO - Cleaning up... 22:13:25 INFO - Return code: 0 22:13:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:25 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:25 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 22:13:25 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 22:13:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:25 INFO - 'HOME': '/Users/cltbld', 22:13:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:25 INFO - 'LOGNAME': 'cltbld', 22:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:25 INFO - 'MOZ_NO_REMOTE': '1', 22:13:25 INFO - 'NO_EM_RESTART': '1', 22:13:25 INFO - 'PAGER': '/bin/cat', 22:13:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:25 INFO - 'PWD': '/builds/slave/test', 22:13:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:25 INFO - 'SHELL': '/bin/bash', 22:13:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:25 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:25 INFO - 'USER': 'cltbld', 22:13:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:25 INFO - 'XPC_FLAGS': '0x0', 22:13:25 INFO - 'XPC_SERVICE_NAME': '0', 22:13:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:25 INFO - Downloading/unpacking blobuploader==1.2.4 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13:27 INFO - Downloading blobuploader-1.2.4.tar.gz 22:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:13:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:13:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:13:28 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 22:13:28 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 22:13:28 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 22:13:28 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 22:13:28 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 22:13:28 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 22:13:29 INFO - Downloading docopt-0.6.1.tar.gz 22:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:13:29 INFO - Installing collected packages: blobuploader, requests, docopt 22:13:29 INFO - Running setup.py install for blobuploader 22:13:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:13:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:13:29 INFO - Running setup.py install for requests 22:13:29 INFO - Running setup.py install for docopt 22:13:29 INFO - Successfully installed blobuploader requests docopt 22:13:29 INFO - Cleaning up... 22:13:30 INFO - Return code: 0 22:13:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:30 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')]} 22:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 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')]} 22:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:30 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:30 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 22:13:30 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 22:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:30 INFO - 'HOME': '/Users/cltbld', 22:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:30 INFO - 'LOGNAME': 'cltbld', 22:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:30 INFO - 'MOZ_NO_REMOTE': '1', 22:13:30 INFO - 'NO_EM_RESTART': '1', 22:13:30 INFO - 'PAGER': '/bin/cat', 22:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:30 INFO - 'PWD': '/builds/slave/test', 22:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:30 INFO - 'SHELL': '/bin/bash', 22:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:30 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:30 INFO - 'USER': 'cltbld', 22:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:30 INFO - 'XPC_FLAGS': '0x0', 22:13:30 INFO - 'XPC_SERVICE_NAME': '0', 22:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-29QHkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hTSLhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GVZ8_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-xxtXBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-NkaGb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-xpW3ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:30 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GuV_p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-mrWTxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5UmJG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ybb7jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-OPuLHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ANKDpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-TcdgmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-xubawm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-WuvMsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:31 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-PreXe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:13:32 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-us3hld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:13:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:13:32 INFO - Running setup.py install for manifestparser 22:13:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:13:32 INFO - Running setup.py install for mozcrash 22:13:32 INFO - Running setup.py install for mozdebug 22:13:32 INFO - Running setup.py install for mozdevice 22:13:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:13:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:13:32 INFO - Running setup.py install for mozfile 22:13:32 INFO - Running setup.py install for mozhttpd 22:13:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Running setup.py install for mozinfo 22:13:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Running setup.py install for mozInstall 22:13:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Running setup.py install for mozleak 22:13:33 INFO - Running setup.py install for mozlog 22:13:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Running setup.py install for moznetwork 22:13:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:13:33 INFO - Running setup.py install for mozprocess 22:13:33 INFO - Running setup.py install for mozprofile 22:13:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:13:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:13:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:13:34 INFO - Running setup.py install for mozrunner 22:13:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:13:34 INFO - Running setup.py install for mozscreenshot 22:13:34 INFO - Running setup.py install for moztest 22:13:34 INFO - Running setup.py install for mozversion 22:13:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:13:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:13:34 INFO - Cleaning up... 22:13:34 INFO - Return code: 0 22:13:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13: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')]} 22:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13: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')]} 22:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:34 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:34 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 22:13:34 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 22:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:34 INFO - 'HOME': '/Users/cltbld', 22:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:34 INFO - 'LOGNAME': 'cltbld', 22:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:34 INFO - 'MOZ_NO_REMOTE': '1', 22:13:34 INFO - 'NO_EM_RESTART': '1', 22:13:34 INFO - 'PAGER': '/bin/cat', 22:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:34 INFO - 'PWD': '/builds/slave/test', 22:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:34 INFO - 'SHELL': '/bin/bash', 22:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:34 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:34 INFO - 'USER': 'cltbld', 22:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:34 INFO - 'XPC_FLAGS': '0x0', 22:13:34 INFO - 'XPC_SERVICE_NAME': '0', 22:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-c5aowl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-7KJcdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-B2i93y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Xd25Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-EQBdbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-aQ4Eih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-4p5tUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-E3RWh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-s6xz5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:35 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)) 22:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Wmg8QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Sjs211-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-edGCj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-cU5AzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-mgkhFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-w3zLzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-oxLJnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:13:36 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)) 22:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:13:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5pPRNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:13:36 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)) 22:13:36 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)) 22:13:36 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)) 22:13:36 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)) 22:13:36 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)) 22:13:36 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)) 22:13:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:13:36 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 22:13:36 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 22:13:36 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 22:13:36 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 22:13:36 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 22:13:36 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 22:13:39 INFO - Downloading blessings-1.6.tar.gz 22:13:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:13:39 INFO - Installing collected packages: blessings 22:13:39 INFO - Running setup.py install for blessings 22:13:39 INFO - Successfully installed blessings 22:13:39 INFO - Cleaning up... 22:13:39 INFO - Return code: 0 22:13:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:40 INFO - Reading from file tmpfile_stdout 22:13:40 INFO - Current package versions: 22:13:40 INFO - blessings == 1.6 22:13:40 INFO - blobuploader == 1.2.4 22:13:40 INFO - docopt == 0.6.1 22:13:40 INFO - functools32 == 3.2.3-2 22:13:40 INFO - jsonschema == 2.5.1 22:13:40 INFO - manifestparser == 1.1 22:13:40 INFO - mozInstall == 1.12 22:13:40 INFO - mozcrash == 0.17 22:13:40 INFO - mozdebug == 0.1 22:13:40 INFO - mozdevice == 0.48 22:13:40 INFO - mozfile == 1.2 22:13:40 INFO - mozhttpd == 0.7 22:13:40 INFO - mozinfo == 0.9 22:13:40 INFO - mozleak == 0.1 22:13:40 INFO - mozlog == 3.4 22:13:40 INFO - moznetwork == 0.27 22:13:40 INFO - mozprocess == 0.23 22:13:40 INFO - mozprofile == 0.28 22:13:40 INFO - mozrunner == 6.12 22:13:40 INFO - mozscreenshot == 0.1 22:13:40 INFO - mozsystemmonitor == 0.3 22:13:40 INFO - moztest == 0.7 22:13:40 INFO - mozversion == 1.4 22:13:40 INFO - psutil == 3.1.1 22:13:40 INFO - requests == 1.2.3 22:13:40 INFO - wsgiref == 0.1.2 22:13:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:40 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')]} 22:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:40 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')]} 22:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:40 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:40 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 22:13:40 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 22:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:40 INFO - 'HOME': '/Users/cltbld', 22:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:40 INFO - 'LOGNAME': 'cltbld', 22:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:40 INFO - 'MOZ_NO_REMOTE': '1', 22:13:40 INFO - 'NO_EM_RESTART': '1', 22:13:40 INFO - 'PAGER': '/bin/cat', 22:13:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:40 INFO - 'PWD': '/builds/slave/test', 22:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:40 INFO - 'SHELL': '/bin/bash', 22:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:40 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:40 INFO - 'USER': 'cltbld', 22:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:40 INFO - 'XPC_FLAGS': '0x0', 22:13:40 INFO - 'XPC_SERVICE_NAME': '0', 22:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 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)) 22:13:40 INFO - Cleaning up... 22:13:40 INFO - Return code: 0 22:13:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:13:40 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')]} 22:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:40 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')]} 22:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:40 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 0x1057c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9781c75d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, '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.UARVv9YDBA/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.M3ywHjJ5az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 22:13:40 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 22:13:40 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 22:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:40 INFO - 'HOME': '/Users/cltbld', 22:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:40 INFO - 'LOGNAME': 'cltbld', 22:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:40 INFO - 'MOZ_NO_REMOTE': '1', 22:13:40 INFO - 'NO_EM_RESTART': '1', 22:13:40 INFO - 'PAGER': '/bin/cat', 22:13:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:40 INFO - 'PWD': '/builds/slave/test', 22:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:40 INFO - 'SHELL': '/bin/bash', 22:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:13:40 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:40 INFO - 'USER': 'cltbld', 22:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:40 INFO - 'XPC_FLAGS': '0x0', 22:13:40 INFO - 'XPC_SERVICE_NAME': '0', 22:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:13:40 INFO - Cleaning up... 22:13:40 INFO - Return code: 0 22:13:40 INFO - Running post-action listener: _resource_record_post_action 22:13:40 INFO - Running post-action listener: _start_resource_monitoring 22:13:40 INFO - Starting resource monitoring. 22:13:40 INFO - [mozharness: 2016-11-03 05:13:40.624207Z] Finished create-virtualenv step (success) 22:13:40 INFO - [mozharness: 2016-11-03 05:13:40.625229Z] Running install step. 22:13:40 INFO - Running pre-action listener: _resource_record_pre_action 22:13:40 INFO - Running main action method: install 22:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:40 INFO - Reading from file tmpfile_stdout 22:13:40 INFO - Detecting whether we're running mozinstall >=1.0... 22:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:13:40 INFO - Reading from file tmpfile_stdout 22:13:40 INFO - Output received: 22:13:40 INFO - Usage: mozinstall [options] installer 22:13:40 INFO - Options: 22:13:40 INFO - -h, --help show this help message and exit 22:13:40 INFO - -d DEST, --destination=DEST 22:13:40 INFO - Directory to install application into. [default: 22:13:40 INFO - "/builds/slave/test"] 22:13:40 INFO - --app=APP Application being installed. [default: firefox] 22:13:40 INFO - mkdir: /builds/slave/test/build/application 22:13:40 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'] 22:13:40 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 22:14:15 INFO - Reading from file tmpfile_stdout 22:14:15 INFO - Output received: 22:14:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:14:15 INFO - Running post-action listener: _resource_record_post_action 22:14:15 INFO - [mozharness: 2016-11-03 05:14:15.734831Z] Finished install step (success) 22:14:15 INFO - [mozharness: 2016-11-03 05:14:15.734984Z] Running run-tests step. 22:14:15 INFO - Running pre-action listener: _resource_record_pre_action 22:14:15 INFO - Running main action method: run_tests 22:14:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:14:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:14:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:14:15 INFO - 2016-11-02 22:14:15.749 screenresolution[1647:8797] starting screenresolution argv=screenresolution get 22:14:15 INFO - 2016-11-02 22:14:15.752 screenresolution[1647:8797] Display 0: 1600x1200x32@60 22:14:15 INFO - 2016-11-02 22:14:15.757 screenresolution[1648:8800] starting screenresolution argv=screenresolution list 22:14:15 INFO - Available Modes on Display 0 22:14:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:14:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:14:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:14:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:14:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:14:15 INFO - Intel Iris: 22:14:15 INFO - Chipset Model: Intel Iris 22:14:15 INFO - Type: GPU 22:14:15 INFO - Bus: Built-In 22:14:15 INFO - VRAM (Dynamic, Max): 1536 MB 22:14:15 INFO - Vendor: Intel (0x8086) 22:14:15 INFO - Device ID: 0x0a2e 22:14:15 INFO - Revision ID: 0x0009 22:14:15 INFO - Displays: 22:14:15 INFO - Display: 22:14:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:14:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:14:15 INFO - Display Serial Number: 1600x1200 60 22:14:15 INFO - Main Display: Yes 22:14:15 INFO - Mirror: Off 22:14:15 INFO - Online: Yes 22:14:15 INFO - Rotation: Supported 22:14:15 INFO - Return code: 0 22:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:14:15 INFO - Python 2.7.3 22:14:15 INFO - Return code: 0 22:14:15 INFO - grabbing minidump binary from tooltool 22:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:16 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 0x1055d9d30>, '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 0x1053ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:14:16 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 22:14:16 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 22:14:16 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 22:14:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:14:16 INFO - Return code: 0 22:14:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:14:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:14:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:14:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:14:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:14:16 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/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 22:14:16 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/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --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 22:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UARVv9YDBA/Render', 22:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:14:16 INFO - 'HOME': '/Users/cltbld', 22:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:14:16 INFO - 'LOGNAME': 'cltbld', 22:14:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:14:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:16 INFO - 'MOZ_NO_REMOTE': '1', 22:14:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:14:16 INFO - 'NO_EM_RESTART': '1', 22:14:16 INFO - 'PAGER': '/bin/cat', 22:14:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:16 INFO - 'PWD': '/builds/slave/test', 22:14:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:14:16 INFO - 'SHELL': '/bin/bash', 22:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M3ywHjJ5az/Listeners', 22:14:16 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 22:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:14:16 INFO - 'USER': 'cltbld', 22:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:16 INFO - 'XPC_FLAGS': '0x0', 22:14:16 INFO - 'XPC_SERVICE_NAME': '0', 22:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:14:16 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/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 22:14:16 INFO - mozversion application_buildid: 20161102193123 22:14:16 INFO - mozversion application_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 22:14:16 INFO - mozversion application_display_name: Nightly 22:14:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:14:16 INFO - mozversion application_name: Firefox 22:14:16 INFO - mozversion application_remotingname: firefox 22:14:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:14:16 INFO - mozversion application_vendor: Mozilla 22:14:16 INFO - mozversion application_version: 52.0a1 22:14:16 INFO - mozversion platform_buildid: 20161102193123 22:14:16 INFO - mozversion platform_changeset: ade8d4a63e57560410de106450f37b50ed71cca5 22:14:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:14:16 INFO - mozversion platform_version: 52.0a1 22:14:16 INFO - using testdate: 1478150056 22:14:16 INFO - actual date: 1478150056 22:14:16 INFO - starting webserver on 'localhost:49272' 22:14:16 INFO - SUITE-START | Running 2 tests 22:14:16 INFO - TEST-START | dromaeo_css 22:14:16 INFO - operating with platform_type : mac_ 22:14:16 INFO - Initialising browser for dromaeo_css test... 22:14:16 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpV_a4tp/profile http://localhost:49272/getInfo.html) 22:14:19 INFO - PROCESS | 1658 | 2016-11-02 22:14:19.228 plugin-container[1660:8969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 22:14:19 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:19 INFO - PROCESS | 1658 | 2016-11-02 22:14:19.231 plugin-container[1660:8969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 22:14:19 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:19 INFO - PROCESS | 1658 | 2016-11-02 22:14:19.231 plugin-container[1660:8969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:14:19 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 22:14:19 INFO - PROCESS | 1658 | colorDepth:24 22:14:19 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 22:14:19 INFO - PROCESS | 1658 | __metrics 22:14:19 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 22:14:20 INFO - TEST-INFO | 1658: exit 0 22:14:20 INFO - Browser initialized. 22:14:20 INFO - Running cycle 1/1 for dromaeo_css test... 22:14:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpV_a4tp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:22 INFO - PROCESS | 1662 | 2016-11-02 22:14:22.589 plugin-container[1663:9097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 22:14:22 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:22 INFO - PROCESS | 1662 | 2016-11-02 22:14:22.590 plugin-container[1663:9097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 22:14:22 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:22 INFO - PROCESS | 1662 | 2016-11-02 22:14:22.590 plugin-container[1663:9097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:14:32 INFO - PROCESS | 1662 | RSS: Main: 238694400 22:14:32 INFO - PROCESS | 1662 | 22:15:21 INFO - PROCESS | 1662 | 1478150121951 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] 22:17:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:17:37 INFO - PROCESS | 1662 | RSS: Main: 253243392 22:17:37 INFO - PROCESS | 1662 | 22:20:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:20:40 INFO - PROCESS | 1662 | RSS: Main: 262938624 22:20:40 INFO - PROCESS | 1662 | 22:23:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:23:43 INFO - PROCESS | 1662 | RSS: Main: 267198464 22:23:43 INFO - PROCESS | 1662 | 22:26:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 22:26:45 INFO - PROCESS | 1662 | RSS: Main: 270462976 22:26:45 INFO - PROCESS | 1662 | 22:29:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 22:29:49 INFO - PROCESS | 1662 | RSS: Main: 274317312 22:29:49 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 22:32:52 INFO - PROCESS | 1662 | RSS: Main: 276791296 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | __start_tp_report 22:32:52 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 22:32:52 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 22:32:52 INFO - PROCESS | 1662 | |i|pagename|runs| 22:32:52 INFO - PROCESS | 1662 | |0;dojo.html;3260.967390326099;3422.3155368926223;3431.759776815623;3442.191085095003;3484.529588505551;4572.862814115573;5743.483086522212;5745.224394706714;5764.971175144129;5815.883682326351;5693.3452652944925;5742.741576629051;5750.511206547438;5803.854903627412;5835.970820145893;5205.765740541685;5242.161254199329;5306.151015837467;5339.5728341732565;5353.839384818461;5254.89647174092;5306.230596563496;5310.256564081032;5379.38137114231;5400.985241205684;5169.30214421054;5198.142306519401;5201.5838732901275;5271.446498117681;5296.443873393316;5193.558547523465;5217.295665085203;5235.65968212065;5246.291750613695;5247.580193584504;5250.291210686585;5290.709011004393;5305.098133317345;5319.946800531984;5320.654157479751;5143;5694.6867922264255;5752.194692743019;5772.653640781525;5785.652860828363;5056.494350564914;6206;6248.78129265478;6312.274088479791;6319.8736025279695;6122.367660829195;6261.8747625047245;6268.686565671698;6298;6302.716377762966;5827.155062515912;5900.557458190653;5917.615355001911;5936.7328470218945;5986.580939334205;6257.101885069798;6296.275928268216;6299;6323.747050117944;6329.873402531924;6126.387361263838;6222.782202622977;6226.782062627786;6245.75016999324;6248.718807653667;5241.554467870197;5993.340732519419;6109.450149486567;6163.260408750979;6171.84570385735;4770.332153498513;5649.180868774005;6123.953766440777;6139.324674285825;6139.921477737097;6097.25246653012;6161.876762464725;6171.259448866075;6171.814845554641;6177.2587289525545;4570.223062079454;4857.878553036132;4921.114199444134;4937.580305673986;5003.699778013331;3932.9016774580864;4417.823287068545;4452.821887124479;4453.332000199931;4508.954910450919;4992.026554821848;6033.909491357633;6035.275766907911;6036.305824830113;6044.727987240584;5235.65968212065;6200.751969921243;6266.060090986297;6269;6269.247690277105;5900.734466948998;5934.762609495659;5971.313298970674;6027.969860150672;6075;5469.671819690751;5486.478784515464;5490.835274941758;5492.258545096441;5492.533134683516;6218.689065546614;6457.257415397289;6457.709403076967;6481.0926470295135;6485.740570377132;6137.193127861533;6141.670122563672;6163.722632481459;6166.352532984151;6227.878981783141;6425.5502114851515;6440.871182576416;6450.612963222222;6455.515836312202;6510.804675859732;6143.754249830135;6229.626222426669;6232.127502149612;6234.096056071753;6239.844003899939;6133.916185115316;6149.44654981036;6162.352952939877;6176.227971503562;6238.906416403848;6171.660558669317;6181.629102253879;6182.536309776695;6193.969030154821;6228.128062071223;6153.507719382528;6175.536834737323;6188.257409110935;6193.81418557444;6202.472789812917;4620.838270660645;5879.323877754113;5946.43508866657;5971.731272092853;5999.820005399845;4441.666874984325;5677.602567820213;6010.7295171716505;6116.974953843861;6202.906956395567;5806.129080637938;6060.090986352083;6067.423594758491;6067.969660151847;6075.848103797263 22:32:52 INFO - PROCESS | 1662 | |1;ext.html;464.3986038080685;488.65549787399885;490.791413649199;504.60388594952946;523.6543881038515;37504.06239844005;40790.38814417786;40900.59099409005;40936.59063409365;40971.79514102429;41307;41514;41767.58232417675;41801.372979405336;41835;9473.526323683822;9593.856092158623;9606.855897161548;9612.567434465465;9625.37435066722;8239.629216685233;9480.099390557885;9571.473568953705;9576.281778866578;9587.712368628952;9593.520323983774;9603.615855365812;9628.22974162069;9641.614335426575;9678.951605241964;9543.713688589354;9570.330076894621;9575.042495750438;9592.424454532751;9615.615375384976;9430.622775088988;9490.762730931712;9507.57215925285;9529.428234305964;9532.094451027142;39949;40103.39844902329;40178.39732404016;40205;40205.994850128685;2973.3310005248863;2975.95841455491;3331.783434076777;3394.4229480988292;3663.9633603663888;25639.615405768927;29552;30200.54699179514;30745.692543074507;31147;3168.9683103168904;3171.762117841171;3193.7285330746913;3227.4836026235926;3234.1429521176906;507141;510660;511401;514919;516078;59754.80490390255;59847.700761495915;60279.397206028254;60285.09572356418;60421.395786042456;61483.77032459326;61705.382946169964;61793;62162.37837621566;62276.688616556625;15806.762898556532;16046.518604441886;16046.67906641884;16079.43721969749;16123.435679751152;12436.937815310866;12440.253584784941;12446.751064978649;12477.251364918135;12508.937455312665;17685.381011664787;18360.357387491375;18365.265389384276;18390.17244224013;18433.354832581063;3128.687131286853;6112.51099912006;7631.7328893489575;7651.579163145969;7761.340286075747;840.9369297302727;898.5808996767232;1041.375174895057;1042.4319440859415;1082.610260306289;29683.851580741957;29768.851155744516;30010.099697009126;30381.69618303833;31117.066488005396;969.2585172343163;1024.2522958240377;1031;1049.21833234241;1134.2003887259461;14970;14989.325480353411;14993.850061499463;15329.616759580877;15367.692646147014;443461;443987;444903.77548112697;447464;598650;53929.73035134878;54285.45714542804;54312.72843635757;54353.91292174134;54395.45604543983;1296.092735085444;1334.8665133486575;1344.4286178374152;1362.9931850340884;1374.1960952842699;27860.860695696392;28729.42541149207;29155.85422072876;29260.56109158407;29331.560026599192;37008.44487332746;49181;49203.26195107148;49260.26109608287;50547.48357549279;596121;596496;597128;598774;600616;25281.49437011286;25661.871690641427;25664.8716756415;25681.22956311314;25686.743132569183;32094.67905320917;35966.74116405913;37546;43892.341614875164;43981;27830;34589.65410345864;36593.81703091467;36952;37430.877073687836;30686;36399.27201456009;36561.90314290576;37147.44278835766;37520.62479375171;35580.82209588936;35932.820335899196;36568;36671.08322291964;36755.44866826946;26217.344566385993;31298;31444;32742.017739467854;32803.835980819946;20162.19351225993;23778.762212377656;23995.880020599787;24071.879640601685;24495.877520612285 22:32:52 INFO - PROCESS | 1662 | |2;jquery.html;348.9092835862676;359.32984983006685;364.06253896217237;376.68923138410815;387.79396078196805;18388.356407525724;18804.059797010144;19681.114349854255;20073.397798066064;20301;19672.409827705193;19924;20000.699989500168;20148.69776953343;20231.797682023174;5788.507976821975;5828.125781132832;6085.269767627891;6111.419415155553;6112.724927378279;5872.941270587304;5986.760529578812;6066.240790170845;6070.848228794272;6085.756569737206;4687.7656117194;5691.630044047144;6018.247719035121;6029.246344206974;6047.818565443022;5912.467877890989;5989.940100598982;6006.279246490405;6008.399160084;6013;4996.675216110977;5126.769295381717;5128.384593848725;5181.989512045153;5204.661696989721;23330.416739581477;23489.765102348927;24585.631215531783;25194.49611007788;25413.74586254151;1782.08222765276;1946.1826033066145;2154.1706443019393;2275.84069115162;2285.6914316567218;9838.606455741834;9862.281664539396;9927.255455840768;10126.341787783771;10366.06705396518;1844.4743248174207;1899.4396652987402;1904.3810761502566;1936.4674714453497;1977.4660841572695;126740;131240.34379828136;132493.33753331268;133649;135935.28129437554;11250;11346.48940797666;11511.884881151082;11571.305721656727;11615.186936914335;14401.567952961428;14455.638609034859;14455.927720361331;14456.204908729911;14484.782728259084;6948.756793512202;8197.34421246299;8388.958055209685;8415.621297041647;8416.495010299279;7051.682674279669;7403.814904627319;8188.631511581993;8355.582220889053;8361.745738139207;3615.367310720613;3729.738918275749;4577.443669152504;4622.46841613212;4633.142868569325;1611.7582362645462;1869.8504119670395;1913.6938089905548;1916.338863092231;1919.6256729937807;799.6481548118926;935.4247138010074;960.7886265021684;961.4711908450324;1001.8296889528798;20454.897725511575;21326.78673213248;21335.088322838074;21526.461838454165;21556.922153892243;892.3619611977338;909.7589138878203;955.7658373698488;959.5921733263335;973.5494113770573;9207.12532309443;9407.388519746195;10170.033846784543;11090.500927458284;11340.262882912584;563656.1817190888;571747;574078;579316;583331;107495;110589;123200;133028;138707.6129238715;928.3084102343797;978.1538968792086;1017.6133069433716;1023.948802559869;1025.3437799808128;19060.904695476434;19185.424437266905;19239.711404328667;19393.4181974541;19501;50772.73068173325;51729.15489648106;55759;57539;57886.89583458404;65207.67396162989;83282.75075873746;85094;85187.5740621293;86828.13171868201;6423.582467139621;7760.883586746316;7993.920060799318;8117.269445749675;8192.508449493049;9637.325387223107;11550.249233799776;12165.087618428477;12244.69388265265;13058.477660893457;9601.855972160283;10160.542775575264;10965.671029869116;11125.276857004268;11384.658460246206;10946.781064378509;11001.22991390595;11087.833682494607;11164.385958772345;11278;9217.861732074158;11128.38793866345;11306.152038596974;11415.315081095161;11646.592369267035;7913.604319784103;7946.165652606393;8074.152214017444;8148.429609927485;8363.163683631588;8194.467359621605;9794.951025244829;10026.799464010533;11075.335479871233;11172.608958686407 22:32:52 INFO - PROCESS | 1662 | |3;mootools.html;1433.304847149133;1486.0637798187145;1633.877459190561;1709.2479309103985;1750.0812073661318;7626.580538070404;7868.213178682129;8157.30662893654;8572.785680357987;8615.353848461367;8030.317423019051;8326.125756795525;8370.246677798998;8451.83096338073;8469.91530084699;2256.8758718270487;2277.7722227777253;2285.7828506291876;2300.608896487601;2303.0442366417915;2272.216085450517;2310.8960096795686;2340.707411573553;2344.3318654183563;2350.647402889563;2273.54529094181;2278.9289034154003;2293.266154830455;2301.735300440446;2305.227748704186;1925.8266755991958;2063.772984971652;2217.079911836586;2281.7718228177214;2291.5531471363092;2287.645414960684;2312.236961802601;2312.814974802012;2318.7565305643;2327.150590034642;7113.430925525946;8064.879026814602;8399.664013439455;8455.154484551556;8490.193431623986;5032;5152;5185.196294574347;5186.299849520342;5205.791768329263;2953.8966136185345;3052.5879006334303;3269.8365081746056;3536.1159710072484;3562.305350456662;886.264400547544;1007.7430255284855;1042.593388578455;1059.3485006720812;1060.8355704865758;10237.283390162691;10701.732456688567;10990.890091099067;11014.944925275402;11033.779324413546;3694.7783133012103;4292.613664770186;4356.237658409766;4389.934150987738;4392.758398288125;7150.213476517579;8179.918200818035;8697.739067827975;8706.347023973227;8707.738767836974;4795.592374648194;5010.073136469765;5016.749162541858;5024.371953505812;5034.798608055637;4210.936835947463;4693.319468677023;4739.454962679336;4757.26262429324;4778.593819525379;3547.52108465359;3595.7123430125525;3626.8730594429067;3656.3601369760186;3703.111253299243;1103.0954617213968;1118.9608363707393;1121.254365846719;1127.4644543841773;1128.7234627516148;843.3169133002287;855.9957200214084;878.4202426398547;895.9741096444535;914.8399030169694;4671.392718946583;5190.92213616796;5224.98837947393;5755.798547050833;5975.671338076448;756.587659725451;839.9202075802789;891.7057371067532;918.8652014761764;944.7449188719007;2878.7265209805037;3703.2593481303848;3748.081719978623;3758.642928921748;3856.652901238902;8643.654253829776;9820.45987470696;10126.291159618904;10198.235132365102;10218.131458825934;10152.390856548483;10156.187504999582;10178.541965611565;10194.949025254975;10197.74505637365;7472.850542989218;7867;7869.3311068559815;7982.042154941328;8010.9585753851625;5484.917726234189;5573.080441727069;5598.468145526169;5705.62913410627;5744.856378590569;8849.557522123996;9239.676611318571;9385.296102792181;9446.008169142142;9482.668106616235;9447.858282125912;9480.478573678238;9568.61725530971;9610.327277090817;9636.46999415039;6663.36698013688;6754.18949726036;7217.6752046157;7223.819404514929;7369.18938916708;7916.287534121957;8075.798105047421;8079.313258373104;8093.635786389509;8098.797530061795;7033.156021277479;7165.176004759519;7166.498345115792;7229.313215244543;7309;6920.273371295942;7012.509124361246;7023.122109736283;7098.893516597358;7122.430205583644;6646.335366463509;6672.466202703869;6729.259781423942;6730.25967143604;6731.562448440835;4757.9048419032115;4798.328234047166;4827.493113223201;4870.610351171968;4905.754712264301;4716.622670186308;4836.903261934812;4838.0082083172165;4891;4906.994066216345 22:32:52 INFO - PROCESS | 1662 | |4;prototype.html;251.96220566914963;268.9421774318521;288.5613866922279;303.8814862203741;303.89971309467876;4554.339620754997;5308.5487733542695;5520.972395138024;5561.249231353771;5584.832455026346;5123.180291153416;5275.894482110355;5463.825277565323;5518;5562.85829955467;4172.082141928786;4191.49106321724;4204.5585213552595;4204.810783514734;4233.851815186469;3745.644163804448;3884.8057597120173;4151.83392664293;4166.166766646667;4215.346621273692;3281.506914353963;3736.0473079364724;4120.896977575555;4123.7938103094875;4127.504699436072;4102.938455923164;4114.403411505345;4115.341545352744;4134.4831896013;4142.975627791729;2939.867705953216;3400.795952242873;3493.580770307554;3509.666326795827;3547.343741407848;4993.475685053054;5328.284468245631;5344.545713614348;5373.328333958255;5419.783208671648;4107.691923105779;4293.806778694966;4675.228587283094;4716.52834716529;4721.315409265637;5514.970059880239;5659.207710920475;5682.801101961453;5760.36635970043;5798.536117110621;3118.469860123784;3555.822208889545;3796.9368576798543;3857.517810273716;3880.6715970417804;4930.383702037246;6086.969565152191;6101.328853826076;6103.816885493442;6121.663308517986;3666.5416822897137;3802.6197380262306;3878.3600705883778;3904.8090332448874;3933.842646294116;3927.56796752357;4002.1984610772574;4007.519097708235;4009.1134383244016;4009.639132478091;4494.73031618104;4693.263492507794;4694.7417892016265;4728.952710472851;4732.935089604812;5376.231240910934;5827.271591051119;5853.765849365977;5915.668974480707;5934.6142500737315;1106.9723256918642;1283.58164228528;1311.7179806341683;1316.3615646411483;1347.5351003903638;887.2192470625808;889.9009722992229;902.702108304258;913.9040400757958;919.3197034194648;4802.855914322576;4899.412070551557;5403.918941215885;5426;5485.341758988947;4681.180793361148;4875.463698993108;5450.509454149145;5922.763089476459;5933.6352638893295;4343.869683909487;5218.608604354688;5473.808416705476;5478.178273259043;5514.3880758103505;2401.4716762312264;2402.5824763389683;2630.921072367832;2632.9605055924176;2663.721413721386;4200.726952748061;4829.975850120727;4856.697445736862;5216.835013469444;5262.289590905179;4377.038779904192;4749.955009897817;5025.170846810309;5099.4390617032095;5128.640995130379;4376.227627810742;4846.151923413459;5205.479452054764;5207.401148867929;5245.449227831175;4845.3767987724505;4948.901021979468;4966.304717339503;4989.176785830297;5049.8790507986;4549.522300158435;4744;4919.532644398776;4935.753212339297;4979.900401992012;4522.4573051234065;5180.300659410931;5195.090859099718;5238.816641417532;5243.3072400200845;3397.779144355609;3407.710344620735;3414.407621748744;3432.931341373109;3439.1917899294144;3983.1635356575443;4147.942274719927;4772.308015337826;4787.428006253949;5106.263870284121;4093.09390332867;4238.491381034296;4327.805248763751;4397.274449715761;4875.926861097016;4384.838017925316;4856.465788763304;4865.70805751656;4882.802738767497;4891.046245982142;4096.651784598343;4168.854090106832;4429.756363400044;4720.480747117883;4723.763811809465;4125.876223713293;4504.481984571685;4514.262009126444;4668.125455871686;4792.568668819823;4761.547652972962;4768.879313361426;4770.785314660918;4773.3555969945;4773.687236009986 22:32:52 INFO - PROCESS | 1662 | |5;yui.html;648.474735464274;674.4873895839162;717.5479447947794;740.5889731199183;784.901887264092;14240.359183836727;15072.171030593314;15081.547553573382;15106.924465377686;15140.697186056286;13569.253691046988;13710.725785484285;13711;13711.45154193831;13747.175169489838;650.8047585724288;657.0702456024743;678.3081257117736;723.9565626062426;798.8282519258358;651.4820717529575;830.2361827119034;832.7210384521193;834.6452757578005;834.7537476444434;764.7629234937187;871.0592560035146;872.4372779557182;873.5937788928147;874.6894852327428;865.3164000439646;870.5821205821178;872.3065163195212;873.3973558244822;873.6636394987912;641;650.8893488107033;651.641597121586;657.9309172536863;664.5381459885382;13911.304434778322;14007.159570425705;14293;14305.928470357685;14744;10873.83689244662;13762;13903.053572499859;14140.363683634261;14188.21964791936;11228.831567526493;11267.549298028069;11312.3212607243;11389.16705499761;11402.543898244061;3691.9261614767825;3728.422094575345;3732.9066773330615;3752.5496940367057;3867.690584753241;85183;86379.13620863773;87084.56457717734;87134;87722.56138719329;10645.6939685309;10656.413897235727;10718.839217411745;10772.89227107719;11062.944685276521;20743.688844667344;20903.74577525323;20951.58096838085;20960.895195523924;20992.580148397254;7647.464677472523;7683.769486915401;7734.303912647776;7752.496087754278;7825.13923468418;4057.127717540743;4384.395398516033;4384.693071484965;4431.0251744616135;4525.547445255448;3386.7290616751093;3498.650134986532;3499.0902365384673;3502.5972013218297;3506.1935754776546;617.5769597825504;624.7879114517821;634.429013887505;641.1952998986243;648.2124219073754;546.3744013105052;583.2272239282967;628.3025841316136;641.4451499453033;643.330935826735;8725.171108744658;9020.548972551345;9044.638214471348;9088.727338179866;9201.171894529525;579.0995002770732;608.8995315772847;615.879903418838;671.5433505216499;672.9394354508117;3473.930521389558;3574.946375804365;3894.824732887027;4069.2878746219885;4361.672874534359;65082.67458662677;70268;91152.54423727971;109814;114379;64971;66118;66121.33878661152;77211.61394192993;102165.48917255366;71313.57372852514;90188.09811901797;91096;91388.71528211847;91509;6726.428253598303;7625.23747625233;7986;7993.1607181247455;8538.274246689101;38270;49869.00261994812;57195.42804572068;58974.705126474095;59927.70036149791;52766.73616632046;72929;76170.85743713738;77316.4536709274;77329.22670773446;12001.699957501132;12164.391780410768;12272.325022123869;12431.813522797345;12733.87266127364;13784.241866697428;14430.711385772016;14452;14512.20182889909;14854.405823766929;13020.47918083266;13249.735005299648;13307.733845323233;13823.308834558433;13891.166530008231;12973.740525189633;13267.66830829237;13269;13369.197848129143;13410.731785364433;12879.034072444416;12923;12995.52757164653;13090.476380944656;13331.941897620163;10849.891501084889;10905.618303359344;11226.66320010401;11506.424678766196;11558.017568506772;10983.780324393307;11081.667549973514;11160.44197790091;11290.661280161608;11320.377379243891 22:32:52 INFO - PROCESS | 1662 | __end_tp_report 22:32:52 INFO - PROCESS | 1662 | __start_cc_report 22:32:52 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,367 22:32:52 INFO - PROCESS | 1662 | __end_cc_report 22:32:52 INFO - PROCESS | 1662 | __startTimestamp1478151172616__endTimestamp 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | ------- Summary: start ------- 22:32:52 INFO - PROCESS | 1662 | Number of tests: 6 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5727.91 Median:5999.82 stddev:671.60 (11.2%) stddev-sans-first:645.17 22:32:52 INFO - PROCESS | 1662 | Values: 3261.0 3422.3 3431.8 3442.2 3484.5 4572.9 5743.5 5745.2 5765.0 5815.9 5693.3 5742.7 5750.5 5803.9 5836.0 5205.8 5242.2 5306.2 5339.6 5353.8 5254.9 5306.2 5310.3 5379.4 5401.0 5169.3 5198.1 5201.6 5271.4 5296.4 5193.6 5217.3 5235.7 5246.3 5247.6 5250.3 5290.7 5305.1 5319.9 5320.7 5143.0 5694.7 5752.2 5772.7 5785.7 5056.5 6206.0 6248.8 6312.3 6319.9 6122.4 6261.9 6268.7 6298.0 6302.7 5827.2 5900.6 5917.6 5936.7 5986.6 6257.1 6296.3 6299.0 6323.7 6329.9 6126.4 6222.8 6226.8 6245.8 6248.7 5241.6 5993.3 6109.5 6163.3 6171.8 4770.3 5649.2 6124.0 6139.3 6139.9 6097.3 6161.9 6171.3 6171.8 6177.3 4570.2 4857.9 4921.1 4937.6 5003.7 3932.9 4417.8 4452.8 4453.3 4509.0 4992.0 6033.9 6035.3 6036.3 6044.7 5235.7 6200.8 6266.1 6269.0 6269.2 5900.7 5934.8 5971.3 6028.0 6075.0 5469.7 5486.5 5490.8 5492.3 5492.5 6218.7 6457.3 6457.7 6481.1 6485.7 6137.2 6141.7 6163.7 6166.4 6227.9 6425.6 6440.9 6450.6 6455.5 6510.8 6143.8 6229.6 6232.1 6234.1 6239.8 6133.9 6149.4 6162.4 6176.2 6238.9 6171.7 6181.6 6182.5 6194.0 6228.1 6153.5 6175.5 6188.3 6193.8 6202.5 4620.8 5879.3 5946.4 5971.7 5999.8 4441.7 5677.6 6010.7 6117.0 6202.9 5806.1 6060.1 6067.4 6068.0 6075.8 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:65853.60 Median:37512.34 stddev:142084.95 (378.8%) stddev-sans-first:142398.71 22:32:52 INFO - PROCESS | 1662 | Values: 464.4 488.7 490.8 504.6 523.7 37504.1 40790.4 40900.6 40936.6 40971.8 41307.0 41514.0 41767.6 41801.4 41835.0 9473.5 9593.9 9606.9 9612.6 9625.4 8239.6 9480.1 9571.5 9576.3 9587.7 9593.5 9603.6 9628.2 9641.6 9679.0 9543.7 9570.3 9575.0 9592.4 9615.6 9430.6 9490.8 9507.6 9529.4 9532.1 39949.0 40103.4 40178.4 40205.0 40206.0 2973.3 2976.0 3331.8 3394.4 3664.0 25639.6 29552.0 30200.5 30745.7 31147.0 3169.0 3171.8 3193.7 3227.5 3234.1 507141.0 510660.0 511401.0 514919.0 516078.0 59754.8 59847.7 60279.4 60285.1 60421.4 61483.8 61705.4 61793.0 62162.4 62276.7 15806.8 16046.5 16046.7 16079.4 16123.4 12436.9 12440.3 12446.8 12477.3 12508.9 17685.4 18360.4 18365.3 18390.2 18433.4 3128.7 6112.5 7631.7 7651.6 7761.3 840.9 898.6 1041.4 1042.4 1082.6 29683.9 29768.9 30010.1 30381.7 31117.1 969.3 1024.3 1031.0 1049.2 1134.2 14970.0 14989.3 14993.9 15329.6 15367.7 443461.0 443987.0 444903.8 447464.0 598650.0 53929.7 54285.5 54312.7 54353.9 54395.5 1296.1 1334.9 1344.4 1363.0 1374.2 27860.9 28729.4 29155.9 29260.6 29331.6 37008.4 49181.0 49203.3 49260.3 50547.5 596121.0 596496.0 597128.0 598774.0 600616.0 25281.5 25661.9 25664.9 25681.2 25686.7 32094.7 35966.7 37546.0 43892.3 43981.0 27830.0 34589.7 36593.8 36952.0 37430.9 30686.0 36399.3 36561.9 37147.4 37520.6 35580.8 35932.8 36568.0 36671.1 36755.4 26217.3 31298.0 31444.0 32742.0 32803.8 20162.2 23778.8 23995.9 24071.9 24495.9 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34549.10 Median:1987.35 stddev:96459.73 (4853.7%) stddev-sans-first:96696.14 22:32:52 INFO - PROCESS | 1662 | Values: 348.9 359.3 364.1 376.7 387.8 18388.4 18804.1 19681.1 20073.4 20301.0 19672.4 19924.0 20000.7 20148.7 20231.8 5788.5 5828.1 6085.3 6111.4 6112.7 5872.9 5986.8 6066.2 6070.8 6085.8 4687.8 5691.6 6018.2 6029.2 6047.8 5912.5 5989.9 6006.3 6008.4 6013.0 4996.7 5126.8 5128.4 5182.0 5204.7 23330.4 23489.8 24585.6 25194.5 25413.7 1782.1 1946.2 2154.2 2275.8 2285.7 9838.6 9862.3 9927.3 10126.3 10366.1 1844.5 1899.4 1904.4 1936.5 1977.5 126740.0 131240.3 132493.3 133649.0 135935.3 11250.0 11346.5 11511.9 11571.3 11615.2 14401.6 14455.6 14455.9 14456.2 14484.8 6948.8 8197.3 8389.0 8415.6 8416.5 7051.7 7403.8 8188.6 8355.6 8361.7 3615.4 3729.7 4577.4 4622.5 4633.1 1611.8 1869.9 1913.7 1916.3 1919.6 799.6 935.4 960.8 961.5 1001.8 20454.9 21326.8 21335.1 21526.5 21556.9 892.4 909.8 955.8 959.6 973.5 9207.1 9407.4 10170.0 11090.5 11340.3 563656.2 571747.0 574078.0 579316.0 583331.0 107495.0 110589.0 123200.0 133028.0 138707.6 928.3 978.2 1017.6 1023.9 1025.3 19060.9 19185.4 19239.7 19393.4 19501.0 50772.7 51729.2 55759.0 57539.0 57886.9 65207.7 83282.8 85094.0 85187.6 86828.1 6423.6 7760.9 7993.9 8117.3 8192.5 9637.3 11550.2 12165.1 12244.7 13058.5 9601.9 10160.5 10965.7 11125.3 11384.7 10946.8 11001.2 11087.8 11164.4 11278.0 9217.9 11128.4 11306.2 11415.3 11646.6 7913.6 7946.2 8074.2 8148.4 8363.2 8194.5 9795.0 10026.8 11075.3 11172.6 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5370.38 Median:5020.56 stddev:2973.01 (59.2%) stddev-sans-first:2966.63 22:32:52 INFO - PROCESS | 1662 | Values: 1433.3 1486.1 1633.9 1709.2 1750.1 7626.6 7868.2 8157.3 8572.8 8615.4 8030.3 8326.1 8370.2 8451.8 8469.9 2256.9 2277.8 2285.8 2300.6 2303.0 2272.2 2310.9 2340.7 2344.3 2350.6 2273.5 2278.9 2293.3 2301.7 2305.2 1925.8 2063.8 2217.1 2281.8 2291.6 2287.6 2312.2 2312.8 2318.8 2327.2 7113.4 8064.9 8399.7 8455.2 8490.2 5032.0 5152.0 5185.2 5186.3 5205.8 2953.9 3052.6 3269.8 3536.1 3562.3 886.3 1007.7 1042.6 1059.3 1060.8 10237.3 10701.7 10990.9 11014.9 11033.8 3694.8 4292.6 4356.2 4389.9 4392.8 7150.2 8179.9 8697.7 8706.3 8707.7 4795.6 5010.1 5016.7 5024.4 5034.8 4210.9 4693.3 4739.5 4757.3 4778.6 3547.5 3595.7 3626.9 3656.4 3703.1 1103.1 1119.0 1121.3 1127.5 1128.7 843.3 856.0 878.4 896.0 914.8 4671.4 5190.9 5225.0 5755.8 5975.7 756.6 839.9 891.7 918.9 944.7 2878.7 3703.3 3748.1 3758.6 3856.7 8643.7 9820.5 10126.3 10198.2 10218.1 10152.4 10156.2 10178.5 10194.9 10197.7 7472.9 7867.0 7869.3 7982.0 8011.0 5484.9 5573.1 5598.5 5705.6 5744.9 8849.6 9239.7 9385.3 9446.0 9482.7 9447.9 9480.5 9568.6 9610.3 9636.5 6663.4 6754.2 7217.7 7223.8 7369.2 7916.3 8075.8 8079.3 8093.6 8098.8 7033.2 7165.2 7166.5 7229.3 7309.0 6920.3 7012.5 7023.1 7098.9 7122.4 6646.3 6672.5 6729.3 6730.3 6731.6 4757.9 4798.3 4827.5 4870.6 4905.8 4716.6 4836.9 4838.0 4891.0 4907.0 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4300.24 Median:4722.54 stddev:1300.69 (27.5%) stddev-sans-first:1268.35 22:32:52 INFO - PROCESS | 1662 | Values: 252.0 268.9 288.6 303.9 303.9 4554.3 5308.5 5521.0 5561.2 5584.8 5123.2 5275.9 5463.8 5518.0 5562.9 4172.1 4191.5 4204.6 4204.8 4233.9 3745.6 3884.8 4151.8 4166.2 4215.3 3281.5 3736.0 4120.9 4123.8 4127.5 4102.9 4114.4 4115.3 4134.5 4143.0 2939.9 3400.8 3493.6 3509.7 3547.3 4993.5 5328.3 5344.5 5373.3 5419.8 4107.7 4293.8 4675.2 4716.5 4721.3 5515.0 5659.2 5682.8 5760.4 5798.5 3118.5 3555.8 3796.9 3857.5 3880.7 4930.4 6087.0 6101.3 6103.8 6121.7 3666.5 3802.6 3878.4 3904.8 3933.8 3927.6 4002.2 4007.5 4009.1 4009.6 4494.7 4693.3 4694.7 4729.0 4732.9 5376.2 5827.3 5853.8 5915.7 5934.6 1107.0 1283.6 1311.7 1316.4 1347.5 887.2 889.9 902.7 913.9 919.3 4802.9 4899.4 5403.9 5426.0 5485.3 4681.2 4875.5 5450.5 5922.8 5933.6 4343.9 5218.6 5473.8 5478.2 5514.4 2401.5 2402.6 2630.9 2633.0 2663.7 4200.7 4830.0 4856.7 5216.8 5262.3 4377.0 4750.0 5025.2 5099.4 5128.6 4376.2 4846.2 5205.5 5207.4 5245.4 4845.4 4948.9 4966.3 4989.2 5049.9 4549.5 4744.0 4919.5 4935.8 4979.9 4522.5 5180.3 5195.1 5238.8 5243.3 3397.8 3407.7 3414.4 3432.9 3439.2 3983.2 4147.9 4772.3 4787.4 5106.3 4093.1 4238.5 4327.8 4397.3 4875.9 4384.8 4856.5 4865.7 4882.8 4891.0 4096.7 4168.9 4429.8 4720.5 4723.8 4125.9 4504.5 4514.3 4668.1 4792.6 4761.5 4768.9 4770.8 4773.4 4773.7 22:32:52 INFO - PROCESS | 1662 | 22:32:52 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19387.75 Median:4384.54 stddev:27433.43 (625.7%) stddev-sans-first:27474.31 22:32:52 INFO - PROCESS | 1662 | Values: 648.5 674.5 717.5 740.6 784.9 14240.4 15072.2 15081.5 15106.9 15140.7 13569.3 13710.7 13711.0 13711.5 13747.2 650.8 657.1 678.3 724.0 798.8 651.5 830.2 832.7 834.6 834.8 764.8 871.1 872.4 873.6 874.7 865.3 870.6 872.3 873.4 873.7 641.0 650.9 651.6 657.9 664.5 13911.3 14007.2 14293.0 14305.9 14744.0 10873.8 13762.0 13903.1 14140.4 14188.2 11228.8 11267.5 11312.3 11389.2 11402.5 3691.9 3728.4 3732.9 3752.5 3867.7 85183.0 86379.1 87084.6 87134.0 87722.6 10645.7 10656.4 10718.8 10772.9 11062.9 20743.7 20903.7 20951.6 20960.9 20992.6 7647.5 7683.8 7734.3 7752.5 7825.1 4057.1 4384.4 4384.7 4431.0 4525.5 3386.7 3498.7 3499.1 3502.6 3506.2 617.6 624.8 634.4 641.2 648.2 546.4 583.2 628.3 641.4 643.3 8725.2 9020.5 9044.6 9088.7 9201.2 579.1 608.9 615.9 671.5 672.9 3473.9 3574.9 3894.8 4069.3 4361.7 65082.7 70268.0 91152.5 109814.0 114379.0 64971.0 66118.0 66121.3 77211.6 102165.5 71313.6 90188.1 91096.0 91388.7 91509.0 6726.4 7625.2 7986.0 7993.2 8538.3 38270.0 49869.0 57195.4 58974.7 59927.7 52766.7 72929.0 76170.9 77316.5 77329.2 12001.7 12164.4 12272.3 12431.8 12733.9 13784.2 14430.7 14452.0 14512.2 14854.4 13020.5 13249.7 13307.7 13823.3 13891.2 12973.7 13267.7 13269.0 13369.2 13410.7 12879.0 12923.0 12995.5 13090.5 13331.9 10849.9 10905.6 11226.7 11506.4 11558.0 10983.8 11081.7 11160.4 11290.7 11320.4 22:32:52 INFO - PROCESS | 1662 | -------- Summary: end -------- 22:32:52 INFO - PROCESS | 1662 | 22:32:53 INFO - TEST-INFO | 1662: exit 0 22:32:53 INFO - TEST-OK | dromaeo_css | took 1116969ms 22:32:53 INFO - TEST-START | kraken 22:32:53 INFO - operating with platform_type : mac_ 22:32:53 INFO - Initialising browser for kraken test... 22:32:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmp6XrMzt/profile http://localhost:49272/getInfo.html) 22:32:56 INFO - PROCESS | 1671 | 2016-11-02 22:32:56.217 plugin-container[1672:11070] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 22:32:56 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:56 INFO - PROCESS | 1671 | 2016-11-02 22:32:56.220 plugin-container[1672:11070] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.CFPasteboardClient' 22:32:56 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:56 INFO - PROCESS | 1671 | 2016-11-02 22:32:56.220 plugin-container[1672:11070] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:32:56 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 22:32:56 INFO - PROCESS | 1671 | colorDepth:24 22:32:56 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 22:32:56 INFO - PROCESS | 1671 | __metrics 22:32:56 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 22:32:57 INFO - TEST-INFO | 1671: exit 0 22:32:57 INFO - Browser initialized. 22:32:57 INFO - Running cycle 1/1 for kraken test... 22:32:57 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmp6XrMzt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:59 INFO - PROCESS | 1673 | 2016-11-02 22:32:59.608 plugin-container[1674:11193] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 22:32:59 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:59 INFO - PROCESS | 1673 | 2016-11-02 22:32:59.609 plugin-container[1674:11193] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 22:32:59 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:59 INFO - PROCESS | 1673 | 2016-11-02 22:32:59.609 plugin-container[1674:11193] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:33:09 INFO - PROCESS | 1673 | RSS: Main: 239058944 22:33:09 INFO - PROCESS | 1673 | 22:33:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 22:33:13 INFO - PROCESS | 1673 | RSS: Main: 251637760 22:33:13 INFO - PROCESS | 1673 | 22:33:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 22:33:16 INFO - PROCESS | 1673 | RSS: Main: 259149824 22:33:16 INFO - PROCESS | 1673 | 22:33:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 22:33:20 INFO - PROCESS | 1673 | RSS: Main: 261050368 22:33:20 INFO - PROCESS | 1673 | 22:33:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 22:33:22 INFO - PROCESS | 1673 | RSS: Main: 262115328 22:33:22 INFO - PROCESS | 1673 | 22:33:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:33:25 INFO - PROCESS | 1673 | RSS: Main: 262578176 22:33:25 INFO - PROCESS | 1673 | 22:33:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 22:33:28 INFO - PROCESS | 1673 | RSS: Main: 264007680 22:33:28 INFO - PROCESS | 1673 | 22:33:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 22:33:32 INFO - PROCESS | 1673 | RSS: Main: 263909376 22:33:32 INFO - PROCESS | 1673 | 22:33:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 22:33:35 INFO - PROCESS | 1673 | RSS: Main: 264577024 22:33:35 INFO - PROCESS | 1673 | 22:33:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:33:37 INFO - PROCESS | 1673 | RSS: Main: 264282112 22:33:37 INFO - PROCESS | 1673 | 22:33:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:33:40 INFO - PROCESS | 1673 | RSS: Main: 264122368 22:33:40 INFO - PROCESS | 1673 | 22:33:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:33:43 INFO - PROCESS | 1673 | RSS: Main: 264151040 22:33:43 INFO - PROCESS | 1673 | 22:33:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:33:47 INFO - PROCESS | 1673 | RSS: Main: 264290304 22:33:47 INFO - PROCESS | 1673 | 22:33:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:33:51 INFO - PROCESS | 1673 | RSS: Main: 264507392 22:33:51 INFO - PROCESS | 1673 | 22:33:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 22:33:53 INFO - PROCESS | 1673 | RSS: Main: 266145792 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | __start_tp_report 22:33:53 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 22:33:53 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 22:33:53 INFO - PROCESS | 1673 | |i|pagename|runs| 22:33:53 INFO - PROCESS | 1673 | |0;ai-astar;105;119;120;120;117;119;118;119;121;118 22:33:53 INFO - PROCESS | 1673 | |1;audio-beat-detection;147;113;121;154;116;131;117;120;142;118 22:33:53 INFO - PROCESS | 1673 | |2;audio-dft;202;216;234;194;197;194;196;202;199;192 22:33:53 INFO - PROCESS | 1673 | |3;audio-fft;82;57;60;83;57;66;79;85;102;58 22:33:53 INFO - PROCESS | 1673 | |4;audio-oscillator;92;73;86;72;70;70;84;70;71;90 22:33:53 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;94;89;91;88;93;88;92;90;91;87 22:33:53 INFO - PROCESS | 1673 | |6;imaging-darkroom;96;97;96;98;97;96;97;97;97;95 22:33:53 INFO - PROCESS | 1673 | |7;imaging-desaturate;89;94;86;87;88;88;91;91;91;86 22:33:53 INFO - PROCESS | 1673 | |8;json-parse-financial;54;54;57;59;57;59;62;59;56;59 22:33:53 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;71;80;76;73;103;100;101;105;104;74 22:33:53 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;89;90;88;93;102;89;80;104;110;109 22:33:53 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;160;167;186;137;145;148;199;144;244;165 22:33:53 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;180;239;180;173;176;172;226;174;176;175 22:33:53 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;72;81;59;80;92;87;91;79;67;68 22:33:53 INFO - PROCESS | 1673 | __end_tp_report 22:33:53 INFO - PROCESS | 1673 | __start_cc_report 22:33:53 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,958 22:33:53 INFO - PROCESS | 1673 | __end_cc_report 22:33:53 INFO - PROCESS | 1673 | __startTimestamp1478151233174__endTimestamp 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | ------- Summary: start ------- 22:33:53 INFO - PROCESS | 1673 | Number of tests: 14 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:117.60 Median:119.00 stddev:4.58 (3.8%) stddev-sans-first:1.22 22:33:53 INFO - PROCESS | 1673 | Values: 105.0 119.0 120.0 120.0 117.0 119.0 118.0 119.0 121.0 118.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:126.00 stddev:14.70 (11.7%) stddev-sans-first:13.87 22:33:53 INFO - PROCESS | 1673 | Values: 147.0 113.0 121.0 154.0 116.0 131.0 117.0 120.0 142.0 118.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:202.60 Median:200.50 stddev:12.97 (6.5%) stddev-sans-first:13.76 22:33:53 INFO - PROCESS | 1673 | Values: 202.0 216.0 234.0 194.0 197.0 194.0 196.0 202.0 199.0 192.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:72.90 Median:72.50 stddev:15.48 (21.4%) stddev-sans-first:16.07 22:33:53 INFO - PROCESS | 1673 | Values: 82.0 57.0 60.0 83.0 57.0 66.0 79.0 85.0 102.0 58.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.50 stddev:9.08 (11.6%) stddev-sans-first:8.04 22:33:53 INFO - PROCESS | 1673 | Values: 92.0 73.0 86.0 72.0 70.0 70.0 84.0 70.0 71.0 90.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:91.00 stddev:2.31 (2.5%) stddev-sans-first:2.03 22:33:53 INFO - PROCESS | 1673 | Values: 94.0 89.0 91.0 88.0 93.0 88.0 92.0 90.0 91.0 87.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:97.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 22:33:53 INFO - PROCESS | 1673 | Values: 96.0 97.0 96.0 98.0 97.0 96.0 97.0 97.0 97.0 95.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:90.00 stddev:2.60 (2.9%) stddev-sans-first:2.76 22:33:53 INFO - PROCESS | 1673 | Values: 89.0 94.0 86.0 87.0 88.0 88.0 91.0 91.0 91.0 86.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:59.00 stddev:2.50 (4.2%) stddev-sans-first:2.29 22:33:53 INFO - PROCESS | 1673 | Values: 54.0 54.0 57.0 59.0 57.0 59.0 62.0 59.0 56.0 59.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:88.70 Median:72.00 stddev:14.89 (20.7%) stddev-sans-first:14.35 22:33:53 INFO - PROCESS | 1673 | Values: 71.0 80.0 76.0 73.0 103.0 100.0 101.0 105.0 104.0 74.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:88.50 stddev:10.13 (11.5%) stddev-sans-first:10.48 22:33:53 INFO - PROCESS | 1673 | Values: 89.0 90.0 88.0 93.0 102.0 89.0 80.0 104.0 110.0 109.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:169.50 Median:166.00 stddev:32.62 (19.7%) stddev-sans-first:34.42 22:33:53 INFO - PROCESS | 1673 | Values: 160.0 167.0 186.0 137.0 145.0 148.0 199.0 144.0 244.0 165.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:178.00 stddev:24.26 (13.6%) stddev-sans-first:25.60 22:33:53 INFO - PROCESS | 1673 | Values: 180.0 239.0 180.0 173.0 176.0 172.0 226.0 174.0 176.0 175.0 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.60 Median:80.50 stddev:10.94 (13.6%) stddev-sans-first:11.41 22:33:53 INFO - PROCESS | 1673 | Values: 72.0 81.0 59.0 80.0 92.0 87.0 91.0 79.0 67.0 68.0 22:33:53 INFO - PROCESS | 1673 | -------- Summary: end -------- 22:33:53 INFO - PROCESS | 1673 | 22:33:53 INFO - TEST-INFO | 1673: exit 0 22:33:54 INFO - TEST-OK | kraken | took 60686ms 22:33:54 INFO - SUITE-END | took 1177s 22:33:54 INFO - Completed test suite (00:19:37) 22:33:54 INFO - javascript benchmark 22:33:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7055.20540097627, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.967390326099, 3422.3155368926223, 3431.759776815623, 3442.191085095003, 3484.529588505551, 4572.862814115573, 5743.483086522212, 5745.224394706714, 5764.971175144129, 5815.883682326351, 5693.3452652944925, 5742.741576629051, 5750.511206547438, 5803.854903627412, 5835.970820145893, 5205.765740541685, 5242.161254199329, 5306.151015837467, 5339.5728341732565, 5353.839384818461, 5254.89647174092, 5306.230596563496, 5310.256564081032, 5379.38137114231, 5400.985241205684, 5169.30214421054, 5198.142306519401, 5201.5838732901275, 5271.446498117681, 5296.443873393316, 5193.558547523465, 5217.295665085203, 5235.65968212065, 5246.291750613695, 5247.580193584504, 5250.291210686585, 5290.709011004393, 5305.098133317345, 5319.946800531984, 5320.654157479751, 5143.0, 5694.6867922264255, 5752.194692743019, 5772.653640781525, 5785.652860828363, 5056.494350564914, 6206.0, 6248.78129265478, 6312.274088479791, 6319.8736025279695, 6122.367660829195, 6261.8747625047245, 6268.686565671698, 6298.0, 6302.716377762966, 5827.155062515912, 5900.557458190653, 5917.615355001911, 5936.7328470218945, 5986.580939334205, 6257.101885069798, 6296.275928268216, 6299.0, 6323.747050117944, 6329.873402531924, 6126.387361263838, 6222.782202622977, 6226.782062627786, 6245.75016999324, 6248.718807653667, 5241.554467870197, 5993.340732519419, 6109.450149486567, 6163.260408750979, 6171.84570385735, 4770.332153498513, 5649.180868774005, 6123.953766440777, 6139.324674285825, 6139.921477737097, 6097.25246653012, 6161.876762464725, 6171.259448866075, 6171.814845554641, 6177.2587289525545, 4570.223062079454, 4857.878553036132, 4921.114199444134, 4937.580305673986, 5003.699778013331, 3932.9016774580864, 4417.823287068545, 4452.821887124479, 4453.332000199931, 4508.954910450919, 4992.026554821848, 6033.909491357633, 6035.275766907911, 6036.305824830113, 6044.727987240584, 5235.65968212065, 6200.751969921243, 6266.060090986297, 6269.0, 6269.247690277105, 5900.734466948998, 5934.762609495659, 5971.313298970674, 6027.969860150672, 6075.0, 5469.671819690751, 5486.478784515464, 5490.835274941758, 5492.258545096441, 5492.533134683516, 6218.689065546614, 6457.257415397289, 6457.709403076967, 6481.0926470295135, 6485.740570377132, 6137.193127861533, 6141.670122563672, 6163.722632481459, 6166.352532984151, 6227.878981783141, 6425.5502114851515, 6440.871182576416, 6450.612963222222, 6455.515836312202, 6510.804675859732, 6143.754249830135, 6229.626222426669, 6232.127502149612, 6234.096056071753, 6239.844003899939, 6133.916185115316, 6149.44654981036, 6162.352952939877, 6176.227971503562, 6238.906416403848, 6171.660558669317, 6181.629102253879, 6182.536309776695, 6193.969030154821, 6228.128062071223, 6153.507719382528, 6175.536834737323, 6188.257409110935, 6193.81418557444, 6202.472789812917, 4620.838270660645, 5879.323877754113, 5946.43508866657, 5971.731272092853, 5999.820005399845, 4441.666874984325, 5677.602567820213, 6010.7295171716505, 6116.974953843861, 6202.906956395567, 5806.129080637938, 6060.090986352083, 6067.423594758491, 6067.969660151847, 6075.848103797263], "value": 5688.068144465205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.3986038080685, 488.65549787399885, 490.791413649199, 504.60388594952946, 523.6543881038515, 37504.06239844005, 40790.38814417786, 40900.59099409005, 40936.59063409365, 40971.79514102429, 41307.0, 41514.0, 41767.58232417675, 41801.372979405336, 41835.0, 9473.526323683822, 9593.856092158623, 9606.855897161548, 9612.567434465465, 9625.37435066722, 8239.629216685233, 9480.099390557885, 9571.473568953705, 9576.281778866578, 9587.712368628952, 9593.520323983774, 9603.615855365812, 9628.22974162069, 9641.614335426575, 9678.951605241964, 9543.713688589354, 9570.330076894621, 9575.042495750438, 9592.424454532751, 9615.615375384976, 9430.622775088988, 9490.762730931712, 9507.57215925285, 9529.428234305964, 9532.094451027142, 39949.0, 40103.39844902329, 40178.39732404016, 40205.0, 40205.994850128685, 2973.3310005248863, 2975.95841455491, 3331.783434076777, 3394.4229480988292, 3663.9633603663888, 25639.615405768927, 29552.0, 30200.54699179514, 30745.692543074507, 31147.0, 3168.9683103168904, 3171.762117841171, 3193.7285330746913, 3227.4836026235926, 3234.1429521176906, 507141.0, 510660.0, 511401.0, 514919.0, 516078.0, 59754.80490390255, 59847.700761495915, 60279.397206028254, 60285.09572356418, 60421.395786042456, 61483.77032459326, 61705.382946169964, 61793.0, 62162.37837621566, 62276.688616556625, 15806.762898556532, 16046.518604441886, 16046.67906641884, 16079.43721969749, 16123.435679751152, 12436.937815310866, 12440.253584784941, 12446.751064978649, 12477.251364918135, 12508.937455312665, 17685.381011664787, 18360.357387491375, 18365.265389384276, 18390.17244224013, 18433.354832581063, 3128.687131286853, 6112.51099912006, 7631.7328893489575, 7651.579163145969, 7761.340286075747, 840.9369297302727, 898.5808996767232, 1041.375174895057, 1042.4319440859415, 1082.610260306289, 29683.851580741957, 29768.851155744516, 30010.099697009126, 30381.69618303833, 31117.066488005396, 969.2585172343163, 1024.2522958240377, 1031.0, 1049.21833234241, 1134.2003887259461, 14970.0, 14989.325480353411, 14993.850061499463, 15329.616759580877, 15367.692646147014, 443461.0, 443987.0, 444903.77548112697, 447464.0, 598650.0, 53929.73035134878, 54285.45714542804, 54312.72843635757, 54353.91292174134, 54395.45604543983, 1296.092735085444, 1334.8665133486575, 1344.4286178374152, 1362.9931850340884, 1374.1960952842699, 27860.860695696392, 28729.42541149207, 29155.85422072876, 29260.56109158407, 29331.560026599192, 37008.44487332746, 49181.0, 49203.26195107148, 49260.26109608287, 50547.48357549279, 596121.0, 596496.0, 597128.0, 598774.0, 600616.0, 25281.49437011286, 25661.871690641427, 25664.8716756415, 25681.22956311314, 25686.743132569183, 32094.67905320917, 35966.74116405913, 37546.0, 43892.341614875164, 43981.0, 27830.0, 34589.65410345864, 36593.81703091467, 36952.0, 37430.877073687836, 30686.0, 36399.27201456009, 36561.90314290576, 37147.44278835766, 37520.62479375171, 35580.82209588936, 35932.820335899196, 36568.0, 36671.08322291964, 36755.44866826946, 26217.344566385993, 31298.0, 31444.0, 32742.017739467854, 32803.835980819946, 20162.19351225993, 23778.762212377656, 23995.880020599787, 24071.879640601685, 24495.877520612285], "value": 19176.134160674872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.9092835862676, 359.32984983006685, 364.06253896217237, 376.68923138410815, 387.79396078196805, 18388.356407525724, 18804.059797010144, 19681.114349854255, 20073.397798066064, 20301.0, 19672.409827705193, 19924.0, 20000.699989500168, 20148.69776953343, 20231.797682023174, 5788.507976821975, 5828.125781132832, 6085.269767627891, 6111.419415155553, 6112.724927378279, 5872.941270587304, 5986.760529578812, 6066.240790170845, 6070.848228794272, 6085.756569737206, 4687.7656117194, 5691.630044047144, 6018.247719035121, 6029.246344206974, 6047.818565443022, 5912.467877890989, 5989.940100598982, 6006.279246490405, 6008.399160084, 6013.0, 4996.675216110977, 5126.769295381717, 5128.384593848725, 5181.989512045153, 5204.661696989721, 23330.416739581477, 23489.765102348927, 24585.631215531783, 25194.49611007788, 25413.74586254151, 1782.08222765276, 1946.1826033066145, 2154.1706443019393, 2275.84069115162, 2285.6914316567218, 9838.606455741834, 9862.281664539396, 9927.255455840768, 10126.341787783771, 10366.06705396518, 1844.4743248174207, 1899.4396652987402, 1904.3810761502566, 1936.4674714453497, 1977.4660841572695, 126740.0, 131240.34379828136, 132493.33753331268, 133649.0, 135935.28129437554, 11250.0, 11346.48940797666, 11511.884881151082, 11571.305721656727, 11615.186936914335, 14401.567952961428, 14455.638609034859, 14455.927720361331, 14456.204908729911, 14484.782728259084, 6948.756793512202, 8197.34421246299, 8388.958055209685, 8415.621297041647, 8416.495010299279, 7051.682674279669, 7403.814904627319, 8188.631511581993, 8355.582220889053, 8361.745738139207, 3615.367310720613, 3729.738918275749, 4577.443669152504, 4622.46841613212, 4633.142868569325, 1611.7582362645462, 1869.8504119670395, 1913.6938089905548, 1916.338863092231, 1919.6256729937807, 799.6481548118926, 935.4247138010074, 960.7886265021684, 961.4711908450324, 1001.8296889528798, 20454.897725511575, 21326.78673213248, 21335.088322838074, 21526.461838454165, 21556.922153892243, 892.3619611977338, 909.7589138878203, 955.7658373698488, 959.5921733263335, 973.5494113770573, 9207.12532309443, 9407.388519746195, 10170.033846784543, 11090.500927458284, 11340.262882912584, 563656.1817190888, 571747.0, 574078.0, 579316.0, 583331.0, 107495.0, 110589.0, 123200.0, 133028.0, 138707.6129238715, 928.3084102343797, 978.1538968792086, 1017.6133069433716, 1023.948802559869, 1025.3437799808128, 19060.904695476434, 19185.424437266905, 19239.711404328667, 19393.4181974541, 19501.0, 50772.73068173325, 51729.15489648106, 55759.0, 57539.0, 57886.89583458404, 65207.67396162989, 83282.75075873746, 85094.0, 85187.5740621293, 86828.13171868201, 6423.582467139621, 7760.883586746316, 7993.920060799318, 8117.269445749675, 8192.508449493049, 9637.325387223107, 11550.249233799776, 12165.087618428477, 12244.69388265265, 13058.477660893457, 9601.855972160283, 10160.542775575264, 10965.671029869116, 11125.276857004268, 11384.658460246206, 10946.781064378509, 11001.22991390595, 11087.833682494607, 11164.385958772345, 11278.0, 9217.861732074158, 11128.38793866345, 11306.152038596974, 11415.315081095161, 11646.592369267035, 7913.604319784103, 7946.165652606393, 8074.152214017444, 8148.429609927485, 8363.163683631588, 8194.467359621605, 9794.951025244829, 10026.799464010533, 11075.335479871233, 11172.608958686407], "value": 9390.694357038012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.304847149133, 1486.0637798187145, 1633.877459190561, 1709.2479309103985, 1750.0812073661318, 7626.580538070404, 7868.213178682129, 8157.30662893654, 8572.785680357987, 8615.353848461367, 8030.317423019051, 8326.125756795525, 8370.246677798998, 8451.83096338073, 8469.91530084699, 2256.8758718270487, 2277.7722227777253, 2285.7828506291876, 2300.608896487601, 2303.0442366417915, 2272.216085450517, 2310.8960096795686, 2340.707411573553, 2344.3318654183563, 2350.647402889563, 2273.54529094181, 2278.9289034154003, 2293.266154830455, 2301.735300440446, 2305.227748704186, 1925.8266755991958, 2063.772984971652, 2217.079911836586, 2281.7718228177214, 2291.5531471363092, 2287.645414960684, 2312.236961802601, 2312.814974802012, 2318.7565305643, 2327.150590034642, 7113.430925525946, 8064.879026814602, 8399.664013439455, 8455.154484551556, 8490.193431623986, 5032.0, 5152.0, 5185.196294574347, 5186.299849520342, 5205.791768329263, 2953.8966136185345, 3052.5879006334303, 3269.8365081746056, 3536.1159710072484, 3562.305350456662, 886.264400547544, 1007.7430255284855, 1042.593388578455, 1059.3485006720812, 1060.8355704865758, 10237.283390162691, 10701.732456688567, 10990.890091099067, 11014.944925275402, 11033.779324413546, 3694.7783133012103, 4292.613664770186, 4356.237658409766, 4389.934150987738, 4392.758398288125, 7150.213476517579, 8179.918200818035, 8697.739067827975, 8706.347023973227, 8707.738767836974, 4795.592374648194, 5010.073136469765, 5016.749162541858, 5024.371953505812, 5034.798608055637, 4210.936835947463, 4693.319468677023, 4739.454962679336, 4757.26262429324, 4778.593819525379, 3547.52108465359, 3595.7123430125525, 3626.8730594429067, 3656.3601369760186, 3703.111253299243, 1103.0954617213968, 1118.9608363707393, 1121.254365846719, 1127.4644543841773, 1128.7234627516148, 843.3169133002287, 855.9957200214084, 878.4202426398547, 895.9741096444535, 914.8399030169694, 4671.392718946583, 5190.92213616796, 5224.98837947393, 5755.798547050833, 5975.671338076448, 756.587659725451, 839.9202075802789, 891.7057371067532, 918.8652014761764, 944.7449188719007, 2878.7265209805037, 3703.2593481303848, 3748.081719978623, 3758.642928921748, 3856.652901238902, 8643.654253829776, 9820.45987470696, 10126.291159618904, 10198.235132365102, 10218.131458825934, 10152.390856548483, 10156.187504999582, 10178.541965611565, 10194.949025254975, 10197.74505637365, 7472.850542989218, 7867.0, 7869.3311068559815, 7982.042154941328, 8010.9585753851625, 5484.917726234189, 5573.080441727069, 5598.468145526169, 5705.62913410627, 5744.856378590569, 8849.557522123996, 9239.676611318571, 9385.296102792181, 9446.008169142142, 9482.668106616235, 9447.858282125912, 9480.478573678238, 9568.61725530971, 9610.327277090817, 9636.46999415039, 6663.36698013688, 6754.18949726036, 7217.6752046157, 7223.819404514929, 7369.18938916708, 7916.287534121957, 8075.798105047421, 8079.313258373104, 8093.635786389509, 8098.797530061795, 7033.156021277479, 7165.176004759519, 7166.498345115792, 7229.313215244543, 7309.0, 6920.273371295942, 7012.509124361246, 7023.122109736283, 7098.893516597358, 7122.430205583644, 6646.335366463509, 6672.466202703869, 6729.259781423942, 6730.25967143604, 6731.562448440835, 4757.9048419032115, 4798.328234047166, 4827.493113223201, 4870.610351171968, 4905.754712264301, 4716.622670186308, 4836.903261934812, 4838.0082083172165, 4891.0, 4906.994066216345], "value": 4332.295388889721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.96220566914963, 268.9421774318521, 288.5613866922279, 303.8814862203741, 303.89971309467876, 4554.339620754997, 5308.5487733542695, 5520.972395138024, 5561.249231353771, 5584.832455026346, 5123.180291153416, 5275.894482110355, 5463.825277565323, 5518.0, 5562.85829955467, 4172.082141928786, 4191.49106321724, 4204.5585213552595, 4204.810783514734, 4233.851815186469, 3745.644163804448, 3884.8057597120173, 4151.83392664293, 4166.166766646667, 4215.346621273692, 3281.506914353963, 3736.0473079364724, 4120.896977575555, 4123.7938103094875, 4127.504699436072, 4102.938455923164, 4114.403411505345, 4115.341545352744, 4134.4831896013, 4142.975627791729, 2939.867705953216, 3400.795952242873, 3493.580770307554, 3509.666326795827, 3547.343741407848, 4993.475685053054, 5328.284468245631, 5344.545713614348, 5373.328333958255, 5419.783208671648, 4107.691923105779, 4293.806778694966, 4675.228587283094, 4716.52834716529, 4721.315409265637, 5514.970059880239, 5659.207710920475, 5682.801101961453, 5760.36635970043, 5798.536117110621, 3118.469860123784, 3555.822208889545, 3796.9368576798543, 3857.517810273716, 3880.6715970417804, 4930.383702037246, 6086.969565152191, 6101.328853826076, 6103.816885493442, 6121.663308517986, 3666.5416822897137, 3802.6197380262306, 3878.3600705883778, 3904.8090332448874, 3933.842646294116, 3927.56796752357, 4002.1984610772574, 4007.519097708235, 4009.1134383244016, 4009.639132478091, 4494.73031618104, 4693.263492507794, 4694.7417892016265, 4728.952710472851, 4732.935089604812, 5376.231240910934, 5827.271591051119, 5853.765849365977, 5915.668974480707, 5934.6142500737315, 1106.9723256918642, 1283.58164228528, 1311.7179806341683, 1316.3615646411483, 1347.5351003903638, 887.2192470625808, 889.9009722992229, 902.702108304258, 913.9040400757958, 919.3197034194648, 4802.855914322576, 4899.412070551557, 5403.918941215885, 5426.0, 5485.341758988947, 4681.180793361148, 4875.463698993108, 5450.509454149145, 5922.763089476459, 5933.6352638893295, 4343.869683909487, 5218.608604354688, 5473.808416705476, 5478.178273259043, 5514.3880758103505, 2401.4716762312264, 2402.5824763389683, 2630.921072367832, 2632.9605055924176, 2663.721413721386, 4200.726952748061, 4829.975850120727, 4856.697445736862, 5216.835013469444, 5262.289590905179, 4377.038779904192, 4749.955009897817, 5025.170846810309, 5099.4390617032095, 5128.640995130379, 4376.227627810742, 4846.151923413459, 5205.479452054764, 5207.401148867929, 5245.449227831175, 4845.3767987724505, 4948.901021979468, 4966.304717339503, 4989.176785830297, 5049.8790507986, 4549.522300158435, 4744.0, 4919.532644398776, 4935.753212339297, 4979.900401992012, 4522.4573051234065, 5180.300659410931, 5195.090859099718, 5238.816641417532, 5243.3072400200845, 3397.779144355609, 3407.710344620735, 3414.407621748744, 3432.931341373109, 3439.1917899294144, 3983.1635356575443, 4147.942274719927, 4772.308015337826, 4787.428006253949, 5106.263870284121, 4093.09390332867, 4238.491381034296, 4327.805248763751, 4397.274449715761, 4875.926861097016, 4384.838017925316, 4856.465788763304, 4865.70805751656, 4882.802738767497, 4891.046245982142, 4096.651784598343, 4168.854090106832, 4429.756363400044, 4720.480747117883, 4723.763811809465, 4125.876223713293, 4504.481984571685, 4514.262009126444, 4668.125455871686, 4792.568668819823, 4761.547652972962, 4768.879313361426, 4770.785314660918, 4773.3555969945, 4773.687236009986], "value": 3887.9921173573052, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.474735464274, 674.4873895839162, 717.5479447947794, 740.5889731199183, 784.901887264092, 14240.359183836727, 15072.171030593314, 15081.547553573382, 15106.924465377686, 15140.697186056286, 13569.253691046988, 13710.725785484285, 13711.0, 13711.45154193831, 13747.175169489838, 650.8047585724288, 657.0702456024743, 678.3081257117736, 723.9565626062426, 798.8282519258358, 651.4820717529575, 830.2361827119034, 832.7210384521193, 834.6452757578005, 834.7537476444434, 764.7629234937187, 871.0592560035146, 872.4372779557182, 873.5937788928147, 874.6894852327428, 865.3164000439646, 870.5821205821178, 872.3065163195212, 873.3973558244822, 873.6636394987912, 641.0, 650.8893488107033, 651.641597121586, 657.9309172536863, 664.5381459885382, 13911.304434778322, 14007.159570425705, 14293.0, 14305.928470357685, 14744.0, 10873.83689244662, 13762.0, 13903.053572499859, 14140.363683634261, 14188.21964791936, 11228.831567526493, 11267.549298028069, 11312.3212607243, 11389.16705499761, 11402.543898244061, 3691.9261614767825, 3728.422094575345, 3732.9066773330615, 3752.5496940367057, 3867.690584753241, 85183.0, 86379.13620863773, 87084.56457717734, 87134.0, 87722.56138719329, 10645.6939685309, 10656.413897235727, 10718.839217411745, 10772.89227107719, 11062.944685276521, 20743.688844667344, 20903.74577525323, 20951.58096838085, 20960.895195523924, 20992.580148397254, 7647.464677472523, 7683.769486915401, 7734.303912647776, 7752.496087754278, 7825.13923468418, 4057.127717540743, 4384.395398516033, 4384.693071484965, 4431.0251744616135, 4525.547445255448, 3386.7290616751093, 3498.650134986532, 3499.0902365384673, 3502.5972013218297, 3506.1935754776546, 617.5769597825504, 624.7879114517821, 634.429013887505, 641.1952998986243, 648.2124219073754, 546.3744013105052, 583.2272239282967, 628.3025841316136, 641.4451499453033, 643.330935826735, 8725.171108744658, 9020.548972551345, 9044.638214471348, 9088.727338179866, 9201.171894529525, 579.0995002770732, 608.8995315772847, 615.879903418838, 671.5433505216499, 672.9394354508117, 3473.930521389558, 3574.946375804365, 3894.824732887027, 4069.2878746219885, 4361.672874534359, 65082.67458662677, 70268.0, 91152.54423727971, 109814.0, 114379.0, 64971.0, 66118.0, 66121.33878661152, 77211.61394192993, 102165.48917255366, 71313.57372852514, 90188.09811901797, 91096.0, 91388.71528211847, 91509.0, 6726.428253598303, 7625.23747625233, 7986.0, 7993.1607181247455, 8538.274246689101, 38270.0, 49869.00261994812, 57195.42804572068, 58974.705126474095, 59927.70036149791, 52766.73616632046, 72929.0, 76170.85743713738, 77316.4536709274, 77329.22670773446, 12001.699957501132, 12164.391780410768, 12272.325022123869, 12431.813522797345, 12733.87266127364, 13784.241866697428, 14430.711385772016, 14452.0, 14512.20182889909, 14854.405823766929, 13020.47918083266, 13249.735005299648, 13307.733845323233, 13823.308834558433, 13891.166530008231, 12973.740525189633, 13267.66830829237, 13269.0, 13369.197848129143, 13410.731785364433, 12879.034072444416, 12923.0, 12995.52757164653, 13090.476380944656, 13331.941897620163, 10849.891501084889, 10905.618303359344, 11226.66320010401, 11506.424678766196, 11558.017568506772, 10983.780324393307, 11081.667549973514, 11160.44197790091, 11290.661280161608, 11320.377379243891], "value": 7147.306002825761, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 120.0, 120.0, 117.0, 119.0, 118.0, 119.0, 121.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 121.0, 154.0, 116.0, 131.0, 117.0, 120.0, 142.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 216.0, 234.0, 194.0, 197.0, 194.0, 196.0, 202.0, 199.0, 192.0], "value": 202.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 60.0, 83.0, 57.0, 66.0, 79.0, 85.0, 102.0, 58.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 86.0, 72.0, 70.0, 70.0, 84.0, 70.0, 71.0, 90.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 91.0, 88.0, 93.0, 88.0, 92.0, 90.0, 91.0, 87.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 98.0, 97.0, 96.0, 97.0, 97.0, 97.0, 95.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 86.0, 87.0, 88.0, 88.0, 91.0, 91.0, 91.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 59.0, 57.0, 59.0, 62.0, 59.0, 56.0, 59.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 76.0, 73.0, 103.0, 100.0, 101.0, 105.0, 104.0, 74.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 93.0, 102.0, 89.0, 80.0, 104.0, 110.0, 109.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 167.0, 186.0, 137.0, 145.0, 148.0, 199.0, 144.0, 244.0, 165.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 239.0, 180.0, 173.0, 176.0, 172.0, 226.0, 174.0, 176.0, 175.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 59.0, 80.0, 92.0, 87.0, 91.0, 79.0, 67.0, 68.0], "value": 77.6, "unit": "score"}]}]} 22:33:54 INFO - Return code: 0 22:33:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:33:54 ERROR - # TBPL SUCCESS # 22:33:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:33:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:33:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:33:54 INFO - 2016-11-02 22:33:54.519 screenresolution[1678:11575] starting screenresolution argv=screenresolution get 22:33:54 INFO - 2016-11-02 22:33:54.522 screenresolution[1678:11575] Display 0: 1600x1200x32@60 22:33:54 INFO - 2016-11-02 22:33:54.528 screenresolution[1679:11578] starting screenresolution argv=screenresolution list 22:33:54 INFO - Available Modes on Display 0 22:33:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:33:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:33:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:33:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:33:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:33:54 INFO - Intel Iris: 22:33:54 INFO - Chipset Model: Intel Iris 22:33:54 INFO - Type: GPU 22:33:54 INFO - Bus: Built-In 22:33:54 INFO - VRAM (Dynamic, Max): 1536 MB 22:33:54 INFO - Vendor: Intel (0x8086) 22:33:54 INFO - Device ID: 0x0a2e 22:33:54 INFO - Revision ID: 0x0009 22:33:54 INFO - Displays: 22:33:54 INFO - Display: 22:33:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:33:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:33:54 INFO - Display Serial Number: 1600x1200 60 22:33:54 INFO - Main Display: Yes 22:33:54 INFO - Mirror: Off 22:33:54 INFO - Online: Yes 22:33:54 INFO - Rotation: Supported 22:33:54 INFO - Return code: 0 22:33:54 INFO - Running post-action listener: _resource_record_post_action 22:33:54 INFO - [mozharness: 2016-11-03 05:33:54.766188Z] Finished run-tests step (success) 22:33:54 INFO - Running post-run listener: _resource_record_post_run 22:33:54 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 33280512; Write bytes: 713778176; Read time: 203; Write time: 2051 22:33:54 INFO - TinderboxPrint: CPU usage
54.8% 22:33:54 INFO - TinderboxPrint: I/O read bytes / time
33,280,512 / 203 22:33:54 INFO - TinderboxPrint: I/O write bytes / time
713,778,176 / 2,051 22:33:54 INFO - TinderboxPrint: CPU idle
2,196.2 (45.2%) 22:33:54 INFO - TinderboxPrint: CPU system
982.4 (20.2%) 22:33:54 INFO - TinderboxPrint: CPU user
1,675.0 (34.5%) 22:33:54 INFO - TinderboxPrint: Swap in / out
436,092,928 / 0 22:33:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407048704; Write bytes: 410828800; Read time: 30799; Write time: 893 22:33:54 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 30163456; Write bytes: 301626368; Read time: 181; Write time: 1152 22:33:55 INFO - Running post-run listener: _upload_blobber_files 22:33:55 INFO - Blob upload gear active. 22:33:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:33:55 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 22:33:55 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'] 22:33:55 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 22:33:55 INFO - (blobuploader) - INFO - Open directory for files ... 22:33:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:33:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:33:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:58 INFO - (blobuploader) - INFO - Done attempting. 22:33:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:33:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:33:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:59 INFO - (blobuploader) - INFO - Done attempting. 22:33:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:33:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:34:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:01 INFO - (blobuploader) - INFO - Done attempting. 22:34:01 INFO - (blobuploader) - INFO - Iteration through files over. 22:34:01 INFO - Return code: 0 22:34:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:34:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:34:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d127971bf8c66a3ab081740bd2c41775c45c05686d33c37c5a185df8ffe3849cb9ce9b7b5ad8020d979e07c935b8c3f1b99e23d621e735eebd0b5241b24f41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19c39ddd0f8371dd3a55977e6a5f89b8410210eca596f2f3e058d3b4100b3ad2b34e00d37b599381ef765ab10a67d714e61dc4fffaebe6dadd5641d218ccf13a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d45603513b784b492cb1e352c787b6015341560ce66c7b5ae84cc4fe5328b0beec30bff6cf01e5c19d604ba403f7169039efaa53f8a9e115370a9a502ab223cc"} 22:34:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:34:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:34:01 INFO - Contents: 22:34:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d127971bf8c66a3ab081740bd2c41775c45c05686d33c37c5a185df8ffe3849cb9ce9b7b5ad8020d979e07c935b8c3f1b99e23d621e735eebd0b5241b24f41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19c39ddd0f8371dd3a55977e6a5f89b8410210eca596f2f3e058d3b4100b3ad2b34e00d37b599381ef765ab10a67d714e61dc4fffaebe6dadd5641d218ccf13a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d45603513b784b492cb1e352c787b6015341560ce66c7b5ae84cc4fe5328b0beec30bff6cf01e5c19d604ba403f7169039efaa53f8a9e115370a9a502ab223cc"} 22:34:01 INFO - Running post-run listener: copy_logs_to_upload_dir 22:34:01 INFO - Copying logs to upload dir... 22:34:01 INFO - mkdir: /builds/slave/test/build/upload/logs 22:34:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.288912 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-02 22:34:01.389322) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 22:34:01.392449) ========= 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.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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/27d127971bf8c66a3ab081740bd2c41775c45c05686d33c37c5a185df8ffe3849cb9ce9b7b5ad8020d979e07c935b8c3f1b99e23d621e735eebd0b5241b24f41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19c39ddd0f8371dd3a55977e6a5f89b8410210eca596f2f3e058d3b4100b3ad2b34e00d37b599381ef765ab10a67d714e61dc4fffaebe6dadd5641d218ccf13a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d45603513b784b492cb1e352c787b6015341560ce66c7b5ae84cc4fe5328b0beec30bff6cf01e5c19d604ba403f7169039efaa53f8a9e115370a9a502ab223cc"} build_url:https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/Zg8lusD6RdKk-mSbzKxl2g/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/27d127971bf8c66a3ab081740bd2c41775c45c05686d33c37c5a185df8ffe3849cb9ce9b7b5ad8020d979e07c935b8c3f1b99e23d621e735eebd0b5241b24f41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19c39ddd0f8371dd3a55977e6a5f89b8410210eca596f2f3e058d3b4100b3ad2b34e00d37b599381ef765ab10a67d714e61dc4fffaebe6dadd5641d218ccf13a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d45603513b784b492cb1e352c787b6015341560ce66c7b5ae84cc4fe5328b0beec30bff6cf01e5c19d604ba403f7169039efaa53f8a9e115370a9a502ab223cc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 22:34:01.425285) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:34:01.425635) ========= 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.UARVv9YDBA/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.M3ywHjJ5az/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005222 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 22:34:01.475242) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 22:34:01.475722) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 22:34:01.476391) ========= ========= Total master_lag: 0.17 =========