builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1480509527.95 results: success (0) buildid: 20161130023343 builduid: 604c678b62ac4541a7646df18445f8b0 revision: 13736e2db6eb94b02dd28cc88f2943b8109aa374 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:47.946927) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:47.947619) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:47.947923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:48.020621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:48.021036) ========= 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.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-30 04:38:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-11-30 04:38:48 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193511 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:48.254400) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:48.254904) ========= 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.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.026216 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:48.308227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 04:38:48.308534) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13736e2db6eb94b02dd28cc88f2943b8109aa374 --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 13736e2db6eb94b02dd28cc88f2943b8109aa374 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-30 04:38:48,404 truncating revision to first 12 chars 2016-11-30 04:38:48,404 Setting DEBUG logging. 2016-11-30 04:38:48,404 attempt 1/10 2016-11-30 04:38:48,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13736e2db6eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 04:38:49,370 unpacking tar archive at: mozilla-central-13736e2db6eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.214975 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 04:38:49.548208) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:49.548586) ========= script_repo_revision: 13736e2db6eb94b02dd28cc88f2943b8109aa374 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:49.549040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:49.549320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 04:38:49.570034) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-30 04:38:49.570436) ========= /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.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:38:49 INFO - MultiFileLogger online at 20161130 04:38:49 in /builds/slave/test 04:38:49 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 04:38:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:49 INFO - {'append_to_log': False, 04:38:49 INFO - 'base_work_dir': '/builds/slave/test', 04:38:49 INFO - 'blob_upload_branch': 'Firefox', 04:38:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:49 INFO - 'branch': 'Firefox', 04:38:49 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:49 INFO - 'config_files': ('talos/mac_config.py',), 04:38:49 INFO - 'default_actions': ('clobber', 04:38:49 INFO - 'read-buildbot-config', 04:38:49 INFO - 'download-and-extract', 04:38:49 INFO - 'populate-webroot', 04:38:49 INFO - 'create-virtualenv', 04:38:49 INFO - 'install', 04:38:49 INFO - 'run-tests'), 04:38:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:49 INFO - 'download_minidump_stackwalk': True, 04:38:49 INFO - 'download_symbols': 'ondemand', 04:38:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:49 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:49 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:49 INFO - 'installer_path': 'installer.exe', 04:38:49 INFO - 'log_level': 'info', 04:38:49 INFO - 'log_name': 'talos', 04:38:49 INFO - 'log_to_console': True, 04:38:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:38:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:38:49 INFO - 'opt_config_files': (), 04:38:49 INFO - 'pip_index': False, 04:38:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:49 INFO - 'cmd': ('bash', 04:38:49 INFO - '-c', 04:38:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:49 INFO - 'enabled': True, 04:38:49 INFO - 'halt_on_failure': False, 04:38:49 INFO - 'name': 'check_screen_resolution'},), 04:38:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:49 INFO - 'cmd': ('bash', 04:38:49 INFO - '-c', 04:38:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:49 INFO - 'enabled': True, 04:38:49 INFO - 'halt_on_failure': False, 04:38:49 INFO - 'name': 'check_screen_resolution'},), 04:38:49 INFO - 'run_cmd_checks_enabled': True, 04:38:49 INFO - 'sps_profile': False, 04:38:49 INFO - 'sps_profile_interval': 0, 04:38:49 INFO - 'suite': 'dromaeojs-e10s', 04:38:49 INFO - 'system_bits': '32', 04:38:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:49 INFO - 'title': 't-yosemite-r7-0154', 04:38:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:49 INFO - 'use_talos_json': True, 04:38:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:49 INFO - 'work_dir': 'build'} 04:38:49 INFO - [mozharness: 2016-11-30 12:38:49.785116Z] Running clobber step. 04:38:49 INFO - Running pre-action listener: _resource_record_pre_action 04:38:49 INFO - Running main action method: clobber 04:38:49 INFO - rmtree: /builds/slave/test/build 04:38:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:52 INFO - Running post-action listener: _resource_record_post_action 04:38:52 INFO - [mozharness: 2016-11-30 12:38:52.078960Z] Finished clobber step (success) 04:38:52 INFO - [mozharness: 2016-11-30 12:38:52.079159Z] Running read-buildbot-config step. 04:38:52 INFO - Running pre-action listener: _resource_record_pre_action 04:38:52 INFO - Running main action method: read_buildbot_config 04:38:52 INFO - Using buildbot properties: 04:38:52 INFO - { 04:38:52 INFO - "project": "", 04:38:52 INFO - "product": "firefox", 04:38:52 INFO - "script_repo_revision": "production", 04:38:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:38:52 INFO - "repository": "", 04:38:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:38:52 INFO - "buildid": "20161130023343", 04:38:52 INFO - "pgo_build": "False", 04:38:52 INFO - "basedir": "/builds/slave/test", 04:38:52 INFO - "buildnumber": 11, 04:38:52 INFO - "slavename": "t-yosemite-r7-0154", 04:38:52 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:38:52 INFO - "platform": "yosemite_r7", 04:38:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:38:52 INFO - "branch": "mozilla-central", 04:38:52 INFO - "repo_path": "mozilla-central", 04:38:52 INFO - "revision": "13736e2db6eb94b02dd28cc88f2943b8109aa374", 04:38:52 INFO - "stage_platform": "macosx64", 04:38:52 INFO - "builduid": "604c678b62ac4541a7646df18445f8b0", 04:38:52 INFO - "slavebuilddir": "test" 04:38:52 INFO - } 04:38:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:38:52 INFO - Running post-action listener: _resource_record_post_action 04:38:52 INFO - [mozharness: 2016-11-30 12:38:52.082452Z] Finished read-buildbot-config step (success) 04:38:52 INFO - [mozharness: 2016-11-30 12:38:52.082582Z] Running download-and-extract step. 04:38:52 INFO - Running pre-action listener: _resource_record_pre_action 04:38:52 INFO - Running main action method: download_and_extract 04:38:52 INFO - mkdir: /builds/slave/test/build/tests 04:38:52 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')]} 04:38:52 INFO - trying https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:38:52 INFO - Downloading https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:38:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:38:52 INFO - Downloaded 1199 bytes. 04:38:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:38:52 INFO - Using the following test package requirements: 04:38:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:38:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:38:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:38:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'jsshell-mac.zip'], 04:38:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:38:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:38:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:38:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:38:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:38:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:38:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:38:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:38:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:38:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:38:52 INFO - Fetch https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:38:53 INFO - Http code: 200 04:38:53 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 04:38:53 INFO - x-amz-cf-id: TAMocR-Ik7jFrDPlpOB8ik9djl1SIrbBGmB5My-HfjWdHA-8Ek9VSQ== 04:38:53 INFO - x-amz-version-id: vEgSDpSV8CrpcALQS5BwaSWazvX5CZd8 04:38:54 INFO - Expected file size: 16286593 04:38:54 INFO - Obtained file size: 16286593 04:39:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:39:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:39:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:00 INFO - Fetch https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:39:01 INFO - Http code: 200 04:39:01 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 04:39:01 INFO - x-amz-cf-id: lEScxDQJ-CBQRZSaHXQnHu6ttbsaRTbHQl_kS8PMOc2n8iWomMPp5g== 04:39:01 INFO - x-amz-version-id: vEgSDpSV8CrpcALQS5BwaSWazvX5CZd8 04:39:02 INFO - Expected file size: 16286593 04:39:02 INFO - Obtained file size: 16286593 04:39:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:39:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:39:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:39:09 INFO - Http code: 200 04:39:09 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 04:39:09 INFO - x-amz-cf-id: 0wxHNFDIteyWN5-tK6ojkI3fTUPJsDWyjgJFFcTemwuX6aJBrJ8oKg== 04:39:09 INFO - x-amz-version-id: Cb0we7qQF_HplW7Xexz5t1T7dXsUAAmR 04:39:09 INFO - Expected file size: 14409702 04:39:09 INFO - Obtained file size: 14409702 04:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:10 INFO - trying https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:10 INFO - Downloading https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:39:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:39:14 INFO - Downloaded 107586940 bytes. 04:39:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:14 INFO - mkdir: /builds/slave/test/properties 04:39:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:14 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:14 INFO - Contents: 04:39:14 INFO - build_url:https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:15 INFO - Running post-action listener: _resource_record_post_action 04:39:15 INFO - Running post-action listener: set_extra_try_arguments 04:39:15 INFO - [mozharness: 2016-11-30 12:39:15.055460Z] Finished download-and-extract step (success) 04:39:15 INFO - [mozharness: 2016-11-30 12:39:15.055611Z] Running populate-webroot step. 04:39:15 INFO - Running pre-action listener: _resource_record_pre_action 04:39:15 INFO - Running main action method: populate_webroot 04:39:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:15 INFO - '--tppagecycles', 04:39:15 INFO - '7'], 04:39:15 INFO - 'tests': ['tsvgm']}}, 04:39:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['damp', 'tps']}, 04:39:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'tests': ['damp', 'tps']}, 04:39:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['dromaeo_dom']}, 04:39:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['basic_compositor_video']}, 04:39:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:39:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['a11yr', 04:39:15 INFO - 'ts_paint', 04:39:15 INFO - 'tpaint', 04:39:15 INFO - 'sessionrestore', 04:39:15 INFO - 'sessionrestore_no_auto_restore', 04:39:15 INFO - 'tabpaint']}, 04:39:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['tsvgx', 04:39:15 INFO - 'tsvgr_opacity', 04:39:15 INFO - 'tart', 04:39:15 INFO - 'tscrollx', 04:39:15 INFO - 'cart', 04:39:15 INFO - 'tsvg_static']}, 04:39:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:15 INFO - 'tsvgr_opacity', 04:39:15 INFO - 'tart', 04:39:15 INFO - 'tscrollx', 04:39:15 INFO - 'cart', 04:39:15 INFO - 'tsvg_static']}, 04:39:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'talos_options': ['--disable-e10s'], 04:39:15 INFO - 'tests': ['tp5o']}, 04:39:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:39:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'talos_options': ['--disable-e10s', 04:39:15 INFO - '--xperf_path', 04:39:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:15 INFO - 'tests': ['tp5n']}, 04:39:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:39:15 INFO - 'talos_options': ['--xperf_path', 04:39:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:15 INFO - 'tests': ['tp5n']}}, 04:39:15 INFO - 'talos.zip': {'path': '', 04:39:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:15 INFO - Running post-action listener: _resource_record_post_action 04:39:15 INFO - [mozharness: 2016-11-30 12:39:15.073927Z] Finished populate-webroot step (success) 04:39:15 INFO - [mozharness: 2016-11-30 12:39:15.074066Z] Running create-virtualenv step. 04:39:15 INFO - Running pre-action listener: _resource_record_pre_action 04:39:15 INFO - Running main action method: create_virtualenv 04:39:15 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39:15 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 04:39:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:15 INFO - Using real prefix '/tools/python27' 04:39:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:15 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:18 INFO - Installing pip.................done. 04:39:18 INFO - Return code: 0 04:39:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:39:19 INFO - Reading from file tmpfile_stdout 04:39:19 INFO - Output received: 04:39:19 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) 04:39:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:39:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:19 INFO - 'HOME': '/Users/cltbld', 04:39:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:19 INFO - 'LOGNAME': 'cltbld', 04:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:19 INFO - 'MOZ_NO_REMOTE': '1', 04:39:19 INFO - 'NO_EM_RESTART': '1', 04:39:19 INFO - 'PAGER': '/bin/cat', 04:39:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:19 INFO - 'PWD': '/builds/slave/test', 04:39:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:19 INFO - 'SHELL': '/bin/bash', 04:39:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:19 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:19 INFO - 'USER': 'cltbld', 04:39:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:19 INFO - 'XPC_FLAGS': '0x0', 04:39:19 INFO - 'XPC_SERVICE_NAME': '0', 04:39:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:19 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 04:39:19 INFO - Cleaning up... 04:39:19 INFO - Return code: 0 04:39:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:39:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:19 INFO - 'HOME': '/Users/cltbld', 04:39:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:19 INFO - 'LOGNAME': 'cltbld', 04:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:19 INFO - 'MOZ_NO_REMOTE': '1', 04:39:19 INFO - 'NO_EM_RESTART': '1', 04:39:19 INFO - 'PAGER': '/bin/cat', 04:39:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:19 INFO - 'PWD': '/builds/slave/test', 04:39:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:19 INFO - 'SHELL': '/bin/bash', 04:39:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:19 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:19 INFO - 'USER': 'cltbld', 04:39:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:19 INFO - 'XPC_FLAGS': '0x0', 04:39:19 INFO - 'XPC_SERVICE_NAME': '0', 04:39:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:19 INFO - Downloading/unpacking psutil>=3.1.1 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:23 INFO - Installing collected packages: psutil 04:39:23 INFO - Running setup.py install for psutil 04:39:23 INFO - building 'psutil._psutil_osx' extension 04:39:23 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 04:39:24 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 04:39:24 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 04:39:24 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 04:39:24 INFO - building 'psutil._psutil_posix' extension 04:39:24 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 04:39:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:39:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:39:24 INFO - ^ 04:39:24 INFO - 1 warning generated. 04:39:24 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 04:39:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:24 INFO - Successfully installed psutil 04:39:24 INFO - Cleaning up... 04:39:24 INFO - Return code: 0 04:39:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:39: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 mozsystemmonitor==0.3 04:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:24 INFO - 'HOME': '/Users/cltbld', 04:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:24 INFO - 'LOGNAME': 'cltbld', 04:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:24 INFO - 'MOZ_NO_REMOTE': '1', 04:39:24 INFO - 'NO_EM_RESTART': '1', 04:39:24 INFO - 'PAGER': '/bin/cat', 04:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:24 INFO - 'PWD': '/builds/slave/test', 04:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:24 INFO - 'SHELL': '/bin/bash', 04:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:24 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:24 INFO - 'USER': 'cltbld', 04:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:24 INFO - 'XPC_FLAGS': '0x0', 04:39:24 INFO - 'XPC_SERVICE_NAME': '0', 04:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:27 INFO - Installing collected packages: mozsystemmonitor 04:39:27 INFO - Running setup.py install for mozsystemmonitor 04:39:27 INFO - Successfully installed mozsystemmonitor 04:39:27 INFO - Cleaning up... 04:39:27 INFO - Return code: 0 04:39:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:27 INFO - 'HOME': '/Users/cltbld', 04:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:27 INFO - 'LOGNAME': 'cltbld', 04:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:27 INFO - 'MOZ_NO_REMOTE': '1', 04:39:27 INFO - 'NO_EM_RESTART': '1', 04:39:27 INFO - 'PAGER': '/bin/cat', 04:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:27 INFO - 'PWD': '/builds/slave/test', 04:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:27 INFO - 'SHELL': '/bin/bash', 04:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:27 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:27 INFO - 'USER': 'cltbld', 04:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:27 INFO - 'XPC_FLAGS': '0x0', 04:39:27 INFO - 'XPC_SERVICE_NAME': '0', 04:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:28 INFO - Downloading/unpacking jsonschema==2.5.1 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:31 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:31 INFO - warning: no files found matching '*.txt' 04:39:31 INFO - no previously-included directories found matching 'build' 04:39:31 INFO - no previously-included directories found matching 'dist' 04:39:31 INFO - no previously-included directories found matching '.git*' 04:39:31 INFO - Installing collected packages: jsonschema, functools32 04:39:31 INFO - Running setup.py install for functools32 04:39:31 INFO - warning: no files found matching '*.txt' 04:39:31 INFO - no previously-included directories found matching 'build' 04:39:31 INFO - no previously-included directories found matching 'dist' 04:39:31 INFO - no previously-included directories found matching '.git*' 04:39:31 INFO - Successfully installed jsonschema functools32 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:39:31 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')]} 04:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:31 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')]} 04:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:31 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:31 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 04:39:31 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 04:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:31 INFO - 'HOME': '/Users/cltbld', 04:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:31 INFO - 'LOGNAME': 'cltbld', 04:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:31 INFO - 'MOZ_NO_REMOTE': '1', 04:39:31 INFO - 'NO_EM_RESTART': '1', 04:39:31 INFO - 'PAGER': '/bin/cat', 04:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:31 INFO - 'PWD': '/builds/slave/test', 04:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:31 INFO - 'SHELL': '/bin/bash', 04:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:31 INFO - 'USER': 'cltbld', 04:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:31 INFO - 'XPC_FLAGS': '0x0', 04:39:31 INFO - 'XPC_SERVICE_NAME': '0', 04:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:31 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')]} 04:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:31 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')]} 04:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:31 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:31 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 04:39:31 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 04:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:31 INFO - 'HOME': '/Users/cltbld', 04:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:31 INFO - 'LOGNAME': 'cltbld', 04:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:31 INFO - 'MOZ_NO_REMOTE': '1', 04:39:31 INFO - 'NO_EM_RESTART': '1', 04:39:31 INFO - 'PAGER': '/bin/cat', 04:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:31 INFO - 'PWD': '/builds/slave/test', 04:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:31 INFO - 'SHELL': '/bin/bash', 04:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:31 INFO - 'USER': 'cltbld', 04:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:31 INFO - 'XPC_FLAGS': '0x0', 04:39:31 INFO - 'XPC_SERVICE_NAME': '0', 04:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:32 INFO - Downloading/unpacking blobuploader==1.2.4 04:39:32 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 04:39:32 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 04:39:32 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 04:39:32 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 04:39:32 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 04:39:32 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 04:39:34 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:34 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 04:39:34 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 04:39:34 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 04:39:34 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 04:39:34 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 04:39:34 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 04:39:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39:35 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 04:39:35 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 04:39:35 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 04:39:35 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 04:39:35 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 04:39:35 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 04:39:35 INFO - Downloading docopt-0.6.1.tar.gz 04:39:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:36 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:36 INFO - Running setup.py install for blobuploader 04:39:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:36 INFO - Running setup.py install for requests 04:39:36 INFO - Running setup.py install for docopt 04:39:36 INFO - Successfully installed blobuploader requests docopt 04:39:36 INFO - Cleaning up... 04:39:36 INFO - Return code: 0 04:39:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:36 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:36 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 04:39:36 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 04:39:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:36 INFO - 'HOME': '/Users/cltbld', 04:39:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:36 INFO - 'LOGNAME': 'cltbld', 04:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:36 INFO - 'MOZ_NO_REMOTE': '1', 04:39:36 INFO - 'NO_EM_RESTART': '1', 04:39:36 INFO - 'PAGER': '/bin/cat', 04:39:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:36 INFO - 'PWD': '/builds/slave/test', 04:39:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:36 INFO - 'SHELL': '/bin/bash', 04:39:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:36 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:36 INFO - 'USER': 'cltbld', 04:39:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:36 INFO - 'XPC_FLAGS': '0x0', 04:39:36 INFO - 'XPC_SERVICE_NAME': '0', 04:39:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-h1DBBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QYDR2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-SnTIva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vBEuca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-52L6Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-DLH6_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FZ0VVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-XmKG9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9K53qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rKitht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ckakbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-IxX1mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FFOFyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-EKFRQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-YASxGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ah6g1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cZ6cBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:38 INFO - Running setup.py install for manifestparser 04:39:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:39 INFO - Running setup.py install for mozcrash 04:39:39 INFO - Running setup.py install for mozdebug 04:39:39 INFO - Running setup.py install for mozdevice 04:39:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:39 INFO - Running setup.py install for mozfile 04:39:39 INFO - Running setup.py install for mozhttpd 04:39:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:39 INFO - Running setup.py install for mozinfo 04:39:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Running setup.py install for mozInstall 04:39:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Running setup.py install for mozleak 04:39:40 INFO - Running setup.py install for mozlog 04:39:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Running setup.py install for moznetwork 04:39:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Running setup.py install for mozprocess 04:39:40 INFO - Running setup.py install for mozprofile 04:39:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:40 INFO - Running setup.py install for mozrunner 04:39:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:41 INFO - Running setup.py install for mozscreenshot 04:39:41 INFO - Running setup.py install for moztest 04:39:41 INFO - Running setup.py install for mozversion 04:39:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:41 INFO - Cleaning up... 04:39:41 INFO - Return code: 0 04:39:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:41 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')]} 04:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:41 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')]} 04:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:41 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:41 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 04:39:41 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 04:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:41 INFO - 'HOME': '/Users/cltbld', 04:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:41 INFO - 'LOGNAME': 'cltbld', 04:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:41 INFO - 'MOZ_NO_REMOTE': '1', 04:39:41 INFO - 'NO_EM_RESTART': '1', 04:39:41 INFO - 'PAGER': '/bin/cat', 04:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:41 INFO - 'PWD': '/builds/slave/test', 04:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:41 INFO - 'SHELL': '/bin/bash', 04:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:41 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:41 INFO - 'USER': 'cltbld', 04:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:41 INFO - 'XPC_FLAGS': '0x0', 04:39:41 INFO - 'XPC_SERVICE_NAME': '0', 04:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tyFzW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:41 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)) 04:39:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aNjD2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-a1uqXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-D7NAux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jwkpgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tjGPUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7zF2bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jF14FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-eBozp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NGIkmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:42 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vsiofd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:42 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)) 04:39:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aYsTaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:43 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)) 04:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5yiKAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:43 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)) 04:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QaFXdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9Wk7FT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:43 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)) 04:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-mDmqOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:43 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)) 04:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:43 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8q22XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:43 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)) 04:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:39:43 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)) 04:39:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:39:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:39:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:43 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 04:39:43 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 04:39:43 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 04:39:43 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 04:39:43 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 04:39:43 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 04:39:46 INFO - Downloading blessings-1.6.tar.gz 04:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:39:46 INFO - Installing collected packages: blessings 04:39:46 INFO - Running setup.py install for blessings 04:39:46 INFO - Successfully installed blessings 04:39:46 INFO - Cleaning up... 04:39:46 INFO - Return code: 0 04:39:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:39:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:46 INFO - Reading from file tmpfile_stdout 04:39:46 INFO - Current package versions: 04:39:46 INFO - blessings == 1.6 04:39:46 INFO - blobuploader == 1.2.4 04:39:46 INFO - docopt == 0.6.1 04:39:46 INFO - functools32 == 3.2.3-2 04:39:46 INFO - jsonschema == 2.5.1 04:39:46 INFO - manifestparser == 1.1 04:39:46 INFO - mozInstall == 1.12 04:39:46 INFO - mozcrash == 1.0 04:39:46 INFO - mozdebug == 0.1 04:39:46 INFO - mozdevice == 0.49 04:39:46 INFO - mozfile == 1.2 04:39:46 INFO - mozhttpd == 0.7 04:39:46 INFO - mozinfo == 0.9 04:39:46 INFO - mozleak == 0.1 04:39:46 INFO - mozlog == 3.4 04:39:46 INFO - moznetwork == 0.27 04:39:46 INFO - mozprocess == 0.23 04:39:46 INFO - mozprofile == 0.28 04:39:46 INFO - mozrunner == 6.13 04:39:46 INFO - mozscreenshot == 0.1 04:39:46 INFO - mozsystemmonitor == 0.3 04:39:46 INFO - moztest == 0.7 04:39:46 INFO - mozversion == 1.4 04:39:46 INFO - psutil == 3.1.1 04:39:46 INFO - requests == 1.2.3 04:39:46 INFO - wsgiref == 0.1.2 04:39:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:46 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')]} 04:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:46 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')]} 04:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:46 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:46 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 04:39:46 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 04:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:46 INFO - 'HOME': '/Users/cltbld', 04:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:46 INFO - 'LOGNAME': 'cltbld', 04:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:46 INFO - 'MOZ_NO_REMOTE': '1', 04:39:46 INFO - 'NO_EM_RESTART': '1', 04:39:46 INFO - 'PAGER': '/bin/cat', 04:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:46 INFO - 'PWD': '/builds/slave/test', 04:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:46 INFO - 'SHELL': '/bin/bash', 04:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:46 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:46 INFO - 'USER': 'cltbld', 04:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:46 INFO - 'XPC_FLAGS': '0x0', 04:39:46 INFO - 'XPC_SERVICE_NAME': '0', 04:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 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)) 04:39:47 INFO - Cleaning up... 04:39:47 INFO - Return code: 0 04:39:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:47 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 0x1051783f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed735773b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, '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.Q86HgNz16T/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.uT6dHZYvSK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 04:39:47 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 04:39:47 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 04:39:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:39:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:47 INFO - 'HOME': '/Users/cltbld', 04:39:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:47 INFO - 'LOGNAME': 'cltbld', 04:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:47 INFO - 'MOZ_NO_REMOTE': '1', 04:39:47 INFO - 'NO_EM_RESTART': '1', 04:39:47 INFO - 'PAGER': '/bin/cat', 04:39:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:47 INFO - 'PWD': '/builds/slave/test', 04:39:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:47 INFO - 'SHELL': '/bin/bash', 04:39:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:39:47 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:39:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:47 INFO - 'USER': 'cltbld', 04:39:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:47 INFO - 'XPC_FLAGS': '0x0', 04:39:47 INFO - 'XPC_SERVICE_NAME': '0', 04:39:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:39:47 INFO - Cleaning up... 04:39:47 INFO - Return code: 0 04:39:47 INFO - Running post-action listener: _resource_record_post_action 04:39:47 INFO - Running post-action listener: _start_resource_monitoring 04:39:47 INFO - Starting resource monitoring. 04:39:47 INFO - [mozharness: 2016-11-30 12:39:47.439113Z] Finished create-virtualenv step (success) 04:39:47 INFO - [mozharness: 2016-11-30 12:39:47.440196Z] Running install step. 04:39:47 INFO - Running pre-action listener: _resource_record_pre_action 04:39:47 INFO - Running main action method: install 04:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:47 INFO - Reading from file tmpfile_stdout 04:39:47 INFO - Detecting whether we're running mozinstall >=1.0... 04:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:39:47 INFO - Reading from file tmpfile_stdout 04:39:47 INFO - Output received: 04:39:47 INFO - Usage: mozinstall [options] installer 04:39:47 INFO - Options: 04:39:47 INFO - -h, --help show this help message and exit 04:39:47 INFO - -d DEST, --destination=DEST 04:39:47 INFO - Directory to install application into. [default: 04:39:47 INFO - "/builds/slave/test"] 04:39:47 INFO - --app=APP Application being installed. [default: firefox] 04:39:47 INFO - mkdir: /builds/slave/test/build/application 04:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:40:20 INFO - Reading from file tmpfile_stdout 04:40:20 INFO - Output received: 04:40:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:40:20 INFO - Running post-action listener: _resource_record_post_action 04:40:20 INFO - [mozharness: 2016-11-30 12:40:20.362549Z] Finished install step (success) 04:40:20 INFO - [mozharness: 2016-11-30 12:40:20.362684Z] Running run-tests step. 04:40:20 INFO - Running pre-action listener: _resource_record_pre_action 04:40:20 INFO - Running main action method: run_tests 04:40:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:20 INFO - 2016-11-30 04:40:20.377 screenresolution[1630:9030] starting screenresolution argv=screenresolution get 04:40:20 INFO - 2016-11-30 04:40:20.379 screenresolution[1630:9030] Display 0: 1600x1200x32@60 04:40:20 INFO - 2016-11-30 04:40:20.385 screenresolution[1631:9032] starting screenresolution argv=screenresolution list 04:40:20 INFO - Available Modes on Display 0 04:40:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:20 INFO - Intel Iris: 04:40:20 INFO - Chipset Model: Intel Iris 04:40:20 INFO - Type: GPU 04:40:20 INFO - Bus: Built-In 04:40:20 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:20 INFO - Vendor: Intel (0x8086) 04:40:20 INFO - Device ID: 0x0a2e 04:40:20 INFO - Revision ID: 0x0009 04:40:20 INFO - Displays: 04:40:20 INFO - Display: 04:40:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:20 INFO - Display Serial Number: 1600x1200 60 04:40:20 INFO - Main Display: Yes 04:40:20 INFO - Mirror: Off 04:40:20 INFO - Online: Yes 04:40:20 INFO - Rotation: Supported 04:40:20 INFO - Return code: 0 04:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:40:20 INFO - Python 2.7.3 04:40:20 INFO - Return code: 0 04:40:20 INFO - grabbing minidump binary from tooltool 04:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:40:20 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 0x104f8ed30>, '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 0x104df5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f86b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:40:20 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 04:40:20 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 04:40:20 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 04:40:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:40:20 INFO - Return code: 0 04:40:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:40:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:40:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:40:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:40:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:40:20 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/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 04:40:20 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/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 04:40:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q86HgNz16T/Render', 04:40:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:20 INFO - 'HOME': '/Users/cltbld', 04:40:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:20 INFO - 'LOGNAME': 'cltbld', 04:40:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:40:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:20 INFO - 'MOZ_NO_REMOTE': '1', 04:40:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:40:20 INFO - 'NO_EM_RESTART': '1', 04:40:20 INFO - 'PAGER': '/bin/cat', 04:40:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:20 INFO - 'PWD': '/builds/slave/test', 04:40:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:40:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:20 INFO - 'SHELL': '/bin/bash', 04:40:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uT6dHZYvSK/Listeners', 04:40:20 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 04:40:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:20 INFO - 'USER': 'cltbld', 04:40:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:20 INFO - 'XPC_FLAGS': '0x0', 04:40:20 INFO - 'XPC_SERVICE_NAME': '0', 04:40:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:20 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/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 04:40:20 INFO - mozversion application_buildid: 20161130023343 04:40:20 INFO - mozversion application_changeset: 13736e2db6eb94b02dd28cc88f2943b8109aa374 04:40:20 INFO - mozversion application_display_name: Nightly 04:40:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:40:20 INFO - mozversion application_name: Firefox 04:40:20 INFO - mozversion application_remotingname: firefox 04:40:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:40:20 INFO - mozversion application_vendor: Mozilla 04:40:20 INFO - mozversion application_version: 53.0a1 04:40:20 INFO - mozversion platform_buildid: 20161130023343 04:40:20 INFO - mozversion platform_changeset: 13736e2db6eb94b02dd28cc88f2943b8109aa374 04:40:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:40:20 INFO - mozversion platform_version: 53.0a1 04:40:20 INFO - using testdate: 1480509620 04:40:20 INFO - actual date: 1480509620 04:40:20 INFO - starting webserver on 'localhost:49236' 04:40:20 INFO - SUITE-START | Running 2 tests 04:40:20 INFO - TEST-START | dromaeo_css 04:40:20 INFO - operating with platform_type : mac_ 04:40:20 INFO - Initialising browser for dromaeo_css test... 04:40:20 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpgjc5hd/profile http://localhost:49236/getInfo.html) 04:40:24 INFO - PROCESS | 1641 | 2016-11-30 04:40:24.281 plugin-container[1644:9211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:40:24 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:24 INFO - PROCESS | 1641 | 2016-11-30 04:40:24.284 plugin-container[1644:9211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 04:40:24 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:24 INFO - PROCESS | 1641 | 2016-11-30 04:40:24.284 plugin-container[1644:9211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:24 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 04:40:24 INFO - PROCESS | 1641 | colorDepth:24 04:40:24 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 04:40:24 INFO - PROCESS | 1641 | __metrics 04:40:24 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 04:40:25 INFO - TEST-INFO | 1641: exit 0 04:40:25 INFO - Browser initialized. 04:40:25 INFO - Running cycle 1/1 for dromaeo_css test... 04:40:25 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpgjc5hd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:27 INFO - PROCESS | 1645 | 2016-11-30 04:40:27.915 plugin-container[1646:9329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 04:40:27 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:27 INFO - PROCESS | 1645 | 2016-11-30 04:40:27.916 plugin-container[1646:9329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 04:40:27 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:27 INFO - PROCESS | 1645 | 2016-11-30 04:40:27.916 plugin-container[1646:9329] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:37 INFO - PROCESS | 1645 | RSS: Main: 243073024 04:40:37 INFO - PROCESS | 1645 | 04:41:27 INFO - PROCESS | 1645 | 1480509687263 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] 04:43:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 04:43:43 INFO - PROCESS | 1645 | RSS: Main: 254922752 04:43:43 INFO - PROCESS | 1645 | 04:46:45 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 04:46:46 INFO - PROCESS | 1645 | RSS: Main: 265891840 04:46:46 INFO - PROCESS | 1645 | 04:49:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 04:49:48 INFO - PROCESS | 1645 | RSS: Main: 270364672 04:49:48 INFO - PROCESS | 1645 | 04:52:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 04:52:51 INFO - PROCESS | 1645 | RSS: Main: 268976128 04:52:51 INFO - PROCESS | 1645 | 04:55:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 04:55:54 INFO - PROCESS | 1645 | RSS: Main: 275083264 04:55:54 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 04:58:57 INFO - PROCESS | 1645 | RSS: Main: 277164032 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | __start_tp_report 04:58:57 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 04:58:57 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 04:58:57 INFO - PROCESS | 1645 | |i|pagename|runs| 04:58:57 INFO - PROCESS | 1645 | |0;dojo.html;3400.846961886715;3406.710429613481;3426.0920855973154;3427.828608569571;3427.982860085699;5577.024020796354;5706.1440783882435;5739.56953228508;5786.479216870481;5789.855253618662;5615.2700148980675;5737.598368114233;5750.964826331269;5753.309602847664;5895.823125306237;5097.49025097491;5231.136862417697;5246.842594722164;5270.288511051017;5288.180332048529;5191.0656081905245;5287.39194992575;5287.788488460457;5314.840554783363;5329.333833270841;5271.341082364705;5273.762680679379;5300.840974770763;5317.840464786043;5340.359156901158;5098.719570423626;5141.228815677641;5175.275461435402;5212.087884620177;5230.816921407771;5102.158143906251;5145.9965306764825;5163.406208286057;5172.250023746573;5195.2986346843445;5472.589555783332;5631.408702086263;5743.597948143632;5752.338481074688;5863.794767183171;6259.217597800275;6266.937330626681;6278.869723894802;6287.528435367366;6302.243730752339;5265.473452654742;5989.161517387569;6293.213348331458;6317.46301564363;6334.208223972004;5688.687122208277;5722.055860782966;5768;5821.650700957956;5837.445442682938;6209.2548894133;6225.221847269091;6254.21822272216;6275.811725648238;6277.2781130170615;5275.498827611371;6183.072539119078;6201.286852011986;6208.317085120633;6210.906836397367;6254.280757712831;6257.499400047986;6267.467265282502;6270.467010304175;6271.843203919939;6098.420650038239;6101.450869421773;6123.526657168203;6129.083728908156;6186.605998014249;6230.7819226327765;6241.469475094577;6250.718717657716;6286.779962701284;6303.369663033752;4912.778924948386;4926.704397736147;4956.851294461172;4974.751262436864;4992.326035985168;4378.627816635558;4386.078923426053;4394.846180383735;4451.421315229064;4468.972136408579;5767.769289228384;5827.650340979555;5962.403759624003;5965.701714914237;5965.910511342333;6258.374162583706;6275.529335299871;6299.307076221612;6305.558610897193;6305.5901366411035;4879.512048795092;5696.23100881384;5743.10981797822;5842.941570584325;5844.1818145459665;5371.677699338053;5378.489043540857;5400.945990540123;5430.185472179126;5442.510174084351;5972.074328479092;6361.395667411496;6403.231612206565;6404.391582799627;6426.742930282736;5436.3476382834315;5466.180072989084;5694;6301.558890877594;6307.243130824147;6175.2589689237575;6231.2834024086;6240.625562466266;6309.589876658002;6340.7463701451425;6212.161358216583;6251.437370636664;6258.780942666985;6267.122602835516;6288.496920246278;4814.988852341047;6086.939130608637;6127.892078906909;6218;6282.371762823681;5385.976664433745;5898.675572843363;5996.070609055603;6101.5804940036605;6122.6938653068055;5145.845624631267;5411.83764487066;6068.816580766708;6197.752089916533;6227.595206311576;5134.845954621367;5577.135543990688;5883.117532370007;5883.264591926009;5995.370486098896;6120.020796672511;6124.930239974474;6132.846678832886;6139.2019037526625;6140.13616995759;6102.974033066949;6107.114468402146;6107.85643655375;6112.503480924647;6117.401149625394 04:58:57 INFO - PROCESS | 1645 | |1;ext.html;450.7408240261849;471.60385276367845;492.4558363008874;516.8191133103417;540.0845566764336;35408;38671.419928701056;38867.805660971724;38882;39000.21999560007;40061.19877602446;40506.7974660127;40523.189536209255;40552.391714124235;40608.593914060926;8214.63034163464;9154.26765858733;9307.860382094274;9313.16181543661;9330.906690933072;8291.543965081919;10986.780264394707;11237.775244495106;11259.718507037349;11416.372099534523;11239.719007024849;11242.775144497105;11287.943560282187;11299.03958163557;11331.03686186675;8854.247388971946;11129.054030407426;11181.608643697473;11213.934676205788;11225.719357016058;11106.27809192408;11143.108551315956;11169.106471482342;11180.04969577587;11194.888051119548;31518.684813151805;38222.61777382218;38342;38671.613283867366;38695.80652096721;3319.5684561007156;3456.5160877476924;3504;3888.066863952631;3955.0903292242656;29064.564031539543;29576.55635165474;29691.5546266804;29819.850900745576;30325.696743032728;2769.8476583787883;3163.4938409854544;3204.839758012113;3220.5008223725126;3228.1122691260093;444060;447870;448223;450989;451076;47971.92238659683;57340.426595734345;57510.42489575134;57623.42376576264;57759;48982.5101748978;58022.70988645114;58457;59182;59574.40425595775;14784.926075369554;15239;15313.23433828304;15337.309821057856;15362.846371536141;10676.89323106759;10940.890591094147;11649.59226427088;11702.76594468106;11832.112591555495;13297.468101276034;16041.438549650706;16393.754093688603;16471.91764041172;16538.751918721227;7131.964340178266;7140.250273721288;7196.856062878713;7203.351698347069;7289.854202915912;835.5571547080058;890.1098901098901;908.8500397434482;928.8560273157672;967.3228739882111;28988.85505572501;29062.41875162527;29070.854645726635;29584.408311833642;29913.401731965674;988.3180605382263;1001.5442973447064;1035.2753072849036;1036.9481525923673;1118.6196693124377;12969;14482.710345793024;15040.398384064734;15061.623459413384;15449.9227503864;543267;548506;549147;549401;551878;53967.73016134894;54325.913481730146;54536.72731636316;54653;54963.72518137384;1220.993895030519;1289.3295486347047;1310.5544115000946;1327.9933600331935;1346.7710489216856;27855;28809.56785648259;28964.16886122386;29309.12072637824;29484.410311794072;47835.521644783104;48280.7585962068;48345.13238940947;48529.514704853915;48653.75673121612;405466;409419;409952;413419.93290033354;413644;32589.837050814593;33406;37392.065198370256;38307;38469.807650962684;43936.560634393245;44138.779306104545;44180.11639767251;44469.77765111154;45406.77296613628;36201;36526.634733652325;36671.81664091662;36793.816030919676;37682;35524.822375887954;36148;36317.0920726984;37604.05989850166;38138;35437.114072148404;35937.82031089828;36594;36673;36937.63062369342;27161.456770864075;28178.01376951797;31569;31872.203194920312;31887.6811231894;21618.89190554037;24670.259892203263;24795.62806557867;24899.96661401294;25272.87363563244 04:58:57 INFO - PROCESS | 1645 | |2;jquery.html;357.15354609575303;367.2050011724615;376.24187262665725;379.6635841836733;396.0909712210478;15007.324670389831;18194.72707909381;19739.5065123372;19823.909684967322;20021.699674504893;16170.434034808784;18769.53076173093;19103.52241193971;19404.417867464;19546.41360759176;5533.584981126422;5754.539636829044;5802.332731735863;5813.6802475863815;5851.94664960307;5538.806141785039;5699.344575373843;5800.709964501779;5815.680137592431;5817.418258174191;4915.508449155073;5755.568332375067;5759.856003599923;5761.078227483604;5771.163181338703;4823.228283474645;4823.445303790074;5485.259489968843;5713.085906254979;5748.5401167906675;4903.730294833783;4976.154053810861;5005.799768009311;5017.996400719834;5019.4227663818765;12382.380880955916;16500.092494912777;17739.911300443546;21183.046762895705;22801.657975130387;2135.2419890938804;2190.58378908007;2280.7149106361703;2530.165045534969;2627.9868600657064;9325.253979681609;9411.529423528797;9563.474008929506;9799.11807937289;9822.901770982271;1760.9500385083702;1878.2111513164366;1886.1323791056132;1894.488488108203;1896.4879482539775;111756;117596.41201793936;119453;120397;120454;9104.226140777975;11141.94429027866;11226.045786108109;11308.378039207922;11490.885091149148;14029.579112626638;14143.929280353532;14192.716145677236;14211.64470888236;14242.643933901736;7865.410094242955;8175.591220438954;8190.918090819135;8290.37822163328;8293.087760346358;7944.6027698614835;7956.721514746956;7972.880406793903;8110.63502142405;8111.432199746079;4368.191884501377;4397.472303323621;4398.714083584621;4425.17639971754;4443.333499974965;1543.9228038598026;1871.868969172172;1920.4526709887614;1925.2395303855178;1934.3133187718438;796.2674339607574;819.5943008210974;820.8358328334357;896.3546246702364;913.7441516375426;19396.612067758848;19626.705599416022;19988;20171.99140042992;20173.780884102736;875.1642181716445;901.2834796336797;905.1491597897955;910.7905181808221;916.4867674102524;7640.808979775439;7994.480358776661;10018.148457381058;10123.493825308706;10661.73345666349;505254;516806;517617;525493;526602;88448;91638;113438;117272;125652;1000.9949950250202;1005.6932635546375;1067.8878717734526;1070.03162138265;1077.5205033760058;18196.636067278316;18425.907870460564;18545;18812;18838.058097095363;41206.793966029974;44623.10753784841;48832.267515988;53795.73102134464;55994.160087597906;68072.63854722977;75061.24938750542;79507.20492795229;80642;81206.59396702978;7607.657655405409;7832.412569057457;7912.960435197787;7946.642401091849;7987.720429784929;10799.784004320027;11120.499577519193;11372;12302.630921072383;12562.748745025232;9204.447733135765;10633.468326583794;10704.678859634223;10749.366405230456;10878.238523303293;9568.473733944422;10352.689419317132;10632.574697012033;10834;10913.126949843834;10811.729706757395;10951.83572246401;10977.725556861142;11225.326480411202;11388.715282118013;5542.919130769623;6478.805635830932;6509.902351464637;7298.598577078312;7349.8162545936775;8079.474834135763;10473.162147028068;10727.624533141303;10764.838527421938;10765.084967777513 04:58:57 INFO - PROCESS | 1645 | |3;mootools.html;1442.632128807154;1487.0037075159642;1640.9097499637523;1739.09567025147;1799.064486467037;7116.3239492248285;7713.614319284027;7916.72291469798;8002.199780022002;8358.958205208974;7125.786226413203;7966.681332746698;7996.640151193195;8344.833103337945;8362.205590468911;2237.59723249815;2281.133169395627;2289.370423133635;2293.3463962770616;2294.3117064880553;2313.9768602314016;2328.93013209604;2348.412896775806;2359.044586942291;2367.1833217540006;2291.232437133554;2300;2312.606856834342;2315.47901722112;2316.6872472216287;2280.5894938910988;2290.541891621682;2308.7259049835898;2312.9884350578222;2314.560233555636;1883.849292056632;2292.255017119426;2302.044651469655;2322.7212734471805;2323.0939933425975;8326.125756795509;8365.372597055246;8400;8421.663133474654;8442.324614030862;5011.749412529395;5153;5168.870778230536;5175.938932518825;5186.014657215117;3233.7413006959387;3287.211069343364;3560.537130173087;3583.157957879896;3589.0847833802472;1027.1320733979776;1030.1243942648764;1072.8766191887955;1086.5110700184948;1086.9945650271777;10821.13430925532;10857.728556786065;10860.67417977462;10888.183386245984;10947.83578246327;4432.778361081933;4565.726056436624;4657.60410365116;4670.065986802585;4677.836275730401;9208;9303.116203960613;9329.906700932905;9335.906640933505;9352.39209451397;5126.564242039469;5130.743462826844;5136.512031357015;5137.717425541557;5140.1004824155625;4198.328267477189;4483.417155769795;4671.719696818202;4679.906401871944;4690.108879312919;3760.6615404613717;3765.3410653135593;3827.2919509890758;3863.4784304118584;3878.4376265441915;1196.5034510678145;1254.905882058831;1263.210493441599;1263.5072321794507;1276.0238417610535;791.3589992106412;888.9911100888909;891.460666296894;893.9910600894038;898.285862739124;5449.972750136224;5567.081431563827;5624.959382514248;5758.7696492140685;5766.077427611646;906.3836591118103;949.3781573069513;960.5437417226901;989.42118860466;1039.2569312941284;3598.8020658864016;3779.9433008504893;3868.574456809749;3878.0886491674432;3881.2237552489614;9771.51142442875;10069.79860402788;10215.693529193988;10224;10227.386356818615;9874.80250394988;9963.750906227257;10027.398356098658;10049.14582260516;10155.492225388702;7708.961455192801;7750.806229844299;7872.291493765589;7874.645640946172;7912.643931023118;4633.119707255611;5458.454154584669;5491.75287112073;5529.474699903502;5573.303337082864;7624.085109786861;7908.6045697713735;8552.930883639545;9363.672271470467;9393.389429687048;8552.786180345542;8922.884639420072;9193.816123677623;9448.574814133242;9518;6324.4940404766585;6491.513136514686;6966.9651651741415;7149.53528020677;7357.006804081595;6697.732090716317;7581.393488520795;7839.09850367215;7977.680892764457;7988.880166797619;7047.859042819217;7099.07711997437;7116.25279345682;7122.928770712227;7250.9637451812405;6906.37842594169;7067.328603782633;7081.858362832818;7082.468814838805;7086.255943126103;6535.869282614226;6567.113439685581;6604.801855944329;6636.966815165893;6637.269900311058;4799.808007679654;4815.085133824702;4853.247746599282;4887.7556122194455;4894.388201474816;4778.04439112172;4848.903021939612;4849.903001940012;4951.554360107608;4969.0807200668 04:58:57 INFO - PROCESS | 1645 | |4;prototype.html;296.7166356129896;297.5046547498415;303.9802412843164;309.1452134847148;312.8341978751261;4333.566643335665;5335.33308336458;5429.728513574325;5600.887982240352;5605.775768969246;5217.843464696056;5313.628046036769;5382;5408.648437851547;5460.0414699342955;4102.241085399196;4172.4784401949755;4176.269152898246;4179.2686279901045;4199.9630179362985;3487.1518095409347;3643.8178091095474;4008.7594744315434;4049.817758200888;4212.957870421303;3755.8309876055505;3967.9683282346655;4111.629953304202;4133.256013917494;4162.375643653446;4121.835126594932;4123.8969025774295;4130.265288578804;4130.277201489742;4138.813753381105;3595.694365978895;3601.207734298476;3603.9457509219756;3632.4209811033934;3646.361886669849;5026;5333.893322133536;5343.599230057723;5344.22508736235;5355.3841308249275;4697.802060474592;4705.835295764632;4715.905681886377;4734.360861283717;4766.904661906777;5802.361740208574;5829.533637309005;5848.88302233953;5855.916655418719;5861.618994765369;3480.5475288212624;3876.127871228979;3890.824912878899;3913.471681323042;3921.5294164700135;6031.728572214217;6080.695965201722;6082.270127584674;6103.481204097657;6213.627182369072;3952.6165841955662;3966.1671049080014;3976.900577485586;3982.7809470478824;3987.7806720630065;3364.4953257011643;3501.719862411001;3836.405357169643;4014.253799597126;4023.6781057515327;3840.577536470986;4154.8338066477;4540.455145382575;4685.273782563708;4732.408448943882;5333.893322133536;5811.401864487215;5877.617954832922;5885.175115944285;5917.911231331534;1082;1183.621241202824;1234.512367614787;1303.5307289375817;1307.6142537951328;856.7277592774725;868.5743985447211;877.5392918717597;878.2447095497866;884.4207044385937;4538.230090264799;4595.678302518859;5045.683758550043;5192.935448233103;5201.895962080737;5013.89972200554;5348.0318423274675;5558.471945165203;5672;5712.6286791358425;4421.712588681726;4588.931166032512;5036.822527897731;5158.63889527737;5193.454687257859;2679.2632026192955;2938.353562216309;2990.5364668476423;3031.120435586202;3076.1540576341235;5134.845954621367;5369.3556773187465;5890.617109887844;5949.9107513387335;5969.224000879858;4956.752162391866;5445.673259604437;5680.648417433651;5782.7905512441;5929.822105336847;5641.956238095965;5749.182900378701;5802.332731735904;5938.368072903142;6020.548458865603;5368.382635996911;5476.863078423071;5538.584606154474;5817.767289308549;5818.534517238695;4984.8753781155765;5622.24099746529;5728.391113598406;5730.828075157585;5732.541396688338;5010.845336505192;5513.58648101402;5531.778728850962;6010.008348622603;6036.849078773066;3276.7870088444174;3431.159365955357;3636.9521399461396;3711.5546134462957;3762.4732537445325;5005.149124648891;5019.318528293063;5583.385827559046;5745.253117094751;5804.535637148933;5402.244270611988;5786.537077033911;5863.270335251026;5875.764969401176;5911.6748578826855;5579.776808927598;5856.209411729362;5872.324682661378;5879.764809407747;5906.970465147647;4895.33912921751;4989.675671081368;5152.681293418052;5549.38956714753;5761.539076873756;5773.682447465262;5842.707864606838;5855.853603659943;5866.6186697864505;5875.911861321998;4843.958548912001;5215.835428297214;5320.441353657965;5509.3664228614225;5748.827535173951 04:58:57 INFO - PROCESS | 1645 | |5;yui.html;620.370324121017;690.4096997067506;718.0629278791179;723.1358526560758;743.9628018599069;14467;14736.926315368435;15081;15266.542003739878;15407.3837046518;13833.031687781859;14024.859751402508;14039.5086171984;14065.859341406584;14071.718565628707;594.4649815166341;671.9092922455479;806.3911746631285;815.8735396013627;820.8440396324679;811.930985866202;820.335528222142;820.8645573480369;822.3914303415489;823.3248736036453;867.3061550759368;870.5168631409565;871.1071152069148;872.6640243506264;873.8995015573227;860.3418384935529;863.1497974495118;864.2783275964546;865.6277800545763;866.3242670716851;632.7469012395032;637.1716768201366;638.5019684645983;638.7764282501134;641.3362170153869;14108.153510789283;14242.287885605678;14519.564413067625;14563.781543276858;14954.775678364833;14057.297135143202;14082.295885205802;14137.717245655029;14396.017423771002;14408.279586020763;10221.33561318512;11008.614698485515;11033.503492342783;11755.294682319089;11927.284362938164;3651.050726811022;3704.499892514503;3719.543805080346;3820.808959552039;3821.3312670282867;103351;105875;106643;107529;110075.44962275137;10785.568577256821;10819.242653014213;10851.945740271405;10863.07663848566;11045.122329203894;21548.78451215499;21721.782782172293;21878.45303867416;22044.008019639154;22071.11715531393;7471.925280747231;7546.660400282;7678.654460549289;7779.338756205672;7831.099423566363;4302.698811083194;4316.935245971313;4346.8043938022865;4412.889677758082;4421.38100665907;2986;3223.16197788572;3286.4084464796088;3397.2865698203655;3499.2651543176216;607.7781609712423;623.2178615837186;631.2046821005566;641.7080228496023;641.8074577626693;467.4414075180195;528.7620570743104;536.4742552298769;584.0305093544696;599.9880002399927;7203.855922881513;8052.436329456882;9353.719388418358;9356.157945784911;9610.327277090537;598.7066337494597;661.4377778888003;665.2781731821028;668.9698963546653;673.8921772516373;3638.6725194732603;4443.577860103285;4653.813847446132;4750.12122757291;4751.477337492873;78171.8274225887;86017.56991215004;104095.4795226019;105378.47310763397;105653;79271;89339.65990510148;94204;94806.52596736971;96087;60548;83079;83230;84859.57570212109;84880.57559712161;7375.852482950419;8927.866082008904;9010.459372437675;9046.864297035681;9055.504355322872;33110.00669979808;40392;49792.00415991732;50960.490395097055;54283.45716542942;68730;70997.03020788495;73785;75099.62450187714;76016.61991690006;12839.807402888777;12882.613521594367;13187.274699891597;13247.867521324664;13691.246981416118;14635.41458341697;14675.633109172357;14818.40726370933;14943;15109.773353399489;13424;13834.93082534581;13912.28710401462;13947.651308717363;14359.497417590333;13739.24434156131;13860.237686927316;14134.858651413766;14419.85580144185;14442.988990770966;13093.869061309266;13424.463021479032;13439.664008399868;14013.83824123659;14121.717565648423;11151.665450036511;11210.64576438957;11221.831672525082;11490.48292826808;11553.884461155281;8022.518648881086;10157.746056348651;11327.603533876274;11596;11646.76706465849 04:58:57 INFO - PROCESS | 1645 | __end_tp_report 04:58:57 INFO - PROCESS | 1645 | __start_cc_report 04:58:57 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,363 04:58:57 INFO - PROCESS | 1645 | __end_cc_report 04:58:57 INFO - PROCESS | 1645 | __startTimestamp1480510737635__endTimestamp 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | ------- Summary: start ------- 04:58:57 INFO - PROCESS | 1645 | Number of tests: 6 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5712.36 Median:5883.12 stddev:642.06 (10.9%) stddev-sans-first:617.89 04:58:57 INFO - PROCESS | 1645 | Values: 3400.8 3406.7 3426.1 3427.8 3428.0 5577.0 5706.1 5739.6 5786.5 5789.9 5615.3 5737.6 5751.0 5753.3 5895.8 5097.5 5231.1 5246.8 5270.3 5288.2 5191.1 5287.4 5287.8 5314.8 5329.3 5271.3 5273.8 5300.8 5317.8 5340.4 5098.7 5141.2 5175.3 5212.1 5230.8 5102.2 5146.0 5163.4 5172.3 5195.3 5472.6 5631.4 5743.6 5752.3 5863.8 6259.2 6266.9 6278.9 6287.5 6302.2 5265.5 5989.2 6293.2 6317.5 6334.2 5688.7 5722.1 5768.0 5821.7 5837.4 6209.3 6225.2 6254.2 6275.8 6277.3 5275.5 6183.1 6201.3 6208.3 6210.9 6254.3 6257.5 6267.5 6270.5 6271.8 6098.4 6101.5 6123.5 6129.1 6186.6 6230.8 6241.5 6250.7 6286.8 6303.4 4912.8 4926.7 4956.9 4974.8 4992.3 4378.6 4386.1 4394.8 4451.4 4469.0 5767.8 5827.7 5962.4 5965.7 5965.9 6258.4 6275.5 6299.3 6305.6 6305.6 4879.5 5696.2 5743.1 5842.9 5844.2 5371.7 5378.5 5400.9 5430.2 5442.5 5972.1 6361.4 6403.2 6404.4 6426.7 5436.3 5466.2 5694.0 6301.6 6307.2 6175.3 6231.3 6240.6 6309.6 6340.7 6212.2 6251.4 6258.8 6267.1 6288.5 4815.0 6086.9 6127.9 6218.0 6282.4 5386.0 5898.7 5996.1 6101.6 6122.7 5145.8 5411.8 6068.8 6197.8 6227.6 5134.8 5577.1 5883.1 5883.3 5995.4 6120.0 6124.9 6132.8 6139.2 6140.1 6103.0 6107.1 6107.9 6112.5 6117.4 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:61064.14 Median:36560.32 stddev:125635.15 (343.6%) stddev-sans-first:125905.16 04:58:57 INFO - PROCESS | 1645 | Values: 450.7 471.6 492.5 516.8 540.1 35408.0 38671.4 38867.8 38882.0 39000.2 40061.2 40506.8 40523.2 40552.4 40608.6 8214.6 9154.3 9307.9 9313.2 9330.9 8291.5 10986.8 11237.8 11259.7 11416.4 11239.7 11242.8 11287.9 11299.0 11331.0 8854.2 11129.1 11181.6 11213.9 11225.7 11106.3 11143.1 11169.1 11180.0 11194.9 31518.7 38222.6 38342.0 38671.6 38695.8 3319.6 3456.5 3504.0 3888.1 3955.1 29064.6 29576.6 29691.6 29819.9 30325.7 2769.8 3163.5 3204.8 3220.5 3228.1 444060.0 447870.0 448223.0 450989.0 451076.0 47971.9 57340.4 57510.4 57623.4 57759.0 48982.5 58022.7 58457.0 59182.0 59574.4 14784.9 15239.0 15313.2 15337.3 15362.8 10676.9 10940.9 11649.6 11702.8 11832.1 13297.5 16041.4 16393.8 16471.9 16538.8 7132.0 7140.3 7196.9 7203.4 7289.9 835.6 890.1 908.9 928.9 967.3 28988.9 29062.4 29070.9 29584.4 29913.4 988.3 1001.5 1035.3 1036.9 1118.6 12969.0 14482.7 15040.4 15061.6 15449.9 543267.0 548506.0 549147.0 549401.0 551878.0 53967.7 54325.9 54536.7 54653.0 54963.7 1221.0 1289.3 1310.6 1328.0 1346.8 27855.0 28809.6 28964.2 29309.1 29484.4 47835.5 48280.8 48345.1 48529.5 48653.8 405466.0 409419.0 409952.0 413419.9 413644.0 32589.8 33406.0 37392.1 38307.0 38469.8 43936.6 44138.8 44180.1 44469.8 45406.8 36201.0 36526.6 36671.8 36793.8 37682.0 35524.8 36148.0 36317.1 37604.1 38138.0 35437.1 35937.8 36594.0 36673.0 36937.6 27161.5 28178.0 31569.0 31872.2 31887.7 21618.9 24670.3 24795.6 24900.0 25272.9 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:31456.00 Median:387.88 stddev:86910.19 (22406.6%) stddev-sans-first:87122.63 04:58:57 INFO - PROCESS | 1645 | Values: 357.2 367.2 376.2 379.7 396.1 15007.3 18194.7 19739.5 19823.9 20021.7 16170.4 18769.5 19103.5 19404.4 19546.4 5533.6 5754.5 5802.3 5813.7 5851.9 5538.8 5699.3 5800.7 5815.7 5817.4 4915.5 5755.6 5759.9 5761.1 5771.2 4823.2 4823.4 5485.3 5713.1 5748.5 4903.7 4976.2 5005.8 5018.0 5019.4 12382.4 16500.1 17739.9 21183.0 22801.7 2135.2 2190.6 2280.7 2530.2 2628.0 9325.3 9411.5 9563.5 9799.1 9822.9 1761.0 1878.2 1886.1 1894.5 1896.5 111756.0 117596.4 119453.0 120397.0 120454.0 9104.2 11141.9 11226.0 11308.4 11490.9 14029.6 14143.9 14192.7 14211.6 14242.6 7865.4 8175.6 8190.9 8290.4 8293.1 7944.6 7956.7 7972.9 8110.6 8111.4 4368.2 4397.5 4398.7 4425.2 4443.3 1543.9 1871.9 1920.5 1925.2 1934.3 796.3 819.6 820.8 896.4 913.7 19396.6 19626.7 19988.0 20172.0 20173.8 875.2 901.3 905.1 910.8 916.5 7640.8 7994.5 10018.1 10123.5 10661.7 505254.0 516806.0 517617.0 525493.0 526602.0 88448.0 91638.0 113438.0 117272.0 125652.0 1001.0 1005.7 1067.9 1070.0 1077.5 18196.6 18425.9 18545.0 18812.0 18838.1 41206.8 44623.1 48832.3 53795.7 55994.2 68072.6 75061.2 79507.2 80642.0 81206.6 7607.7 7832.4 7913.0 7946.6 7987.7 10799.8 11120.5 11372.0 12302.6 12562.7 9204.4 10633.5 10704.7 10749.4 10878.2 9568.5 10352.7 10632.6 10834.0 10913.1 10811.7 10951.8 10977.7 11225.3 11388.7 5542.9 6478.8 6509.9 7298.6 7349.8 8079.5 10473.2 10727.6 10764.8 10765.1 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5379.26 Median:5128.65 stddev:2921.11 (57.0%) stddev-sans-first:2914.32 04:58:57 INFO - PROCESS | 1645 | Values: 1442.6 1487.0 1640.9 1739.1 1799.1 7116.3 7713.6 7916.7 8002.2 8359.0 7125.8 7966.7 7996.6 8344.8 8362.2 2237.6 2281.1 2289.4 2293.3 2294.3 2314.0 2328.9 2348.4 2359.0 2367.2 2291.2 2300.0 2312.6 2315.5 2316.7 2280.6 2290.5 2308.7 2313.0 2314.6 1883.8 2292.3 2302.0 2322.7 2323.1 8326.1 8365.4 8400.0 8421.7 8442.3 5011.7 5153.0 5168.9 5175.9 5186.0 3233.7 3287.2 3560.5 3583.2 3589.1 1027.1 1030.1 1072.9 1086.5 1087.0 10821.1 10857.7 10860.7 10888.2 10947.8 4432.8 4565.7 4657.6 4670.1 4677.8 9208.0 9303.1 9329.9 9335.9 9352.4 5126.6 5130.7 5136.5 5137.7 5140.1 4198.3 4483.4 4671.7 4679.9 4690.1 3760.7 3765.3 3827.3 3863.5 3878.4 1196.5 1254.9 1263.2 1263.5 1276.0 791.4 889.0 891.5 894.0 898.3 5450.0 5567.1 5625.0 5758.8 5766.1 906.4 949.4 960.5 989.4 1039.3 3598.8 3779.9 3868.6 3878.1 3881.2 9771.5 10069.8 10215.7 10224.0 10227.4 9874.8 9963.8 10027.4 10049.1 10155.5 7709.0 7750.8 7872.3 7874.6 7912.6 4633.1 5458.5 5491.8 5529.5 5573.3 7624.1 7908.6 8552.9 9363.7 9393.4 8552.8 8922.9 9193.8 9448.6 9518.0 6324.5 6491.5 6967.0 7149.5 7357.0 6697.7 7581.4 7839.1 7977.7 7988.9 7047.9 7099.1 7116.3 7122.9 7251.0 6906.4 7067.3 7081.9 7082.5 7086.3 6535.9 6567.1 6604.8 6637.0 6637.3 4799.8 4815.1 4853.2 4887.8 4894.4 4778.0 4848.9 4849.9 4951.6 4969.1 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4598.15 Median:5193.20 stddev:1433.31 (27.6%) stddev-sans-first:1400.50 04:58:57 INFO - PROCESS | 1645 | Values: 296.7 297.5 304.0 309.1 312.8 4333.6 5335.3 5429.7 5600.9 5605.8 5217.8 5313.6 5382.0 5408.6 5460.0 4102.2 4172.5 4176.3 4179.3 4200.0 3487.2 3643.8 4008.8 4049.8 4213.0 3755.8 3968.0 4111.6 4133.3 4162.4 4121.8 4123.9 4130.3 4130.3 4138.8 3595.7 3601.2 3603.9 3632.4 3646.4 5026.0 5333.9 5343.6 5344.2 5355.4 4697.8 4705.8 4715.9 4734.4 4766.9 5802.4 5829.5 5848.9 5855.9 5861.6 3480.5 3876.1 3890.8 3913.5 3921.5 6031.7 6080.7 6082.3 6103.5 6213.6 3952.6 3966.2 3976.9 3982.8 3987.8 3364.5 3501.7 3836.4 4014.3 4023.7 3840.6 4154.8 4540.5 4685.3 4732.4 5333.9 5811.4 5877.6 5885.2 5917.9 1082.0 1183.6 1234.5 1303.5 1307.6 856.7 868.6 877.5 878.2 884.4 4538.2 4595.7 5045.7 5192.9 5201.9 5013.9 5348.0 5558.5 5672.0 5712.6 4421.7 4588.9 5036.8 5158.6 5193.5 2679.3 2938.4 2990.5 3031.1 3076.2 5134.8 5369.4 5890.6 5949.9 5969.2 4956.8 5445.7 5680.6 5782.8 5929.8 5642.0 5749.2 5802.3 5938.4 6020.5 5368.4 5476.9 5538.6 5817.8 5818.5 4984.9 5622.2 5728.4 5730.8 5732.5 5010.8 5513.6 5531.8 6010.0 6036.8 3276.8 3431.2 3637.0 3711.6 3762.5 5005.1 5019.3 5583.4 5745.3 5804.5 5402.2 5786.5 5863.3 5875.8 5911.7 5579.8 5856.2 5872.3 5879.8 5907.0 4895.3 4989.7 5152.7 5549.4 5761.5 5773.7 5842.7 5855.9 5866.6 5875.9 4844.0 5215.8 5320.4 5509.4 5748.8 04:58:57 INFO - PROCESS | 1645 | 04:58:57 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20325.71 Median:4309.82 stddev:29433.13 (682.9%) stddev-sans-first:29478.51 04:58:57 INFO - PROCESS | 1645 | Values: 620.4 690.4 718.1 723.1 744.0 14467.0 14736.9 15081.0 15266.5 15407.4 13833.0 14024.9 14039.5 14065.9 14071.7 594.5 671.9 806.4 815.9 820.8 811.9 820.3 820.9 822.4 823.3 867.3 870.5 871.1 872.7 873.9 860.3 863.1 864.3 865.6 866.3 632.7 637.2 638.5 638.8 641.3 14108.2 14242.3 14519.6 14563.8 14954.8 14057.3 14082.3 14137.7 14396.0 14408.3 10221.3 11008.6 11033.5 11755.3 11927.3 3651.1 3704.5 3719.5 3820.8 3821.3 103351.0 105875.0 106643.0 107529.0 110075.4 10785.6 10819.2 10851.9 10863.1 11045.1 21548.8 21721.8 21878.5 22044.0 22071.1 7471.9 7546.7 7678.7 7779.3 7831.1 4302.7 4316.9 4346.8 4412.9 4421.4 2986.0 3223.2 3286.4 3397.3 3499.3 607.8 623.2 631.2 641.7 641.8 467.4 528.8 536.5 584.0 600.0 7203.9 8052.4 9353.7 9356.2 9610.3 598.7 661.4 665.3 669.0 673.9 3638.7 4443.6 4653.8 4750.1 4751.5 78171.8 86017.6 104095.5 105378.5 105653.0 79271.0 89339.7 94204.0 94806.5 96087.0 60548.0 83079.0 83230.0 84859.6 84880.6 7375.9 8927.9 9010.5 9046.9 9055.5 33110.0 40392.0 49792.0 50960.5 54283.5 68730.0 70997.0 73785.0 75099.6 76016.6 12839.8 12882.6 13187.3 13247.9 13691.2 14635.4 14675.6 14818.4 14943.0 15109.8 13424.0 13834.9 13912.3 13947.7 14359.5 13739.2 13860.2 14134.9 14419.9 14443.0 13093.9 13424.5 13439.7 14013.8 14121.7 11151.7 11210.6 11221.8 11490.5 11553.9 8022.5 10157.7 11327.6 11596.0 11646.8 04:58:57 INFO - PROCESS | 1645 | -------- Summary: end -------- 04:58:57 INFO - PROCESS | 1645 | 04:58:58 INFO - TEST-INFO | 1645: exit 0 04:58:58 INFO - TEST-OK | dromaeo_css | took 1117318ms 04:58:58 INFO - TEST-START | kraken 04:58:58 INFO - operating with platform_type : mac_ 04:58:58 INFO - Initialising browser for kraken test... 04:58:58 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpSw9hY5/profile http://localhost:49236/getInfo.html) 04:59:01 INFO - PROCESS | 1653 | 2016-11-30 04:59:01.505 plugin-container[1654:11543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 04:59:01 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:01 INFO - PROCESS | 1653 | 2016-11-30 04:59:01.508 plugin-container[1654:11543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 04:59:01 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:01 INFO - PROCESS | 1653 | 2016-11-30 04:59:01.508 plugin-container[1654:11543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:59:01 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:59:01 INFO - PROCESS | 1653 | colorDepth:24 04:59:01 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:59:01 INFO - PROCESS | 1653 | __metrics 04:59:01 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 04:59:02 INFO - TEST-INFO | 1653: exit 0 04:59:02 INFO - Browser initialized. 04:59:02 INFO - Running cycle 1/1 for kraken test... 04:59:02 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpSw9hY5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:04 INFO - PROCESS | 1655 | 2016-11-30 04:59:04.961 plugin-container[1656:11662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 04:59:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:04 INFO - PROCESS | 1655 | 2016-11-30 04:59:04.961 plugin-container[1656:11662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:59:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:04 INFO - PROCESS | 1655 | 2016-11-30 04:59:04.961 plugin-container[1656:11662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:59:14 INFO - PROCESS | 1655 | RSS: Main: 243302400 04:59:14 INFO - PROCESS | 1655 | 04:59:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 04:59:18 INFO - PROCESS | 1655 | RSS: Main: 255262720 04:59:18 INFO - PROCESS | 1655 | 04:59:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 04:59:22 INFO - PROCESS | 1655 | RSS: Main: 262860800 04:59:22 INFO - PROCESS | 1655 | 04:59:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 04:59:26 INFO - PROCESS | 1655 | RSS: Main: 265539584 04:59:26 INFO - PROCESS | 1655 | 04:59:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 04:59:28 INFO - PROCESS | 1655 | RSS: Main: 267710464 04:59:28 INFO - PROCESS | 1655 | 04:59:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:59:31 INFO - PROCESS | 1655 | RSS: Main: 267923456 04:59:31 INFO - PROCESS | 1655 | 04:59:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:35 INFO - PROCESS | 1655 | RSS: Main: 274059264 04:59:35 INFO - PROCESS | 1655 | 04:59:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:38 INFO - PROCESS | 1655 | RSS: Main: 272998400 04:59:38 INFO - PROCESS | 1655 | 04:59:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 04:59:42 INFO - PROCESS | 1655 | RSS: Main: 273047552 04:59:42 INFO - PROCESS | 1655 | 04:59:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:59:44 INFO - PROCESS | 1655 | RSS: Main: 272363520 04:59:44 INFO - PROCESS | 1655 | 04:59:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:59:47 INFO - PROCESS | 1655 | RSS: Main: 272474112 04:59:47 INFO - PROCESS | 1655 | 04:59:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:59:50 INFO - PROCESS | 1655 | RSS: Main: 272416768 04:59:50 INFO - PROCESS | 1655 | 04:59:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:59:54 INFO - PROCESS | 1655 | RSS: Main: 272666624 04:59:54 INFO - PROCESS | 1655 | 04:59:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:59:57 INFO - PROCESS | 1655 | RSS: Main: 272506880 04:59:57 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 04:59:59 INFO - PROCESS | 1655 | RSS: Main: 274296832 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | __start_tp_report 04:59:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:59:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:59:59 INFO - PROCESS | 1655 | |i|pagename|runs| 04:59:59 INFO - PROCESS | 1655 | |0;ai-astar;107;116;151;175;172;121;117;119;119;117 04:59:59 INFO - PROCESS | 1655 | |1;audio-beat-detection;148;115;120;184;121;122;116;130;168;147 04:59:59 INFO - PROCESS | 1655 | |2;audio-dft;207;231;245;241;196;202;199;193;193;193 04:59:59 INFO - PROCESS | 1655 | |3;audio-fft;79;75;84;106;82;72;59;60;75;66 04:59:59 INFO - PROCESS | 1655 | |4;audio-oscillator;90;69;80;68;83;104;67;94;101;115 04:59:59 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;137;133;95;88;93;86;89;87;89;86 04:59:59 INFO - PROCESS | 1655 | |6;imaging-darkroom;97;98;97;96;98;96;96;111;152;99 04:59:59 INFO - PROCESS | 1655 | |7;imaging-desaturate;152;150;92;91;129;150;91;94;151;150 04:59:59 INFO - PROCESS | 1655 | |8;json-parse-financial;58;59;58;59;59;58;60;61;61;59 04:59:59 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;68;74;69;86;77;75;70;91;106;71 04:59:59 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;92;90;90;90;89;88;86;91;88;89 04:59:59 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;160;167;161;152;147;154;153;155;213;202 04:59:59 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;220;176;175;174;179;177;178;174;176;179 04:59:59 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;62;87;96;72;70;72;70;72;83;79 04:59:59 INFO - PROCESS | 1655 | __end_tp_report 04:59:59 INFO - PROCESS | 1655 | __start_cc_report 04:59:59 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,948 04:59:59 INFO - PROCESS | 1655 | __end_cc_report 04:59:59 INFO - PROCESS | 1655 | __startTimestamp1480510799678__endTimestamp 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:59:59 INFO - PROCESS | 1655 | Number of tests: 14 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:131.40 Median:120.00 stddev:24.94 (20.8%) stddev-sans-first:24.84 04:59:59 INFO - PROCESS | 1655 | Values: 107.0 116.0 151.0 175.0 172.0 121.0 117.0 119.0 119.0 117.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:138.50 stddev:23.89 (17.2%) stddev-sans-first:25.01 04:59:59 INFO - PROCESS | 1655 | Values: 148.0 115.0 120.0 184.0 121.0 122.0 116.0 130.0 168.0 147.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:210.00 Median:204.50 stddev:20.77 (10.2%) stddev-sans-first:22.01 04:59:59 INFO - PROCESS | 1655 | Values: 207.0 231.0 245.0 241.0 196.0 202.0 199.0 193.0 193.0 193.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:75.80 Median:75.00 stddev:13.63 (18.2%) stddev-sans-first:14.41 04:59:59 INFO - PROCESS | 1655 | Values: 79.0 75.0 84.0 106.0 82.0 72.0 59.0 60.0 75.0 66.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:74.50 stddev:16.59 (22.3%) stddev-sans-first:17.56 04:59:59 INFO - PROCESS | 1655 | Values: 90.0 69.0 80.0 68.0 83.0 104.0 67.0 94.0 101.0 115.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:88.50 stddev:19.58 (22.1%) stddev-sans-first:14.94 04:59:59 INFO - PROCESS | 1655 | Values: 137.0 133.0 95.0 88.0 93.0 86.0 89.0 87.0 89.0 86.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:97.00 stddev:17.45 (18.0%) stddev-sans-first:18.32 04:59:59 INFO - PROCESS | 1655 | Values: 97.0 98.0 97.0 96.0 98.0 96.0 96.0 111.0 152.0 99.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:125.00 Median:121.50 stddev:29.17 (24.0%) stddev-sans-first:29.26 04:59:59 INFO - PROCESS | 1655 | Values: 152.0 150.0 92.0 91.0 129.0 150.0 91.0 94.0 151.0 150.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.00 stddev:1.14 (1.9%) stddev-sans-first:1.12 04:59:59 INFO - PROCESS | 1655 | Values: 58.0 59.0 58.0 59.0 59.0 58.0 60.0 61.0 61.0 59.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:74.50 stddev:12.17 (16.3%) stddev-sans-first:12.27 04:59:59 INFO - PROCESS | 1655 | Values: 68.0 74.0 69.0 86.0 77.0 75.0 70.0 91.0 106.0 71.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:90.00 stddev:1.70 (1.9%) stddev-sans-first:1.50 04:59:59 INFO - PROCESS | 1655 | Values: 92.0 90.0 90.0 90.0 89.0 88.0 86.0 91.0 88.0 89.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:166.40 Median:160.50 stddev:22.50 (14.0%) stddev-sans-first:23.75 04:59:59 INFO - PROCESS | 1655 | Values: 160.0 167.0 161.0 152.0 147.0 154.0 153.0 155.0 213.0 202.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:177.50 stddev:13.89 (7.8%) stddev-sans-first:1.94 04:59:59 INFO - PROCESS | 1655 | Values: 220.0 176.0 175.0 174.0 179.0 177.0 178.0 174.0 176.0 179.0 04:59:59 INFO - PROCESS | 1655 | 04:59:59 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:75.50 stddev:9.97 (13.2%) stddev-sans-first:9.13 04:59:59 INFO - PROCESS | 1655 | Values: 62.0 87.0 96.0 72.0 70.0 72.0 70.0 72.0 83.0 79.0 04:59:59 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:59:59 INFO - PROCESS | 1655 | 05:00:00 INFO - TEST-INFO | 1655: exit 0 05:00:00 INFO - TEST-OK | kraken | took 62508ms 05:00:00 INFO - SUITE-END | took 1179s 05:00:00 INFO - Completed test suite (00:19:39) 05:00:00 INFO - javascript benchmark 05:00:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7111.482777746689, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.846961886715, 3406.710429613481, 3426.0920855973154, 3427.828608569571, 3427.982860085699, 5577.024020796354, 5706.1440783882435, 5739.56953228508, 5786.479216870481, 5789.855253618662, 5615.2700148980675, 5737.598368114233, 5750.964826331269, 5753.309602847664, 5895.823125306237, 5097.49025097491, 5231.136862417697, 5246.842594722164, 5270.288511051017, 5288.180332048529, 5191.0656081905245, 5287.39194992575, 5287.788488460457, 5314.840554783363, 5329.333833270841, 5271.341082364705, 5273.762680679379, 5300.840974770763, 5317.840464786043, 5340.359156901158, 5098.719570423626, 5141.228815677641, 5175.275461435402, 5212.087884620177, 5230.816921407771, 5102.158143906251, 5145.9965306764825, 5163.406208286057, 5172.250023746573, 5195.2986346843445, 5472.589555783332, 5631.408702086263, 5743.597948143632, 5752.338481074688, 5863.794767183171, 6259.217597800275, 6266.937330626681, 6278.869723894802, 6287.528435367366, 6302.243730752339, 5265.473452654742, 5989.161517387569, 6293.213348331458, 6317.46301564363, 6334.208223972004, 5688.687122208277, 5722.055860782966, 5768.0, 5821.650700957956, 5837.445442682938, 6209.2548894133, 6225.221847269091, 6254.21822272216, 6275.811725648238, 6277.2781130170615, 5275.498827611371, 6183.072539119078, 6201.286852011986, 6208.317085120633, 6210.906836397367, 6254.280757712831, 6257.499400047986, 6267.467265282502, 6270.467010304175, 6271.843203919939, 6098.420650038239, 6101.450869421773, 6123.526657168203, 6129.083728908156, 6186.605998014249, 6230.7819226327765, 6241.469475094577, 6250.718717657716, 6286.779962701284, 6303.369663033752, 4912.778924948386, 4926.704397736147, 4956.851294461172, 4974.751262436864, 4992.326035985168, 4378.627816635558, 4386.078923426053, 4394.846180383735, 4451.421315229064, 4468.972136408579, 5767.769289228384, 5827.650340979555, 5962.403759624003, 5965.701714914237, 5965.910511342333, 6258.374162583706, 6275.529335299871, 6299.307076221612, 6305.558610897193, 6305.5901366411035, 4879.512048795092, 5696.23100881384, 5743.10981797822, 5842.941570584325, 5844.1818145459665, 5371.677699338053, 5378.489043540857, 5400.945990540123, 5430.185472179126, 5442.510174084351, 5972.074328479092, 6361.395667411496, 6403.231612206565, 6404.391582799627, 6426.742930282736, 5436.3476382834315, 5466.180072989084, 5694.0, 6301.558890877594, 6307.243130824147, 6175.2589689237575, 6231.2834024086, 6240.625562466266, 6309.589876658002, 6340.7463701451425, 6212.161358216583, 6251.437370636664, 6258.780942666985, 6267.122602835516, 6288.496920246278, 4814.988852341047, 6086.939130608637, 6127.892078906909, 6218.0, 6282.371762823681, 5385.976664433745, 5898.675572843363, 5996.070609055603, 6101.5804940036605, 6122.6938653068055, 5145.845624631267, 5411.83764487066, 6068.816580766708, 6197.752089916533, 6227.595206311576, 5134.845954621367, 5577.135543990688, 5883.117532370007, 5883.264591926009, 5995.370486098896, 6120.020796672511, 6124.930239974474, 6132.846678832886, 6139.2019037526625, 6140.13616995759, 6102.974033066949, 6107.114468402146, 6107.85643655375, 6112.503480924647, 6117.401149625394], "value": 5674.120952635588, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.7408240261849, 471.60385276367845, 492.4558363008874, 516.8191133103417, 540.0845566764336, 35408.0, 38671.419928701056, 38867.805660971724, 38882.0, 39000.21999560007, 40061.19877602446, 40506.7974660127, 40523.189536209255, 40552.391714124235, 40608.593914060926, 8214.63034163464, 9154.26765858733, 9307.860382094274, 9313.16181543661, 9330.906690933072, 8291.543965081919, 10986.780264394707, 11237.775244495106, 11259.718507037349, 11416.372099534523, 11239.719007024849, 11242.775144497105, 11287.943560282187, 11299.03958163557, 11331.03686186675, 8854.247388971946, 11129.054030407426, 11181.608643697473, 11213.934676205788, 11225.719357016058, 11106.27809192408, 11143.108551315956, 11169.106471482342, 11180.04969577587, 11194.888051119548, 31518.684813151805, 38222.61777382218, 38342.0, 38671.613283867366, 38695.80652096721, 3319.5684561007156, 3456.5160877476924, 3504.0, 3888.066863952631, 3955.0903292242656, 29064.564031539543, 29576.55635165474, 29691.5546266804, 29819.850900745576, 30325.696743032728, 2769.8476583787883, 3163.4938409854544, 3204.839758012113, 3220.5008223725126, 3228.1122691260093, 444060.0, 447870.0, 448223.0, 450989.0, 451076.0, 47971.92238659683, 57340.426595734345, 57510.42489575134, 57623.42376576264, 57759.0, 48982.5101748978, 58022.70988645114, 58457.0, 59182.0, 59574.40425595775, 14784.926075369554, 15239.0, 15313.23433828304, 15337.309821057856, 15362.846371536141, 10676.89323106759, 10940.890591094147, 11649.59226427088, 11702.76594468106, 11832.112591555495, 13297.468101276034, 16041.438549650706, 16393.754093688603, 16471.91764041172, 16538.751918721227, 7131.964340178266, 7140.250273721288, 7196.856062878713, 7203.351698347069, 7289.854202915912, 835.5571547080058, 890.1098901098901, 908.8500397434482, 928.8560273157672, 967.3228739882111, 28988.85505572501, 29062.41875162527, 29070.854645726635, 29584.408311833642, 29913.401731965674, 988.3180605382263, 1001.5442973447064, 1035.2753072849036, 1036.9481525923673, 1118.6196693124377, 12969.0, 14482.710345793024, 15040.398384064734, 15061.623459413384, 15449.9227503864, 543267.0, 548506.0, 549147.0, 549401.0, 551878.0, 53967.73016134894, 54325.913481730146, 54536.72731636316, 54653.0, 54963.72518137384, 1220.993895030519, 1289.3295486347047, 1310.5544115000946, 1327.9933600331935, 1346.7710489216856, 27855.0, 28809.56785648259, 28964.16886122386, 29309.12072637824, 29484.410311794072, 47835.521644783104, 48280.7585962068, 48345.13238940947, 48529.514704853915, 48653.75673121612, 405466.0, 409419.0, 409952.0, 413419.93290033354, 413644.0, 32589.837050814593, 33406.0, 37392.065198370256, 38307.0, 38469.807650962684, 43936.560634393245, 44138.779306104545, 44180.11639767251, 44469.77765111154, 45406.77296613628, 36201.0, 36526.634733652325, 36671.81664091662, 36793.816030919676, 37682.0, 35524.822375887954, 36148.0, 36317.0920726984, 37604.05989850166, 38138.0, 35437.114072148404, 35937.82031089828, 36594.0, 36673.0, 36937.63062369342, 27161.456770864075, 28178.01376951797, 31569.0, 31872.203194920312, 31887.6811231894, 21618.89190554037, 24670.259892203263, 24795.62806557867, 24899.96661401294, 25272.87363563244], "value": 19260.291026357725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.15354609575303, 367.2050011724615, 376.24187262665725, 379.6635841836733, 396.0909712210478, 15007.324670389831, 18194.72707909381, 19739.5065123372, 19823.909684967322, 20021.699674504893, 16170.434034808784, 18769.53076173093, 19103.52241193971, 19404.417867464, 19546.41360759176, 5533.584981126422, 5754.539636829044, 5802.332731735863, 5813.6802475863815, 5851.94664960307, 5538.806141785039, 5699.344575373843, 5800.709964501779, 5815.680137592431, 5817.418258174191, 4915.508449155073, 5755.568332375067, 5759.856003599923, 5761.078227483604, 5771.163181338703, 4823.228283474645, 4823.445303790074, 5485.259489968843, 5713.085906254979, 5748.5401167906675, 4903.730294833783, 4976.154053810861, 5005.799768009311, 5017.996400719834, 5019.4227663818765, 12382.380880955916, 16500.092494912777, 17739.911300443546, 21183.046762895705, 22801.657975130387, 2135.2419890938804, 2190.58378908007, 2280.7149106361703, 2530.165045534969, 2627.9868600657064, 9325.253979681609, 9411.529423528797, 9563.474008929506, 9799.11807937289, 9822.901770982271, 1760.9500385083702, 1878.2111513164366, 1886.1323791056132, 1894.488488108203, 1896.4879482539775, 111756.0, 117596.41201793936, 119453.0, 120397.0, 120454.0, 9104.226140777975, 11141.94429027866, 11226.045786108109, 11308.378039207922, 11490.885091149148, 14029.579112626638, 14143.929280353532, 14192.716145677236, 14211.64470888236, 14242.643933901736, 7865.410094242955, 8175.591220438954, 8190.918090819135, 8290.37822163328, 8293.087760346358, 7944.6027698614835, 7956.721514746956, 7972.880406793903, 8110.63502142405, 8111.432199746079, 4368.191884501377, 4397.472303323621, 4398.714083584621, 4425.17639971754, 4443.333499974965, 1543.9228038598026, 1871.868969172172, 1920.4526709887614, 1925.2395303855178, 1934.3133187718438, 796.2674339607574, 819.5943008210974, 820.8358328334357, 896.3546246702364, 913.7441516375426, 19396.612067758848, 19626.705599416022, 19988.0, 20171.99140042992, 20173.780884102736, 875.1642181716445, 901.2834796336797, 905.1491597897955, 910.7905181808221, 916.4867674102524, 7640.808979775439, 7994.480358776661, 10018.148457381058, 10123.493825308706, 10661.73345666349, 505254.0, 516806.0, 517617.0, 525493.0, 526602.0, 88448.0, 91638.0, 113438.0, 117272.0, 125652.0, 1000.9949950250202, 1005.6932635546375, 1067.8878717734526, 1070.03162138265, 1077.5205033760058, 18196.636067278316, 18425.907870460564, 18545.0, 18812.0, 18838.058097095363, 41206.793966029974, 44623.10753784841, 48832.267515988, 53795.73102134464, 55994.160087597906, 68072.63854722977, 75061.24938750542, 79507.20492795229, 80642.0, 81206.59396702978, 7607.657655405409, 7832.412569057457, 7912.960435197787, 7946.642401091849, 7987.720429784929, 10799.784004320027, 11120.499577519193, 11372.0, 12302.630921072383, 12562.748745025232, 9204.447733135765, 10633.468326583794, 10704.678859634223, 10749.366405230456, 10878.238523303293, 9568.473733944422, 10352.689419317132, 10632.574697012033, 10834.0, 10913.126949843834, 10811.729706757395, 10951.83572246401, 10977.725556861142, 11225.326480411202, 11388.715282118013, 5542.919130769623, 6478.805635830932, 6509.902351464637, 7298.598577078312, 7349.8162545936775, 8079.474834135763, 10473.162147028068, 10727.624533141303, 10764.838527421938, 10765.084967777513], "value": 8969.29692567694, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.632128807154, 1487.0037075159642, 1640.9097499637523, 1739.09567025147, 1799.064486467037, 7116.3239492248285, 7713.614319284027, 7916.72291469798, 8002.199780022002, 8358.958205208974, 7125.786226413203, 7966.681332746698, 7996.640151193195, 8344.833103337945, 8362.205590468911, 2237.59723249815, 2281.133169395627, 2289.370423133635, 2293.3463962770616, 2294.3117064880553, 2313.9768602314016, 2328.93013209604, 2348.412896775806, 2359.044586942291, 2367.1833217540006, 2291.232437133554, 2300.0, 2312.606856834342, 2315.47901722112, 2316.6872472216287, 2280.5894938910988, 2290.541891621682, 2308.7259049835898, 2312.9884350578222, 2314.560233555636, 1883.849292056632, 2292.255017119426, 2302.044651469655, 2322.7212734471805, 2323.0939933425975, 8326.125756795509, 8365.372597055246, 8400.0, 8421.663133474654, 8442.324614030862, 5011.749412529395, 5153.0, 5168.870778230536, 5175.938932518825, 5186.014657215117, 3233.7413006959387, 3287.211069343364, 3560.537130173087, 3583.157957879896, 3589.0847833802472, 1027.1320733979776, 1030.1243942648764, 1072.8766191887955, 1086.5110700184948, 1086.9945650271777, 10821.13430925532, 10857.728556786065, 10860.67417977462, 10888.183386245984, 10947.83578246327, 4432.778361081933, 4565.726056436624, 4657.60410365116, 4670.065986802585, 4677.836275730401, 9208.0, 9303.116203960613, 9329.906700932905, 9335.906640933505, 9352.39209451397, 5126.564242039469, 5130.743462826844, 5136.512031357015, 5137.717425541557, 5140.1004824155625, 4198.328267477189, 4483.417155769795, 4671.719696818202, 4679.906401871944, 4690.108879312919, 3760.6615404613717, 3765.3410653135593, 3827.2919509890758, 3863.4784304118584, 3878.4376265441915, 1196.5034510678145, 1254.905882058831, 1263.210493441599, 1263.5072321794507, 1276.0238417610535, 791.3589992106412, 888.9911100888909, 891.460666296894, 893.9910600894038, 898.285862739124, 5449.972750136224, 5567.081431563827, 5624.959382514248, 5758.7696492140685, 5766.077427611646, 906.3836591118103, 949.3781573069513, 960.5437417226901, 989.42118860466, 1039.2569312941284, 3598.8020658864016, 3779.9433008504893, 3868.574456809749, 3878.0886491674432, 3881.2237552489614, 9771.51142442875, 10069.79860402788, 10215.693529193988, 10224.0, 10227.386356818615, 9874.80250394988, 9963.750906227257, 10027.398356098658, 10049.14582260516, 10155.492225388702, 7708.961455192801, 7750.806229844299, 7872.291493765589, 7874.645640946172, 7912.643931023118, 4633.119707255611, 5458.454154584669, 5491.75287112073, 5529.474699903502, 5573.303337082864, 7624.085109786861, 7908.6045697713735, 8552.930883639545, 9363.672271470467, 9393.389429687048, 8552.786180345542, 8922.884639420072, 9193.816123677623, 9448.574814133242, 9518.0, 6324.4940404766585, 6491.513136514686, 6966.9651651741415, 7149.53528020677, 7357.006804081595, 6697.732090716317, 7581.393488520795, 7839.09850367215, 7977.680892764457, 7988.880166797619, 7047.859042819217, 7099.07711997437, 7116.25279345682, 7122.928770712227, 7250.9637451812405, 6906.37842594169, 7067.328603782633, 7081.858362832818, 7082.468814838805, 7086.255943126103, 6535.869282614226, 6567.113439685581, 6604.801855944329, 6636.966815165893, 6637.269900311058, 4799.808007679654, 4815.085133824702, 4853.247746599282, 4887.7556122194455, 4894.388201474816, 4778.04439112172, 4848.903021939612, 4849.903001940012, 4951.554360107608, 4969.0807200668], "value": 4384.15707367297, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.7166356129896, 297.5046547498415, 303.9802412843164, 309.1452134847148, 312.8341978751261, 4333.566643335665, 5335.33308336458, 5429.728513574325, 5600.887982240352, 5605.775768969246, 5217.843464696056, 5313.628046036769, 5382.0, 5408.648437851547, 5460.0414699342955, 4102.241085399196, 4172.4784401949755, 4176.269152898246, 4179.2686279901045, 4199.9630179362985, 3487.1518095409347, 3643.8178091095474, 4008.7594744315434, 4049.817758200888, 4212.957870421303, 3755.8309876055505, 3967.9683282346655, 4111.629953304202, 4133.256013917494, 4162.375643653446, 4121.835126594932, 4123.8969025774295, 4130.265288578804, 4130.277201489742, 4138.813753381105, 3595.694365978895, 3601.207734298476, 3603.9457509219756, 3632.4209811033934, 3646.361886669849, 5026.0, 5333.893322133536, 5343.599230057723, 5344.22508736235, 5355.3841308249275, 4697.802060474592, 4705.835295764632, 4715.905681886377, 4734.360861283717, 4766.904661906777, 5802.361740208574, 5829.533637309005, 5848.88302233953, 5855.916655418719, 5861.618994765369, 3480.5475288212624, 3876.127871228979, 3890.824912878899, 3913.471681323042, 3921.5294164700135, 6031.728572214217, 6080.695965201722, 6082.270127584674, 6103.481204097657, 6213.627182369072, 3952.6165841955662, 3966.1671049080014, 3976.900577485586, 3982.7809470478824, 3987.7806720630065, 3364.4953257011643, 3501.719862411001, 3836.405357169643, 4014.253799597126, 4023.6781057515327, 3840.577536470986, 4154.8338066477, 4540.455145382575, 4685.273782563708, 4732.408448943882, 5333.893322133536, 5811.401864487215, 5877.617954832922, 5885.175115944285, 5917.911231331534, 1082.0, 1183.621241202824, 1234.512367614787, 1303.5307289375817, 1307.6142537951328, 856.7277592774725, 868.5743985447211, 877.5392918717597, 878.2447095497866, 884.4207044385937, 4538.230090264799, 4595.678302518859, 5045.683758550043, 5192.935448233103, 5201.895962080737, 5013.89972200554, 5348.0318423274675, 5558.471945165203, 5672.0, 5712.6286791358425, 4421.712588681726, 4588.931166032512, 5036.822527897731, 5158.63889527737, 5193.454687257859, 2679.2632026192955, 2938.353562216309, 2990.5364668476423, 3031.120435586202, 3076.1540576341235, 5134.845954621367, 5369.3556773187465, 5890.617109887844, 5949.9107513387335, 5969.224000879858, 4956.752162391866, 5445.673259604437, 5680.648417433651, 5782.7905512441, 5929.822105336847, 5641.956238095965, 5749.182900378701, 5802.332731735904, 5938.368072903142, 6020.548458865603, 5368.382635996911, 5476.863078423071, 5538.584606154474, 5817.767289308549, 5818.534517238695, 4984.8753781155765, 5622.24099746529, 5728.391113598406, 5730.828075157585, 5732.541396688338, 5010.845336505192, 5513.58648101402, 5531.778728850962, 6010.008348622603, 6036.849078773066, 3276.7870088444174, 3431.159365955357, 3636.9521399461396, 3711.5546134462957, 3762.4732537445325, 5005.149124648891, 5019.318528293063, 5583.385827559046, 5745.253117094751, 5804.535637148933, 5402.244270611988, 5786.537077033911, 5863.270335251026, 5875.764969401176, 5911.6748578826855, 5579.776808927598, 5856.209411729362, 5872.324682661378, 5879.764809407747, 5906.970465147647, 4895.33912921751, 4989.675671081368, 5152.681293418052, 5549.38956714753, 5761.539076873756, 5773.682447465262, 5842.707864606838, 5855.853603659943, 5866.6186697864505, 5875.911861321998, 4843.958548912001, 5215.835428297214, 5320.441353657965, 5509.3664228614225, 5748.827535173951], "value": 4130.946092199841, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.370324121017, 690.4096997067506, 718.0629278791179, 723.1358526560758, 743.9628018599069, 14467.0, 14736.926315368435, 15081.0, 15266.542003739878, 15407.3837046518, 13833.031687781859, 14024.859751402508, 14039.5086171984, 14065.859341406584, 14071.718565628707, 594.4649815166341, 671.9092922455479, 806.3911746631285, 815.8735396013627, 820.8440396324679, 811.930985866202, 820.335528222142, 820.8645573480369, 822.3914303415489, 823.3248736036453, 867.3061550759368, 870.5168631409565, 871.1071152069148, 872.6640243506264, 873.8995015573227, 860.3418384935529, 863.1497974495118, 864.2783275964546, 865.6277800545763, 866.3242670716851, 632.7469012395032, 637.1716768201366, 638.5019684645983, 638.7764282501134, 641.3362170153869, 14108.153510789283, 14242.287885605678, 14519.564413067625, 14563.781543276858, 14954.775678364833, 14057.297135143202, 14082.295885205802, 14137.717245655029, 14396.017423771002, 14408.279586020763, 10221.33561318512, 11008.614698485515, 11033.503492342783, 11755.294682319089, 11927.284362938164, 3651.050726811022, 3704.499892514503, 3719.543805080346, 3820.808959552039, 3821.3312670282867, 103351.0, 105875.0, 106643.0, 107529.0, 110075.44962275137, 10785.568577256821, 10819.242653014213, 10851.945740271405, 10863.07663848566, 11045.122329203894, 21548.78451215499, 21721.782782172293, 21878.45303867416, 22044.008019639154, 22071.11715531393, 7471.925280747231, 7546.660400282, 7678.654460549289, 7779.338756205672, 7831.099423566363, 4302.698811083194, 4316.935245971313, 4346.8043938022865, 4412.889677758082, 4421.38100665907, 2986.0, 3223.16197788572, 3286.4084464796088, 3397.2865698203655, 3499.2651543176216, 607.7781609712423, 623.2178615837186, 631.2046821005566, 641.7080228496023, 641.8074577626693, 467.4414075180195, 528.7620570743104, 536.4742552298769, 584.0305093544696, 599.9880002399927, 7203.855922881513, 8052.436329456882, 9353.719388418358, 9356.157945784911, 9610.327277090537, 598.7066337494597, 661.4377778888003, 665.2781731821028, 668.9698963546653, 673.8921772516373, 3638.6725194732603, 4443.577860103285, 4653.813847446132, 4750.12122757291, 4751.477337492873, 78171.8274225887, 86017.56991215004, 104095.4795226019, 105378.47310763397, 105653.0, 79271.0, 89339.65990510148, 94204.0, 94806.52596736971, 96087.0, 60548.0, 83079.0, 83230.0, 84859.57570212109, 84880.57559712161, 7375.852482950419, 8927.866082008904, 9010.459372437675, 9046.864297035681, 9055.504355322872, 33110.00669979808, 40392.0, 49792.00415991732, 50960.490395097055, 54283.45716542942, 68730.0, 70997.03020788495, 73785.0, 75099.62450187714, 76016.61991690006, 12839.807402888777, 12882.613521594367, 13187.274699891597, 13247.867521324664, 13691.246981416118, 14635.41458341697, 14675.633109172357, 14818.40726370933, 14943.0, 15109.773353399489, 13424.0, 13834.93082534581, 13912.28710401462, 13947.651308717363, 14359.497417590333, 13739.24434156131, 13860.237686927316, 14134.858651413766, 14419.85580144185, 14442.988990770966, 13093.869061309266, 13424.463021479032, 13439.664008399868, 14013.83824123659, 14121.717565648423, 11151.665450036511, 11210.64576438957, 11221.831672525082, 11490.48292826808, 11553.884461155281, 8022.518648881086, 10157.746056348651, 11327.603533876274, 11596.0, 11646.76706465849], "value": 7285.521182330024, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1619.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 151.0, 175.0, 172.0, 121.0, 117.0, 119.0, 119.0, 117.0], "value": 131.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 120.0, 184.0, 121.0, 122.0, 116.0, 130.0, 168.0, 147.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 231.0, 245.0, 241.0, 196.0, 202.0, 199.0, 193.0, 193.0, 193.0], "value": 210.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 84.0, 106.0, 82.0, 72.0, 59.0, 60.0, 75.0, 66.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 80.0, 68.0, 83.0, 104.0, 67.0, 94.0, 101.0, 115.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 133.0, 95.0, 88.0, 93.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 96.0, 98.0, 96.0, 96.0, 111.0, 152.0, 99.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 92.0, 91.0, 129.0, 150.0, 91.0, 94.0, 151.0, 150.0], "value": 125.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 59.0, 58.0, 60.0, 61.0, 61.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 69.0, 86.0, 77.0, 75.0, 70.0, 91.0, 106.0, 71.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 90.0, 90.0, 89.0, 88.0, 86.0, 91.0, 88.0, 89.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 167.0, 161.0, 152.0, 147.0, 154.0, 153.0, 155.0, 213.0, 202.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 176.0, 175.0, 174.0, 179.0, 177.0, 178.0, 174.0, 176.0, 179.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 87.0, 96.0, 72.0, 70.0, 72.0, 70.0, 72.0, 83.0, 79.0], "value": 76.3, "unit": "score"}]}]} 05:00:00 INFO - Return code: 0 05:00:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:00:00 ERROR - # TBPL SUCCESS # 05:00:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:00:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:00:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:00:00 INFO - 2016-11-30 05:00:00.984 screenresolution[1660:12040] starting screenresolution argv=screenresolution get 05:00:00 INFO - 2016-11-30 05:00:00.988 screenresolution[1660:12040] Display 0: 1600x1200x32@60 05:00:00 INFO - 2016-11-30 05:00:00.993 screenresolution[1661:12043] starting screenresolution argv=screenresolution list 05:00:00 INFO - Available Modes on Display 0 05:00:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:00:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:00:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:00:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:00:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:00:01 INFO - Intel Iris: 05:00:01 INFO - Chipset Model: Intel Iris 05:00:01 INFO - Type: GPU 05:00:01 INFO - Bus: Built-In 05:00:01 INFO - VRAM (Dynamic, Max): 1536 MB 05:00:01 INFO - Vendor: Intel (0x8086) 05:00:01 INFO - Device ID: 0x0a2e 05:00:01 INFO - Revision ID: 0x0009 05:00:01 INFO - Displays: 05:00:01 INFO - Display: 05:00:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:00:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:00:01 INFO - Display Serial Number: 1600x1200 60 05:00:01 INFO - Main Display: Yes 05:00:01 INFO - Mirror: Off 05:00:01 INFO - Online: Yes 05:00:01 INFO - Rotation: Supported 05:00:01 INFO - Return code: 0 05:00:01 INFO - Running post-action listener: _resource_record_post_action 05:00:01 INFO - [mozharness: 2016-11-30 13:00:01.238709Z] Finished run-tests step (success) 05:00:01 INFO - Running post-run listener: _resource_record_post_run 05:00:01 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 34365952; Write bytes: 698954752; Read time: 362; Write time: 1999 05:00:01 INFO - TinderboxPrint: CPU usage
54.5% 05:00:01 INFO - TinderboxPrint: I/O read bytes / time
34,365,952 / 362 05:00:01 INFO - TinderboxPrint: I/O write bytes / time
698,954,752 / 1,999 05:00:01 INFO - TinderboxPrint: CPU idle
2,206.0 (45.4%) 05:00:01 INFO - TinderboxPrint: CPU system
979.2 (20.2%) 05:00:01 INFO - TinderboxPrint: CPU user
1,668.5 (34.4%) 05:00:01 INFO - TinderboxPrint: Swap in / out
413,982,720 / 0 05:00:01 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 364962304; Write bytes: 360450048; Read time: 27559; Write time: 672 05:00:01 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 33444352; Write bytes: 311483392; Read time: 349; Write time: 1261 05:00:01 INFO - Running post-run listener: _upload_blobber_files 05:00:01 INFO - Blob upload gear active. 05:00:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:00:01 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 05:00:01 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'] 05:00:01 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 05:00:02 INFO - (blobuploader) - INFO - Open directory for files ... 05:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:00:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:03 INFO - (blobuploader) - INFO - Done attempting. 05:00:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:00:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:00:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:05 INFO - (blobuploader) - INFO - Done attempting. 05:00:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:00:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:00:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:07 INFO - (blobuploader) - INFO - Done attempting. 05:00:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:00:07 INFO - Return code: 0 05:00:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:00:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:00:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676e86707196f7bab023f0bfe1e5a62e8025ac4d8d3a020bbc10d895a9a9e072cf1784d641ae4f9345f34a6348aad12a31a5c724f05c9237ec87c5184d56f9b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d9c32b0167b6821f084d8cc1a4ea824227a8bff79becbae10df92d0e796b13b9da04d63b5928e31e364088c0a4545f27f4677853bbee124c7f2b06fc390c88e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27f42ffb76438dcfe535311e867cc0889c765ba3686a468ac0bac5c0583432f86a32f4ee0d944143ac50fd6f9793079d809d0004771c862851be01b3ccd92fb3"} 05:00:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:00:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:00:07 INFO - Contents: 05:00:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676e86707196f7bab023f0bfe1e5a62e8025ac4d8d3a020bbc10d895a9a9e072cf1784d641ae4f9345f34a6348aad12a31a5c724f05c9237ec87c5184d56f9b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d9c32b0167b6821f084d8cc1a4ea824227a8bff79becbae10df92d0e796b13b9da04d63b5928e31e364088c0a4545f27f4677853bbee124c7f2b06fc390c88e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27f42ffb76438dcfe535311e867cc0889c765ba3686a468ac0bac5c0583432f86a32f4ee0d944143ac50fd6f9793079d809d0004771c862851be01b3ccd92fb3"} 05:00:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:00:07 INFO - Copying logs to upload dir... 05:00:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:00:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.327718 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-30 05:00:07.936726) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:00:07.940250) ========= 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.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/676e86707196f7bab023f0bfe1e5a62e8025ac4d8d3a020bbc10d895a9a9e072cf1784d641ae4f9345f34a6348aad12a31a5c724f05c9237ec87c5184d56f9b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d9c32b0167b6821f084d8cc1a4ea824227a8bff79becbae10df92d0e796b13b9da04d63b5928e31e364088c0a4545f27f4677853bbee124c7f2b06fc390c88e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27f42ffb76438dcfe535311e867cc0889c765ba3686a468ac0bac5c0583432f86a32f4ee0d944143ac50fd6f9793079d809d0004771c862851be01b3ccd92fb3"} build_url:https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010914 build_url: 'https://queue.taskcluster.net/v1/task/DL85i0TKQEyPmvvd0V3-JA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/676e86707196f7bab023f0bfe1e5a62e8025ac4d8d3a020bbc10d895a9a9e072cf1784d641ae4f9345f34a6348aad12a31a5c724f05c9237ec87c5184d56f9b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d9c32b0167b6821f084d8cc1a4ea824227a8bff79becbae10df92d0e796b13b9da04d63b5928e31e364088c0a4545f27f4677853bbee124c7f2b06fc390c88e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27f42ffb76438dcfe535311e867cc0889c765ba3686a468ac0bac5c0583432f86a32f4ee0d944143ac50fd6f9793079d809d0004771c862851be01b3ccd92fb3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:00:07.973948) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:00:07.974271) ========= 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.Q86HgNz16T/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.uT6dHZYvSK/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:00:08.022638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:00:08.022961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:00:08.023276) ========= ========= Total master_lag: 0.20 =========